Grazy,
first of all thank you for your patience and collaboration, really appreciated.
New log:
rst:0x1 (POWERON_RESET),boot:0x17 (SPI_FAST_FLASH_BOOT)
flash read err, 1000
Falling back to built-in command interpreter.
OK
>ets Jun 8 2016 00:22:57
rst:0x10 (RTCWDT_RTC_RESET),boot:0x17 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0018,len:4
load:0x3fff001c,len:952
load:0x40078000,len:6084
load:0x40080000,len:7944
entry 0x40080310
Starting
1337: Set reset pin 21 to 0
1427: Set reset pin 21 to float
<<
>1<0
>0<0
1754: Reg 1, Read:0
>>
<<
>81<0
>0<0
>>
<<
>1<0
>0<0
1774: Reg 1, Write 0, Readback:0
>>
<<
>42<0
>0<0
1849: Reg 42, Read:0
>>
1985: Chip version : 0
<<
>1<0
>0<0
2196: Reg 1, Read:0
>>
<<
>81<0
>0<0
>>
<<
>1<0
>0<0
2527: Reg 1, Write 0, Readback:0
>>
<<
>1<0
>0<0
2814: Reg 1, Read:0
>>
<<
>81<0
>0<0
>>
<<
>1<0
>0<0
3146: Reg 1, Write 0, Readback:0
>>
3348: Init Default Channel join?=1
3541: do job
3618: engineUpdate, opmode=0x8.
3782: Init Default Channel join?=1
3972: Init Join loop : avail=3970 txend=7754
4215: Scheduled job 0x3ffc247c ASAP
4410: Packet queued
4519: Running job 0x3ffc247c, deadline 0
4742: EV_JOINING
4833: engineUpdate, opmode=0xc.
5008: Uplink join pending
5148: Airtime available at 7754 (previously determined)
5452: Uplink delayed until 7754
5625: Scheduled job 0x3ffc247c, atRun 7629
7630: Running job 0x3ffc247c, deadline 7629
7635: engineUpdate, opmode=0xc.
7640: Uplink join pending
7643: Airtime available at 7754 (previously determined)
7795: Ready for uplink
7928: Time on air : 61 ms
8061: Updating info for TX at 7638, airtime will be 3856. Setting available time for band 868500000 to 3863638
<<
>81<0
>88<0
>>
<<
>1<0
>0<0
8869: Reg 1, Write 88, Readback:0
>>
<<
>1<0
>0<0
9162: Reg 1, Read:0
>>
<<
>81<0
>1<0
>>
<<
>1<0
>0<0
9493: Reg 1, Write 1, Readback:0
>>
<<
>9D<0
>72<0
>>
<<
>1D<0
>0<0
9905: Reg 1d, Write 72, Readback:0
>>
<<
>9E<0
>74<0
>>
<<
>1E<0
>0<0
10328: Reg 1e, Write 74, Readback:0
>>
<<
>A6<0
>4<0
>>
<<
>26<0
>0<0
10751: Reg 26, Write 4, Readback:0
>>
<<
>86<0
>D9<0
>>
<<
>6<0
>0<0
11169: Reg 6, Write d9, Readback:0
>>
<<
>87<0
>20<0
>>
<<
>7<0
>0<0
11587: Reg 7, Write 20, Readback:0
>>
<<
>88<0
>0<0
>>
<<
>8<0
>0<0
11999: Reg 8, Write 0, Readback:0
>>
<<
>A<0
>0<0
12293: Reg a, Read:0
>>
<<
>8A<0
>8<0
>>
<<
>A<0
>0<0
12628: Reg a, Write 8, Readback:0
>>
<<
>89<0
>8C<0
>>
<<
>9<0
>0<0
13041: Reg 9, Write 8c, Readback:0
>>
<<
>4D<0
>0<0
13345: Reg 4d, Read:0
>>
<<
>CD<0
>4<0
>>
<<
>4D<0
>0<0
13692: Reg 4d, Write 4, Readback:0
>>
<<
>B9<0
>34<0
>>
<<
>39<0
>0<0
14115: Reg 39, Write 34, Readback:0
>>
<<
>C0<0
>F0<0
>>
<<
>40<0
>0<0
14544: Reg 40, Write f0, Readback:0
>>
<<
>92<0
>FF<0
>>
<<
>12<0
>0<0
14972: Reg 12, Write ff, Readback:0
>>
<<
>91<0
>F7<0
>>
<<
>11<0
>0<0
15401: Reg 11, Write f7, Readback:0
>>
<<
>8E<0
>0<0
>>
<<
>E<0
>0<0
15819: Reg e, Write 0, Readback:0
>>
<<
>8D<0
>0<0
>>
<<
>D<0
>0<0
16226: Reg d, Write 0, Readback:0
>>
<<
>A2<0
>17<0
>>
<<
>22<0
>0<0
16643: Reg 22, Write 17, Readback:0
>>
<<
>80<0
>0<0
>BA<0
>1B<0
>1<0
>D0<0
>7E<0
>D5<0
>B3<0
>70<0
>65<0
>F4<0
>A9<0
>61<0
>8A<0
>59<0
>33<0
>0<0
>57<0
>C4<0
>80<0
>38<0
>CB<0
>D<0
>>
<<
>1<0
>0<0
17880: Reg 1, Read:0
>>
<<
>81<0
>3<0
>>
<<
>1<0
>0<0
18216: Reg 1, Write 3, Readback:0
>>
18423: TXMODE, freq=868500000, len=23, SF=7, BW=125, CR=4/5, IH=0
And the output with the extra added log to the working sketch:
rst:0x1 (POWERON_RESET),boot:0x17 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0018,len:4
load:0x3fff001c,len:952
load:0x40078000,len:6084
load:0x40080000,len:7944
entry 0x40080310
Starting...
Manual read of chip version (should be 18) = 0
RXMODE_RSSI
163136: engineUpdate, opmode=0x808
163162: Scheduled job 0x3ffc3a48, cb 0x400d3870 ASAP
Sending uplink packet...
164430: Running job 0x3ffc3a48, cb 0x400d3870, deadline 0
164440: EV_JOINING: -> Joining...
164727: engineUpdate, opmode=0xc
164735: Uplink join pending
164738: Airtime available at 350203 (previously determined)
164924: Uplink delayed until 350203
165125: Scheduled job 0x3ffc3a48, cb 0x400d387c at 350078
350078: Running job 0x3ffc3a48, cb 0x400d387c, deadline 350078
350082: engineUpdate, opmode=0xc
350086: Uplink join pending
350089: Airtime available at 350203 (previously determined)
350404: Ready for uplink
350561: Updating info for TX at 350086, airtime will be 3856. Setting available time for band 0 to 4206086
351154: TXMODE, freq=868100000, len=23, SF=7, BW=125, CR=4/5, IH=0
355021: irq: dio: 0x0 flags: 0x8
355030: Scheduled job 0x3ffc3a48, cb 0x400d24ec ASAP
355035: Running job 0x3ffc3a48, cb 0x400d24ec, deadline 0
355129: Scheduled job 0x3ffc3a48, cb 0x400d21e4 at 664386
664386: Running job 0x3ffc3a48, cb 0x400d21e4, deadline 664386
664512: RXMODE_SINGLE, freq=868100000, SF=7, BW=125, CR=4/5, IH=0
672057: irq: dio: 0x0 flags: 0x40
672073: Scheduled job 0x3ffc3a48, cb 0x400d3824 ASAP
672078: Running job 0x3ffc3a48, cb 0x400d3824, deadline 0
672190: Setup channel, idx=3, freq=867100000
672417: Setup channel, idx=4, freq=867300000
672666: Setup channel, idx=5, freq=867500000
672915: Setup channel, idx=6, freq=867700000
673165: Setup channel, idx=7, freq=867900000
673422: EV_JOINED
674743: engineUpdate, opmode=0x808
674751: Uplink data pending
674755: Considering band 0, which is available at 672188
674760: Considering band 3, which is available at 0
675009: No channel found in band 3
675204: Considering band 0, which is available at 672188
675518: Airtime available at 672188 (channel duty limit)
675833: Ready for uplink
675994: Updating info for TX at 674751, airtime will be 2896. Setting available time for band 0 to 3570751
676580: TXMODE, freq=867100000, len=14, SF=7, BW=125, CR=4/5, IH=0
679487: irq: dio: 0x0 flags: 0x8
679496: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
679501: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
679593: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 741348
741348: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 741348
741474: RXMODE_SINGLE, freq=867100000, SF=7, BW=125, CR=4/5, IH=0
743030: irq: dio: 0x1 flags: 0x80
743038: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
743042: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
743141: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 803592
803592: Running job 0x3ffc3a48, cb 0x400d2228, deadline 803592
803718: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
807322: irq: dio: 0x1 flags: 0x80
807330: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
807334: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
807434: EV_TXCOMPLETE (includes waiting for RX windows)
807822: Scheduled job 0x3ffc247c, cb 0x400d1040 at 2682822
809054: engineUpdate, opmode=0x900
2682822: Running job 0x3ffc247c, cb 0x400d1040, deadline 2682822
2682828: engineUpdate, opmode=0x908
2682831: Uplink data pending
2682850: Considering band 0, which is available at 3570751
2683176: Considering band 1, which is available at 672188
2683496: Considering band 3, which is available at 0
2683789: No channel found in band 3
2683990: Considering band 0, which is available at 3570751
2684315: Considering band 1, which is available at 672188
2684635: Airtime available at 672188 (channel duty limit)
2684955: Airtime available at 1019268 (global duty limit)
2685276: Ready for uplink
2685438: Updating info for TX at 2682831, airtime will be 2896. Setting available time for band 1 to 2972431
2686039: TXMODE, freq=868100000, len=14, SF=7, BW=125, CR=4/5, IH=0
Sending uplink packet...
2688945: irq: dio: 0x0 flags: 0x8
2688958: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
2688963: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
2689071: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 2750806
2750806: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 2750806
2750933: RXMODE_SINGLE, freq=868100000, SF=7, BW=125, CR=4/5, IH=0
2752488: irq: dio: 0x1 flags: 0x80
2752496: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
2752500: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
2752615: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 2813050
2813050: Running job 0x3ffc3a48, cb 0x400d2228, deadline 2813050
2813176: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
2816780: irq: dio: 0x1 flags: 0x80
2816788: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
2816792: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
2816908: EV_TXCOMPLETE (includes waiting for RX windows)
2817300: Scheduled job 0x3ffc247c, cb 0x400d1040 at 4692300
2818848: engineUpdate, opmode=0x900
4692300: Running job 0x3ffc247c, cb 0x400d1040, deadline 4692300
4692306: engineUpdate, opmode=0x908
4692309: Uplink data pending
4692328: Considering band 0, which is available at 3570751
4692654: Considering band 1, which is available at 2972431
4692980: Considering band 2, which is available at 672188
4693300: Considering band 3, which is available at 0
4693593: No channel found in band 3
4693793: Considering band 0, which is available at 3570751
4694119: Considering band 1, which is available at 2972431
4694444: Considering band 2, which is available at 672188
4694765: No channel found in band 2
4694965: Considering band 0, which is available at 3570751
4695291: Considering band 1, which is available at 2972431
4695616: Airtime available at 2972431 (channel duty limit)
4695942: Airtime available at 3037929 (global duty limit)
4696262: Ready for uplink
4696424: Updating info for TX at 4692309, airtime will be 2896. Setting available time for band 1 to 4981909
4697025: TXMODE, freq=868300000, len=14, SF=7, BW=125, CR=4/5, IH=0
Sending uplink packet...
4699931: irq: dio: 0x0 flags: 0x8
4699944: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
4699949: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
4700057: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 4761792
4761792: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 4761792
4761918: RXMODE_SINGLE, freq=868300000, SF=7, BW=125, CR=4/5, IH=0
4763474: irq: dio: 0x1 flags: 0x80
4763482: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
4763486: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
4763600: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 4824036
4824036: Running job 0x3ffc3a48, cb 0x400d2228, deadline 4824036
4824162: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
4827766: irq: dio: 0x1 flags: 0x80
4827773: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
4827778: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
4827897: EV_TXCOMPLETE (includes waiting for RX windows)
4828286: Scheduled job 0x3ffc247c, cb 0x400d1040 at 6703286
4829846: engineUpdate, opmode=0x900
6703286: Running job 0x3ffc247c, cb 0x400d1040, deadline 6703286
6703292: engineUpdate, opmode=0x908
6703296: Uplink data pending
6703315: Considering band 0, which is available at 3570751
6703640: Considering band 2, which is available at 672188
6703960: Considering band 3, which is available at 0
6704253: No channel found in band 3
6704454: Considering band 0, which is available at 3570751
6704780: Considering band 2, which is available at 672188
6705100: No channel found in band 2
6705300: Considering band 0, which is available at 3570751
6705626: Airtime available at 3570751 (channel duty limit)
6705951: Airtime available at 5110149 (global duty limit)
6706271: Ready for uplink
6706434: Updating info for TX at 6703296, airtime will be 2896. Setting available time for band 0 to 9599296
6707035: TXMODE, freq=867300000, len=14, SF=7, BW=125, CR=4/5, IH=0
Sending uplink packet...
6709941: irq: dio: 0x0 flags: 0x8
6709954: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
6709959: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
6710067: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 6771802
6771802: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 6771802
6771928: RXMODE_SINGLE, freq=867300000, SF=7, BW=125, CR=4/5, IH=0
6773484: irq: dio: 0x1 flags: 0x80
6773492: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
6773496: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
6773611: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 6834046
6834046: Running job 0x3ffc3a48, cb 0x400d2228, deadline 6834046
6834172: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
6837776: irq: dio: 0x1 flags: 0x80
6837784: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
6837789: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
6837903: EV_TXCOMPLETE (includes waiting for RX windows)
6838296: Scheduled job 0x3ffc247c, cb 0x400d1040 at 8713296
6839846: engineUpdate, opmode=0x900
8713296: Running job 0x3ffc247c, cb 0x400d1040, deadline 8713296
8713302: engineUpdate, opmode=0x908
8713305: Uplink data pending
8713324: Considering band 0, which is available at 9599296
8713650: Considering band 1, which is available at 4981909
8713976: Considering band 2, which is available at 672188
8714296: Considering band 3, which is available at 0
8714589: No channel found in band 3
8714789: Considering band 0, which is available at 9599296
8715115: Considering band 1, which is available at 4981909
8715440: Considering band 2, which is available at 672188
8715761: No channel found in band 2
8715961: Considering band 0, which is available at 9599296
8716287: Considering band 1, which is available at 4981909
8716612: Airtime available at 4981909 (channel duty limit)
8716938: Airtime available at 7063581 (global duty limit)
8717258: Ready for uplink
8717421: Updating info for TX at 8713305, airtime will be 2896. Setting available time for band 1 to 9002905
8718021: TXMODE, freq=868500000, len=14, SF=7, BW=125, CR=4/5, IH=0
Sending uplink packet...
8720927: irq: dio: 0x0 flags: 0x8
8720940: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
8720945: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
8721053: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 8782788
8782788: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 8782788
8782914: RXMODE_SINGLE, freq=868500000, SF=7, BW=125, CR=4/5, IH=0
8784470: irq: dio: 0x1 flags: 0x80
8784478: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
8784482: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
8784597: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 8845032
8845032: Running job 0x3ffc3a48, cb 0x400d2228, deadline 8845032
8845159: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
8848762: irq: dio: 0x1 flags: 0x80
8848770: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
8848774: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
8848890: EV_TXCOMPLETE (includes waiting for RX windows)
8849283: Scheduled job 0x3ffc247c, cb 0x400d1040 at 10724282
8850846: engineUpdate, opmode=0x900
10724282: Running job 0x3ffc247c, cb 0x400d1040, deadline 10724282
10724288: engineUpdate, opmode=0x908
10724292: Uplink data pending
10724333: Considering band 0, which is available at 9599296
10724664: Considering band 1, which is available at 9002905
10724995: Considering band 2, which is available at 672188
10725320: Considering band 3, which is available at 0
10725619: No channel found in band 3
10725825: Considering band 0, which is available at 9599296
10726156: Considering band 1, which is available at 9002905
10726487: Considering band 2, which is available at 672188
10726812: No channel found in band 2
10727018: Considering band 0, which is available at 9599296
10727349: Considering band 1, which is available at 9002905
10727680: Airtime available at 9002905 (channel duty limit)
10728011: Airtime available at 9105630 (global duty limit)
10728337: Ready for uplink
10728505: Updating info for TX at 10724292, airtime will be 2896. Setting available time for band 1 to 11013892
10729121: TXMODE, freq=868100000, len=14, SF=7, BW=125, CR=4/5, IH=0
Sending uplink packet...
10732028: irq: dio: 0x0 flags: 0x8
10732041: Scheduled job 0x3ffc3a48, cb 0x400d24d0 ASAP
10732046: Running job 0x3ffc3a48, cb 0x400d24d0, deadline 0
10732170: Scheduled job 0x3ffc3a48, cb 0x400d21d8 at 10793889
10793889: Running job 0x3ffc3a48, cb 0x400d21d8, deadline 10793889
10794015: RXMODE_SINGLE, freq=868100000, SF=7, BW=125, CR=4/5, IH=0
10795570: irq: dio: 0x1 flags: 0x80
10795579: Scheduled job 0x3ffc3a48, cb 0x400d39fc ASAP
10795583: Running job 0x3ffc3a48, cb 0x400d39fc, deadline 0
10795714: Scheduled job 0x3ffc3a48, cb 0x400d2228 at 10856133
10856133: Running job 0x3ffc3a48, cb 0x400d2228, deadline 10856133
10856259: RXMODE_SINGLE, freq=869525000, SF=9, BW=125, CR=4/5, IH=0
10859863: irq: dio: 0x1 flags: 0x80
10859871: Scheduled job 0x3ffc3a48, cb 0x400d3a2c ASAP
10859875: Running job 0x3ffc3a48, cb 0x400d3a2c, deadline 0
10860007: EV_TXCOMPLETE (includes waiting for RX windows)
10860405: Scheduled job 0x3ffc247c, cb 0x400d1040 at 12735405
10861970: engineUpdate, opmode=0x900