Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Gateway loses network connection and needs manual reset to recover, often initiated by MQTT problems or by reboot for firmware upgrade #8

Open
avbentem opened this issue Feb 12, 2018 · 17 comments

Comments

Projects
None yet
5 participants
@avbentem
Copy link

commented Feb 12, 2018

My gateway lost its WiFi connection over an hour ago, and could not recover without cycling its power, like others have reported on the forum as well. Some observations:

While failing to reconnect

  • I think it had been running for about 20 hours. (Certainly not a full day; I took a picture at 9 PM last night, when it was not connected; at around 6 PM today it showed as "Last seen: 54 minutes ago".)

  • It might have been in AP mode (LED 2 flashing slow and fast).

  • Plugging in a debug cable restarted the gateway.

  • The unexpected reset of the gateway did not resolve this.

  • After the reset, it kept reporting things like:

    INET: Trying to connect to WiFi router again (after 120 seconds)
    INET: State change to 0
    MON: SYS Stack size: 3959
    MON: heap usage: 148KB (148KB), free: 191KB
    INET: No Ethernet and WiFi link (after 10 seconds)
    INET: State change to 1
    
  • The FRITZ!Box 7360 WiFi AP did not log any connection attempts.

  • Restarting the WiFi AP did not resolve this. All other devices (Sonos, Apple TV, Chromecast, laptop, tablet, telephone) reconnected just fine.

  • Attaching an ethernet cable did not resolve this. The connector's LEDs were flashing, but I forgot to check if the router showed a connection.

  • Finally cycling power resolved it.

During normal operation

  • When connected to ethernet and unplugging the cable, it nicely recovers by connecting to WiFi. (LED 2 flashing for some time, while LED 1 and 3 are solid.)

  • Restarting the AP when the gateway is connected to its WiFi, makes the gateway reboot:

    LORA: Accepted packet
    MQTT: Sending UPLINK failed: -1
    
    MAIN: MQTT error
    
    MAIN: Leaving state 5
    SNTP: State change from 0 to 0
    SNTP: State change from 0 to 0
    

    Next, when it cannot connect to WiFi after restarting, it starts in AP mode (LED 2 flashing) but eventually connects to WiFi again, and all is good.

@avbentem

This comment has been minimized.

Copy link
Author

commented Feb 14, 2018

It kept running for 36 hours, but eventually got into a non-recoverable network problem again. The new logs shows:

  • It can recover from some MQTT problems (10:07 this morning, Amsterdam time).

  • Sometimes it cannot recover from MQTT problems, even though it has an internet connection and can nicely fetch its configuration from the internet.

So I wonder:

  • Could the non-recoverable problem have started by MQTT problems on the server, around 2018-02-14 17:05:15 (Amsterdam time), and be different MQTT problems than the one it could recover from?

Nicely recovering from MQTT: Sending status failed (after running for 36 hours)

  • Running my own firmware, with debug logging enabled. (See PR #7.)

  • Logging started 2018-02-12 22:42:10. I'm not sure how long the gateway was running then, but not for very long.

  • It failed about 36 hours later, MQTT: Sending status failed, but recovered. All logs here have MON:-lines removed for brevity:

     [2018-02-14 10:06:11] MQTT: Sending UPLINK OK
     [2018-02-14 10:06:22] MQTT: Sending status packet
     [2018-02-14 10:06:22] MQTT: Sending status succeeded: 1351
     [2018-02-14 10:06:47] LORA: Packet dropped! Bad CRC
     [2018-02-14 10:06:52] MQTT: Sending status packet
     [2018-02-14 10:06:52] MQTT: Sending status succeeded: 1352
     [2018-02-14 10:07:07] LORA: Packet dropped! Bad CRC
     [2018-02-14 10:07:22] MQTT: Sending status packet
     [2018-02-14 10:07:22] MQTT: Sending status succeeded: 1353
     [2018-02-14 10:07:52] CB: MQTT: Sending status packet
     [2018-02-14 10:07:54] MQTT: Sending status failed
     [2018-02-14 10:07:54] 
     [2018-02-14 10:07:54] MAIN: MQTT error
     [2018-02-14 10:07:55] 
     [2018-02-14 10:07:55] MAIN: Leaving state 5
     [2018-02-14 10:07:57] MAIN: Entering state 6
     [2018-02-14 10:07:57] INET: State change to 0
     [2018-02-14 10:07:57] WIFI: Disabling modules
     [2018-02-14 10:07:57] SNTP: State change from 7 to 8
     [2018-02-14 10:07:57] CB: Disconnect
     [2018-02-14 10:07:57] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 10:07:57] SNTP: State change from 8 to 1
     [2018-02-14 10:07:57] WIFI: Entering state 3
     [2018-02-14 10:07:58] WIFI: Enabling modules for client
     [2018-02-14 10:07:58] WIFI: Entering state 6
     [2018-02-14 10:07:58] WIFI: IP Address: 0.0.0.0 
     [2018-02-14 10:08:00] CB: INET: Gateway has WiFi
     [2018-02-14 10:08:00] INET: State change to 2
     [2018-02-14 10:08:00] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
     [2018-02-14 10:08:01] SNTP: State change from 1 to 2
     [2018-02-14 10:08:04] CB: MON: SYS Stack size: 2833
     [2018-02-14 10:08:05] INET: State change to 3
     [2018-02-14 10:08:05] INET: Ping probe
     [2018-02-14 10:08:05] INET: Error sending probe on Eth
     [2018-02-14 10:08:05] LORA: Accepted packet
     [2018-02-14 10:08:06] SNTP: State change from 2 to 6
     [2018-02-14 10:08:09] INET: Ping Timeout
     [2018-02-14 10:08:09] INET: Ping probe
     [2018-02-14 10:08:09] INET: Error sending probe on Eth
     [2018-02-14 10:08:11] LORA: Accepted packet
     [2018-02-14 10:08:13] INET: Ping Timeout
     [2018-02-14 10:08:13] INET: Ping probe
     [2018-02-14 10:08:13] INET: Error sending probe on Eth
     [2018-02-14 10:08:16] CB: Disconnect
     [2018-02-14 10:08:17] INET: Ping Timeout
     [2018-02-14 10:08:17] INET: State change to 1
     [2018-02-14 10:08:17] WIFI: Disabling modules
     [2018-02-14 10:08:17] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 10:08:17] SNTP: State change from 6 to 1
     [2018-02-14 10:08:17] WIFI: Entering state 3
     [2018-02-14 10:08:18] WIFI: Entering state 4
     [2018-02-14 10:08:18] WIFI: Entering SCAN state 1
     [2018-02-14 10:08:18] Scan is completed successfully
     [2018-02-14 10:08:18] WIFI: Entering SCAN state 2
     [2018-02-14 10:08:18] WIFI: Entering SCAN state 3
     [2018-02-14 10:08:18] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 10:08:18] SNTP: State change from 1 to 1
     [2018-02-14 10:08:18] WIFI: Entering SCAN state 4
     [2018-02-14 10:08:19] WIFI: Entering SCAN state 5
     [2018-02-14 10:08:19] WIFI: Entering SCAN state 0
     [2018-02-14 10:08:19] WIFI: Entering state 5
     [2018-02-14 10:08:19] WIFI: Enabling modules for server
     [2018-02-14 10:08:19] WIFI: Entering state 6
     [2018-02-14 10:08:19] WIFI: IP Address: 192.168.84.1 
     [2018-02-14 10:08:20] LORA: Accepted packet
     [2018-02-14 10:08:24] CB: MON: SYS Stack size: 2833
     [2018-02-14 10:08:36] LORA: Accepted packet
     [2018-02-14 10:08:43] LORA: Accepted packet
     [2018-02-14 10:08:43] LORA: Packet dropped! Bad CRC
     [2018-02-14 10:09:12] LORA: Accepted packet
     [2018-02-14 10:09:17] LORA: Accepted packet
     [2018-02-14 10:09:23] LORA: Accepted packet
     [2018-02-14 10:09:23] LORA: Packet dropped! Bad CRC
     [2018-02-14 10:10:17] INET: Trying to connect to WiFi router again (after 120 seconds)
     [2018-02-14 10:10:17] INET: State change to 0
     [2018-02-14 10:10:17] WIFI: Disabling modules
     [2018-02-14 10:10:17] CB: Disconnect
     [2018-02-14 10:10:17] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 10:10:17] SNTP: State change from 1 to 1
     [2018-02-14 10:10:17] WIFI: Entering state 3
     [2018-02-14 10:10:18] WIFI: Enabling modules for client
     [2018-02-14 10:10:18] WIFI: Entering state 6
     [2018-02-14 10:10:18] WIFI: IP Address: 0.0.0.0 
     [2018-02-14 10:10:19] CB: INET: Gateway has WiFi
     [2018-02-14 10:10:19] INET: State change to 2
     [2018-02-14 10:10:19] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
     [2018-02-14 10:10:20] SNTP: State change from 1 to 2
     [2018-02-14 10:10:21] WIFI: IP Address: 192.168.178.53 
     [2018-02-14 10:10:23] LORA: Kick LoRa module with ACK after not acked it for 60s
     [2018-02-14 10:10:24] INET: State change to 3
     [2018-02-14 10:10:24] INET: Ping probe
     [2018-02-14 10:10:24] INET: Error sending probe on Eth
     [2018-02-14 10:10:24] INET: Ping response from MRF24WN, set as default
     [2018-02-14 10:10:24] INET: State change to 5
     [2018-02-14 10:10:24] 
     [2018-02-14 10:10:24] MAIN: Leaving state 6
     [2018-02-14 10:10:24] MAIN: Entering state 5
     [2018-02-14 10:10:24] MQTT: GOT IP: 52.169.76.203
     [2018-02-14 10:10:24] Connecting to: 52.169.76.203
     [2018-02-14 10:10:24] MQTT: Connection Opened: Starting TLS Negotiation
     [2018-02-14 10:10:24] MQTT: Wait for SSL Connect
     [2018-02-14 10:10:25] MQTT: TLS ready: Connect MQTT
     [2018-02-14 10:10:25] RQMQTT: Connected
     [2018-02-14 10:10:25] 
     [2018-02-14 10:10:25] *************************
     [2018-02-14 10:10:25] MAIN: Gateway bridging
     [2018-02-14 10:10:25] *************************
     [2018-02-14 10:10:25] 
     [2018-02-14 10:10:25] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:25] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:25] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:25] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:25] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:25] MQTT: Sending status packet
     [2018-02-14 10:10:25] MQTT: Report config error: 0600000020
     [2018-02-14 10:10:25] MQTT: Sending status succeeded: 1354
     [2018-02-14 10:10:25] SNTP: State change from 2 to 3
     [2018-02-14 10:10:27] SNTP: State change from 3 to 4
     [2018-02-14 10:10:28] SNTP: State change from 4 to 5
     [2018-02-14 10:10:28] SNTP: State change from 5 to 6
     [2018-02-14 10:10:28] SNTP: State change from 6 to 7
     [2018-02-14 10:10:28] LORA: Accepted packet
     [2018-02-14 10:10:29] MQTT: Sending UPLINK OK
     [2018-02-14 10:10:36] LORA: Packet dropped! Bad CRC
     [2018-02-14 10:10:36] LORA: Accepted packet
     [2018-02-14 10:10:36] MQTT: Sending UPLINK OK
    

Not recovering from MQTT: Sending status failed while having an internet connection

  • All well until another MQTT: Sending status failed, for which it did not try to recover but restarted right away:

     [2018-02-14 17:05:01] MQTT: Sending UPLINK OK
     [2018-02-14 17:05:07] LORA: Accepted packet
     [2018-02-14 17:05:07] MQTT: Sending UPLINK OK
     [2018-02-14 17:05:12] MQTT: Sending status packet
     [2018-02-14 17:05:15] LORA: Accepted packet
     [2018-02-14 17:05:15] MQTT: Sending status failed
     [2018-02-14 17:05:15] 
     [2018-02-14 17:05:15] MAIN: MQTT error
     [2018-02-14 17:05:15] 
     [2018-02-14 17:05:15] MAIN: Leaving state 5
     [2018-02-14 17:05:24] SNTP: State change from 0 to 0
     [2018-02-14 17:05:24] SNTP: State change from 0 to 0
     [2018-02-14 17:05:24] 
     [2018-02-14 17:05:24] 
     [2018-02-14 17:05:24] 
     [2018-02-14 17:05:24] **************************
     [2018-02-14 17:05:24] *   The Things Network   *
     [2018-02-14 17:05:24] *      G A T E W A Y     *
     [2018-02-14 17:05:24] **************************
     [2018-02-14 17:05:24] Firmware name: Arjan's v2 (flush UART, extended logging), type: 0, version: 0.0.0, commit: 00000000, timestamp: 0
     [2018-02-14 17:05:24] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
     [2018-02-14 17:05:24] Build time: Feb 11 2018 12:03:25
     [2018-02-14 17:05:24] Reboot reason: 0x10
    
  • This worked okay: it connected fine, downloaded its configuration and all and was almost ready to resume normal operation, until RQMQTT: Connection failed:

     [2018-02-14 17:05:51] LORA: Configuration succeeded
     [2018-02-14 17:05:51] LORA: Starting operation
     [2018-02-14 17:05:51] LORA: Changing state from 4 to 6
     [2018-02-14 17:05:57] LORA: Changing state from 6 to 3
     [2018-02-14 17:05:57] LORA: GOING ASYNC
     [2018-02-14 17:05:57] LORA: Changing state from 3 to 9
     [2018-02-14 17:05:57] 
     [2018-02-14 17:05:57] CNFG: Load online user config state change to 9
     [2018-02-14 17:05:57] 
     [2018-02-14 17:05:57] MAIN: Leaving state 3
     [2018-02-14 17:05:57] MAIN: Entering state 4
     [2018-02-14 17:05:57] SDCARD: Mounted disk
     [2018-02-14 17:05:57] FLASH: Loading FOTA Data
     [2018-02-14 17:05:57] FLASH: Reading 40 bytes from address 12288
     [2018-02-14 17:05:57] FIRM: APP_URL_Buffer: https://thethingsproducts.blob.core.windows.net/the-things-gateway/v1/stable
     [2018-02-14 17:05:57] FIRM: Valid URL
     [2018-02-14 17:05:57] FIRM: Requesting key ...
     [2018-02-14 17:05:57] FLASH: Loading data succeeded
     [2018-02-14 17:05:57] HTTP: Starting connection
     [2018-02-14 17:05:57] HTTP: - Host: thethingsproducts.blob.core.windows.net
     [2018-02-14 17:05:57] HTTP: - Path: the-things-gateway/v1/stable
     [2018-02-14 17:05:57] HTTP: - Port: 443
     [2018-02-14 17:05:57] HTTPS: Connection Opened: Starting TLS Negotiation
     [2018-02-14 17:05:57] HTTP: Wait for TLS Connect
     [2018-02-14 17:05:59] HTTP: TLS Connection Opened: Starting Clear Text Communication
     [2018-02-14 17:05:59] HTTP: Got 515 bytes
     [2018-02-14 17:06:01] HTTP: Connection Closed
     [2018-02-14 17:06:01] HTTP: Close active socket 1
     [2018-02-14 17:06:01] FIRM: Starting download
     [2018-02-14 17:06:01] FIRM: available bytes: 79
     [2018-02-14 17:06:01] FIRM: (Downloaded FOTA key) 69 AE B7 78 1F 49 4E 7F BC B6 C7 CD 9C 59 4F 5D FA AA 3D 81 D4 9C 56 90 A6 83 81 98 FF 18 88 6A 
     [2018-02-14 17:06:01] FIRM: (Stored FOTA key) 69 AE B7 78 1F 49 4E 7F BC B6 C7 CD 9C 59 4F 5D FA AA 3D 81 D4 9C 56 90 A6 83 81 98 FF 18 88 6A 
     [2018-02-14 17:06:01] FIRM: Firmware is already downloaded
     [2018-02-14 17:06:01] MAIN: No new firmware available
     [2018-02-14 17:06:01] 
     [2018-02-14 17:06:01] MAIN: Leaving state 4
     [2018-02-14 17:06:01] MAIN: Entering state 5
     [2018-02-14 17:06:01] MQTT: GOT IP: 52.169.76.203
     [2018-02-14 17:06:01] Connecting to: 52.169.76.203
     [2018-02-14 17:06:01] MQTT: Connection Opened: Starting TLS Negotiation
     [2018-02-14 17:06:01] MQTT: Wait for SSL Connect
     [2018-02-14 17:06:01] MQTT: TLS ready: Connect MQTT
     [2018-02-14 17:06:04] RQMQTT: Connection failed
     [2018-02-14 17:06:04] 
     [2018-02-14 17:06:04] MAIN: MQTT error
     [2018-02-14 17:06:04] 
     [2018-02-14 17:06:04] MAIN: Leaving state 5
     [2018-02-14 17:06:04] MAIN: Entering state 6
     [2018-02-14 17:06:04] INET: State change to 0
     [2018-02-14 17:06:04] WIFI: Disabling modules
     [2018-02-14 17:06:04] MAIN: Leaving state 5
     [2018-02-14 17:06:04] MAIN: Entering state 6
     [2018-02-14 17:06:04] INET: State change to 0
     [2018-02-14 17:06:04] WIFI: Disabling modules
     [2018-02-14 17:06:04] SNTP: State change from 7 to 8
     [2018-02-14 17:06:04] CB: Disconnect
    
  • It keeps trying, again gets an internet connection, but again fails when trying to use that for MQTT:

     [2018-02-14 17:06:04] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 17:06:04] SNTP: State change from 8 to 1
     [2018-02-14 17:06:04] WIFI: Entering state 3
     [2018-02-14 17:06:05] WIFI: Enabling modules for client
     [2018-02-14 17:06:05] WIFI: Entering state 6
     [2018-02-14 17:06:05] WIFI: IP Address: 0.0.0.0 
     [2018-02-14 17:06:06] CB: INET: Gateway has WiFi
     [2018-02-14 17:06:06] INET: State change to 2
     [2018-02-14 17:06:06] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
     [2018-02-14 17:06:06] SNTP: State change from 1 to 2
     [2018-02-14 17:06:08] SNTP: State change from 2 to 3
     [2018-02-14 17:06:08] WIFI: IP Address: 192.168.178.53 
     [2018-02-14 17:06:09] SNTP: State change from 3 to 4
     [2018-02-14 17:06:10] SNTP: State change from 4 to 5
     [2018-02-14 17:06:10] SNTP: State change from 5 to 6
     [2018-02-14 17:06:10] SNTP: State change from 6 to 7
     [2018-02-14 17:06:11] INET: State change to 3
     [2018-02-14 17:06:11] INET: Ping probe
     [2018-02-14 17:06:11] INET: Error sending probe on Eth
     [2018-02-14 17:06:11] INET: Ping response from MRF24WN, set as default
     [2018-02-14 17:06:11] INET: State change to 5
     [2018-02-14 17:06:11] 
     [2018-02-14 17:06:11] MAIN: Leaving state 6
     [2018-02-14 17:06:11] MAIN: Entering state 5
     [2018-02-14 17:06:11] MQTT: GOT IP: 52.169.76.203
     [2018-02-14 17:06:11] Connecting to: 52.169.76.203
     [2018-02-14 17:06:11] MQTT: Connection Opened: Starting TLS Negotiation
     [2018-02-14 17:06:11] MQTT: Wait for SSL Connect
     [2018-02-14 17:06:12] MQTT: TLS ready: Connect MQTT
     [2018-02-14 17:06:14] RQMON: SYS Stack size: 2839
     [2018-02-14 17:06:14] MQTT: Connection failed
     [2018-02-14 17:06:14] 
     [2018-02-14 17:06:14] MAIN: MQTT error
     [2018-02-14 17:06:14] 
     [2018-02-14 17:06:14] MAIN: Leaving state 5
     [2018-02-14 17:06:14] MAIN: Entering state 6
     [2018-02-14 17:06:14] INET: State change to 0
     [2018-02-14 17:06:14] WIFI: Disabling modules
     [2018-02-14 17:06:14] SNTP: State change from 7 to 8
     [2018-02-14 17:06:14] CB: Disconnect
    
  • The above is repeated many times, until:

     [2018-02-14 17:14:19] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 17:14:19] SNTP: State change from 8 to 1
     [2018-02-14 17:14:19] WIFI: Entering state 3
     [2018-02-14 17:14:20] WIFI: Enabling modules for client
     [2018-02-14 17:14:20] WIFI: Entering state 6
     [2018-02-14 17:14:20] WIFI: IP Address: 0.0.0.0 
     [2018-02-14 17:14:29] INET: No Ethernet and WiFi link (after 10 seconds)
     [2018-02-14 17:14:29] INET: State change to 1
     [2018-02-14 17:14:29] WIFI: Disabling modules
     [2018-02-14 17:14:29] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 17:14:29] SNTP: State change from 1 to 1
     [2018-02-14 17:14:29] WIFI: Entering state 3
     [2018-02-14 17:14:30] WIFI: Entering state 4
     [2018-02-14 17:14:30] WIFI: Entering SCAN state 1
     [2018-02-14 17:14:32] Scan is completed successfully
     [2018-02-14 17:14:32] WIFI: Entering SCAN state 2
     [2018-02-14 17:14:32] WIFI: Entering SCAN state 3
     [2018-02-14 17:14:32] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 17:14:32] SNTP: State change from 1 to 1
     [2018-02-14 17:14:32] WIFI: Entering SCAN state 4
     [2018-02-14 17:14:33] WIFI: Entering SCAN state 5
     [2018-02-14 17:14:33] WIFI: Entering SCAN state 0
     [2018-02-14 17:14:33] WIFI: Entering state 5
     [2018-02-14 17:14:33] WIFI: Enabling modules for server
     [2018-02-14 17:14:33] WIFI: Entering state 6
     [2018-02-14 17:14:33] WIFI: IP Address: 192.168.84.1 
     [2018-02-14 17:14:39] CB: MON: SYS Stack size: 2839
     [2018-02-14 17:15:06] LORA: Kick LoRa module with ACK after not acked it for 60s
     [2018-02-14 17:15:08] LORA: Packet dropped! Bad CRC
     [2018-02-14 17:15:11] LORA: Accepted packet
     [2018-02-14 17:15:11] LORA: Packet dropped! Bad CRC
     [2018-02-14 17:15:19] LORA: Accepted packet
     [2018-02-14 17:15:19] LORA: Packet dropped! Bad CRC
     [2018-02-14 17:15:19] LORA: Packet dropped! Bad CRC
     [2018-02-14 17:15:19] LORA: Packet dropped! Bad CRC
     [2018-02-14 17:16:19] LORA: Kick LoRa module with ACK after not acked it for 60s
     [2018-02-14 17:16:29] INET: Trying to connect to WiFi router again (after 120 seconds)
     [2018-02-14 17:16:29] INET: State change to 0
     [2018-02-14 17:16:29] WIFI: Disabling modules
     [2018-02-14 17:16:29] CB: Disconnect
    
  • After the INET: Trying to connect to WiFi router again (after 120 seconds) above it seems to connect to the internet just fine again, repeating the following many times, CB: INET: Gateway has WiFi, MQTT: Connection Opened: Starting TLS Negotiation, MQTT: Connection failed:

     [2018-02-14 17:16:29] Head magic match void: trying to free an already freed block, ignore
     [2018-02-14 17:16:29] SNTP: State change from 1 to 1
     [2018-02-14 17:16:29] WIFI: Entering state 3
     [2018-02-14 17:16:30] WIFI: Enabling modules for client
     [2018-02-14 17:16:30] WIFI: Entering state 6
     [2018-02-14 17:16:30] WIFI: IP Address: 0.0.0.0 
     [2018-02-14 17:16:31] CB: INET: Gateway has WiFi
     [2018-02-14 17:16:31] INET: State change to 2
     [2018-02-14 17:16:31] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
     [2018-02-14 17:16:31] SNTP: State change from 1 to 2
     [2018-02-14 17:16:33] WIFI: IP Address: 192.168.178.53 
     [2018-02-14 17:16:36] SNTP: State change from 2 to 3
     [2018-02-14 17:16:36] INET: State change to 3
     [2018-02-14 17:16:36] INET: Ping probe
     [2018-02-14 17:16:36] INET: Error sending probe on Eth
     [2018-02-14 17:16:36] INET: Ping response from MRF24WN, set as default
     [2018-02-14 17:16:36] INET: State change to 5
     [2018-02-14 17:16:36] 
     [2018-02-14 17:16:36] MAIN: Leaving state 6
     [2018-02-14 17:16:36] MAIN: Entering state 5
     [2018-02-14 17:16:36] MQTT: GOT IP: 52.169.76.203
     [2018-02-14 17:16:36] Connecting to: 52.169.76.203
     [2018-02-14 17:16:36] MQTT: Connection Opened: Starting TLS Negotiation
     [2018-02-14 17:16:36] MQTT: Wait for SSL Connect
     [2018-02-14 17:16:37] SNTP: State change from 3 to 4
     [2018-02-14 17:16:37] MQTT: TLS ready: Connect MQTT
     [2018-02-14 17:16:38] RQSNTP: State change from 4 to 5
     [2018-02-14 17:16:39] MQTT: Connection failed
     [2018-02-14 17:16:39] 
     [2018-02-14 17:16:39] MAIN: MQTT error
     [2018-02-14 17:16:39] 
     [2018-02-14 17:16:39] MAIN: Leaving state 5
     [2018-02-14 17:16:40] MAIN: Entering state 6
     [2018-02-14 17:16:40] INET: State change to 0
     [2018-02-14 17:16:40] WIFI: Disabling modules
     [2018-02-14 17:16:40] SNTP: State change from 5 to 8
     [2018-02-14 17:16:40] CB: Disconnect
    
  • ...and again, and again, ..., (sometimes with some more INET: No Ethernet and WiFi link (after 10 seconds) and INET: Trying to connect to WiFi router again (after 120 seconds).

  • At some point it somehow restarted after RQMQTT: Connection failed. Not sure where the RQ is coming from; the log is not perfect so it might very well be some other incomplete logging, in which case it actually looks like the logging above, but unlike above resets now:

     [2018-02-14 17:28:07] MQTT: GOT IP: 52.169.76.203
     [2018-02-14 17:28:07] Connecting to: 52.169.76.203
     [2018-02-14 17:28:07] MQTT: Connection Opened: Starting TLS Negotiation
     [2018-02-14 17:28:07] MQTT: Wait for SSL Connect
     [2018-02-14 17:28:08] MQTT: TLS ready: Connect MQTT
     [2018-02-14 17:28:10] RQMQTT: Connection failed
     [2018-02-14 17:28:10] 
     [2018-02-14 17:28:10] MAIN: MQTT error
     [2018-02-14 17:28:10] 
     [2018-02-14 17:28:10] MAIN: Leaving state 5
     [2018-02-14 17:28:12] SNTP: State change from 5 to 6
     [2018-02-14 17:28:19] SNTP: State change from 0 to 0
     [2018-02-14 17:28:19] SNTP: State change from 0 to 0
     [2018-02-14 17:28:19] 
     [2018-02-14 17:28:19] 
     [2018-02-14 17:28:19]
     [2018-02-14 17:28:19]
     [2018-02-14 17:28:19] **************************
     [2018-02-14 17:28:19] *   The Things Network   *
     [2018-02-14 17:28:19] *      G A T E W A Y     *
     [2018-02-14 17:28:19] **************************
     [2018-02-14 17:28:19] Firmware name: Arjan's v2 (flush UART, extended logging), type: 0, version: 0.0.0, commit: 00000000, timestamp: 0
     [2018-02-14 17:28:19] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
     [2018-02-14 17:28:19] Build time: Feb 11 2018 12:03:25
     [2018-02-14 17:28:19] Reboot reason: 0x10
    
  • ...but fails to connect:

     [2018-02-14 17:28:29] INET: No Ethernet and WiFi link (after 10 seconds)
    
  • ...while the router logs (seconds may be off a bit):

     [14.02.18 17:28:02] Wireless device deregistered itself (2,4 GHz), THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
     [14.02.18 17:28:04] Wireless device registered (2,4 GHz), 72 Mbit/s, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
    
  • The gateway keeps trying:

     [2018-02-14 17:30:29] INET: Trying to connect to WiFi router again (after 120 seconds)
     [2018-02-14 17:30:29] INET: State change to 0
     [2018-02-14 17:30:39] INET: No Ethernet and WiFi link (after 10 seconds)
     ...
     [2018-02-14 17:32:39] INET: Trying to connect to WiFi router again (after 120 seconds)
     [2018-02-14 17:32:39] INET: State change to 0
     [2018-02-14 17:32:49] INET: No Ethernet and WiFi link (after 10 seconds)
     ...
     [2018-02-14 17:34:49] INET: Trying to connect to WiFi router again (after 120 seconds)
     [2018-02-14 17:34:49] INET: State change to 0
     [2018-02-14 17:34:59] INET: No Ethernet and WiFi link (after 10 seconds)
    
  • ...though at some point the router logs show one final:

     [14.02.18 17:33:12] Deregistering wireless device (2,4 GHz): Wireless device not responding, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B. (#0104).
    
  • ...after which the gateway keeps repeating the same thing, but the router no longer shows its attempts in its logs.

  • At this point, the gateway does not get any connection anymore.

  • LED 2 flashing, gateway not in AP mode.

  • Like earlier, plugging in an ethernet cable does not help either. Green LED solid, orange LED flashing (sometimes slow, sometimes fast).

  • I forgot to reset the WiFi AP this time.

  • Eventually, pressing the "mode" button helps (I did not cycle the full power this time):

     2018-02-14 18:15:02] **************************
     [2018-02-14 18:15:02] *   The Things Network   *
     [2018-02-14 18:15:02] *      G A T E W A Y     *
     [2018-02-14 18:15:02] **************************
     [2018-02-14 18:15:02] Firmware name: Arjan's v2 (flush UART, extended logging), type: 0, version: 0.0.0, commit: 00000000, timestamp: 0
     [2018-02-14 18:15:02] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
     [2018-02-14 18:15:02] Build time: Feb 11 2018 12:03:25
     [2018-02-14 18:15:02] Reboot reason: 0x40
    
  • ...and all is fine again.

Full log files

Even more logging in the attached files...

LEDs while failing to connect (just before the manual reset)

image

@avbentem avbentem changed the title Gateway loses network connection and needs power cycle to recover Gateway loses network connection and needs manual reset to recover, possibly initiated by MQTT problems Feb 14, 2018

@avbentem

This comment has been minimized.

Copy link
Author

commented Feb 18, 2018

Update: it has been running for over 3 days (since 2018-02-14 23:18 GMT+1) now. It has seen a bunch of MQTT: Sending UPLINK failed and MQTT: Sending status failed, has rebooted 8 times (3 of which for MAIN: Rebooting gateway for firmware update check), but has always recovered during the last 3 days.

(Still running my own patched firmware, being develop from February 14th with debug logging enabled.)

@grahamehorner

This comment has been minimized.

Copy link

commented Feb 18, 2018

@avbentem that’s great news, so given the gateway reboots every 24hrs in the code, you’ve got 5 unattend reboots which is a MASSIVE improvement by far, great work

@avbentem

This comment has been minimized.

Copy link
Author

commented Feb 18, 2018

To clarify, @grahamehorner, the 5 unwanted reboots occurred after running into a MAIN: MQTT error (which, however, does not always imply a reboot; often it just recovers without such reboot and I've not yet discovered in which cases it deems a reboot required), and seem unrelated to reboot issues during activation, like those reported in #1.

I'm eager to test what happens when I make my router disconnect the WiFi, or shutdown the router altogether. But I don't want to interfere with the current test which meanwhile has given me a log file of almost 4 days now, and which may be useful to compare to server side logs, to find the cause of MQTT issues.

@grahamehorner

This comment has been minimized.

Copy link

commented Feb 18, 2018

@avbentem that sound like a great plan, wondering if the gateway should have some SNMP added late to allow remote diagnostics and or management; but it great to hear that progress is begin made

@johanstokking

This comment has been minimized.

Copy link
Contributor

commented Feb 20, 2018

@avbentem do you have the dates and times of these disconnects? We have had some issues with EU endpoints, including MQTT, very recently. This may be server initiated disconnects which the gateway recovered from naturally.

cc @htdvisser

@avbentem

This comment has been minimized.

Copy link
Author

commented Feb 20, 2018

Still running, almost 6 days now. And it nicely recovered at about 17:40 GMT+1, without me interfering, from the major EU region problems that were happening today.

During the problems I got into regular reboots with RQMQTT: Connection failed followed by MAIN: MQTT error and Reboot reason: 0x10.

At other times in the recent logs I see MQTT: Connection lost (rather than failed) like the following:

LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 1
MQTT: Connection lost

MAIN: MQTT error

...followed by many:

MQTT: GOT IP: 52.169.76.203
Connecting to: 52.169.76.203
MQTT: Opening socket timed out, restarting
MQTT: GOT IP: 52.169.76.203
Connecting to: 52.169.76.203
MQTT: Opening socket timed out, restarting
MQTT: GOT IP: 52.169.76.203
Connecting to: 52.169.76.203
MQTT: Opening socket timed out, restarting

So, it seems Connection failed (often) yields a reboot, but Connection lost makes the firmware try forever.

@avbentem

This comment has been minimized.

Copy link
Author

commented Feb 20, 2018

@johanstokking and @htdvisser, here's a full log since February 14th:

arjan-gateway-20180220.log.zip

Like noted in my previous comment: this did NOT need any intervention to recover, but it did reboot a few times.

And like earlier, this is using my own firmware, based on the February 14th develop branch, only changed to enable debug logging and to increase the debug cable's baud rate. It's been compiled with a free version (or a trial?) of the Microchip XC32 v1.42 compiler. Timestamps have been added using LOG="ttn-gateway-`date +'%Y%m%d-%H%M%S'`.log"; pio serialports monitor --raw -b 921600 | while read l; do echo "[`date +'%F %T'`] $l" | tee -a $LOG; done (The attached file has been renamed to use today's date instead of ttn-gateway-20180214-231744.log.)

The last time it needed a reset to recover was 6 days ago. Like stated earlier:

So I wonder:

  • Could the non-recoverable problem have started by MQTT problems on the server, around 2018-02-14 17:05:15 (Amsterdam time), and be different MQTT problems than the one it could recover from?

Only now I see the following on https://status.thethings.network/incident/40

image

Note that on February 14th things were fixed immediately when performing the manual reset at 18:15 GMT+1 Amsterdam time, so I assume the update was done long before I performed that reset. If you know exact times, then see the February 14th log. And what happened February 12th, when it needed a reset too?

@avbentem

This comment has been minimized.

Copy link
Author

commented Mar 2, 2018

Quick update: my gateway nicely recovered from today's https://status.thethings.network/incident/45 So, it has not needed any reboot since February 14th.

[2018-03-02 10:28:05] MQTT: Sending UPLINK OK

Nicely recovering from some subsequent MQTT errors

Half an hour later, it ran into:

[2018-03-02 11:02:43] MQTT: Sending UPLINK OK
[2018-03-02 11:02:45] MON: SYS Stack size: 2847
[2018-03-02 11:02:45] MON: heap usage: 262KB (263KB), free: 77KB
[2018-03-02 11:02:45] MQTT: Connection lost
[2018-03-02 11:02:45] 
[2018-03-02 11:02:45] MAIN: MQTT error
...
[2018-03-02 11:02:58] MQTT: GOT IP: 52.169.76.203
[2018-03-02 11:02:58] Connecting to: 52.169.76.203
[2018-03-02 11:02:59] MQTT: Opening socket timed out, restarting
[2018-03-02 11:02:59] MQTT: GOT IP: 52.169.76.203
[2018-03-02 11:02:59] Connecting to: 52.169.76.203
[2018-03-02 11:03:00] MQTT: Opening socket timed out, restarting

Again, it nicely recovered:

[2018-03-02 11:10:38] RQMQTT: Connected
[2018-03-02 11:10:38] 
[2018-03-02 11:10:38] *************************
[2018-03-02 11:10:38] MAIN: Gateway bridging
[2018-03-02 11:10:38] *************************
[2018-03-02 11:10:38] 
[2018-03-02 11:10:38] MQTT: Sending UPLINK OK
[2018-03-02 11:10:38] MQTT: Sending UPLINK OK
[2018-03-02 11:10:38] MQTT: Sending status packet
[2018-03-02 11:10:38] MQTT: Report config error: 0600000020
[2018-03-02 11:10:38] MQTT: Sending status succeeded: 82
[2018-03-02 11:10:46] LORA: Accepted packet
[2018-03-02 11:10:46] MQTT: Sending UPLINK OK

The WiFi logs show:

[02.03.18 11:02:53] Wireless device deregistered itself (2,4 GHz), THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:02:55] New wireless device registered itself (2,4 GHz), 72 Mbit/s, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:03:51] Wireless device deregistered itself (2,4 GHz), THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:03:53] Wireless device registered (2,4 GHz), 72 Mbit/s, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:09:41] Wireless device deregistered itself (2,4 GHz), THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:09:43] Wireless device registered (2,4 GHz), 72 Mbit/s, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:10:31] Wireless device deregistered itself (2,4 GHz), THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.
[02.03.18 11:10:33] Wireless device registered (2,4 GHz), 72 Mbit/s, THINGS-GATEWAY, IP 192.168.178.53, MAC 00:1E:C0:3A:F6:1B.

...and the gateway runs fine again:

[2018-03-02 11:10:38] MQTT: TLS ready: Connect MQTT
[2018-03-02 11:10:38] LORA: Accepted packet
[2018-03-02 11:10:38] RQMQTT: Connected
[2018-03-02 11:10:38] 
[2018-03-02 11:10:38] *************************
[2018-03-02 11:10:38] MAIN: Gateway bridging
[2018-03-02 11:10:38] *************************
[2018-03-02 11:10:38] 
[2018-03-02 11:10:38] MQTT: Sending UPLINK OK
[2018-03-02 11:10:38] MQTT: Sending UPLINK OK

However:

Not recovering from MQTT errors starting around 2018-03-02 11:39:28 Amsterdam time:

[2018-03-02 11:39:28] LORA: Accepted packet
[2018-03-02 11:39:31] MQTT: Sending UPLINK failed: -1
[2018-03-02 11:39:31] 
[2018-03-02 11:39:31] MAIN: MQTT error

It then rebooted, and started showing:

[2018-03-02 11:39:50] INET: No Ethernet and WiFi link (after 10 seconds)
...
[2018-03-02 11:41:50] INET: Trying to connect to WiFi router again (after 120 seconds)
[2018-03-02 11:41:50] INET: State change to 0
[2018-03-02 11:42:00] MON: SYS Stack size: 3959
[2018-03-02 11:42:00] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-02 11:42:00] INET: No Ethernet and WiFi link (after 10 seconds)
...
... repeated many, many times
...
[2018-03-02 13:58:02] INET: Trying to connect to WiFi router again (after 120 seconds)
[2018-03-02 13:58:02] INET: State change to 0
[2018-03-02 13:58:03] MON: SYS Stack size: 3959
[2018-03-02 13:58:03] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-02 13:58:12] INET: No Ethernet and WiFi link (after 10 seconds)

It has been like this for over two hours now. The WiFi AP is running just fine (same timezone), but for the gateway only shows a single connection attempt:

[02.03.18 11:44:15] Wireless LAN registration failed(2,4 GHz): Access check failed. MAC address: 00:1E:C0:3A:F6:1B.

Will post full logs later on, but just in case something special happened around 2018-03-02 11:39:31 Amsterdam time then you might want to remember that?

Hylke noted in Slack:

htdvisser [Mar 2nd at 2:13 PM]

[...] our metrics, but they don't show anything interesting around 2018-03-02 10:39:31 UTC

@avbentem

This comment has been minimized.

Copy link
Author

commented Mar 7, 2018

I doubt it helps to add yet another occurrence, but last night it restarted for a firmware check and did not get a network connection until I pressed the mode button a few minutes ago.

[2018-03-06T22:10:30.432Z] LORA: Accepted packet
[2018-03-06T22:10:30.466Z] MQTT: Sending UPLINK OK
[2018-03-06T22:10:31.229Z]
[2018-03-06T22:10:31.231Z] MAIN: Rebooting gateway for firmware update check
[2018-03-06T22:10:35.563Z] MON: SYS Stack size: 2837
[2018-03-06T22:10:35.564Z] MON: heap usage: 262KB (263KB), free: 77KB
[2018-03-06T22:10:40.112Z] SNTP: State change from 0 to 0
[2018-03-06T22:10:40.114Z] SNTP: State change from 0 to 0
[2018-03-06T22:10:40.116Z]
[2018-03-06T22:10:40.116Z]
[2018-03-06T22:10:40.117Z]
[2018-03-06T22:10:40.117Z] **************************
[2018-03-06T22:10:40.118Z] *   The Things Network   *
[2018-03-06T22:10:40.120Z] *      G A T E W A Y     *
[2018-03-06T22:10:40.121Z] **************************
[2018-03-06T22:10:40.122Z] Firmware name: Arjan's v4 (develop, debug, baudrate), type: 0, version: 0.0.0, commit: 00000000, timestamp: 0
[2018-03-06T22:10:40.128Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-03-06T22:10:40.129Z] Build time: Feb 14 2018 23:12:28
[2018-03-06T22:10:40.130Z] Reboot reason: 0x10
[2018-03-06T22:10:40.136Z] BOOT: (persisted info) 6F 72 72 65 01 10 8B 2C E7 16 0D 49 B2 3A 5C B4
[2018-03-06T22:10:40.136Z]
[2018-03-06T22:10:40.137Z]
[2018-03-06T22:10:40.137Z]
[2018-03-06T22:10:40.138Z]
[2018-03-06T22:10:40.138Z] WIFI: Entering state 0
[2018-03-06T22:10:40.139Z] WIFI: Entering SCAN state 0
[2018-03-06T22:10:40.140Z]
[2018-03-06T22:10:40.140Z] MAIN: Initialisation complete
[2018-03-06T22:10:40.141Z] LORA: Changing state from 0 to 0
[2018-03-06T22:10:40.358Z]
[2018-03-06T22:10:40.359Z] MAIN: Leaving state 0
[2018-03-06T22:10:40.360Z] MAIN: Entering state 1
[2018-03-06T22:10:40.362Z] FLASH: Global Block Protection Unlock
[2018-03-06T22:10:40.363Z] FLASH: Manufacturer ID:bf Device Type:26 Device ID:43
[2018-03-06T22:10:40.364Z] FLASH: Status Register[7:0]: 00
[2018-03-06T22:10:40.369Z] FLASH: Configuration Register[7:0]: 08
[2018-03-06T22:10:40.374Z] FLASH: Block-Protection Register[143:0]: 0000 00000000 00000000 00000000 00000000
[2018-03-06T22:10:40.375Z] FLASH: Magic bytes found: wifi config present
[2018-03-06T22:10:40.376Z] FLASH: Magic bytes found: activation data present
[2018-03-06T22:10:40.378Z] FLASH: Magic bytes found: FOTA data present
[2018-03-06T22:10:40.378Z] FLASH: Loading Firmware Data
[2018-03-06T22:10:40.380Z] FLASH: Reading 32 bytes from address 0
[2018-03-06T22:10:40.381Z] FLASH: Loading data succeeded
[2018-03-06T22:10:40.383Z] CNFG: (Firmware HASH (sha256)) BC 56 70 F8 2C 4A 70 13 C4 23 6D 7B 53 BE 61 2D 53 3D 0D 7A F5 78 67 23 85 3C FE 15 22 3D C9 6F
[2018-03-06T22:10:40.384Z] FLASH: Loading WiFi Data
[2018-03-06T22:10:40.385Z] FLASH: Reading 104 bytes from address 4096
[2018-03-06T22:10:40.386Z] FLASH: Loading data succeeded
[2018-03-06T22:10:40.387Z] CNFG: WiFi SSID:      <redacted>
[2018-03-06T22:10:40.388Z] CNFG: WiFi key:       ***
[2018-03-06T22:10:40.389Z] CNFG: WiFi conn_type: 1
[2018-03-06T22:10:40.390Z] CNFG: WiFi sec_type:  4
[2018-03-06T22:10:40.390Z] FLASH: Loading Activation Data
[2018-03-06T22:10:40.392Z] FLASH: Reading 560 bytes from address 8192
[2018-03-06T22:10:40.393Z] FLASH: Loading data succeeded
[2018-03-06T22:10:40.394Z] CNFG: Gateway ID:         arjanvanb-gw-1
[2018-03-06T22:10:40.395Z] CNFG: Gateway Key:        ***
[2018-03-06T22:10:40.396Z] CNFG: Account Server URL: https://account.thethingsnetwork.org
[2018-03-06T22:10:40.396Z] CNFG: Locked:             true
[2018-03-06T22:10:40.397Z] CNFG: Locked first time:  false
[2018-03-06T22:10:40.398Z]
[2018-03-06T22:10:40.398Z] MAIN: Leaving state 1
[2018-03-06T22:10:40.399Z] MAIN: Entering state 2
[2018-03-06T22:10:40.400Z] INET: State change to 0
[2018-03-06T22:10:40.734Z] LORA: Initialisation complete
[2018-03-06T22:10:40.735Z] LORA: Changing state from 0 to 1
[2018-03-06T22:10:47.726Z] LORA: Wait init complete, waiting for application.
[2018-03-06T22:10:47.727Z] LORA: Changing state from 1 to 2
[2018-03-06T22:10:50.098Z] MON: SYS Stack size: 3959
[2018-03-06T22:10:50.099Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:10:50.377Z] INET: No Ethernet and WiFi link (after 10 seconds)
[2018-03-06T22:10:50.381Z] INET: State change to 1
[2018-03-06T22:11:00.083Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:00.084Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:10.067Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:10.068Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:20.051Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:20.052Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:30.036Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:30.037Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:40.020Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:40.021Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:50.004Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:50.005Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:11:59.989Z] MON: SYS Stack size: 3959
[2018-03-06T22:11:59.989Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:09.971Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:09.972Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:19.953Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:19.954Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:29.935Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:29.936Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:39.916Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:39.917Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:49.898Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:49.899Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:12:50.165Z] INET: Trying to connect to WiFi router again (after 120 seconds)
[2018-03-06T22:12:50.169Z] INET: State change to 0
[2018-03-06T22:12:59.882Z] MON: SYS Stack size: 3959
[2018-03-06T22:12:59.883Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:00.149Z] INET: No Ethernet and WiFi link (after 10 seconds)
[2018-03-06T22:13:00.159Z] INET: State change to 1
[2018-03-06T22:13:09.865Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:09.866Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:19.848Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:19.849Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:29.834Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:29.835Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:39.819Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:39.819Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:49.803Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:49.804Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:13:59.784Z] MON: SYS Stack size: 3959
[2018-03-06T22:13:59.785Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:09.761Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:09.762Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:19.742Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:19.743Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:29.722Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:29.723Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:39.700Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:39.701Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:49.679Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:49.680Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:59.660Z] MON: SYS Stack size: 3959
[2018-03-06T22:14:59.661Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-06T22:14:59.917Z] INET: Trying to connect to WiFi router again (after 120 seconds)
[2018-03-06T22:14:59.921Z] INET: State change to 0

...repeated all day long until I pressed the mode button:

[2018-03-07T17:02:58.118Z] INET: Trying to connect to WiFi router again (after 120 seconds)
[2018-03-07T17:02:58.125Z] INET: State change to 0
[2018-03-07T17:03:03.437Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:03.438Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:03:08.096Z] INET: No Ethernet and WiFi link (after 10 seconds)
[2018-03-07T17:03:08.100Z] INET: State change to 1
[2018-03-07T17:03:13.413Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:13.414Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:03:23.387Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:23.388Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:03:33.362Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:33.363Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:03:43.338Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:43.339Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:03:53.314Z] MON: SYS Stack size: 3959
[2018-03-07T17:03:53.315Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:04:03.288Z] MON: SYS Stack size: 3959
[2018-03-07T17:04:03.289Z] MON: heap usage: 148KB (148KB), free: 191KB
[2018-03-07T17:04:13.066Z] BTN: change to state: 1
[2018-03-07T17:04:13.248Z] BTN: change to state: 2
[2018-03-07T17:04:13.250Z]
[2018-03-07T17:04:13.251Z] MAIN: L
[2018-03-07T17:04:13.252Z] *** Application reboot
[2018-03-07T17:04:13.300Z]
[2018-03-07T17:04:15.463Z] SNTP: State change from 0 to 0
[2018-03-07T17:04:15.466Z] SNTP: State change from 0 to 0
[2018-03-07T17:04:15.467Z]
[2018-03-07T17:04:15.467Z]
[2018-03-07T17:04:15.468Z]
[2018-03-07T17:04:15.469Z] **************************
[2018-03-07T17:04:15.469Z] *   The Things Network   *
[2018-03-07T17:04:15.470Z] *      G A T E W A Y     *
[2018-03-07T17:04:15.473Z] **************************

So, this time it did not get its configuration either. And it was NOT initiated by an MQTT error either. I forget to check which LEDs were on.

@avbentem

This comment has been minimized.

Copy link
Author

commented Mar 16, 2018

No changes using @martinichka 's #1 (comment) (so, the latest firmware version, installed using an SD card without using the PIC32 programmer).

Also, again no difference if WiFi or ethernet is used. (And the Raspberry Pi that is monitoring my gateway's serial output, seems to be able to report all network problems using the very same WiFi connection.)

Firmware name: AmazingAckermann, type: 0, version: 1.0.1, commit: 91648b7e, timestamp: 1520959697
Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
Build time: Mar 13 2018 16:49:17
@avbentem

This comment has been minimized.

Copy link
Author

commented Apr 4, 2018

Problem still exists in 1.0.3 (still loaded from an SD card, while it was officially released today as well, I think). This time (again) initiated by a reboot for a firmware check, while it was running just fine:

[2018-04-04T19:40:26.171Z] LORA: Accepted packet
[2018-04-04T19:40:26.202Z] MQTT: Sending UPLINK OK
[2018-04-04T19:40:26.240Z] LORA: Packet dropped! Bad CRC
[2018-04-04T19:40:32.384Z] MON: SYS Stack size: 2851
[2018-04-04T19:40:32.387Z] MON: heap usage: 263KB (267KB), free: 76KB
[2018-04-04T19:40:42.377Z] MON: SYS Stack size: 2851
[2018-04-04T19:40:42.380Z] MON: heap usage: 263KB (267KB), free: 76KB
[2018-04-04T19:40:52.369Z] MON: SYS Stack size: 2851
[2018-04-04T19:40:52.372Z] MON: heap usage: 263KB (267KB), free: 76KB
[2018-04-04T19:40:54.008Z] 
[2018-04-04T19:40:54.012Z] MAIN: Rebooting gateway for firmware update check
[2018-04-04T19:40:54.801Z] LORA: Accepted packet
[2018-04-04T19:40:56.602Z] SNTP: State change from 7 to 1
[2018-04-04T19:40:57.700Z] SNTP: State change from 1 to 2
[2018-04-04T19:40:58.798Z] SNTP: State change from 2 to 3
[2018-04-04T19:40:59.897Z] SNTP: State change from 3 to 4
[2018-04-04T19:41:00.912Z] LORA: Accepted packet
[2018-04-04T19:41:00.993Z] SNTP: State change from 4 to 5
[2018-04-04T19:41:01.011Z] SNTP: State change from 5 to 6
[2018-04-04T19:41:01.013Z] SNTP: State change from 6 to 7
[2018-04-04T19:41:02.890Z] SNTP: State change from 0 to 0
[2018-04-04T19:41:02.892Z] SNTP: State change from 0 to 0
[2018-04-04T19:41:02.895Z] 
[2018-04-04T19:41:02.895Z] 
[2018-04-04T19:41:02.896Z] 
[2018-04-04T19:41:02.899Z] **************************
[2018-04-04T19:41:02.900Z] *   The Things Network   *
[2018-04-04T19:41:02.903Z] *      G A T E W A Y     *
[2018-04-04T19:41:02.905Z] **************************
[2018-04-04T19:41:02.911Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.3, commit: 2c56ce09, timestamp: 1521721252
[2018-04-04T19:41:02.939Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-04-04T19:41:02.940Z] Build time: Mar 22 2018 12:21:52
[2018-04-04T19:41:02.954Z] Reboot reason: 0x10
[2018-04-04T19:41:02.963Z] BOOT: (persisted info) 6F 72 72 65 01 10 E3 2C E7 16 05 49 B2 3A 51 B7 

...after which it got neither ethernet nor WiFi, until I pressed the Mode button at 2018-04-04T21:38:03.384Z.

@avbentem avbentem changed the title Gateway loses network connection and needs manual reset to recover, possibly initiated by MQTT problems Gateway loses network connection and needs manual reset to recover, often initiated by MQTT problems or by reboot for firmware upgrade Apr 4, 2018

@bvdbrule

This comment has been minimized.

Copy link

commented Apr 5, 2018

I have received a OTA firmware upgrade v1.0.3-2c56ce09 (2018-04-04T12:54:45Z), but after connecting a serial esp-link solution from BoRRoZ and turning "on" in the console the switches Automatically update gateway and Beta Updates it goes to version v1.0.3-2c56ce09 from 2018-03-22T12:20:52Z. but after some time it loops: hereby the log

nging state from 0 to 0

MAIN: Leaving state 0
MAIN: Entering state 1
FLASH: Magic bytes found: wifi config present
FLASH: Magic bytes found: activation data present
FLASH: Magic bytes found: FOTA data present
FLASH: Loading Firmware Data
CNFG: (Firmware HASH (sha256)) 87 93 F2 0A DA 7C FB 56 3C
EB E0 29 21 C5 BB 76 F3 9C A6 A4 A9 2C CE 01 EC A8 29 5B 59
48 9C 20
FLASH: Loading WiFi Data
CNFG: WiFi SSID: 88888888-Lora
CNFG: WiFi key: ***
CNFG: WiFi conn_type: 1
CNFG: WiFi sec_type: 4
FLASH: Loading Activation Data
CNFG: Gateway ID: vdbc-lora-gw
CNFG: Gateway Key: ***
CNFG: Account Server URL:
https://account.thethingsnetwork.org
CNFG: Locked: true
CNFG: Locked first time: false

MAIN: Leaving state 1
MAIN: Entering state 2
INET: State change to 0
LORA: Initialisation complete
LORA: Changing state from 0 to 1
WIFI: Entering state 1
ETH: IP Address: 0.0.0.0
WIFI: Entering state 4
WIFI: Entering SCAN state 1
SNTP: State change from 0 to 1
Scan is completed successfully
WIFI: Entering SCAN state 2
WIFI: Entering SCAN state 5
WIFI: Entering SCAN state 0
WIFI: Entering state 2
WIFI: Disabling modules
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 1 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6

WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
LORA: Wait init complete, waiting for application.
LORA: Changing state from 1 to 2
MON: SYS Stack size: 3967
MON: heap usage: 147KB (156KB), free: 192KB
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 4
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
INET: State change to 5

MAIN: Leaving state 2
MAIN: Entering state 3

CNFG: Load online user config state change to 4
HTTP: Close active socket 0
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
HTTP: Got 1523 bytes
HTTP: Connection Closed
HTTP: Close active socket 1
CONF: Parsing response token: HTTP/1.1 200 OK
CONF: ROUTER URL: mqtts://bridge.eu.thethings.network:8883

CNFG: Load online user config state change to 6
FREQ: APP_URL_Buffer:
https://account.thethingsnetwork.org/api/v2/frequency-plans/EU_863_870
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
HTTP: Got 1232 bytes
MON: SYS Stack size: 2831
MON: heap usage: 227KB (233KB), free: 111KB
HTTP: Connection Closed
HTTP: Close active socket 1

CNFG: Load online user config state change to 7

CNFG: Configuring LoRa module
LORA: Changing state from 2 to 4
LORA: Starting reconfiguration
LORA: version: 01
LORA: Configuration succeeded
LORA: Starting operation
LORA: Changing state from 4 to 6
MON: SYS Stack size: 2831
MON: heap usage: 151KB (233KB), free: 188KB
LORA: Changing state from 6 to 3
LORA: GOING ASYNC
LORA: Changing state from 3 to 9

CNFG: Load online user config state change to 9

MAIN: Leaving state 3
MAIN: Entering state 4
FLASH: Loading FOTA Data
FIRM: Requesting key ...
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
HTTP: Got 515 bytes
HTTP: Connection Closed
HTTP: Close active socket 1
FIRM: Starting download
FIRM: available bytes: 79
FIRM: (Downloaded FOTA key) 14 3E 99 52 CC 5B 11 E1 A2 21
8A 04 06 AA 73 1D 1D D8 25 CE FF BD C3 28 34 1E FB 0B B2 AC
9B 56
FIRM: (Stored FOTA key) 87 93 F2 0A DA 7C FB 56 3C EB E0 29
21 C5 BB 76 F3 9C A6 A4 A9 2C CE 01 EC A8 29 5B 59 48 9C 20

FLASH: Erasing FOTA Data and Image
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 168KB (252KB), free: 171KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 168KB (252KB), free: 171KB
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 168KB (252KB), free: 171KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 168KB (252KB), free: 171KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 168KB (252KB), free: 171KB
FLASH: Storing FOTA Checksum
FIRM: Finishing writing to storage
FIRM: New firmware found, start downloading!
FIRM: (OLD key) 87 93 F2 0A DA 7C FB 56 3C EB E0 29 21 C5
BB 76 F3 9C A6 A4 A9 2C CE 01 EC A8 29 5B 59 48 9C 20
FIRM: (NEW key) 14 3E 99 52 CC 5B 11 E1 A2 21 8A 04 06 AA
73 1D 1D D8 25 CE FF BD C3 28 34 1E FB 0B B2 AC 9B 56
FIRM: Requesting firmware ...
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
FIRM: Starting download
FIRM: available bytes: 583
FLASH: Storing FOTA Image
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
LORA: Accepted packet
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2831
MON: heap usage: 246KB (254KB), free: 93KB
HTTP: Connection Closed
FIRM: Finishing writing to storage
FLASH: Finalizing FOTA

MAIN: NEW FIRMWARE, REBOO
*** Application reboot

52
Reboot reason: 0x40
BOOT: (persisted info) 6F 72 72 65 01 03 3F 93 6D DF 34 36
ED 7E 75 6C

WIFI: Entering state 0
WIFI: Entering SCAN state 0

MAIN: Initialisation complete
LORA: Changing state from 0 to 0

MAIN: Leaving state 0
MAIN: Entering state 1
FLASH: Magic bytes found: wifi config present
FLASH: Magic bytes found: activation data present
FLASH: Magic bytes found: FOTA data present
FLASH: Loading Firmware Data
CNFG: (Firmware HASH (sha256)) 14 3E 99 52 CC 5B 11 E1 A2
21 8A 04 06 AA 73 1D 1D D8 25 CE FF BD C3 28 34 1E FB 0B B2
AC 9B 56
FLASH: Loading WiFi Data
CNFG: WiFi SSID: 88888888-Lora
CNFG: WiFi key: ***
CNFG: WiFi conn_type: 1
CNFG: WiFi sec_type: 4
FLASH: Loading Activation Data
CNFG: Gateway ID: vdbc-lora-gw
CNFG: Gateway Key: ***
CNFG: Account Server URL:
https://account.thethingsnetwork.org
CNFG: Locked: true
CNFG: Locked first time: false

MAIN: Leaving state 1
MAIN: Entering state 2
INET: State change to 0
LORA: Initialisation complete
LORA: Changing state from 0 to 1
WIFI: Entering state 1
ETH: IP Address: 0.0.0.0
WIFI: Entering state 4
WIFI: Entering SCAN state 1
Scan is completed successfully
WIFI: Entering SCAN state 2
WIFI: Entering SCAN state 5
WIFI: Entering SCAN state 0
WIFI: Entering state 2
WIFI: Disabling modules
Head magic match void: trying to free an already freed
block, ignore
WIFI: Entering state 3
SNTP: State change from 0 to 1
WIFI: Enabling modules for client
WIFI: Entering state 6

WIFI: IP Address: 0.0.0.0
LORA: Wait init complete, check for communication on 115200
baud.
LORA: Changing state from 1 to 2
LORA: Communication on 115200 OK, waiting for application.
LORA: Changing state from 2 to 4
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
INET: No Ethernet and WiFi link (after 10 seconds)
INET: State change to 1
WIFI: Disabling modules
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 1 to 1
WIFI: Entering state 3
WIFI: Entering state 4
WIFI: Entering SCAN state 1
Scan is completed successfully
WIFI: Entering SCAN state 2
WIFI: Entering SCAN state 3
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 1 to 1
WIFI: Entering SCAN state 4
WIFI: Entering SCAN state 5
WIFI: Entering SCAN state 0
WIFI: Entering state 5
WIFI: Enabling modules for server
WIFI: Entering state 6
WIFI: IP Address: 192.168.84.1
CB: MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
MON: SYS Stack size: 3959
MON: heap usage: 146KB (156KB), free: 193KB
INET: Trying to connect to WiFi router again (after 120
seconds)
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 1 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
SNTP: State change from 2 to 6
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 4
SNTP: State change from 6 to 1
INET: Initiated NTP request.
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
MON: SYS Stack size: 3959
MON: heap usage: 147KB (156KB), free: 192KB
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 1
INET: Initiated NTP request.
SNTP: State change from 1 to 2
MON: SYS Stack size: 3959
MON: heap usage: 147KB (156KB), free: 192KB
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
INET: Not received any NTP response. Wait for network again
(after 20 seconds).
INET: State change to 0
WIFI: Disabling modules
SNTP: State change from 5 to 8
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 8 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 193KB
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 4
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
SNTP: State change from 5 to 6
SNTP: State change from 6 to 1
INET: Initiated NTP request.
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
SNTP: State change from 5 to 6
SNTP: State change from 6 to 1
INET: Initiated NTP request.
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
INET: Not received any NTP response. Wait for network again
(after 20 seconds).
INET: State change to 0
WIFI: Disabling modules
SNTP: State change from 4 to 8
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 8 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
WIFI: IP Address: 192.168.101.200
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 4
SNTP: State change from 5 to 6
SNTP: State change from 6 to 1
INET: Initiated NTP request.
SNTP: State change from 1 to 2
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 1
INET: Initiated NTP request.
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
INET: Not received any NTP response. Wait for network again
(after 20 seconds).
INET: State change to 0
WIFI: Disabling modules
SNTP: State change from 3 to 8
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 8 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
MON: SYS Stack size: 3959
MON: heap usage: 147KB (157KB), free: 192KB
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 4
INET: State change to 5

MAIN: Leaving state 2
MAIN: Entering state 3

CNFG: Load online user config state change to 4
HTTP: Close active socket 0
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
HTTP: Got 1523 bytes
HTTP: Connection Closed
HTTP: Close active socket 1
CONF: Parsing response token: HTTP/1.1 200 OK
CONF: ROUTER URL: mqtts://bridge.eu.thethings.network:8883

CNFG: Load online user config state change to 6
FREQ: APP_URL_Buffer:
https://account.thethingsnetwork.org/api/v2/frequency-plans/EU_863_870
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
HTTP: Got 1232 bytes

figuration
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: version: 00
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: Configuration failed, retry
LORA: Starting reconfiguration
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: version: 00
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: Configuration failed, retry
LORA: Starting reconfiguration
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: version: 00
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: UART WRITE ERROR!
LORA: Configuration failed, retry
LORA: RESET MODULE
LORA: Changing state from 6 to 0
LORA: Initialisation complete
LORA: Changing state from 0 to 1
MON: SYS Stack size: 2837
MON: heap usage: 151KB (233KB), free: 188KB
LORA: Wait init complete, check for communication on 115200
baud.
LORA: Changing state from 1 to 2
LORA: Communication on 115200 OK, waiting for application.
LORA: Changing state from 2 to 4
LORA: Changing state from 4 to 6
LORA: Starting reconfiguration
LORA: version: 01
LORA: UART TIMEOUT
LORA: Configuration succeeded
LORA: Starting operation
LORA: Changing state from 6 to 8
MON: SYS Stack size: 2837
MON: heap usage: 151KB (233KB), free: 188KB
LORA: Changing state from 8 to 5
LORA: GOING ASYNC
LORA: Changing state from 5 to 11

CNFG: Load online user config state change to 9

MAIN: Leaving state 3
MAIN: Entering state 4
FLASH: Loading FOTA Data
FIRM: Requesting key ...
HTTP: Starting connection
HTTPS: Connection Opened: Starting TLS Negotiation
HTTP: Wait for TLS Connect
HTTP: TLS Connection Opened: Starting Clear Text
Communication
LORA: Accepted packet
HTTP: Got 515 bytes
LORA: Accepted packet
HTTP: Connection Closed
HTTP: Close active socket 1
FIRM: Starting download
FIRM: available bytes: 79
FIRM: (Downloaded FOTA key) 14 3E 99 52 CC 5B 11 E1 A2 21
8A 04 06 AA 73 1D 1D D8 25 CE FF BD C3 28 34 1E FB 0B B2 AC
9B 56
FIRM: (Stored FOTA key) 14 3E 99 52 CC 5B 11 E1 A2 21 8A 04
06 AA 73 1D 1D D8 25 CE FF BD C3 28 34 1E FB 0B B2 AC 9B 56

FIRM: Firmware is already downloaded
MAIN: No new firmware available

MAIN: Leaving state 4
MAIN: Entering state 5
MQTT: GOT IP: 52.169.76.203
Connecting to: 52.169.76.203
MQTT: Connection Opened: Starting TLS Negotiation
MQTT: Wait for SSL Connect
MQTT: TLS ready: Connect MQTT
RQMQTT: Connected


MAIN: Gateway bridging


MQTT: Sending UPLINK OK
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Report reboot error: 0103
MQTT: Report config error: 0600000080
MQTT: Sending status succeeded: 0
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 1
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 2
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 3
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 4
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 5
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 6
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 7
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 8
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 9
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 10
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 11
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 12
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 13
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 14
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 15
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 16
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 17
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 18
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 19
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
SNTP: State change from 7 to 1
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 20
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 21
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Received DOWNLINK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 22
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 23
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 24
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
LORA: Accepted packet
MQTT: Sending status succeeded: 25
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 26
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 27
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 28
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 29
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 30
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 31
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 32
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 33
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 34
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK

OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 44
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 45
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 46
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 47
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 48
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 49
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 50
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Received DOWNLINK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 51
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 52
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 53
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 54
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 55
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 56
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 57
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 58
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (265KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 59
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
SNTP: State change from 7 to 1
SNTP: State change from 1 to 2
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 60
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 61
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 62
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 63
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 64
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 65
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 66
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 67
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 68
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 69
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 70
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK

OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 96
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 97
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 98
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 99
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
SNTP: State change from 7 to 1
SNTP: State change from 1 to 2
LORA: Accepted packet
MQTT: Sending UPLINK OK
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
MQTT: Sending status packet
MQTT: Sending status succeeded: 100
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 101
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 102
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 103
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 104
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 105
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 263KB (266KB), free: 75KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 106
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 107
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 108
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 109
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 110
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 111
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 112
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 113
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 114
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 115
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 116
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MQTT: Sending status packet
MQTT: Sending status succeeded: 117
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 118
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 119
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 120
SNTP: State change from 7 to 1
SNTP: State change from 1 to 2
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
SNTP: State change from 2 to 3
SNTP: State change from 3 to 4
SNTP: State change from 4 to 5
SNTP: State change from 5 to 6
SNTP: State change from 6 to 7
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MQTT: Sending status packet
MQTT: Sending status succeeded: 121
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Accepted packet
MQTT: Sending UPLINK OK
LORA: Packet dropped! Bad CRC
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
MQTT: Sending status packet
MQTT: Sending status succeeded: 122
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK
MON: SYS Stack size: 2837
MON: heap usage: 262KB (266KB), free: 77KB
LORA: Accepted packet
MQTT: Sending UPLINK OK

: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
MON: SYS Stack size: 2837
MON: heap usage: 167KB (266KB), free: 172KB
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
LORA: Accepted packet
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
LORA: Accepted packet
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
LORA: Accepted packet
WIFI: IP Address: 192.168.101.200
MON: SYS Stack size: 2837
MON: heap usage: 168KB (266KB), free: 171KB
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
LORA: Accepted packet
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
MON: SYS Stack size: 2837
MON: heap usage: 168KB (266KB), free: 171KB
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
LORA: Accepted packet
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
LORA: Accepted packet
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
LORA: Accepted packet
CB: SNTP: State change from 1 to 2
INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
MON: SYS Stack size: 2837
MON: heap usage: 168KB (266KB), free: 171KB
WIFI: IP Address: 192.168.101.200
LORA: Packet dropped! Bad CRC
SNTP: State change from 2 to 6
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
LORA: Accepted packet
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
MON: SYS Stack size: 2837
MON: heap usage: 167KB (266KB), free: 172KB
LORA: Accepted packet
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
LORA: Accepted packet
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 170KB
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
SNTP: State change from 2 to 6
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
MON: SYS Stack size: 2837
MON: heap usage: 168KB (266KB), free: 171KB
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
LORA: Accepted packet
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
SNTP: State change from 2 to 6
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
LORA: Accepted packet
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
LORA: Packet dropped! Bad CRC
LORA: Accepted packet
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping
SNTP: State change from 1 to 2
WIFI: IP Address: 192.168.101.200
LORA: Accepted packet
LORA: Accepted packet
LORA: Packet dropped! Bad CRC
INET: State change to 3
INET: Ping probe
INET: Error sending probe on Eth
INET: Ping response from MRF24WN, set as default
INET: State change to 5

MAIN: Leaving state 6
MAIN: Entering state 5
SNTP: State change from 2 to 6
MON: SYS Stack size: 2837
MON: heap usage: 169KB (266KB), free: 169KB
HTTP: TCPIP_DNS_IsResolved returned failure code -5
MQTT: Wrong DNS, stopping operation

MAIN: MQTT error

MAIN: Leaving state 5
LORA: Accepted packet
MAIN: Entering state 6
INET: State change to 0
WIFI: Disabling modules
CB: Disconnect
Head magic match void: trying to free an already freed
block, ignore
SNTP: State change from 6 to 1
WIFI: Entering state 3
WIFI: Enabling modules for client
WIFI: Entering state 6
WIFI: IP Address: 0.0.0.0
CB: SNTP: State change from 1 to 2
INET: Gateway has WiFi
INET: State change to 2
INET: Connected to a network, waiting for DHCP lease,
checking validity with ping

@avbentem

This comment has been minimized.

Copy link
Author

commented May 2, 2018

Today's 1.0.4 gets me a reboot loop, so I am back at 1.0.3 which is still giving me this issue a few times per week. (I am using ethernet; have not tested 1.0.4 with WiFi.)

@avbentem

This comment has been minimized.

Copy link
Author

commented May 2, 2018

@bvdbrule can you please edit your earlier comment to put the long log into something like the following?

<details>
<summary>Reboot loop in 1.0.3</summary>

```text
your
log
here
```
</details>

...which will get you:

Reboot loop in 1.0.3
your
log
here

Thanks! (I will delete this comment later.)
@avbentem

This comment has been minimized.

Copy link
Author

commented Aug 5, 2018

@bvdbrule, did you see my comment? Please edit, many thanks!

@avbentem

This comment has been minimized.

Copy link
Author

commented Aug 5, 2018

I've upgraded from 1.0.3 to 1.0.5 on Tuesday July 17th, around 9 PM Amsterdam time. This is using the factory bootloader. The problem still exists since the upgrade, but the cause of the reboot and the logging after the reboot are different. So maybe it's a different problem after all.

The logging might also indicate this is related to #47, however, now the LoRa module is actually working just fine. So I guess it's related to this very issue, not to #47.

Since the upgrade my gateway has run into *** assert ../src/app_lora.c:842:w == size:LORA Uart write should be blocking 225 times; see #52. In 3 of those 225 occurences it failed to recover after it rebooted: it got an ethernet connection, and showed HTTP: Starting connection but never showed anything about the network after that (like it did NOT show Gateway bridging), though it was receiving LoRa packets. A manual reboot using the pink Mode button fixed this.

Apart from the following 3 occasions, the gateway has rebooted many, many more times, like after an MQTT error and also for the 222 other occurences of the above assert. But for those reboots, it recovered just fine.

Saturday July 21st, no gateway bridging after reboot, until manual reboot, in 1.0.5
[2018-07-21T05:07:09.551Z] MQTT: Sending status packet
[2018-07-21T05:07:09.585Z] MQTT: Sending status succeeded: 671
[2018-07-21T05:07:13.413Z] MON: SYS Stack size: 2837
[2018-07-21T05:07:13.416Z] MON: TCPIP Stack size: 3771
[2018-07-21T05:07:13.418Z] MON: APP Stack size: 3271
[2018-07-21T05:07:13.420Z] MON: LoRa Stack size: 3857
[2018-07-21T05:07:13.424Z] MON: heap usage: 282KB (283KB), free: 56KB
[2018-07-21T05:07:17.288Z] LORA: Kic
[2018-07-21T05:07:17.294Z] *** assert ../src/app_lora.c:842:w == size:LORA Uart write should be blocking
[2018-07-21T05:07:17.297Z] 
[2018-07-21T05:07:19.324Z] SNTP: State change from 0 to 0
[2018-07-21T05:07:19.328Z] SNTP: State change from 0 to 0
[2018-07-21T05:07:19.331Z] 
[2018-07-21T05:07:19.332Z] 
[2018-07-21T05:07:19.333Z] 
[2018-07-21T05:07:19.334Z] **************************
[2018-07-21T05:07:19.337Z] *   The Things Network   *
[2018-07-21T05:07:19.339Z] *      G A T E W A Y     *
[2018-07-21T05:07:19.342Z] **************************
[2018-07-21T05:07:19.346Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-07-21T05:07:19.367Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-07-21T05:07:19.369Z] Build time: Jul 17 2018 08:12:51
[2018-07-21T05:07:19.382Z] Reboot reason: 0x40
[2018-07-21T05:07:19.391Z] BOOT: (persisted info) 6F 72 72 65 01 40 AB 2C E7 16 05 C9 B2 3A 50 97 
[2018-07-21T05:07:19.393Z] 
[2018-07-21T05:07:19.394Z] 
[2018-07-21T05:07:19.395Z] 
[2018-07-21T05:07:19.396Z] 
[2018-07-21T05:07:19.397Z] WIFI: Entering state 0
[2018-07-21T05:07:19.399Z] WIFI: Entering SCAN state 0
[2018-07-21T05:07:19.400Z] 
[2018-07-21T05:07:19.401Z] MAIN: Initialisation complete
[2018-07-21T05:07:19.415Z] LORA: Changing state from 0 to 0
[2018-07-21T05:07:19.570Z] 
[2018-07-21T05:07:19.573Z] MAIN: Leaving state 0
[2018-07-21T05:07:19.575Z] MAIN: Entering state 1
[2018-07-21T05:07:19.582Z] FLASH: Magic bytes found: wifi config present
[2018-07-21T05:07:19.589Z] FLASH: Magic bytes found: activation data present
[2018-07-21T05:07:19.593Z] FLASH: Magic bytes found: FOTA data present
[2018-07-21T05:07:19.596Z] FLASH: Loading Firmware Data
[2018-07-21T05:07:19.607Z] CNFG: (Firmware HASH (sha256)) 93 F4 C1 0E 77 81 3F 60 0D A8 A5 97 57 7C DD 9C 95 64 1F DF F4 8D 44 3F 92 9A 20 A9 2D B4 B9 F5 
[2018-07-21T05:07:19.610Z] FLASH: Loading WiFi Data
[2018-07-21T05:07:19.613Z] CNFG: WiFi SSID:      <redacted>
[2018-07-21T05:07:19.616Z] CNFG: WiFi key:       ***
[2018-07-21T05:07:19.618Z] CNFG: WiFi conn_type: 1
[2018-07-21T05:07:19.621Z] CNFG: WiFi sec_type:  4
[2018-07-21T05:07:19.623Z] FLASH: Loading Activation Data
[2018-07-21T05:07:19.626Z] CNFG: Gateway ID:         arjanvanb-gw-1
[2018-07-21T05:07:19.629Z] CNFG: Gateway Key:        ***
[2018-07-21T05:07:19.635Z] CNFG: Account Server URL: https://account.thethingsnetwork.org
[2018-07-21T05:07:19.638Z] CNFG: Locked:             true
[2018-07-21T05:07:19.641Z] CNFG: Locked first time:  false
[2018-07-21T05:07:19.643Z] 
[2018-07-21T05:07:19.645Z] MAIN: Leaving state 1
[2018-07-21T05:07:19.647Z] MAIN: Entering state 2
[2018-07-21T05:07:19.650Z] INET: State change to 0
[2018-07-21T05:07:19.947Z] LORA: Initialisation complete
[2018-07-21T05:07:19.950Z] LORA: Changing state from 0 to 1
[2018-07-21T05:07:20.202Z] WIFI: Entering state 1
[2018-07-21T05:07:20.205Z] ETH: IP Address: 0.0.0.0 
[2018-07-21T05:07:20.208Z] WIFI: Entering state 2
[2018-07-21T05:07:20.211Z] WIFI: Disabling modules
[2018-07-21T05:07:20.215Z] Head magic match void: trying to free an already freed block, ignore
[2018-07-21T05:07:20.218Z] WIFI: Entering state 3
[2018-07-21T05:07:21.086Z] WIFI: Enabling modules for client
[2018-07-21T05:07:21.089Z] WIFI: Entering state 6
[2018-07-21T05:07:21.091Z] 
[2018-07-21T05:07:21.092Z] 
[2018-07-21T05:07:21.094Z] >WIFI: IP Address: 0.0.0.0 
[2018-07-21T05:07:21.296Z] SNTP: State change from 0 to 1
[2018-07-21T05:07:21.356Z] INET: Gateway has Ethernet
[2018-07-21T05:07:21.359Z] INET: State change to 2
[2018-07-21T05:07:21.365Z] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
[2018-07-21T05:07:22.394Z] SNTP: State change from 1 to 2
[2018-07-21T05:07:24.392Z] ETH: IP Address: 192.168.0.12 
[2018-07-21T05:07:26.355Z] INET: State change to 3
[2018-07-21T05:07:26.358Z] INET: Ping probe
[2018-07-21T05:07:26.361Z] INET: Error sending probe on WiFi
[2018-07-21T05:07:26.371Z] INET: Ping response from PIC32INT, set as default
[2018-07-21T05:07:26.374Z] INET: State change to 4
[2018-07-21T05:07:26.947Z] LORA: Wait init complete, waiting for application.
[2018-07-21T05:07:26.950Z] LORA: Changing state from 1 to 2
[2018-07-21T05:07:27.887Z] SNTP: State change from 2 to 3
[2018-07-21T05:07:28.986Z] CB: SNTP: State change from 3 to 4
[2018-07-21T05:07:29.320Z] MON: SYS Stack size: 3959
[2018-07-21T05:07:29.323Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:07:29.325Z] MON: APP Stack size: 8008
[2018-07-21T05:07:29.327Z] MON: LoRa Stack size: 3913
[2018-07-21T05:07:29.331Z] MON: heap usage: 183KB (188KB), free: 156KB
[2018-07-21T05:07:30.085Z] SNTP: State change from 4 to 5
[2018-07-21T05:07:30.094Z] SNTP: State change from 5 to 6
[2018-07-21T05:07:30.097Z] SNTP: State change from 6 to 7
[2018-07-21T05:07:30.100Z] INET: State change to 5
[2018-07-21T05:07:30.102Z] 
[2018-07-21T05:07:30.104Z] MAIN: Leaving state 2
[2018-07-21T05:07:30.106Z] MAIN: Entering state 3
[2018-07-21T05:07:30.108Z] 
[2018-07-21T05:07:30.110Z] CNFG: Load online user config state change to 4
[2018-07-21T05:07:30.113Z] HTTP: Close active socket 0
[2018-07-21T05:07:30.115Z] HTTP: Starting connection
[2018-07-21T05:07:30.385Z] WIFI: IP Address: 192.168.178.53 
[2018-07-21T05:07:39.318Z] MON: SYS Stack size: 3959
[2018-07-21T05:07:39.321Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:07:39.323Z] MON: APP Stack size: 4217
[2018-07-21T05:07:39.325Z] MON: LoRa Stack size: 3913
[2018-07-21T05:07:39.329Z] MON: heap usage: 185KB (188KB), free: 154KB
[2018-07-21T05:07:49.316Z] MON: SYS Stack size: 3959
[2018-07-21T05:07:49.319Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:07:49.321Z] MON: APP Stack size: 4217
[2018-07-21T05:07:49.323Z] MON: LoRa Stack size: 3913
[2018-07-21T05:07:49.330Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-21T05:07:59.314Z] MON: SYS Stack size: 3959
[2018-07-21T05:07:59.316Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:07:59.319Z] MON: APP Stack size: 4217
[2018-07-21T05:07:59.321Z] MON: LoRa Stack size: 3913
[2018-07-21T05:07:59.325Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-21T05:08:00.776Z] LGMD:LORA: Accepted packet
[2018-07-21T05:08:00.779Z] 
[2018-07-21T05:08:09.311Z] MON: SYS Stack size: 3959
[2018-07-21T05:08:09.313Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:08:09.316Z] MON: APP Stack size: 4217
[2018-07-21T05:08:09.318Z] MON: LoRa Stack size: 3913
[2018-07-21T05:08:09.322Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-21T05:08:19.310Z] MON: SYS Stack size: 3959
[2018-07-21T05:08:19.312Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:08:19.314Z] MON: APP Stack size: 4217
[2018-07-21T05:08:19.317Z] MON: LoRa Stack size: 3913
[2018-07-21T05:08:19.321Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-21T05:08:20.722Z] LGMD:Rejected packet (0x11)
[2018-07-21T05:08:20.725Z] 
[2018-07-21T05:08:29.308Z] MON: SYS Stack size: 3959
[2018-07-21T05:08:29.310Z] MON: TCPIP Stack size: 3815
[2018-07-21T05:08:29.313Z] MON: APP Stack size: 4217
[2018-07-21T05:08:29.315Z] MON: LoRa Stack size: 3913
[2018-07-21T05:08:29.322Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-21T05:08:29.421Z] LGMD:LORA: Accepted packet
[2018-07-21T05:08:29.424Z] 
[2018-07-21T05:08:35.331Z] LGMD:LORA: Accepted packet
[2018-07-21T05:08:35.333Z] 
...
...
[2018-07-21T08:53:02.935Z] LGMD:LORA: Accepted packet
[2018-07-21T08:53:02.937Z] 
[2018-07-21T08:53:06.649Z] MON: SYS Stack size: 3959
[2018-07-21T08:53:06.651Z] MON: TCPIP Stack size: 3743
[2018-07-21T08:53:06.653Z] MON: APP Stack size: 4217
[2018-07-21T08:53:06.655Z] MON: LoRa Stack size: 3913
[2018-07-21T08:53:06.660Z] MON: heap usage: 184KB (189KB), free: 155KB
[2018-07-21T08:53:11.092Z] BTN: change to state: 1
[2018-07-21T08:53:11.200Z] BTN: change to sta
[2018-07-21T08:53:11.203Z] *** Application reboot
[2018-07-21T08:53:11.210Z] 
[2018-07-21T08:53:13.231Z] SNTP: State change from 0 to 0
[2018-07-21T08:53:13.234Z] SNTP: State change from 0 to 0
[2018-07-21T08:53:13.238Z] 
[2018-07-21T08:53:13.239Z] 
[2018-07-21T08:53:13.240Z] 
[2018-07-21T08:53:13.242Z] **************************
[2018-07-21T08:53:13.244Z] *   The Things Network   *
[2018-07-21T08:53:13.247Z] *      G A T E W A Y     *
[2018-07-21T08:53:13.248Z] **************************
[2018-07-21T08:53:13.254Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-07-21T08:53:13.272Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-07-21T08:53:13.275Z] Build time: Jul 17 2018 08:12:51
[2018-07-21T08:53:13.287Z] Reboot reason: 0x40
Monday July 30st, no gateway bridging after reboot, until manual reboot, in 1.0.5
[2018-07-30T02:35:11.170Z] MQTT: Sending status packet
[2018-07-30T02:35:11.199Z] MQTT: Sending status succeeded: 432
[2018-07-30T02:35:20.476Z] MON: SYS Stack size: 2853
[2018-07-30T02:35:20.479Z] MON: TCPIP Stack size: 3765
[2018-07-30T02:35:20.481Z] MON: APP Stack size: 3294
[2018-07-30T02:35:20.484Z] MON: LoRa Stack size: 3877
[2018-07-30T02:35:20.487Z] MON: heap usage: 279KB (283KB), free: 60KB
[2018-07-30T02:35:30.476Z] MON: SYS Stack size: 2853
[2018-07-30T02:35:30.479Z] MON: TCPIP Stack size: 3765
[2018-07-30T02:35:30.482Z] MON: APP Stack size: 3294
[2018-07-30T02:35:30.484Z] MON: LoRa Stack size: 3877
[2018-07-30T02:35:30.487Z] MON: heap usage: 279KB (283KB), free: 60KB
[2018-07-30T02:35:36.063Z] LORA: Kick LoRa module with
[2018-07-30T02:35:36.073Z] *** assert ../src/app_lora.c:842:w == size:LORA Uart write should be blocking
[2018-07-30T02:35:36.075Z] 
[2018-07-30T02:35:38.100Z] SNTP: State change from 0 to 0
[2018-07-30T02:35:38.103Z] SNTP: State change from 0 to 0
[2018-07-30T02:35:38.106Z] 
[2018-07-30T02:35:38.107Z] 
[2018-07-30T02:35:38.109Z] 
[2018-07-30T02:35:38.110Z] **************************
[2018-07-30T02:35:38.113Z] *   The Things Network   *
[2018-07-30T02:35:38.115Z] *      G A T E W A Y     *
[2018-07-30T02:35:38.119Z] **************************
[2018-07-30T02:35:38.122Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-07-30T02:35:38.141Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-07-30T02:35:38.143Z] Build time: Jul 17 2018 08:12:51
[2018-07-30T02:35:38.155Z] Reboot reason: 0x40
[2018-07-30T02:35:38.163Z] BOOT: (persisted info) 6F 72 72 65 01 40 AB 2C E7 16 05 C9 B2 3A 50 97 
[2018-07-30T02:35:38.166Z] 
[2018-07-30T02:35:38.167Z] 
[2018-07-30T02:35:38.168Z] 
[2018-07-30T02:35:38.169Z] 
[2018-07-30T02:35:38.169Z] WIFI: Entering state 0
[2018-07-30T02:35:38.171Z] WIFI: Entering SCAN state 0
[2018-07-30T02:35:38.172Z] 
[2018-07-30T02:35:38.183Z] MAIN: Initialisation complete
[2018-07-30T02:35:38.185Z] LORA: Changing state from 0 to 0
[2018-07-30T02:35:38.345Z] 
[2018-07-30T02:35:38.348Z] MAIN: Leaving state 0
[2018-07-30T02:35:38.351Z] MAIN: Entering state 1
[2018-07-30T02:35:38.358Z] FLASH: Magic bytes found: wifi config present
[2018-07-30T02:35:38.363Z] FLASH: Magic bytes found: activation data present
[2018-07-30T02:35:38.368Z] FLASH: Magic bytes found: FOTA data present
[2018-07-30T02:35:38.371Z] FLASH: Loading Firmware Data
[2018-07-30T02:35:38.383Z] CNFG: (Firmware HASH (sha256)) 93 F4 C1 0E 77 81 3F 60 0D A8 A5 97 57 7C DD 9C 95 64 1F DF F4 8D 44 3F 92 9A 20 A9 2D B4 B9 F5 
[2018-07-30T02:35:38.387Z] FLASH: Loading WiFi Data
[2018-07-30T02:35:38.393Z] CNFG: WiFi SSID:      <redacted>
[2018-07-30T02:35:38.396Z] CNFG: WiFi key:       ***
[2018-07-30T02:35:38.399Z] CNFG: WiFi conn_type: 1
[2018-07-30T02:35:38.402Z] CNFG: WiFi sec_type:  4
[2018-07-30T02:35:38.403Z] FLASH: Loading Activation Data
[2018-07-30T02:35:38.407Z] CNFG: Gateway ID:         arjanvanb-gw-1
[2018-07-30T02:35:38.409Z] CNFG: Gateway Key:        ***
[2018-07-30T02:35:38.412Z] CNFG: Account Server URL: https://account.thethingsnetwork.org
[2018-07-30T02:35:38.415Z] CNFG: Locked:             true
[2018-07-30T02:35:38.416Z] CNFG: Locked first time:  false
[2018-07-30T02:35:38.418Z] 
[2018-07-30T02:35:38.421Z] MAIN: Leaving state 1
[2018-07-30T02:35:38.422Z] MAIN: Entering state 2
[2018-07-30T02:35:38.425Z] INET: State change to 0
[2018-07-30T02:35:38.722Z] LORA: Initialisation complete
[2018-07-30T02:35:38.725Z] LORA: Changing state from 0 to 1
[2018-07-30T02:35:38.977Z] WIFI: Entering state 1
[2018-07-30T02:35:38.981Z] ETH: IP Address: 0.0.0.0 
[2018-07-30T02:35:38.984Z] WIFI: Entering state 2
[2018-07-30T02:35:38.987Z] WIFI: Disabling modules
[2018-07-30T02:35:38.993Z] Head magic match void: trying to free an already freed block, ignore
[2018-07-30T02:35:38.996Z] WIFI: Entering state 3
[2018-07-30T02:35:39.861Z] WIFI: Enabling modules for client
[2018-07-30T02:35:39.864Z] WIFI: Entering state 6
[2018-07-30T02:35:39.866Z] 
[2018-07-30T02:35:39.868Z] 
[2018-07-30T02:35:39.870Z] >WIFI: IP Address: 0.0.0.0 
[2018-07-30T02:35:40.072Z] SNTP: State change from 0 to 1
[2018-07-30T02:35:40.122Z] INET: Gateway has Ethernet
[2018-07-30T02:35:40.125Z] INET: State change to 2
[2018-07-30T02:35:40.131Z] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
[2018-07-30T02:35:41.170Z] SNTP: State change from 1 to 2
[2018-07-30T02:35:43.168Z] ETH: IP Address: 192.168.0.12 
[2018-07-30T02:35:45.122Z] INET: State change to 3
[2018-07-30T02:35:45.125Z] INET: Ping probe
[2018-07-30T02:35:45.128Z] INET: Error sending probe on WiFi
[2018-07-30T02:35:45.137Z] INET: Ping response from PIC32INT, set as default
[2018-07-30T02:35:45.140Z] INET: State change to 4
[2018-07-30T02:35:45.724Z] LORA: Wait init complete, waiting for application.
[2018-07-30T02:35:45.727Z] LORA: Changing state from 1 to 2
[2018-07-30T02:35:46.664Z] SNTP: State change from 2 to 3
[2018-07-30T02:35:47.763Z] CB: SNTP: State change from 3 to 4
[2018-07-30T02:35:48.097Z] MON: SYS Stack size: 3959
[2018-07-30T02:35:48.100Z] MON: TCPIP Stack size: 3815
[2018-07-30T02:35:48.102Z] MON: APP Stack size: 8008
[2018-07-30T02:35:48.104Z] MON: LoRa Stack size: 3913
[2018-07-30T02:35:48.108Z] MON: heap usage: 180KB (188KB), free: 160KB
[2018-07-30T02:35:48.862Z] SNTP: State change from 4 to 5
[2018-07-30T02:35:48.875Z] SNTP: State change from 5 to 6
[2018-07-30T02:35:48.878Z] SNTP: State change from 6 to 7
[2018-07-30T02:35:48.881Z] INET: State change to 5
[2018-07-30T02:35:48.883Z] 
[2018-07-30T02:35:48.885Z] MAIN: Leaving state 2
[2018-07-30T02:35:48.887Z] MAIN: Entering state 3
[2018-07-30T02:35:48.889Z] 
[2018-07-30T02:35:48.891Z] CNFG: Load online user config state change to 4
[2018-07-30T02:35:48.894Z] HTTP: Close active socket 0
[2018-07-30T02:35:48.896Z] HTTP: Starting connection
[2018-07-30T02:35:49.162Z] WIFI: IP Address: 192.168.178.53 
[2018-07-30T02:35:58.094Z] MON: SYS Stack size: 3959
[2018-07-30T02:35:58.097Z] MON: TCPIP Stack size: 3815
[2018-07-30T02:35:58.099Z] MON: APP Stack size: 4217
[2018-07-30T02:35:58.101Z] MON: LoRa Stack size: 3913
[2018-07-30T02:35:58.105Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-30T02:36:08.092Z] MON: SYS Stack size: 3959
[2018-07-30T02:36:08.094Z] MON: TCPIP Stack size: 3815
[2018-07-30T02:36:08.097Z] MON: APP Stack size: 4217
[2018-07-30T02:36:08.099Z] MON: LoRa Stack size: 3913
[2018-07-30T02:36:08.103Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-30T02:36:14.156Z] LGMD:Rejected packet (0x11)
[2018-07-30T02:36:14.158Z] 
[2018-07-30T02:36:18.089Z] MON: SYS Stack size: 3959
[2018-07-30T02:36:18.091Z] MON: TCPIP Stack size: 3815
[2018-07-30T02:36:18.094Z] MON: APP Stack size: 4217
[2018-07-30T02:36:18.096Z] MON: LoRa Stack size: 3913
[2018-07-30T02:36:18.100Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-30T02:36:25.460Z] LGMD:LORA: Accepted packet
[2018-07-30T02:36:25.462Z] 
...
...
[2018-07-30T15:55:16.466Z] LGMD:LORA: Accepted packet
[2018-07-30T15:55:16.468Z] 
[2018-07-30T15:55:16.533Z] LGMD:Rejected packet (0x11)
[2018-07-30T15:55:16.535Z] 
[2018-07-30T15:55:25.540Z] MON: SYS Stack size: 3959
[2018-07-30T15:55:25.543Z] MON: TCPIP Stack size: 3765
[2018-07-30T15:55:25.546Z] MON: APP Stack size: 4217
[2018-07-30T15:55:25.548Z] MON: LoRa Stack size: 3913
[2018-07-30T15:55:25.551Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-07-30T15:55:33.179Z] BTN: change to state: 1
[2018-07-30T15:55:33.279Z] BTN: change to sta
[2018-07-30T15:55:33.282Z] *** Application reboot
[2018-07-30T15:55:33.290Z] 
[2018-07-30T15:55:35.313Z] SNTP: State change from 0 to 0
[2018-07-30T15:55:35.316Z] SNTP: State change from 0 to 0
[2018-07-30T15:55:35.318Z] 
[2018-07-30T15:55:35.320Z] 
[2018-07-30T15:55:35.321Z] 
[2018-07-30T15:55:35.323Z] **************************
[2018-07-30T15:55:35.325Z] *   The Things Network   *
[2018-07-30T15:55:35.329Z] *      G A T E W A Y     *
[2018-07-30T15:55:35.331Z] **************************
[2018-07-30T15:55:35.335Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-07-30T15:55:35.354Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-07-30T15:55:35.358Z] Build time: Jul 17 2018 08:12:51
[2018-07-30T15:55:35.372Z] Reboot reason: 0x40
Thursday August 2nd, no gateway bridging after reboot, until manual reboot, in 1.0.5
[2018-08-02T00:37:50.377Z] MQTT: Sending status packet
[2018-08-02T00:37:50.408Z] MQTT: Sending status succeeded: 457
[2018-08-02T00:37:59.677Z] MON: SYS Stack size: 2831
[2018-08-02T00:37:59.680Z] MON: TCPIP Stack size: 3765
[2018-08-02T00:37:59.682Z] MON: APP Stack size: 3259
[2018-08-02T00:37:59.684Z] MON: LoRa Stack size: 3877
[2018-08-02T00:37:59.687Z] MON: heap usage: 279KB (283KB), free: 60KB
[2018-08-02T00:38:09.676Z] MON: SYS Stack size: 2831
[2018-08-02T00:38:09.678Z] MON: TCPIP Stack size: 3765
[2018-08-02T00:38:09.680Z] MON: APP Stack size: 3259
[2018-08-02T00:38:09.682Z] MON: LoRa Stack size: 3877
[2018-08-02T00:38:09.686Z] MON: heap usage: 279KB (283KB), free: 60KB
[2018-08-02T00:38:19.673Z] MON: SYS Stack size: 2831
[2018-08-02T00:38:19.675Z] MON: TCPIP Stack size: 3765
[2018-08-02T00:38:19.678Z] MON: APP Stack size: 3259
[2018-08-02T00:38:19.680Z] MON: LoRa Stack size: 3877
[2018-08-02T00:38:19.684Z] MON: heap usage: 279KB (283KB), free: 60KB
[2018-08-02T00:38:20.319Z] LORA: Kick LoRa module with ACK afte
[2018-08-02T00:38:20.326Z] *** assert ../src/app_lora.c:842:w == size:LORA Uart write should be blocking
[2018-08-02T00:38:20.329Z] 
[2018-08-02T00:38:22.356Z] SNTP: State change from 0 to 0
[2018-08-02T00:38:22.360Z] SNTP: State change from 0 to 0
[2018-08-02T00:38:22.363Z] 
[2018-08-02T00:38:22.364Z] 
[2018-08-02T00:38:22.365Z] 
[2018-08-02T00:38:22.366Z] **************************
[2018-08-02T00:38:22.369Z] *   The Things Network   *
[2018-08-02T00:38:22.371Z] *      G A T E W A Y     *
[2018-08-02T00:38:22.374Z] **************************
[2018-08-02T00:38:22.379Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-08-02T00:38:22.421Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-08-02T00:38:22.424Z] Build time: Jul 17 2018 08:12:51
[2018-08-02T00:38:22.425Z] Reboot reason: 0x40
[2018-08-02T00:38:22.434Z] BOOT: (persisted info) 6F 72 72 65 01 40 AB 2C E7 16 05 C9 B2 3A 50 97 
[2018-08-02T00:38:22.436Z] 
[2018-08-02T00:38:22.437Z] 
[2018-08-02T00:38:22.438Z] 
[2018-08-02T00:38:22.439Z] 
[2018-08-02T00:38:22.440Z] WIFI: Entering state 0
[2018-08-02T00:38:22.442Z] WIFI: Entering SCAN state 0
[2018-08-02T00:38:22.444Z] 
[2018-08-02T00:38:22.445Z] MAIN: Initialisation complete
[2018-08-02T00:38:22.461Z] LORA: Changing state from 0 to 0
[2018-08-02T00:38:22.602Z] 
[2018-08-02T00:38:22.605Z] MAIN: Leaving state 0
[2018-08-02T00:38:22.608Z] MAIN: Entering state 1
[2018-08-02T00:38:22.614Z] FLASH: Magic bytes found: wifi config present
[2018-08-02T00:38:22.620Z] FLASH: Magic bytes found: activation data present
[2018-08-02T00:38:22.624Z] FLASH: Magic bytes found: FOTA data present
[2018-08-02T00:38:22.627Z] FLASH: Loading Firmware Data
[2018-08-02T00:38:22.639Z] CNFG: (Firmware HASH (sha256)) 93 F4 C1 0E 77 81 3F 60 0D A8 A5 97 57 7C DD 9C 95 64 1F DF F4 8D 44 3F 92 9A 20 A9 2D B4 B9 F5 
[2018-08-02T00:38:22.646Z] FLASH: Loading WiFi Data
[2018-08-02T00:38:22.650Z] CNFG: WiFi SSID:      <redacted>
[2018-08-02T00:38:22.653Z] CNFG: WiFi key:       ***
[2018-08-02T00:38:22.653Z] CNFG: WiFi key:       ***
[2018-08-02T00:38:22.655Z] CNFG: WiFi conn_type: 1
[2018-08-02T00:38:22.656Z] CNFG: WiFi sec_type:  4
[2018-08-02T00:38:22.663Z] FLASH: Loading Activation Data
[2018-08-02T00:38:22.665Z] CNFG: Gateway ID:         arjanvanb-gw-1
[2018-08-02T00:38:22.668Z] CNFG: Gateway Key:        ***
[2018-08-02T00:38:22.671Z] CNFG: Account Server URL: https://account.thethingsnetwork.org
[2018-08-02T00:38:22.674Z] CNFG: Locked:             true
[2018-08-02T00:38:22.676Z] CNFG: Locked first time:  false
[2018-08-02T00:38:22.677Z] 
[2018-08-02T00:38:22.678Z] MAIN: Leaving state 1
[2018-08-02T00:38:22.681Z] MAIN: Entering state 2
[2018-08-02T00:38:22.683Z] INET: State change to 0
[2018-08-02T00:38:22.979Z] LORA: Initialisation complete
[2018-08-02T00:38:22.982Z] LORA: Changing state from 0 to 1
[2018-08-02T00:38:23.234Z] WIFI: Entering state 1
[2018-08-02T00:38:23.237Z] ETH: IP Address: 0.0.0.0 
[2018-08-02T00:38:23.240Z] WIFI: Entering state 2
[2018-08-02T00:38:23.243Z] WIFI: Disabling modules
[2018-08-02T00:38:23.248Z] Head magic match void: trying to free an already freed block, ignore
[2018-08-02T00:38:23.250Z] WIFI: Entering state 3
[2018-08-02T00:38:24.118Z] WIFI: Enabling modules for client
[2018-08-02T00:38:24.121Z] WIFI: Entering state 6
[2018-08-02T00:38:24.123Z] 
[2018-08-02T00:38:24.124Z] 
[2018-08-02T00:38:24.126Z] >WIFI: IP Address: 0.0.0.0 
[2018-08-02T00:38:24.328Z] SNTP: State change from 0 to 1
[2018-08-02T00:38:24.453Z] INET: Gateway has Ethernet
[2018-08-02T00:38:24.456Z] INET: State change to 2
[2018-08-02T00:38:24.462Z] INET: Connected to a network, waiting for DHCP lease, checking validity with ping
[2018-08-02T00:38:26.525Z] SNTP: State change from 1 to 2
[2018-08-02T00:38:27.425Z] ETH: IP Address: 192.168.0.12 
[2018-08-02T00:38:29.453Z] INET: State change to 3
[2018-08-02T00:38:29.456Z] INET: Ping probe
[2018-08-02T00:38:29.460Z] INET: Error sending probe on WiFi
[2018-08-02T00:38:29.468Z] INET: Ping response from PIC32INT, set as default
[2018-08-02T00:38:29.472Z] INET: State change to 4
[2018-08-02T00:38:29.980Z] LORA: Wait init complete, waiting for application.
[2018-08-02T00:38:29.983Z] LORA: Changing state from 1 to 2
[2018-08-02T00:38:32.023Z] SNTP: State change from 2 to 3
[2018-08-02T00:38:32.353Z] CB: MON: SYS Stack size: 3965
[2018-08-02T00:38:32.357Z] MON: TCPIP Stack size: 3815
[2018-08-02T00:38:32.360Z] MON: APP Stack size: 8008
[2018-08-02T00:38:32.362Z] MON: LoRa Stack size: 3911
[2018-08-02T00:38:32.365Z] MON: heap usage: 179KB (188KB), free: 160KB
[2018-08-02T00:38:33.118Z] SNTP: State change from 3 to 4
[2018-08-02T00:38:34.215Z] SNTP: State change from 4 to 5
[2018-08-02T00:38:34.229Z] SNTP: State change from 5 to 6
[2018-08-02T00:38:34.233Z] SNTP: State change from 6 to 7
[2018-08-02T00:38:34.236Z] INET: State change to 5
[2018-08-02T00:38:34.239Z] 
[2018-08-02T00:38:34.241Z] MAIN: Leaving state 2
[2018-08-02T00:38:34.244Z] MAIN: Entering state 3
[2018-08-02T00:38:34.246Z] 
[2018-08-02T00:38:34.248Z] CNFG: Load online user config state change to 4
[2018-08-02T00:38:34.250Z] HTTP: Close active socket 0
[2018-08-02T00:38:34.252Z] HTTP: Starting connection
[2018-08-02T00:38:34.416Z] WIFI: IP Address: 192.168.178.53 
[2018-08-02T00:38:42.348Z] MON: SYS Stack size: 3959
[2018-08-02T00:38:42.351Z] MON: TCPIP Stack size: 3815
[2018-08-02T00:38:42.353Z] MON: APP Stack size: 4217
[2018-08-02T00:38:42.355Z] MON: LoRa Stack size: 3911
[2018-08-02T00:38:42.359Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-08-02T00:38:52.346Z] MON: SYS Stack size: 3959
[2018-08-02T00:38:52.349Z] MON: TCPIP Stack size: 3815
[2018-08-02T00:38:52.351Z] MON: APP Stack size: 4217
[2018-08-02T00:38:52.353Z] MON: LoRa Stack size: 3911
[2018-08-02T00:38:52.356Z] MON: heap usage: 184KB (188KB), free: 155KB
[2018-08-02T00:38:59.802Z] LGMD:LORA: Accepted packet
...
...
[2018-08-02T04:32:05.113Z] LGMD:Rejected packet (0x11)
[2018-08-02T04:32:05.116Z] 
[2018-08-02T04:32:05.956Z] SNTP: State change from 1 to 2
[2018-08-02T04:32:06.897Z] BTN: change to state: 1
[2018-08-02T04:32:06.998Z] BTN: change to sta
[2018-08-02T04:32:07.001Z] *** Application reboot
[2018-08-02T04:32:07.008Z] 
[2018-08-02T04:32:09.030Z] SNTP: State change from 0 to 0
[2018-08-02T04:32:09.033Z] SNTP: State change from 0 to 0
[2018-08-02T04:32:09.035Z] 
[2018-08-02T04:32:09.037Z] 
[2018-08-02T04:32:09.038Z] 
[2018-08-02T04:32:09.040Z] **************************
[2018-08-02T04:32:09.042Z] *   The Things Network   *
[2018-08-02T04:32:09.045Z] *      G A T E W A Y     *
[2018-08-02T04:32:09.047Z] **************************
[2018-08-02T04:32:09.052Z] Firmware name: AmazingAckermann, type: 0, version: 1.0.5, commit: fa89b993, timestamp: 1531815112
[2018-08-02T04:32:09.074Z] Bootloader revision: 1, commit: 7167873a, timestamp: 1496411298
[2018-08-02T04:32:09.077Z] Build time: Jul 17 2018 08:12:51
[2018-08-02T04:32:09.091Z] Reboot reason: 0x40
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.