search

lorabasics / basicstation

LoRa Basics™ Station - The LoRaWAN Gateway Software
https://doc.sm.tc/station
Other
340 stars 178 forks source link

Periodic (~3min) disconnection with cellular modem #127

Open orvio-craig opened 2 years ago

orvio-craig commented 2 years ago

Hello,

I've been testing basicstation and trying to get it up and running and it seems to work reasonably well when I've got a wlan connection. However, when I try to connect over a cellular-based modem I get periodic disconnection events that show the below error; around 3m15s after the .tc.trust connection every single time...

2021-10-25 12:29:08.820 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:29:08.820 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:29:08.821 [TCE:VERB] Connection to MUXS closed in state 4

Hardware: RPi4, RAK2287 Pi Hat concentrator Cellular modules tried: RAK2013, Huawei E3372 LTE USB stick End Point: AWS IoTCore

Below is the output over a longer period. I know my PPS pulse keeps getting lost (I think my office location is very bad for GPS despite the antenna being outdoors), but I don't seem to get the same periodic disconnection when connected over Wifi regardless of the PPS.

Can anyone help?

2021-10-25 11:55:25.660 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 11:55:25.663 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 11:55:25.663 [AIO:INFO]
2021-10-25 11:55:25.706 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 11:55:25.706 [TCE:VERB] Connecting to MUXS...
2021-10-25 11:55:26.090 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 11:55:26.091 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 11:55:26.512 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 11:55:26.512 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 11:55:26.709 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 11:55:26.709 [TCE:VERB] Connected to MUXS.
2021-10-25 11:55:26.709 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 11:55:26.909 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,1250,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 11:55:26.909 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"rtiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 11:55:26.909 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 11:55:26.909 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 11:55:26.909 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 11:55:26.911 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 11:55:27.352 [SYS:INFO] Process rinit.sh (pid=2495) completed
ERROR: Failed to stop TX trigger
ERROR: Failed to stop TX trigger
ERROR: failed to close I2C device (err=-1)
2021-10-25 11:55:27.352 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 11:55:27.377 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 11:55:27.377 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.377 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.378 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 11:55:27.378 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 11:55:27.378 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 11:55:29.898 [RAL:VERB] Concentrator started (2s519ms)
2021-10-25 11:55:29.898 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 11:55:29.898 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 11:55:29.898 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 11:55:29.898 [S2E:VERB]   DR7  FSK
2021-10-25 11:55:29.898 [S2E:VERB]   DR8  undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR9  undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR10 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR11 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR12 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR13 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR14 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   DR15 undefined
2021-10-25 11:55:29.898 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 11:55:29.898 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 11:55:29.898 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 11:55:29.898 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 11:55:34.103 [SYN:INFO] First PPS pulse acquired
2021-10-25 11:55:34.103 [SYN:INFO] Obtained initial PPS offset (376696) - starting timesync with LNS
2021-10-25 11:55:34.097 [SYN:INFO] Time sync: ustime=0x11E4F3488 utc=0x5CF2C09D637D0 gpsOffset=0x0 ppsOffset=376696 syncQual=260
2021-10-25 11:55:34.097 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x11E4F3221 xtime=0xF80000004D8012 pps_xtime=0xF80000004C0429
2021-10-25 11:55:34.097 [SYN:INFO] Time sync: Last PPS     ustime=0x11E4F3221 xtime=0xF80000004D8012 pps_ustime=0x11E4DB638 pps_xtime=0xF80000004C0429
2021-10-25 11:55:34.097 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":4803474635}
2021-10-25 11:55:34.097 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 11:55:34.169 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":4803545749}
2021-10-25 11:55:34.169 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 11:55:34.240 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":4803616869}
2021-10-25 11:55:34.240 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 11:55:34.293 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":4803474635,"gpstime":1319198152208071}
2021-10-25 11:55:34.293 [SYN:VERB] Timesync LNS: tx/rx:0x11E497553..0x11E4C7180 (195ms629us) us/gps:0x11E4DB638/0x4AFCDC1402EC7 (pps offset=376696) - 1 solutions
2021-10-25 11:55:34.293 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCDC13D0200
2021-10-25 11:55:34.293 [SYN:INFO] Time sync: ustime=0x11E523128 utc=0x5CF2C09D93470 gpsOffset=0x4AFCDC13D0200 ppsOffset=376696 syncQual=260
2021-10-25 11:55:34.293 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x11E4F3221 xtime=0xF80000004D8012 pps_xtime=0xF80000004C0429
2021-10-25 11:55:34.293 [SYN:INFO] Time sync: Last PPS     ustime=0x11E4F3221 xtime=0xF80000004D8012 pps_ustime=0x11E4DB638 pps_xtime=0xF80000004C0429
2021-10-25 11:55:34.293 [SYN:INFO] Time ref:  Last PPS     sys->UTC=11:55:34.000000  SX130X->GPS=11:55:52.000000  leaps=18s diff=0
2021-10-25 11:55:34.455 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":4803545749,"gpstime":1319198152368987}
2021-10-25 11:55:34.455 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":4803616869,"gpstime":1319198152369186}
2021-10-25 11:56:19.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.3ppm  q50: -3.3ppm  q80: -6.2ppm  max: -9.4ppm - threshold q90: -8.9ppm
2021-10-25 11:56:19.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -3.3ppm
2021-10-25 11:56:22.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 11:56:37.601 [SYN:INFO] Changed PPS offset: 376696 => 376502 (delta: -194)
2021-10-25 11:56:42.602 [SYN:INFO] Time sync qualities: min=236 q90=260 max=261 (previous q90=2147483647)
2021-10-25 11:56:44.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 11:56:46.503 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 11:56:48.604 [SYN:VERB] Time sync rejected: quality=262 threshold=260
2021-10-25 11:57:14.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.6ppm  q50: -2.8ppm  q80: -3.3ppm  max: -8.3ppm - threshold q90: -7.9ppm
2021-10-25 11:57:14.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -3.0ppm
2021-10-25 11:57:17.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.3ppm - threshold q80: +0.0ppm
2021-10-25 11:57:42.601 [SYN:INFO] Changed PPS offset: 376502 => 376334 (delta: -168)
2021-10-25 11:57:54.401 [SYN:INFO] Time sync qualities: min=238 q90=261 max=262 (previous q90=260)
2021-10-25 11:58:04.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.9ppm  q50: -2.5ppm  q80: -2.8ppm  max: -6.2ppm - threshold q90: -5.6ppm
2021-10-25 11:58:04.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -2.3ppm
2021-10-25 11:58:07.602 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 11:58:18.961 [any:XDEB] RX mod=LORA f=868100000 bw=673367503 sz=23 dr=10 000000190000BDD6FC71A8190000BDD6FCC11EBCD5FCD8
2021-10-25 11:58:18.962 [S2E:VERB] RX 868.1MHz DR2 SF10/BW125 snr=13.5 rssi=-59 xtime=0xF800000A20EE0D - jreq MHdr=00 JoinEUI=fcd6:bd00:19:0 DevEUI=fcd6:bd00:19:a871 DevNonce=7873 MIC=-654518852
2021-10-25 11:58:18.962 [AIO:XDEB] [3|WS] > {"msgtype":"jreq","MHdr":0,"JoinEUI":"FC-D6-BD-00-00-19-00-00","DevEUI":"FC-D6-BD-00-00-19-A8-71","DevNonce":7873,"MIC":-654518852,"RefTime":0.000000,"DR":2,"Freq":868100000,"upinfo":{"rctx":0,"xtime":69805794394172941,"gpstime":1319198316948443,"fts":-1,"rssi":-59,"snr":13.5,"rxtime":1635163098.962930}}
2021-10-25 11:58:19.659 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"00-00-00-00-00-00-00-00","regionid":1,"dnmode":"updn","dC":0,"diid":49323,"pdu":"200b50fef7692ac4055ce1169d3d74dea3524ac20c8b71cc16b6f841a9a82169cb","priority":1,"RxDelay":5,"RX1DR":2,"RX1Freq":868100000,"xtime":69805794394172941,"rctx":0}
2021-10-25 11:58:19.659 [S2E:WARN] Unknown field in dnmsg - ignored: regionid
2021-10-25 11:58:19.659 [S2E:DEBU] ::0 diid=49323 [ant#0] - next TX start ahead by 4s288ms
2021-10-25 11:58:23.928 [S2E:VERB] ::0 diid=49323 [ant#0] - starting TX in 19ms910us
2021-10-25 11:58:23.953 [S2E:INFO] TX ::0 diid=49323 [ant#0] - on air: 868.1MHz 0.0dBm ant#0(0) DR2 SF10/BW125 frame=200B50FEF7692AC4055CE116..A82169CB
2021-10-25 11:58:24.401 [S2E:DEBU] Tx done diid=49323
2021-10-25 11:58:28.885 [any:XDEB] RX mod=LORA f=867300000 bw=683290980 sz=30 dr=10 8082501901000000030B3D876E265BC13290FEC92E7F87809B11465D81FE
2021-10-25 11:58:28.886 [S2E:VERB] RX 867.3MHz DR2 SF10/BW125 snr=13.8 rssi=-46 xtime=0xF800000AB84E16 - updf mhdr=80 DevAddr=01195082 FCtrl=00 FCnt=0 FOpts=[] 030B3D87..9B11 mic=-25076410 (30 bytes)
2021-10-25 11:58:28.886 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":128,"DevAddr":18436226,"FCtrl":0,"FCnt":0,"FOpts":"","FPort":3,"FRMPayload":"0B3D876E265BC13290FEC92E7F87809B11","MIC":-25076410,"RefTime":0.000000,"DR":2,"Freq":867300000,"upinfo":{"rctx":0,"xtime":69805794404093462,"gpstime":1319198326868963,"fts":-1,"rssi":-46,"snr":13.75,"rxtime":1635163108.886288}}
2021-10-25 11:58:29.512 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"00-00-00-00-00-00-00-00","regionid":1,"dnmode":"updn","dC":0,"diid":64378,"pdu":"6082501901a10000067234036c","priority":1,"RxDelay":1,"RX1DR":2,"RX1Freq":867300000,"xtime":69805794404093462,"rctx":0}
2021-10-25 11:58:29.513 [S2E:WARN] Unknown field in dnmsg - ignored: regionid
2021-10-25 11:58:29.513 [S2E:DEBU] ::0 diid=64378 [ant#0] - next TX start ahead by 355ms784us
2021-10-25 11:58:29.848 [S2E:VERB] ::0 diid=64378 [ant#0] - starting TX in 19ms915us
2021-10-25 11:58:29.874 [S2E:INFO] TX ::0 diid=64378 [ant#0] - on air: 867.3MHz 0.0dBm ant#0(0) DR2 SF10/BW125 frame=6082501901A10000067234036C
2021-10-25 11:58:30.157 [S2E:DEBU] Tx done diid=64378
2021-10-25 11:58:34.401 [SYN:INFO] Changed PPS offset: 376334 => 376214 (delta: -120)
2021-10-25 11:58:53.601 [SYN:INFO] MCU/SX130X drift stats: min: +1.2ppm  q50: -2.5ppm  q80: -2.8ppm  max: -6.3ppm - threshold q90: -4.9ppm
2021-10-25 11:58:53.601 [SYN:INFO] Mean MCU drift vs SX130X#0: -2.1ppm
2021-10-25 11:58:58.096 [any:XDEB] RX mod=LORA f=868300000 bw=712501584 sz=23 dr=10 4082501901000100067DC199AFD3CF6A905A6651AB6B1F
2021-10-25 11:58:58.097 [S2E:VERB] RX 868.3MHz DR2 SF10/BW125 snr=13.2 rssi=-53 xtime=0xF800000C761546 - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=1 FOpts=[] 067DC199..5A66 mic=527149905 (23 bytes)
2021-10-25 11:58:58.097 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":1,"FOpts":"","FPort":6,"FRMPayload":"7DC199AFD3CF6A905A66","MIC":527149905,"RefTime":0.000000,"DR":2,"Freq":868300000,"upinfo":{"rctx":0,"xtime":69805794433307974,"gpstime":1319198356083474,"fts":-1,"rssi":-53,"snr":13.25,"rxtime":1635163138.097330}}
2021-10-25 11:59:02.007 [SYN:ERRO] No PPS pulse for ~12 secs
2021-10-25 11:59:06.210 [SYN:INFO] Time sync qualities: min=236 q90=260 max=261 (previous q90=261)
2021-10-25 11:59:12.514 [SYN:ERRO] No PPS pulse for ~23 secs
2021-10-25 11:59:17.704 [any:XDEB] RX mod=LORA f=867100000 bw=732110337 sz=23 dr=10 4082501901000200062E6E129F644B27C994F027F756B0
2021-10-25 11:59:17.705 [S2E:VERB] RX 867.1MHz DR2 SF10/BW125 snr=14.8 rssi=-50 xtime=0xF800000DA15EC9 - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=2 FOpts=[] 062E6E12..94F0 mic=-1336477913 (23 bytes)
2021-10-25 11:59:17.705 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":2,"FOpts":"","FPort":6,"FRMPayload":"2E6E129F644B27C994F0","MIC":-1336477913,"RefTime":0.000000,"DR":2,"Freq":867100000,"upinfo":{"rctx":0,"xtime":69805794452922057,"gpstime":1319198375697557,"fts":-1,"rssi":-50,"snr":14.75,"rxtime":1635163157.705719}}
2021-10-25 11:59:20.920 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 11:59:31.427 [SYN:ERRO] No PPS pulse for ~41 secs
2021-10-25 11:59:35.629 [SYN:INFO] MCU/SX130X drift stats: min: -1.9ppm  q50: -2.4ppm  q80: -4.8ppm  max: -7.6ppm - threshold q90: -7.1ppm
2021-10-25 11:59:35.629 [SYN:INFO] Mean MCU drift vs SX130X#0: -2.6ppm
2021-10-25 11:59:37.324 [any:XDEB] RX mod=LORA f=868500000 bw=751730308 sz=23 dr=10 408250190100030006EC7F83106A0AE06FD8B7CF3CF4FE
2021-10-25 11:59:37.325 [S2E:VERB] RX 868.5MHz DR2 SF10/BW125 snr=10.8 rssi=-56 xtime=0xF800000ECCA88C - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=3 FOpts=[] 06EC7F83..D8B7 mic=-17548081 (23 bytes)
2021-10-25 11:59:37.325 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":3,"FOpts":"","FPort":6,"FRMPayload":"EC7F83106A0AE06FD8B7","MIC":-17548081,"RefTime":0.000000,"DR":2,"Freq":868500000,"upinfo":{"rctx":0,"xtime":69805794472536204,"gpstime":1319198395311704,"fts":-1,"rssi":-56,"snr":10.75,"rxtime":1635163177.325674}}
2021-10-25 11:59:56.940 [any:XDEB] RX mod=LORA f=867300000 bw=771346088 sz=23 dr=10 408250190100040006FEB053130CDA94398529CFB00E63
2021-10-25 11:59:56.941 [S2E:VERB] RX 867.3MHz DR2 SF10/BW125 snr=13.5 rssi=-56 xtime=0xF800000FF7F28A - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=4 FOpts=[] 06FEB053..8529 mic=1661907151 (23 bytes)
2021-10-25 11:59:56.941 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":4,"FOpts":"","FPort":6,"FRMPayload":"FEB053130CDA94398529","MIC":1661907151,"RefTime":0.000000,"DR":2,"Freq":867300000,"upinfo":{"rctx":0,"xtime":69805794492150410,"gpstime":1319198414925910,"fts":-1,"rssi":-56,"snr":13.5,"rxtime":1635163196.941825}}
2021-10-25 12:00:03.996 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:00:06.097 [SYN:INFO] Time sync qualities: min=185 q90=261 max=262 (previous q90=260)
2021-10-25 12:00:06.097 [SYN:VERB] Time sync rejected: quality=262 threshold=261
2021-10-25 12:00:13.452 [SYN:ERRO] No PPS pulse for ~83 secs
2021-10-25 12:00:16.555 [any:XDEB] RX mod=LORA f=868300000 bw=790961344 sz=23 dr=10 4082501901000500066350CB28863BF6D2C160956B533A
2021-10-25 12:00:16.556 [S2E:VERB] RX 868.3MHz DR2 SF10/BW125 snr=13.8 rssi=-53 xtime=0xF8000011233C4C - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=5 FOpts=[] 066350CB..C160 mic=978545557 (23 bytes)
2021-10-25 12:00:16.556 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":5,"FOpts":"","FPort":6,"FRMPayload":"6350CB28863BF6D2C160","MIC":978545557,"RefTime":0.000000,"DR":2,"Freq":868300000,"upinfo":{"rctx":0,"xtime":69805794511764556,"gpstime":1319198434540056,"fts":-1,"rssi":-53,"snr":13.75,"rxtime":1635163216.556725}}
2021-10-25 12:00:19.756 [SYN:INFO] MCU/SX130X drift stats: min: -1.0ppm  q50: -2.4ppm  q80: -5.4ppm  max: -8.6ppm - threshold q90: -7.6ppm
2021-10-25 12:00:19.756 [SYN:INFO] Mean MCU drift vs SX130X#0: -2.4ppm
2021-10-25 12:00:34.319 [SYN:INFO] Time sync: ustime=0x130343824 utc=0x5CF2C1BBB3D4E gpsOffset=0x4AFCDCCEBBB00 ppsOffset=376214 syncQual=261
2021-10-25 12:00:34.319 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x130165E0D xtime=0xF800001214AECE pps_xtime=0xF800000A0F22B9
2021-10-25 12:00:34.319 [SYN:INFO] Time sync: Last PPS     ustime=0x12A028C84 xtime=0xF800000C00DC77 pps_ustime=0x129FC6C73 pps_xtime=0xF800000BFABD34
2021-10-25 12:00:34.319 [SYN:INFO] Time ref:  Last PPS     sys->UTC=11:58:49.999773  SX130X->GPS=11:59:08.000000  leaps=18s diff=227us
2021-10-25 12:00:36.161 [any:XDEB] RX mod=LORA f=867700000 bw=810566778 sz=23 dr=10 4082501901000600060AA216415006E1632193C10EA41F
2021-10-25 12:00:36.162 [S2E:VERB] RX 867.7MHz DR2 SF10/BW125 snr=14.5 rssi=-50 xtime=0xF80000124E860F - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=6 FOpts=[] 060AA216..2193 mic=530845377 (23 bytes)
2021-10-25 12:00:36.162 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":6,"FOpts":"","FPort":6,"FRMPayload":"0AA216415006E1632193","MIC":530845377,"RefTime":0.000000,"DR":2,"Freq":867700000,"upinfo":{"rctx":0,"xtime":69805794531378703,"gpstime":1319198454154203,"fts":-1,"rssi":-50,"snr":14.5,"rxtime":1635163236.162169}}
2021-10-25 12:00:55.784 [any:XDEB] RX mod=LORA f=868500000 bw=830189997 sz=23 dr=10 408250190100070006888420D7F5E58BED867C81CD7D29
2021-10-25 12:00:55.785 [S2E:VERB] RX 868.5MHz DR2 SF10/BW125 snr=9.8 rssi=-54 xtime=0xF800001379CF93 - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=7 FOpts=[] 06888420..867C mic=696110465 (23 bytes)
2021-10-25 12:00:55.785 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":7,"FOpts":"","FPort":6,"FRMPayload":"888420D7F5E58BED867C","MIC":696110465,"RefTime":0.000000,"DR":2,"Freq":868500000,"upinfo":{"rctx":0,"xtime":69805794550992787,"gpstime":1319198473768287,"fts":-1,"rssi":-54,"snr":9.75,"rxtime":1635163255.785748}}
2021-10-25 12:01:01.781 [SYN:INFO] MCU/SX130X drift stats: min: -1.0ppm  q50: -2.9ppm  q80: -6.7ppm  max: -7.1ppm - threshold q90: -7.1ppm
2021-10-25 12:01:01.781 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.4ppm
2021-10-25 12:01:08.084 [SYN:INFO] Time sync qualities: min=238 q90=260 max=261 (previous q90=261)
2021-10-25 12:01:15.394 [any:XDEB] RX mod=LORA f=867700000 bw=849800074 sz=23 dr=10 4082501901000800060AD87A3DA25963E05B500880F5BF
2021-10-25 12:01:15.395 [S2E:VERB] RX 867.7MHz DR2 SF10/BW125 snr=14.8 rssi=-51 xtime=0xF8000014A51992 - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=8 FOpts=[] 060AD87A..5B50 mic=-1074429944 (23 bytes)
2021-10-25 12:01:15.395 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":8,"FOpts":"","FPort":6,"FRMPayload":"0AD87A3DA25963E05B50","MIC":-1074429944,"RefTime":0.000000,"DR":2,"Freq":867700000,"upinfo":{"rctx":0,"xtime":69805794570606994,"gpstime":1319198493382494,"fts":-1,"rssi":-51,"snr":14.75,"rxtime":1635163275.395456}}
2021-10-25 12:01:31.199 [SYN:ERRO] No PPS pulse for ~161 secs
2021-10-25 12:01:35.005 [any:XDEB] RX mod=LORA f=868500000 bw=869411046 sz=23 dr=10 408250190100090006FA56A93824DD7F3171A290C62A77
2021-10-25 12:01:35.006 [S2E:VERB] RX 868.5MHz DR2 SF10/BW125 snr=9.2 rssi=-45 xtime=0xF8000015D06315 - updf mhdr=40 DevAddr=01195082 FCtrl=00 FCnt=9 FOpts=[] 06FA56A9..71A2 mic=1999292048 (23 bytes)
2021-10-25 12:01:35.006 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":18436226,"FCtrl":0,"FCnt":9,"FOpts":"","FPort":6,"FRMPayload":"FA56A93824DD7F3171A2","MIC":1999292048,"RefTime":0.000000,"DR":2,"Freq":868500000,"upinfo":{"rctx":0,"xtime":69805794590221077,"gpstime":1319198512996577,"fts":-1,"rssi":-45,"snr":9.25,"rxtime":1635163295.006388}}
2021-10-25 12:01:43.806 [SYN:INFO] MCU/SX130X drift stats: min: -1.0ppm  q50: -1.9ppm  q80: -6.7ppm  max: -7.1ppm - threshold q90: -6.7ppm
2021-10-25 12:01:43.806 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.8ppm
2021-10-25 12:01:50.112 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:01:54.540 [any:XDEB] RX mod=LORA f=867700000 bw=888945796 sz=14 dr=10 8082501901000A0001A075674FD1
2021-10-25 12:01:54.541 [S2E:VERB] RX 867.7MHz DR2 SF10/BW125 snr=12.5 rssi=-46 xtime=0xF8000016FA6B17 - updf mhdr=80 DevAddr=01195082 FCtrl=00 FCnt=10 FOpts=[] 01A0 mic=-783325323 (14 bytes)
2021-10-25 12:01:54.541 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":128,"DevAddr":18436226,"FCtrl":0,"FCnt":10,"FOpts":"","FPort":1,"FRMPayload":"A0","MIC":-783325323,"RefTime":0.000000,"DR":2,"Freq":867700000,"upinfo":{"rctx":0,"xtime":69805794609752855,"gpstime":1319198532528355,"fts":-1,"rssi":-46,"snr":12.5,"rxtime":1635163314.541110}}
2021-10-25 12:01:55.040 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"00-00-00-00-00-00-00-00","regionid":1,"dnmode":"updn","dC":0,"diid":521,"pdu":"6082501901a001007546f606","priority":1,"RxDelay":1,"RX1DR":2,"RX1Freq":867700000,"xtime":69805794609752855,"rctx":0}
2021-10-25 12:01:55.040 [S2E:WARN] Unknown field in dnmsg - ignored: regionid
2021-10-25 12:01:55.040 [S2E:DEBU] ::0 diid=521 [ant#0] - next TX start ahead by 487ms722us
2021-10-25 12:01:55.508 [S2E:VERB] ::0 diid=521 [ant#0] - starting TX in 19ms299us
2021-10-25 12:01:55.533 [S2E:INFO] TX ::0 diid=521 [ant#0] - on air: 867.7MHz 0.0dBm ant#0(0) DR2 SF10/BW125 frame=6082501901A001007546F606
2021-10-25 12:01:55.816 [S2E:DEBU] Tx done diid=521
2021-10-25 12:02:11.124 [SYN:INFO] Time sync qualities: min=238 q90=260 max=261 (previous q90=260)
2021-10-25 12:02:27.934 [SYN:INFO] MCU/SX130X drift stats: min: +1.0ppm  q50: -1.4ppm  q80: +3.3ppm  max: -6.7ppm - threshold q90: -6.2ppm
2021-10-25 12:02:27.934 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.2ppm
2021-10-25 12:02:42.644 [SYN:VERB] Time sync rejected: quality=287 threshold=260
2021-10-25 12:02:49.999 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:03:12.062 [SYN:INFO] Time sync qualities: min=186 q90=260 max=287 (previous q90=260)
2021-10-25 12:03:12.062 [SYN:INFO] MCU/SX130X drift stats: min: -0.5ppm  q50: -1.9ppm  q80: -6.2ppm  max: -7.6ppm - threshold q90: -7.1ppm
2021-10-25 12:03:12.062 [SYN:INFO] Mean MCU drift vs SX130X#0: -2.0ppm
2021-10-25 12:03:49.887 [SYN:VERB] Time sync rejected: quality=1391 threshold=260
2021-10-25 12:03:56.191 [SYN:INFO] MCU/SX130X drift stats: min: -0.5ppm  q50: -1.4ppm  q80: +4.3ppm  max: -6.7ppm - threshold q90: -6.7ppm
2021-10-25 12:03:56.191 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.3ppm
2021-10-25 12:04:00.393 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:04:02.495 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:04:10.900 [SYN:ERRO] No PPS pulse for ~321 secs
2021-10-25 12:04:15.102 [SYN:INFO] Time sync qualities: min=236 q90=261 max=1391 (previous q90=260)
2021-10-25 12:04:42.419 [SYN:INFO] MCU/SX130X drift stats: min: -0.5ppm  q50: -1.4ppm  q80: +4.3ppm  max: -6.7ppm - threshold q90: -6.2ppm
2021-10-25 12:04:42.419 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.2ppm
2021-10-25 12:04:55.335 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:04:55.335 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:04:55.335 [TCE:VERB] Connection to MUXS closed in state 4
2021-10-25 12:04:55.335 [TCE:INFO] MUXS reconnect backoff 1s (retry 0)
2021-10-25 12:04:56.336 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 12:04:56.343 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 12:04:56.343 [AIO:INFO]
2021-10-25 12:04:56.469 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:56.470 [TCE:VERB] Connecting to MUXS...
2021-10-25 12:04:56.858 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:56.859 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:56.860 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:56.860 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:57.338 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:04:57.339 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 12:04:57.554 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 12:04:57.554 [TCE:VERB] Connected to MUXS.
2021-10-25 12:04:57.554 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 12:04:57.764 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,125,0],[7,250,0],[0,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 12:04:57.764 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 12:04:57.764 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 12:04:57.764 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 12:04:57.764 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 12:04:57.765 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 12:04:58.302 [SYS:INFO] Process rinit.sh (pid=2644) completed
2021-10-25 12:04:58.306 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 12:04:58.325 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 12:04:58.325 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 12:04:58.325 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:04:58.325 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 12:05:00.854 [RAL:VERB] Concentrator started (2s528ms)
2021-10-25 12:05:00.854 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 12:05:00.855 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 12:05:00.855 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 12:05:00.855 [S2E:VERB]   DR7  FSK
2021-10-25 12:05:00.855 [S2E:VERB]   DR8  undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR9  undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR10 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR11 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR12 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR13 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR14 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   DR15 undefined
2021-10-25 12:05:00.855 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 12:05:00.855 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 12:05:00.855 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 12:05:00.855 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 12:05:05.060 [SYN:INFO] First PPS pulse acquired
2021-10-25 12:05:05.060 [SYN:INFO] Obtained initial PPS offset (375662) - starting timesync with LNS
2021-10-25 12:05:05.061 [SYN:INFO] Time sync: ustime=0x1405765BE utc=0x5CF2C2BDE6D10 gpsOffset=0x0 ppsOffset=375662 syncQual=240
2021-10-25 12:05:05.061 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x140576389 xtime=0x190000004DA21B pps_xtime=0x190000004CB580
2021-10-25 12:05:05.061 [SYN:INFO] Time sync: Last PPS     ustime=0x140576389 xtime=0x190000004DA21B pps_ustime=0x1405676EE pps_xtime=0x190000004CB580
2021-10-25 12:05:05.061 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5374436866}
2021-10-25 12:05:05.061 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 12:05:05.132 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5374507980}
2021-10-25 12:05:05.132 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 12:05:05.203 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5374579099}
2021-10-25 12:05:05.203 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 12:05:05.265 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5374436866,"gpstime":1319198723178335}
2021-10-25 12:05:05.265 [SYN:VERB] Timesync LNS: tx/rx:0x14051AA94..0x14054C8C1 (204ms333us) us/gps:0x1405676EE/0x4AFCDE3487F5F (pps offset=375662) - 1 solutions
2021-10-25 12:05:05.265 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCDE345C6C0
2021-10-25 12:05:05.265 [SYN:INFO] Time sync: ustime=0x1405A845F utc=0x5CF2C2BE18BB1 gpsOffset=0x4AFCDE345C6C0 ppsOffset=375662 syncQual=240
2021-10-25 12:05:05.265 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x140576389 xtime=0x190000004DA21B pps_xtime=0x190000004CB580
2021-10-25 12:05:05.265 [SYN:INFO] Time sync: Last PPS     ustime=0x140576389 xtime=0x190000004DA21B pps_ustime=0x1405676EE pps_xtime=0x190000004CB580
2021-10-25 12:05:05.265 [SYN:INFO] Time ref:  Last PPS     sys->UTC=12:05:05.000000  SX130X->GPS=12:05:23.000000  leaps=18s diff=0
2021-10-25 12:05:05.428 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5374507980,"gpstime":1319198723340852}
2021-10-25 12:05:05.428 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5374579099,"gpstime":1319198723341079}
2021-10-25 12:05:23.601 [SYN:INFO] Time sync qualities: min=112 q90=260 max=262 (previous q90=2147483647)
2021-10-25 12:05:50.401 [SYN:INFO] MCU/SX130X drift stats: min: -0.3ppm  q50: -1.1ppm  q80: -4.4ppm  max: -5.7ppm - threshold q90: +5.6ppm
2021-10-25 12:05:50.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.7ppm
2021-10-25 12:05:53.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:06:00.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:06:26.835 [any:XDEB] RX mod=LORA f=868300000 bw=1161240257 sz=52 dr=12 4030DA00012725512300745D00D423FFE18426FF036092BAB2EF5D06637E70D78265E4E12CE8CC563C12DC76A5ADEC683C75C839
2021-10-25 12:06:26.836 [S2E:VERB] RX 868.3MHz DR0 SF12/BW125 snr=-19.8 rssi=-120 xtime=0x190000052D4A97 - updf mhdr=40 DevAddr=0100DA30 FCtrl=27 FCnt=20773 FOpts=[2300745D00D423] FFE18426..EC68 mic=969438524 (52 bytes)
2021-10-25 12:06:26.836 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":16833072,"FCtrl":39,"FCnt":20773,"FOpts":"2300745D00D423","FPort":255,"FRMPayload":"E18426FF036092BAB2EF5D06637E70D78265E4E12CE8CC563C12DC76A5ADEC68","MIC":969438524,"RefTime":0.000000,"DR":0,"Freq":868300000,"upinfo":{"rctx":0,"xtime":7036874504620695,"gpstime":1319198804827091,"fts":-1,"rssi":-120,"snr":-19.75,"rxtime":1635163586.836513}}
2021-10-25 12:06:36.401 [SYN:INFO] Time sync qualities: min=238 q90=260 max=261 (previous q90=260)
2021-10-25 12:06:41.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -0.9ppm  q80: -3.7ppm  max: +5.6ppm - threshold q90: +5.0ppm
2021-10-25 12:06:41.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.5ppm
2021-10-25 12:06:45.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:06:55.601 [SYN:VERB] Time sync rejected: quality=280 threshold=260
2021-10-25 12:07:04.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:07:06.503 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:07:08.604 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:07:37.601 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -1.7ppm  q80: +3.2ppm  max: -4.1ppm - threshold q90: -3.8ppm
2021-10-25 12:07:37.601 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.6ppm
2021-10-25 12:07:37.601 [SYN:INFO] Changed PPS offset: 375662 => 375560 (delta: -102)
2021-10-25 12:07:42.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:07:45.453 [SYN:INFO] Time sync qualities: min=238 q90=261 max=280 (previous q90=260)
2021-10-25 12:08:19.401 [SYN:INFO] MCU/SX130X drift stats: min: -0.3ppm  q50: +2.8ppm  q80: -4.1ppm  max: -6.7ppm - threshold q90: +6.3ppm
2021-10-25 12:08:19.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -1.0ppm
2021-10-25 12:08:37.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:08:54.401 [SYN:INFO] Time sync qualities: min=238 q90=260 max=261 (previous q90=261)
2021-10-25 12:08:54.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:09:10.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -0.6ppm  q80: -4.1ppm  max: -5.7ppm - threshold q90: +5.6ppm
2021-10-25 12:09:10.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.1ppm
2021-10-25 12:09:27.328 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:09:27.328 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:09:27.328 [TCE:VERB] Connection to MUXS closed in state 4
2021-10-25 12:09:27.329 [TCE:INFO] MUXS reconnect backoff 2s (retry 1)
2021-10-25 12:09:29.329 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 12:09:29.336 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 12:09:29.336 [AIO:INFO]
2021-10-25 12:09:29.450 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:29.450 [TCE:VERB] Connecting to MUXS...
2021-10-25 12:09:29.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:09:29.836 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:29.837 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:29.837 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:29.838 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:30.273 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:09:30.273 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 12:09:30.477 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 12:09:30.477 [TCE:VERB] Connected to MUXS.
2021-10-25 12:09:30.477 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 12:09:30.674 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,125,0],[7,250,0],[0,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 12:09:30.674 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 12:09:30.674 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 12:09:30.674 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 12:09:30.674 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 12:09:30.675 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 12:09:31.209 [SYS:INFO] Process rinit.sh (pid=2739) completed
2021-10-25 12:09:31.213 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 12:09:31.232 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 12:09:31.232 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.232 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.233 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 12:09:31.233 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:09:31.233 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 12:09:33.774 [RAL:VERB] Concentrator started (2s539ms)
2021-10-25 12:09:33.774 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 12:09:33.774 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 12:09:33.774 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 12:09:33.774 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 12:09:33.774 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 12:09:33.775 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 12:09:33.775 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 12:09:33.775 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 12:09:33.775 [S2E:VERB]   DR7  FSK
2021-10-25 12:09:33.775 [S2E:VERB]   DR8  undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR9  undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR10 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR11 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR12 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR13 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR14 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   DR15 undefined
2021-10-25 12:09:33.775 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 12:09:33.775 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 12:09:33.775 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 12:09:33.775 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 12:09:37.980 [SYN:INFO] First PPS pulse acquired
2021-10-25 12:09:37.980 [SYN:INFO] Obtained initial PPS offset (375515) - starting timesync with LNS
2021-10-25 12:09:37.980 [SYN:INFO] Time sync: ustime=0x1509BD045 utc=0x5CF2C3C22D82A gpsOffset=0x0 ppsOffset=375515 syncQual=259
2021-10-25 12:09:37.980 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x1509BCDE4 xtime=0x150000004DC838 pps_xtime=0x150000003ED4AF
2021-10-25 12:09:37.980 [SYN:INFO] Time sync: Last PPS     ustime=0x1509BCDE4 xtime=0x150000004DC838 pps_ustime=0x1508CDA5B pps_xtime=0x150000003ED4AF
2021-10-25 12:09:37.980 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5647356038}
2021-10-25 12:09:37.980 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 12:09:38.051 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5647427154}
2021-10-25 12:09:38.051 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 12:09:38.122 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5647498300}
2021-10-25 12:09:38.122 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 12:09:38.179 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5647356038,"gpstime":1319198996094639}
2021-10-25 12:09:38.179 [SYN:VERB] Timesync LNS: tx/rx:0x1509615AB..0x150991F4D (199ms74us) us/gps:0x1509C1C9B/0x4AFCDF38CDEAF (pps offset=375515) - 1 solutions
2021-10-25 12:09:38.179 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCDF37C2AC0
2021-10-25 12:09:38.179 [SYN:INFO] Time sync: ustime=0x1509EDA58 utc=0x5CF2C3C25E23D gpsOffset=0x4AFCDF37C2AC0 ppsOffset=375515 syncQual=259
2021-10-25 12:09:38.179 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x1509BCDE4 xtime=0x150000004DC838 pps_xtime=0x150000003ED4AF
2021-10-25 12:09:38.179 [SYN:INFO] Time sync: Last PPS     ustime=0x1509BCDE4 xtime=0x150000004DC838 pps_ustime=0x1508CDA5B pps_xtime=0x150000003ED4AF
2021-10-25 12:09:38.179 [SYN:INFO] Time ref:  Last PPS     sys->UTC=12:09:37.000000  SX130X->GPS=12:09:55.000000  leaps=18s diff=0
2021-10-25 12:09:38.341 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5647427154,"gpstime":1319198996255951}
2021-10-25 12:09:38.341 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5647498300,"gpstime":1319198996256136}
2021-10-25 12:10:08.401 [SYN:INFO] Time sync qualities: min=238 q90=260 max=302 (previous q90=2147483647)
2021-10-25 12:10:23.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:10:25.503 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -3.4ppm  q80: +6.1ppm  max: -6.7ppm - threshold q90: -6.1ppm
2021-10-25 12:10:25.503 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.5ppm
2021-10-25 12:10:27.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:10:39.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:10:55.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:11:16.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -0.5ppm  q80: +3.1ppm  max: +6.1ppm - threshold q90: -5.6ppm
2021-10-25 12:11:16.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.4ppm
2021-10-25 12:11:19.601 [SYN:INFO] Time sync qualities: min=238 q90=261 max=261 (previous q90=260)
2021-10-25 12:11:19.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:12:03.452 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -1.1ppm  q80: -4.6ppm  max: -6.1ppm - threshold q90: +6.1ppm
2021-10-25 12:12:03.453 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.4ppm
2021-10-25 12:12:11.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.3ppm - threshold q80: +0.0ppm
2021-10-25 12:12:29.401 [SYN:INFO] Time sync qualities: min=238 q90=260 max=260 (previous q90=261)
2021-10-25 12:12:38.676 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:12:38.676 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:12:38.676 [TCE:VERB] Connection to MUXS closed in state 4
2021-10-25 12:12:38.676 [TCE:INFO] MUXS reconnect backoff 4s (retry 2)
2021-10-25 12:12:42.677 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 12:12:42.684 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 12:12:42.684 [AIO:INFO]
2021-10-25 12:12:42.820 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:12:42.820 [TCE:VERB] Connecting to MUXS...
2021-10-25 12:12:43.215 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:12:43.216 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:12:43.216 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:12:43.637 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:12:43.637 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 12:12:43.834 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 12:12:43.834 [TCE:VERB] Connected to MUXS.
2021-10-25 12:12:43.834 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 12:12:44.036 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,125,0],[7,250,0],[0,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 12:12:44.036 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 12:12:44.036 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 12:12:44.036 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 12:12:44.036 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 12:12:44.038 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 12:12:44.577 [SYS:INFO] Process rinit.sh (pid=2802) completed
2021-10-25 12:12:44.580 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 12:12:44.598 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 12:12:44.598 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 12:12:44.598 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 12:12:44.599 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:12:44.599 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 12:12:47.147 [RAL:VERB] Concentrator started (2s547ms)
2021-10-25 12:12:47.147 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 12:12:47.147 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 12:12:47.147 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 12:12:47.147 [S2E:VERB]   DR7  FSK
2021-10-25 12:12:47.147 [S2E:VERB]   DR8  undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR9  undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR10 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR11 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR12 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR13 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR14 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   DR15 undefined
2021-10-25 12:12:47.147 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 12:12:47.147 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 12:12:47.147 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 12:12:47.147 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 12:12:51.352 [SYN:INFO] First PPS pulse acquired
2021-10-25 12:12:51.352 [SYN:INFO] Obtained initial PPS offset (375465) - starting timesync with LNS
2021-10-25 12:12:51.352 [SYN:INFO] Time sync: ustime=0x15C226EF1 utc=0x5CF2C47A97708 gpsOffset=0x0 ppsOffset=375465 syncQual=242
2021-10-25 12:12:51.352 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x15C226C8D xtime=0x840000004DE573 pps_xtime=0x8400000048878F
2021-10-25 12:12:51.352 [SYN:INFO] Time sync: Last PPS     ustime=0x15C226C8D xtime=0x840000004DE573 pps_ustime=0x15C1D0EA9 pps_xtime=0x8400000048878F
2021-10-25 12:12:51.352 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5840727859}
2021-10-25 12:12:51.352 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 12:12:51.423 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5840799060}
2021-10-25 12:12:51.423 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 12:12:51.494 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":5840870179}
2021-10-25 12:12:51.494 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 12:12:51.554 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5840727859,"gpstime":1319199189468117}
2021-10-25 12:12:51.554 [SYN:VERB] Timesync LNS: tx/rx:0x15C1CB48A..0x15C1FCA22 (202ms136us) us/gps:0x15C1D0EA9/0x4AFCDFF1383D5 (pps offset=375465) - 1 solutions
2021-10-25 12:12:51.554 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCDFF0C5F40
2021-10-25 12:12:51.554 [SYN:INFO] Time sync: ustime=0x15C25851C utc=0x5CF2C47AC8D33 gpsOffset=0x4AFCDFF0C5F40 ppsOffset=375465 syncQual=242
2021-10-25 12:12:51.554 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x15C226C8D xtime=0x840000004DE573 pps_xtime=0x8400000048878F
2021-10-25 12:12:51.554 [SYN:INFO] Time sync: Last PPS     ustime=0x15C226C8D xtime=0x840000004DE573 pps_ustime=0x15C1D0EA9 pps_xtime=0x8400000048878F
2021-10-25 12:12:51.554 [SYN:INFO] Time ref:  Last PPS     sys->UTC=12:12:51.000000  SX130X->GPS=12:13:09.000000  leaps=18s diff=0
2021-10-25 12:12:51.716 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5840799060,"gpstime":1319199189630119}
2021-10-25 12:12:51.716 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":5840870179,"gpstime":1319199189630322}
2021-10-25 12:13:36.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -3.4ppm  q80: +6.1ppm  max: +6.7ppm - threshold q90: -6.1ppm
2021-10-25 12:13:36.401 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.2ppm
2021-10-25 12:13:39.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:13:45.601 [SYN:INFO] Time sync qualities: min=238 q90=260 max=260 (previous q90=2147483647)
2021-10-25 12:13:47.401 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:13:49.503 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:14:03.807 [SYN:ERRO] No PPS pulse for ~11 secs
2021-10-25 12:14:14.314 [SYN:ERRO] No PPS pulse for ~21 secs
2021-10-25 12:14:20.618 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -0.5ppm  q80: -5.2ppm  max: +10.0ppm - threshold q90: +6.7ppm
2021-10-25 12:14:20.618 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.7ppm
2021-10-25 12:14:35.326 [SYN:ERRO] No PPS pulse for ~42 secs
2021-10-25 12:14:45.832 [SYN:INFO] Time sync qualities: min=211 q90=260 max=261 (previous q90=260)
2021-10-25 12:15:01.592 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: +0.5ppm  q80: +5.2ppm  max: +7.6ppm - threshold q90: -5.7ppm
2021-10-25 12:15:01.592 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.5ppm
2021-10-25 12:15:05.795 [SYN:VERB] Time sync rejected: quality=283 threshold=260
2021-10-25 12:15:12.098 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:15:14.200 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:15:16.301 [SYN:ERRO] No PPS pulse for ~83 secs
2021-10-25 12:15:33.111 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:15:35.212 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:15:37.313 [SYN:VERB] Time sync rejected: quality=261 threshold=260
2021-10-25 12:15:47.819 [SYN:INFO] Time sync qualities: min=237 q90=261 max=283 (previous q90=260)
2021-10-25 12:15:51.952 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:15:51.952 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:15:51.952 [TCE:VERB] Connection to MUXS closed in state 4
2021-10-25 12:15:51.952 [TCE:INFO] MUXS reconnect backoff 8s (retry 3)
2021-10-25 12:15:56.224 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: +1.0ppm  q80: +5.7ppm  max: +5.7ppm - threshold q90: -5.7ppm
2021-10-25 12:15:56.224 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.1ppm
2021-10-25 12:15:59.953 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 12:15:59.960 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 12:15:59.960 [AIO:INFO]
2021-10-25 12:16:00.090 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:16:00.090 [TCE:VERB] Connecting to MUXS...
2021-10-25 12:16:00.483 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:16:00.484 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:16:00.485 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:16:00.903 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:16:00.903 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 12:16:01.102 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 12:16:01.102 [TCE:VERB] Connected to MUXS.
2021-10-25 12:16:01.102 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 12:16:01.302 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,125,0],[7,250,0],[0,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 12:16:01.302 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 12:16:01.302 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 12:16:01.302 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 12:16:01.302 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 12:16:01.303 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 12:16:01.832 [SYS:INFO] Process rinit.sh (pid=2880) completed
2021-10-25 12:16:01.837 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 12:16:01.854 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 12:16:01.854 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 12:16:01.854 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 12:16:01.855 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:16:01.855 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 12:16:04.410 [RAL:VERB] Concentrator started (2s542ms)
2021-10-25 12:16:04.411 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 12:16:04.411 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 12:16:04.411 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 12:16:04.411 [S2E:VERB]   DR7  FSK
2021-10-25 12:16:04.411 [S2E:VERB]   DR8  undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR9  undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR10 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR11 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR12 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR13 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR14 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   DR15 undefined
2021-10-25 12:16:04.411 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 12:16:04.411 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 12:16:04.411 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 12:16:04.411 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 12:16:08.616 [SYN:INFO] First PPS pulse acquired
2021-10-25 12:16:08.616 [SYN:INFO] Obtained initial PPS offset (375452) - starting timesync with LNS
2021-10-25 12:16:08.616 [SYN:INFO] Time sync: ustime=0x167E471D3 utc=0x5CF2C536B79F7 gpsOffset=0x0 ppsOffset=375452 syncQual=236
2021-10-25 12:16:08.616 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x167E46F76 xtime=0x50000004DC6D6 pps_xtime=0x500000044613C
2021-10-25 12:16:08.616 [SYN:INFO] Time sync: Last PPS     ustime=0x167E46F76 xtime=0x50000004DC6D6 pps_ustime=0x167DB09DC pps_xtime=0x500000044613C
2021-10-25 12:16:08.616 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6037991955}
2021-10-25 12:16:08.616 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 12:16:08.687 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6038063068}
2021-10-25 12:16:08.687 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 12:16:08.758 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6038134186}
2021-10-25 12:16:08.758 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 12:16:08.830 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6038205954}
2021-10-25 12:16:08.830 [SYN:DEBU] Timesync #5 sent to server
2021-10-25 12:16:08.901 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6038277068}
2021-10-25 12:16:08.901 [SYN:DEBU] Timesync #6 sent to server
2021-10-25 12:16:08.972 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6038348190}
2021-10-25 12:16:08.972 [SYN:DEBU] Timesync #7 sent to server
2021-10-25 12:16:09.001 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6037991955,"gpstime":1319199386908493}
2021-10-25 12:16:09.001 [SYN:VERB] Timesync LNS: tx/rx:0x167DEB777..0x167E497B3 (385ms84us) us/gps:0x167DB09DC/0x4AFCE0AD8374D (pps offset=375452) - 1 solutions
2021-10-25 12:16:09.001 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCE0ACA5A80
2021-10-25 12:16:09.001 [SYN:INFO] Time sync: ustime=0x167EA527F utc=0x5CF2C53715AA3 gpsOffset=0x4AFCE0ACA5A80 ppsOffset=375452 syncQual=236
2021-10-25 12:16:09.001 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x167E46F76 xtime=0x50000004DC6D6 pps_xtime=0x500000044613C
2021-10-25 12:16:09.001 [SYN:INFO] Time sync: Last PPS     ustime=0x167E46F76 xtime=0x50000004DC6D6 pps_ustime=0x167DB09DC pps_xtime=0x500000044613C
2021-10-25 12:16:09.001 [SYN:INFO] Time ref:  Last PPS     sys->UTC=12:16:08.000000  SX130X->GPS=12:16:26.000000  leaps=18s diff=0
2021-10-25 12:16:09.153 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6038063068,"gpstime":1319199387068748}
2021-10-25 12:16:09.153 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6038134186,"gpstime":1319199387068961}
2021-10-25 12:16:09.153 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6038205954,"gpstime":1319199387069121}
2021-10-25 12:16:09.154 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6038277068,"gpstime":1319199387069277}
2021-10-25 12:16:09.313 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6038348190,"gpstime":1319199387229148}
2021-10-25 12:16:52.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -0.6ppm  q80: +3.3ppm  max: +5.6ppm - threshold q90: -3.4ppm
2021-10-25 12:16:52.401 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.1ppm
2021-10-25 12:16:55.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:16:58.452 [SYN:INFO] Time sync qualities: min=236 q90=260 max=275 (previous q90=2147483647)
2021-10-25 12:17:31.401 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: -2.6ppm  q80: +4.8ppm  max: -6.3ppm - threshold q90: -6.1ppm
2021-10-25 12:17:31.401 [SYN:INFO] Mean MCU drift vs SX130X#0: -0.5ppm
2021-10-25 12:17:49.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.3ppm - threshold q80: +0.0ppm
2021-10-25 12:18:04.601 [SYN:INFO] Time sync qualities: min=237 q90=260 max=260 (previous q90=260)
2021-10-25 12:18:20.601 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: +0.3ppm  q80: +3.4ppm  max: +6.7ppm - threshold q90: +6.1ppm
2021-10-25 12:18:20.601 [SYN:INFO] Mean MCU drift vs SX130X#0: 0.5ppm
2021-10-25 12:18:39.504 [SYN:ERRO] Repeated excessive clock drifts between MCU/SX130X#0 (3 retries): 18.2ppm (threshold 6.1ppm)
2021-10-25 12:18:42.659 [SYN:ERRO] Repeated excessive clock drifts between MCU/SX130X#0 (6 retries): 21.0ppm (threshold 6.1ppm)
2021-10-25 12:18:45.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.5ppm - threshold q80: +0.0ppm
2021-10-25 12:19:00.601 [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: +20.3ppm  q80: +21.1ppm  max: +24.1ppm - threshold q90: +21.1ppm
2021-10-25 12:19:00.601 [SYN:INFO] Mean MCU drift vs SX130X#0: 14.2ppm
2021-10-25 12:19:00.601 [SYN:INFO] Changed PPS offset: 375452 => 375983 (delta: 531)
2021-10-25 12:19:07.401 [SYN:INFO] Time sync qualities: min=237 q90=260 max=260 (previous q90=260)
2021-10-25 12:19:09.614 [AIO:DEBU] [3] Connection closed unexpectedly
2021-10-25 12:19:09.614 [AIO:DEBU] [3] WS connection shutdown...
2021-10-25 12:19:09.615 [TCE:VERB] Connection to MUXS closed in state 4
2021-10-25 12:19:09.615 [TCE:INFO] MUXS reconnect backoff 16s (retry 4)
2021-10-25 12:19:22.704 [SYN:ERRO] Repeated excessive clock drifts between MCU/SX130X#0 (3 retries): 21.9ppm (threshold 21.1ppm)
2021-10-25 12:19:24.806 [SYN:INFO] Changed PPS offset: 375983 => 376469 (delta: 486)
2021-10-25 12:19:25.614 [any:INFO] ./tc.trust:
cert. version     : 3
serial number     : A7:0E:4A:4C:34:82:B7:7F
issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority
subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2
issued  on        : 2009-09-02 00:00:00
expires on        : 2034-06-28 17:39:16
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic con2021-10-25 12:19:25.621 [any:INFO] ./tc.crt:
cert. version     : 3
serial number     : 70:B9:E0:68:0E:62:85:AC:A4:7F:0D:0C:81:3D:6D:06:D7:95:C0:AE
issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US
subject name      : CN=AWS IoT Certificate
issued  on        : 2021-10-05 13:52:18
expires on        : 2049-12-31 23:59:59
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage         : Digital Signature
2021-10-25 12:19:25.621 [AIO:INFO]
2021-10-25 12:19:25.780 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:19:25.780 [TCE:VERB] Connecting to MUXS...
2021-10-25 12:19:26.171 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:19:26.172 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:19:26.172 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:19:26.603 [AIO:XDEB] [3] ws_connecting state=1
2021-10-25 12:19:26.603 [AIO:XDEB] [3] ws_connecting state=2
2021-10-25 12:19:26.803 [AIO:XDEB] [3] ws_connecting state=3
2021-10-25 12:19:26.803 [TCE:VERB] Connected to MUXS.
2021-10-25 12:19:26.803 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(corecell/std)","firmware":null,"package":null,"model":"corecell","protocol":2,"features":"rmtsh"}
2021-10-25 12:19:27.003 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEUI":null,"region":"EU868","hwspec":"sx1301/1","freq_range":[863000000,870000000],"DRs":[[12,125,0],[11,125,0],[10,125,0],[9,125,0],[8,125,0],[7,125,0],[7,250,0],[0,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":867500000},"radio_1":{"enable":true,"freq":868500000},"chan_FSK":{"enable":true},"chan_Lora_std":{"enable":true,"radio":1,"if"
2021-10-25 12:19:27.003 [AIO:XDEB] [3|WS] . :-200000,"bandwidth":250000,"spread_factor":7},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable":true
2021-10-25 12:19:27.003 [AIO:XDEB] [3|WS] . ,"radio":1,"if":0}}],"protocol":0,"regionid":0,"max_eirp":0}
2021-10-25 12:19:27.003 [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
2021-10-25 12:19:27.003 [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
2021-10-25 12:19:27.004 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO17...
2021-10-25 12:19:27.539 [SYS:INFO] Process rinit.sh (pid=2964) completed
2021-10-25 12:19:27.543 [RAL:INFO] Lora gateway library version: Version: 1.0.5;
2021-10-25 12:19:27.561 [RAL:VERB] Connecting to device: /dev/spidev0.0
2021-10-25 12:19:27.561 [RAL:DEBU] SX130x txlut table (16 entries)
2021-10-25 12:19:27.561 [RAL:VERB] SX1302 txlut  0:  rf_power=12 pa_gain=0 pwr_idx=15
2021-10-25 12:19:27.561 [RAL:VERB] SX1302 txlut  1:  rf_power=13 pa_gain=0 pwr_idx=16
2021-10-25 12:19:27.561 [RAL:VERB] SX1302 txlut  2:  rf_power=14 pa_gain=0 pwr_idx=17
2021-10-25 12:19:27.561 [RAL:VERB] SX1302 txlut  3:  rf_power=15 pa_gain=0 pwr_idx=19
2021-10-25 12:19:27.561 [RAL:VERB] SX1302 txlut  4:  rf_power=16 pa_gain=0 pwr_idx=20
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut  5:  rf_power=17 pa_gain=0 pwr_idx=22
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut  6:  rf_power=18 pa_gain=1 pwr_idx=1
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut  7:  rf_power=19 pa_gain=1 pwr_idx=2
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut  8:  rf_power=20 pa_gain=1 pwr_idx=3
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut  9:  rf_power=21 pa_gain=1 pwr_idx=4
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 10:  rf_power=22 pa_gain=1 pwr_idx=5
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 11:  rf_power=23 pa_gain=1 pwr_idx=6
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 12:  rf_power=24 pa_gain=1 pwr_idx=7
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=9
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=11
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=14
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 rssi_tcomp: coeff_a=0.000 coeff_b=0.000 coeff_c=20.410 coeff_d=2162.560 coeff_e=0.000
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 rxrfchain 0: enable=1 freq=867.5MHz rssi_offset=-215.399994 type=5 tx_enable=1
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 rxrfchain 1: enable=1 freq=868.5MHz rssi_offset=-215.399994 type=5 tx_enable=0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  0: enable=1 rf_chain=0 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  1: enable=1 rf_chain=0 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  2: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  3: enable=1 rf_chain=0 freq=200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  4: enable=1 rf_chain=0 freq=400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  5: enable=1 rf_chain=1 freq=-400000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  6: enable=1 rf_chain=1 freq=-200000 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  7: enable=1 rf_chain=1 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  8: enable=1 rf_chain=1 freq=-200000 bw=5 SF=7 sync_word=0/0 [STD] Explicit header
2021-10-25 12:19:27.562 [RAL:VERB] SX1302 ifchain  9: enable=1 rf_chain=0 freq=0 bw=0 SF=0 sync_word=0/0
2021-10-25 12:19:27.562 [RAL:VERB] Station device: /dev/spidev0.0 (PPS capture enabled)
2021-10-25 12:19:30.116 [RAL:VERB] Concentrator started (2s541ms)
2021-10-25 12:19:30.116 [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
2021-10-25 12:19:30.116 [S2E:VERB]   DR0  SF12/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR1  SF11/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR2  SF10/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR3  SF9/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR4  SF8/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR5  SF7/BW125
2021-10-25 12:19:30.116 [S2E:VERB]   DR6  SF7/BW250
2021-10-25 12:19:30.116 [S2E:VERB]   DR7  FSK
2021-10-25 12:19:30.116 [S2E:VERB]   DR8  undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR9  undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR10 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR11 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR12 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR13 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR14 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   DR15 undefined
2021-10-25 12:19:30.116 [S2E:VERB]   TX power: 0.0 dBm EIRP
2021-10-25 12:19:30.116 [S2E:VERB]   JoinEUI list: 0 entries
2021-10-25 12:19:30.116 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2021-10-25 12:19:30.116 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2021-10-25 12:19:34.322 [SYN:INFO] First PPS pulse acquired
2021-10-25 12:19:34.322 [SYN:INFO] Obtained initial PPS offset (376665) - starting timesync with LNS
2021-10-25 12:19:34.322 [SYN:INFO] Time sync: ustime=0x17427487A utc=0x5CF2C5FAE4BE1 gpsOffset=0x0 ppsOffset=376665 syncQual=239
2021-10-25 12:19:34.322 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x174274617 xtime=0x430000004DC889 pps_xtime=0x4300000048E08B
2021-10-25 12:19:34.322 [SYN:INFO] Time sync: Last PPS     ustime=0x174274617 xtime=0x430000004DC889 pps_ustime=0x174225E19 pps_xtime=0x4300000048E08B
2021-10-25 12:19:34.322 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6243698876}
2021-10-25 12:19:34.322 [SYN:DEBU] Timesync #2 sent to server
2021-10-25 12:19:34.393 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6243770524}
2021-10-25 12:19:34.393 [SYN:DEBU] Timesync #3 sent to server
2021-10-25 12:19:34.464 [AIO:XDEB] [3|WS] > {"msgtype":"timesync","txtime":6243841644}
2021-10-25 12:19:34.465 [SYN:DEBU] Timesync #4 sent to server
2021-10-25 12:19:34.522 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6243698876,"gpstime":1319199592438230}
2021-10-25 12:19:34.522 [SYN:VERB] Timesync LNS: tx/rx:0x174218963..0x1742497E4 (200ms321us) us/gps:0x174225E19/0x4AFCE171859D6 (pps offset=376665) - 1 solutions
2021-10-25 12:19:34.522 [SYN:INFO] Timesync with LNS: gpsOffset=0x4AFCE1711AA00
2021-10-25 12:19:34.522 [SYN:INFO] Time sync: ustime=0x1742A576E utc=0x5CF2C5FB15AD5 gpsOffset=0x4AFCE1711AA00 ppsOffset=376665 syncQual=239
2021-10-25 12:19:34.522 [SYN:INFO] Time sync: MCU/SX130X#0 ustime=0x174274617 xtime=0x430000004DC889 pps_xtime=0x4300000048E08B
2021-10-25 12:19:34.522 [SYN:INFO] Time sync: Last PPS     ustime=0x174274617 xtime=0x430000004DC889 pps_ustime=0x174225E19 pps_xtime=0x4300000048E08B
2021-10-25 12:19:34.522 [SYN:INFO] Time ref:  Last PPS     sys->UTC=12:19:34.000000  SX130X->GPS=12:19:52.000000  leaps=18s diff=0
2021-10-25 12:19:34.683 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6243770524,"gpstime":1319199592598729}
2021-10-25 12:19:34.684 [AIO:XDEB] [3|WS] < {"msgtype":"timesync","txtime":6243841644,"gpstime":1319199592598921}
2021-10-25 12:20:17.601 [SYN:INFO] Time sync qualities: min=112 q90=259 max=302 (previous q90=2147483647)
2021-10-25 12:20:19.401 [SYN:VERB] Time sync rejected: quality=260 threshold=259
2021-10-25 12:20:21.503 [SYN:VERB] Time sync rejected: quality=260 threshold=259
2021-10-25 12:20:23.604 [SYN:INFO] MCU/SX130X drift stats: min: +11.7ppm  q50: +18.3ppm  q80: +19.0ppm  max: +25.0ppm - threshold q90: +22.5ppm
2021-10-25 12:20:23.604 [SYN:INFO] Mean MCU drift vs SX130X#0: 18.2ppm
2021-10-25 12:20:23.604 [SYN:INFO] Changed PPS offset: 376665 => 377568 (delta: 903)
2021-10-25 12:20:25.601 [SYN:INFO] PPS/SX130X drift stats: min: +0.0ppm  q50: +0.0ppm  q80: +0.0ppm  max: +0.3ppm - threshold q80: +0.0ppm
2021-10-25 12:20:27.401 [SYN:VERB] Time sync rejected: quality=261 threshold=259
2021-10-25 12:20:29.503 [SYN:VERB] Time sync rejected: quality=262 threshold=259
2021-10-25 12:20:35.401 [SYN:VERB] Time sync rejected: quality=260 threshold=259
2021-10-25 12:20:37.503 [SYN:VERB] Time sync rejected: quality=261 threshold=259
2021-10-25 12:20:51.601 [SYN:INFO] Changed PPS offset: 377568 => 378064 (delta: 496)
2021-10-25 12:20:53.401 [SYN:VERB] Time sync rejected: quality=260 threshold=259
beitler commented 2 years ago

This looks suspiciously like a network time out issue. Did you try using a different modem / provider or placing the cellular antenna into more favourable conditions?

smtc-bot commented 1 year ago

Thank you for your inquiry.

Customers are encouraged to submit technical questions via our dedicated support portal at https://semtech.force.com/ldp/ldp_support.

We invite all users to visit the LoRa Developer Portal Forum at https://forum.lora-developers.semtech.com and to join the thriving LoRa development community!