Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

Segmentation Fault while remote Bootstrapping with Leshan #560

Closed
aleparmi opened this issue Mar 24, 2021 · 8 comments
Closed

Segmentation Fault while remote Bootstrapping with Leshan #560

aleparmi opened this issue Mar 24, 2021 · 8 comments
Labels
question

Comments

@aleparmi
Copy link

During the Bootstrap Process with a remote Leshan Server I get the following on the Wakaama Client:

-> State: STATE_BOOTSTRAPPING [BOOTSTRAP] backup security and server objects Sending 67 bytes to [***]:5784

......................

decrypt_verify(): found 8 bytes cleartext Segmentation fault

The remote Leshan Bootstrap Server reports:

ContentFormat [name=SENML_JSON, code=110] 2021-03-24 14:24:19,222 DEBUG DefaultBootstrapHandler$3 - Error for DefaultBootstrapSession [id=BMWfXnawRv, endpoint=lwm2m_ale, identity=Identity /***:49858[psk=***], authorized=true, contentFormat=ContentFormat [name=SENML_JSON, code=110], creationTime=1616595792969, request=org.eclipse.leshan.core.request.BootstrapRequest@4a0c2354, cancelled=false] while sending BootstrapDeleteRequest [/0] org.eclipse.leshan.core.request.exception.TimeoutException: Request coap://***:49858/0 timed out : CoAP or blockwise timeout at org.eclipse.leshan.core.californium.CoapAsyncRequestObserver.onTimeout(CoapAsyncRequestObserver.java:119) at org.eclipse.californium.core.coap.Message.setTimedOut(Message.java:954) at org.eclipse.californium.core.coap.Request.setTimedOut(Request.java:1096) at org.eclipse.californium.core.network.Exchange.setTimedOut(Exchange.java:707) at org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.retry(ReliabilityLayer.java:524) at org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask.access$200(ReliabilityLayer.java:430) at org.eclipse.californium.core.network.stack.ReliabilityLayer$RetransmissionTask$1.run(ReliabilityLayer.java:467) at org.eclipse.californium.elements.util.SerialExecutor$1.run(SerialExecutor.java:289) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at java.base/java.lang.Thread.run(Thread.java:834)

I am using DTLS with PSK. Firewall settings are OK since the Leshan Client is able to connect in DTLS with Leshan Bootstrap Server and Server. The client runs on a RaspberryPi.

What am I missing?

Thank you in advance.
@aleparmi
Copy link
Author

Update: Connecting Wakaama and Leshan Servers with DTLS in localhost works with no issues

@aleparmi
Copy link
Author

Update: it works by adding TLV format as described in https://github.com/eclipse/wakaama before commit id: da23477

After that something seems to be broken when using DTLS PSK with a remote leshan bootstrap server with either TLV or SENML_JSON

@sbertin-telular
Copy link
Contributor

Can you run the Wakaama client under the debugger and get a stack trace?

@aleparmi
Copy link
Author

Unfortunately this is everything what I could get debugging remotely on a raspberry... I could try to find a better results in the next days

transaction_handleResponse (Unknown Source:0)
lwm2m_handle_packet (Unknown Source:0)
read_from_peer (Unknown Source:0)
dtls_handle_message (Unknown Source:0)
connection_handle_packet (Unknown Source:0)
main (Unknown Source:0)

@aleparmi
Copy link
Author

Got some more precise stack. As in #594 my contextP is 0x0

transaction_handleResponse(lwm2m_context_t * contextP, void * fromSessionH, coap_packet_t * message, coap_packet_t * response) (c:\Users\Code\wakaama\coap\transaction.c:283)
lwm2m_handle_packet(lwm2m_context_t * contextP, uint8_t * buffer, int length, void * fromSessionH) (c:\Users\Code\wakaama\core\packet.c:817)
read_from_peer(struct dtls_context_t * ctx, session_t * session, uint8 * data, size_t len) (c:\Users\Code\wakaama\examples\shared\dtlsconnection.c:274)
dtls_handle_message(dtls_context_t * ctx, session_t * session, uint8 * msg, int msglen) (c:\Users\Code\wakaama\examples\shared\tinydtls\dtls.c:3894)
connection_handle_packet(dtls_connection_t * connP, uint8_t * buffer, size_t numBytes) (c:\Users\Code\wakaama\examples\shared\dtlsconnection.c:597)
main(int argc, char ** argv) (c:\Users\Code\wakaama\examples\client\lwm2mclient.c:1385)

@rettichschnidi
Copy link
Contributor

@aleparmi This might be resolved by #647. Any chance you could give it a try?

@aleparmi
Copy link
Author

Unfortunately not at the moment :(

@rettichschnidi rettichschnidi reopened this Feb 4, 2022
@rettichschnidi
Copy link
Contributor

Using the current master (d57d547), I was able to bootstrap successfully:

$ examples/client/lwm2mclient_tinydtls -h leshan.eclipseprojects.io -4 -p 5784 -n wakaama-560-with-dtls -i wakaama-560-with-dtls -s 5cdafa07cf5d7e0e65b8e504bc0dc9 -b 
Trying to bind LWM2M Client to port 56830
[lwm2m_init:65] Entering
[lwm2m_configure:272] endpointName: "wakaama-560-with-dtls", msisdn: "", altPath: "", numObject: 9
LWM2M Client "wakaama-560-with-dtls" started on port 56830
> [lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_INITIAL
[object_getServers:1040] Entering
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_bool:519] value: true
[lwm2m_data_decode_bool:529] Entering
[lwm2m_data_decode_bool:580] result: 1, value: true
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 10
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 10
[lwm2m_data_free:178] size: 1
[bootstrap_start:623] Entering
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_string:214] "coaps://leshan.eclipseprojects.io:5784"
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 0
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 0
[lwm2m_data_free:178] size: 1
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_DEREGISTERED
[bootstrap_step:583] Final status: STATE_BS_HOLD_OFF
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 10
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
[BOOTSTRAP] backup security and server objects
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_HOLD_OFF
[prv_requestBootstrap:81] Entering
[prv_requestBootstrap:134] Bootstrap server connection opened
[transaction_new:160] method: 2, altPath: "", mID: 45795, token_len: 4
[transaction_new:162] NULL
[transaction_new:242] Exiting on success. new transac=0x55759e461ef0
[transaction_send:373] Entering: transaction=0x55759e461ef0
Sending 67 bytes to [23.97.187.154]:5784
16 FE FD 00  00 00 00 00  00 00 00 00  36 01 00 00   ............6...
2A 00 00 00  00 00 00 00  2A FE FD 00  00 00 0A 52   *.......*......R
8E E2 5A 85  25 AE 08 C6  BA FF 39 47  56 A4 B2 C8   ..Z.%.....9GV...
7E 4D E4 F3  94 56 48 59  7D 14 C5 00  00 00 02 C0   ~M...VHY}.......
A8 01 00                                             ...
[prv_requestBootstrap:150] CI bootstrap requested to BS server
[bootstrap_step:583] Final status: STATE_BS_INITIATED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 2
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
60 bytes received from [23.97.187.154]:5784
16 FE FD 00  00 00 00 00  00 00 00 00  2F 03 00 00   ............/...
23 00 00 00  00 00 00 00  23 FE FD 20  56 6F 96 05   #.......#.. Vo..
69 E0 3B 98  D5 E9 63 34  04 AA A4 FE  40 99 7F CF   i.;...c4....@...
8F E6 53 1C  41 F2 53 A3  14 E7 3B 0F                ..S.A.S...;.
Sending 99 bytes to [23.97.187.154]:5784
16 FE FD 00  00 00 00 00  00 00 01 00  56 01 00 00   ............V...
4A 00 01 00  00 00 00 00  4A FE FD 00  00 00 0A 52   J.......J......R
8E E2 5A 85  25 AE 08 C6  BA FF 39 47  56 A4 B2 C8   ..Z.%.....9GV...
7E 4D E4 F3  94 56 48 59  7D 14 C5 00  20 56 6F 96   ~M...VHY}... Vo.
05 69 E0 3B  98 D5 E9 63  34 04 AA A4  FE 40 99 7F   .i.;...c4....@..
CF 8F E6 53  1C 41 F2 53  A3 14 E7 3B  0F 00 02 C0   ...S.A.S...;....
A8 01 00                                             ...
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_INITIATED
[bootstrap_step:583] Final status: STATE_BS_INITIATED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 2
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
88 bytes received from [23.97.187.154]:5784
16 FE FD 00  00 00 00 00  00 00 01 00  32 02 00 00   ............2...
26 00 01 00  00 00 00 00  26 FE FD 61  FF 0D 97 90   &.......&..a....
1A 92 C7 1D  00 5C FA 6D  35 05 8E 28  E0 1A C6 C8   .....\.m5..(....
07 9B 2F 50  8C F7 9B 91  98 D2 73 00  C0 A8 00 16   ../P......s.....
FE FD 00 00  00 00 00 00  00 02 00 0C  0E 00 00 00   ................
00 02 00 00  00 00 00 00                             ........
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_opaque:251] length: 21
[lwm2m_data_free:178] size: 1
Sending 48 bytes to [23.97.187.154]:5784
16 FE FD 00  00 00 00 00  00 00 02 00  23 10 00 00   ............#...
17 00 02 00  00 00 00 00  17 00 15 77  61 6B 61 61   ...........wakaa
6D 61 2D 35  36 30 2D 77  69 74 68 2D  64 74 6C 73   ma-560-with-dtls
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_opaque:251] length: 15
[lwm2m_data_free:178] size: 1
Sending 14 bytes to [23.97.187.154]:5784
14 FE FD 00  00 00 00 00  00 00 03 00  01 01  ..............
Sending 53 bytes to [23.97.187.154]:5784
16 FE FD 00  01 00 00 00  00 00 00 00  28 00 01 00   ............(...
00 00 00 00  00 0B F0 69  98 89 00 48  F9 81 57 56   .......i...H..WV
B8 07 E0 32  D5 5A 1E F5  D7 9E 38 3F  4F E6 84 35   ...2.Z....8?O..5
AD C0 17 E3  E3                                      .....
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_INITIATED
[bootstrap_step:583] Final status: STATE_BS_INITIATED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 2
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
67 bytes received from [23.97.187.154]:5784
14 FE FD 00  00 00 00 00  00 00 03 00  01 01 16 FE   ................
FD 00 01 00  00 00 00 00  00 00 28 00  01 00 00 00   ..........(.....
00 00 00 F6  E5 5B 6A 9C  95 73 56 22  48 96 DE F9   .....[j..sV"H...
03 8A 79 50  F0 58 17 BF  CB 20 E0 EC  D9 2D 57 10   ..yP.X... ...-W.
D4 10 0E                                             ...
decrypt_verify(): found 24 bytes cleartext
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_INITIATED
[bootstrap_step:583] Final status: STATE_BS_INITIATED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 2
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_INITIATED
[bootstrap_step:583] Final status: STATE_BS_INITIATED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[transaction_send:373] Entering: transaction=0x55759e461ef0
Sending 74 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 01 00  3D 00 01 00   ............=...
00 00 00 00  01 42 EC C2  9F AE C9 39  B6 39 25 5D   .....B.....9.9%]
B4 97 87 B5  15 A7 B5 00  C7 5C 18 CD  FA 2E A2 EC   .........\......
62 19 77 20  C8 CA 23 F8  5B 1E F1 C2  5E E2 7C 87   b.w ..#.[...^.|.
CD CB 75 C5  F2 6C 8A 03  EA B3                      ..u..l....
[lwm2m_step:488] Final timeoutP: 4
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
37 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 01 00  18 00 01 00   ................
00 00 00 00  01 8F 1F 72  27 46 23 3F  FF D3 22 BF   .......r'F#?..".
32 0D 0E 0C  31                                      2...1
decrypt_verify(): found 8 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 2, tkl 4, code 2.04, mid 45795, Content type: 0
[lwm2m_handle_packet:494] Payload: 
[transaction_handleResponse:296] Entering
[prv_handleBootstrapReply:58] Entering
[prv_handleResponse:39] Received ACK/2.04, Bootstrap pending, waiting for DEL/PUT from BS server...
[transaction_remove:282] Entering. transaction=0x55759e461ef0
[transaction_free:258] Entering. transaction=0x55759e461ef0
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_PENDING
[bootstrap_step:583] Final status: STATE_BS_PENDING
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
43 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 02 00  1E 00 01 00   ................
00 00 00 00  02 CE 8C 1F  5C 34 E1 26  A1 FE C5 E1   ........\4.&....
D9 4D 80 32  A3 50 9A 1B  CD F4 EE                   .M.2.P.....
decrypt_verify(): found 14 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 0, tkl 8, code 0.04, mid 1286, Content type: 0
[lwm2m_handle_packet:494] Payload: 
[handle_request:147] Entering
[uri_decode:103] altPath: ""
[bootstrap_handleCommand:762] Code: 04
[bootstrap_handleCommand:763] /0
[prv_checkServerStatus:682] Initial status: STATE_BS_PENDING
[bootstrap_handleCommand:968] Server status: STATE_BS_PENDING
[message_send:870] Entering
[message_send:872] Size to allocate: 12
[message_send:879] coap_serialize_message() returned 12
Sending 41 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 02 00  1C 00 01 00   ................
00 00 00 00  02 03 A2 A5  B7 FF 8D E4  70 66 47 05   ............pfG.
16 26 31 3C  87 A9 8D AD  4F                         .&1<....O
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_PENDING
[bootstrap_step:583] Final status: STATE_BS_PENDING
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
43 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 03 00  1E 00 01 00   ................
00 00 00 00  03 C9 EB 40  A9 10 01 B1  88 02 8B 1C   .......@........
1C 46 AC ED  DD 88 49 7A  8D 7B 50                   .F....Iz.{P
decrypt_verify(): found 14 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 0, tkl 8, code 0.04, mid 1287, Content type: 0
[lwm2m_handle_packet:494] Payload: 
[handle_request:147] Entering
[uri_decode:103] altPath: ""
[bootstrap_handleCommand:762] Code: 04
[bootstrap_handleCommand:763] /1
[prv_checkServerStatus:682] Initial status: STATE_BS_PENDING
[object_delete:617] /1
[object_delete:622] Entering
[observe_clear:302] /1/57360
[object_delete:654] result: 2.02
[bootstrap_handleCommand:968] Server status: STATE_BS_PENDING
[message_send:870] Entering
[message_send:872] Size to allocate: 12
[message_send:879] coap_serialize_message() returned 12
Sending 41 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 03 00  1C 00 01 00   ................
00 00 00 00  03 43 A8 9E  5E 36 B0 DA  EA 50 73 DB   .....C..^6...Ps.
2C 20 3C 94  B3 24 66 58  8C                         , <..$fX.
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_PENDING
[bootstrap_step:583] Final status: STATE_BS_PENDING
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
331 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 04 01  3E 00 01 00   ............>...
00 00 00 00  04 FD 96 4E  44 A5 36 A2  B1 88 F0 8B   .......ND.6.....
E9 80 AE 55  AC 1A F0 C4  94 EF DE 03  76 69 31 90   ...U........vi1.
56 01 76 D8  F3 56 5E D8  4F 82 FE 2B  96 E3 4D AB   V.v..V^.O..+..M.
C6 85 A2 A6  45 B6 9E 88  9E 41 D0 7E  56 66 D8 03   ....E....A.~Vf..
97 28 EC E2  99 36 7E 53  1F 5D 9E 6E  EC 42 8F 03   .(...6~S.].n.B..
2A CE E4 56  86 55 CF 56  B3 1A 9A 84  CC 37 D7 CF   *..V.U.V.....7..
F8 2C 38 0C  F9 C7 5E 70  65 B6 85 58  2F AA 33 85   .,8...^pe..X/.3.
B9 87 EB 10  DE 5A 4A 6F  45 94 1C 2B  A3 48 12 B2   .....ZJoE..+.H..
87 A1 D3 F4  54 B7 95 29  0F 5C 6D E2  DE A9 C5 3B   ....T..).\m....;
BF C4 D4 04  3C F1 0C CE  0C B7 7A 2C  2B ED C9 95   ....<.....z,+...
25 28 EE 1F  27 C0 FA CC  A1 C4 78 9B  EC 7F A5 B0   %(..'.....x.....
B9 F1 13 90  25 8A F4 F8  88 80 1C 4D  1F 72 79 AD   ....%......M.ry.
4D 01 02 A9  4B 0E 6F 6F  7B A8 A6 DD  A1 60 C9 14   M...K.oo{....`..
0C 97 27 18  1B 40 49 5B  BC 41 FD 22  58 77 6A B0   ..'..@I[.A."Xwj.
52 13 B7 D7  9C 24 CD 8E  51 63 A0 6D  47 75 B6 83   R....$..Qc.mGu..
9B 91 6E 9E  7D EC AB 58  81 08 77 E9  E0 CF D6 FD   ..n.}..X..w.....
D1 3C 7F CB  AC 61 7F 32  DA A8 3F B9  D2 60 4D B6   .<...a.2..?..`M.
EC 7D 63 B2  CA 3E C7 36  B7 13 9C 48  27 F0 1F 92   .}c..>.6...H'...
1A 6B 79 A7  F5 85 C7 22  9F 45 02 87  8C 5B A7 CC   .ky....".E...[..
26 72 03 DF  07 CF 1C 19  6B FE F6                   &r......k..
decrypt_verify(): found 302 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 0, tkl 8, code 0.03, mid 1288, Content type: 110
[lwm2m_handle_packet:494] Payload: [{"bn":"/0/1/","n":"0","vs":"coap://leshan.eclipseprojects.io:5683"},{"n":"1","vb":false},{"n":"2","v":3},{"n":"3","vd":""},{"n":"4","vd":""},{"n":"5","vd":""},{"n":"6","v":3},{"n":"7","vd":""},{"n":"8","vd":""},{"n":"9","vs":""},{"n":"10","v":123},{"n":"11","v":1},{"n":"12","v":0}]
[handle_request:147] Entering
[uri_decode:103] altPath: ""
[bootstrap_handleCommand:762] Code: 03
[bootstrap_handleCommand:763] /0/1
[prv_checkServerStatus:682] Initial status: STATE_BS_PENDING
[object_isInstanceNew:744] Entering
[object_create:466] /0/1
[lwm2m_data_parse:653] format: LWM2M_CONTENT_SENML_JSON, bufferLen: 283
[lwm2m_data_parse:654] /0/1
[senml_json_parse:613] bufferLen: 283, buffer: "[{"bn":"/0/1/","n":"0","vs":"coap://leshan.eclipseprojects.io:5683"},{"n":"1","vb":false},{"n":"2","v":3},{"n":"3","vd":""},{"n":"4","vd":""},{"n":"5","vd":""},{"n":"6","v":3},{"n":"7","vd":""},{"n":"8","vd":""},{"n":"9","vs":""},{"n":"10","v":123},{"n":"11","v":1},{"n":"12","v":0}]P$��ɵ(d"
[senml_json_parse:614] /0/1
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/0"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/0
[lwm2m_data_encode_bool:519] value: false
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/1"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/1
[lwm2m_data_encode_uint:370] value: 3
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/2"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/2
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/3"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/3
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/4"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/4
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/5"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/5
[lwm2m_data_encode_uint:370] value: 3
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/6"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/6
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/7"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/7
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/8"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/8
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/0/1/9"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /0/1/9
[lwm2m_data_encode_uint:370] value: 123
[lwm2m_stringToUri:227] buffer_len: 7, buffer: "/0/1/10"
[lwm2m_stringToUri:283] Parsed characters: 7
[lwm2m_stringToUri:284] /0/1/10
[lwm2m_data_encode_uint:370] value: 1
[lwm2m_stringToUri:227] buffer_len: 7, buffer: "/0/1/11"
[lwm2m_stringToUri:283] Parsed characters: 7
[lwm2m_stringToUri:284] /0/1/11
[lwm2m_data_encode_uint:370] value: 0
[lwm2m_stringToUri:227] buffer_len: 7, buffer: "/0/1/12"
[lwm2m_stringToUri:283] Parsed characters: 7
[lwm2m_stringToUri:284] /0/1/12
[lwm2m_data_new:160] size: 13
[lwm2m_data_new:160] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_nstring:277] length: 37, string: "coap://leshan.eclipseprojects.io:5683"
[lwm2m_data_encode_opaque:251] length: 37
[lwm2m_data_new:160] size: 2
[lwm2m_data_new:160] size: 3
[lwm2m_data_new:160] size: 4
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 5
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 6
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 7
[lwm2m_data_new:160] size: 8
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 9
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 10
[lwm2m_data_encode_nstring:277] length: 0, string: ""
[lwm2m_data_encode_opaque:251] length: 0
[lwm2m_data_new:160] size: 11
[lwm2m_data_new:160] size: 12
[lwm2m_data_new:160] size: 13
[lwm2m_data_new:160] size: 1
[lwm2m_data_new:160] size: 13
[lwm2m_data_free:178] size: 1
[lwm2m_data_free:178] size: 1
[lwm2m_data_free:178] size: 13
[senml_json_parse:772] Parsing successful. count: 13
[lwm2m_data_decode_bool:529] Entering
[lwm2m_data_decode_bool:580] result: 1, value: false
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 3
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 1
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 0
[lwm2m_data_free:178] size: 13
[object_create:514] result: 2.01
[bootstrap_handleCommand:968] Server status: STATE_BS_PENDING
[message_send:870] Entering
[message_send:872] Size to allocate: 12
[message_send:879] coap_serialize_message() returned 12
Sending 41 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 04 00  1C 00 01 00   ................
00 00 00 00  04 A7 61 47  22 E5 2D 43  29 AB 8C 9A   ......aG".-C)...
E9 A5 EB 24  85 82 35 1D  9E                         ...$..5..
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_PENDING
[bootstrap_step:583] Final status: STATE_BS_PENDING
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
153 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 05 00  8C 00 01 00   ................
00 00 00 00  05 FF 99 C0  13 9D 63 C2  4C 20 AD 43   ..........c.L .C
AA 2F 34 07  60 33 EB 06  EF B5 2F D7  AE 5B 71 BB   ./4.`3..../..[q.
36 85 C2 F8  BF 63 68 70  A2 9C D8 37  AB DC 21 FD   6....chp...7..!.
52 EA 93 B7  F1 5B 2B 04  67 0D 26 59  19 F9 3D 47   R....[+.g.&Y..=G
82 F0 33 2F  E3 C8 AB 4B  08 7A 6D A2  61 59 7D A9   ..3/...K.zm.aY}.
95 14 FF 05  17 51 68 59  8A C9 71 82  3C 7C AC 20   .....QhY..q.<|. 
5A 66 56 EA  88 A3 DA 44  A1 49 49 D5  BF 3D 03 26   ZfV....D.II..=.&
10 AC D3 B6  BF 4B 56 32  1F ED 8F 53  83 D9 9F 74   .....KV2...S...t
D8 C9 7A F4  46 BC 12 DA  4A                         ..z.F...J
decrypt_verify(): found 124 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 0, tkl 8, code 0.03, mid 1289, Content type: 110
[lwm2m_handle_packet:494] Payload: [{"bn":"/1/0/","n":"0","v":123},{"n":"1","v":300},{"n":"2","v":1},{"n":"6","vb":true},{"n":"7","vs":"U"}]
[handle_request:147] Entering
[uri_decode:103] altPath: ""
[bootstrap_handleCommand:762] Code: 03
[bootstrap_handleCommand:763] /1/0
[prv_checkServerStatus:682] Initial status: STATE_BS_PENDING
[object_isInstanceNew:744] Entering
[object_create:466] /1/0
[lwm2m_data_parse:653] format: LWM2M_CONTENT_SENML_JSON, bufferLen: 105
[lwm2m_data_parse:654] /1/0
[senml_json_parse:613] bufferLen: 105, buffer: "[{"bn":"/1/0/","n":"0","v":123},{"n":"1","v":300},{"n":"2","v":1},{"n":"6","vb":true},{"n":"7","vs":"U"}]!Rj;OV","vd":""},{"n":"4","vd":""},{"n":"5","vd":""},{"n":"6","v":3},{"n":"7","vd":""},{"n":"8","vd":""},{"n":"9","vs":""},{"n":"10","v":123},{"n":"11","v":1},{"n":"12","v":0}]P$��ɵ(d"
[senml_json_parse:614] /1/0
[lwm2m_data_encode_uint:370] value: 123
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/1/0/0"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /1/0/0
[lwm2m_data_encode_uint:370] value: 300
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/1/0/1"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /1/0/1
[lwm2m_data_encode_uint:370] value: 1
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/1/0/2"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /1/0/2
[lwm2m_data_encode_bool:519] value: true
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/1/0/6"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /1/0/6
[lwm2m_stringToUri:227] buffer_len: 6, buffer: "/1/0/7"
[lwm2m_stringToUri:283] Parsed characters: 6
[lwm2m_stringToUri:284] /1/0/7
[lwm2m_data_new:160] size: 5
[lwm2m_data_new:160] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_new:160] size: 2
[lwm2m_data_new:160] size: 3
[lwm2m_data_new:160] size: 4
[lwm2m_data_new:160] size: 5
[lwm2m_data_encode_nstring:277] length: 1, string: "U"
[lwm2m_data_encode_opaque:251] length: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_new:160] size: 5
[lwm2m_data_free:178] size: 1
[lwm2m_data_free:178] size: 1
[lwm2m_data_free:178] size: 5
[senml_json_parse:772] Parsing successful. count: 5
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 300
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 1
[lwm2m_data_decode_bool:529] Entering
[lwm2m_data_decode_bool:580] result: 1, value: true
[lwm2m_data_free:178] size: 5
[object_create:514] result: 2.01
[bootstrap_handleCommand:968] Server status: STATE_BS_PENDING
[message_send:870] Entering
[message_send:872] Size to allocate: 12
[message_send:879] coap_serialize_message() returned 12
Sending 41 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 05 00  1C 00 01 00   ................
00 00 00 00  05 4E 53 7F  1B 92 09 61  43 66 40 6A   .....NS....aCf@j
89 BD E0 27  AC 90 57 C7  E4                         ...'..W..
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_PENDING
[bootstrap_step:583] Final status: STATE_BS_PENDING
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
44 bytes received from [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 06 00  1F 00 01 00   ................
00 00 00 00  06 E2 C4 18  70 C8 4E 6B  DE 62 50 0B   ........p.Nk.bP.
84 AF 0A B2  E5 A8 4D B6  98 A1 9D 4A                ......M....J
decrypt_verify(): found 15 bytes cleartext
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 0, tkl 8, code 0.02, mid 1290, Content type: 0
[lwm2m_handle_packet:494] Payload: 
[handle_request:147] Entering
[uri_decode:103] altPath: ""
[bootstrap_handleFinish:593] Entering
[object_getServers:1040] Entering
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_bool:519] value: false
[lwm2m_data_decode_bool:529] Entering
[lwm2m_data_decode_bool:580] result: 1, value: false
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 2
[lwm2m_data_encode_int:289] value: 300
[lwm2m_data_encode_string:214] "U"
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 300
[lwm2m_data_free:178] size: 2
[lwm2m_data_free:178] size: 1
[bootstrap_handleFinish:600] Bootstrap server status changed to STATE_BS_FINISHING
[message_send:870] Entering
[message_send:872] Size to allocate: 12
[message_send:879] coap_serialize_message() returned 12
Sending 41 bytes to [23.97.187.154]:5784
17 FE FD 00  01 00 00 00  00 00 06 00  1C 00 01 00   ................
00 00 00 00  06 98 D0 B0  E4 02 F5 8A  9F 59 10 FE   .............Y..
C0 9C D4 28  C8 BE AA 17  49                         ...(....I
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_PENDING
[bootstrap_step:517] entering
[bootstrap_step:521] Initial status: STATE_BS_FINISHING
[bootstrap_step:583] Final status: STATE_BS_FINISHED
[observe_step:521] Entering
[registration_step:2054] State: STATE_BOOTSTRAPPING
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 0
[lwm2m_step:490] Final state: STATE_BOOTSTRAPPING
 -> State: STATE_BOOTSTRAPPING
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_BOOTSTRAPPING
[bootstrap_getStatus:646] Entering
[bootstrap_getStatus:675] Returned status: STATE_BS_FINISHED
[object_getServers:1040] Entering
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_bool:519] value: false
[lwm2m_data_decode_bool:529] Entering
[lwm2m_data_decode_bool:580] result: 1, value: false
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 123
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 123
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 2
[lwm2m_data_encode_int:289] value: 300
[lwm2m_data_encode_string:214] "U"
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 300
[lwm2m_data_free:178] size: 2
[lwm2m_data_free:178] size: 1
[registration_start:1028] State: STATE_REGISTER_REQUIRED
[lwm2m_data_new:160] size: 1
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_free:178] size: 1
[observe_step:521] Entering
[registration_step:2054] State: STATE_REGISTERING
[object_getRegisterPayloadBufferLength:788] Entering
[object_getRegisterPayload:886] Entering
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_string:214] "coap://leshan.eclipseprojects.io:5683"
[lwm2m_data_free:178] size: 1
[lwm2m_data_new:160] size: 1
[lwm2m_data_encode_int:289] value: 3
[lwm2m_data_decode_int:299] Entering
[lwm2m_data_decode_int:362] result: 1, value: 3
[lwm2m_data_free:178] size: 1
[transaction_new:160] method: 2, altPath: "", mID: 45796, token_len: 4
[transaction_new:162] NULL
[transaction_new:242] Exiting on success. new transac=0x55759e463390
[transaction_send:373] Entering: transaction=0x55759e463390
Sending 201 bytes to [23.97.187.154]:5683
44 02 B2 E4  E4 B2 99 0D  B2 72 64 11  28 39 6C 77   D........rd.(9lw
6D 32 6D 3D  31 2E 31 0D  0B 65 70 3D  77 61 6B 61   m2m=1.1..ep=waka
61 6D 61 2D  35 36 30 2D  77 69 74 68  2D 64 74 6C   ama-560-with-dtl
73 03 62 3D  55 06 6C 74  3D 33 30 30  FF 3C 2F 3E   s.b=U.lt=300.</>
3B 72 74 3D  22 6F 6D 61  2E 6C 77 6D  32 6D 22 3B   ;rt="oma.lwm2m";
63 74 3D 31  31 30 2C 3C  2F 31 3E 3B  76 65 72 3D   ct=110,</1>;ver=
31 2E 31 2C  3C 2F 31 2F  30 3E 2C 3C  2F 32 2F 30   1.1,</1/0>,</2/0
3E 2C 3C 2F  33 2F 30 3E  2C 3C 2F 34  2F 30 3E 2C   >,</3/0>,</4/0>,
3C 2F 35 2F  30 3E 2C 3C  2F 36 2F 30  3E 2C 3C 2F   </5/0>,</6/0>,</
37 2F 30 3E  2C 3C 2F 33  31 30 32 34  3E 3B 76 65   7/0>,</31024>;ve
72 3D 31 2E  30 2C 3C 2F  33 31 30 32  34 2F 31 30   r=1.0,</31024/10
3E 2C 3C 2F  33 31 30 32  34 2F 31 31  3E 2C 3C 2F   >,</31024/11>,</
33 31 30 32  34 2F 31 32  3E                         31024/12>
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 2
[lwm2m_step:490] Final state: STATE_REGISTERING
 -> State: STATE_REGISTERING
22 bytes received from [23.97.187.154]:5683
64 41 B2 E4  E4 B2 99 0D  82 72 64 0A  41 39 49 44   dA.......rd.A9ID
7A 47 32 52  74 49                                   zG2RtI
[lwm2m_handle_packet:485] Entering
[lwm2m_handle_packet:492] Parsed: ver 1, type 2, tkl 4, code 2.01, mid 45796, Content type: 0
[lwm2m_handle_packet:494] Payload:
[transaction_handleResponse:296] Entering
[prv_handleRegistrationReply:670] 123 Registration successful
[lwm2m_data_new:160] size: 1
[lwm2m_data_free:178] size: 1
[transaction_remove:282] Entering. transaction=0x55759e463390
[transaction_free:258] Entering. transaction=0x55759e463390
[lwm2m_step:381] timeoutP: 60
[lwm2m_step:386] State: STATE_REGISTERING
[registration_getStatus:1094] State: STATE_REGISTERING
[registration_getStatus:1101] 123 status: STATE_REGISTERED
[registration_getStatus:1125] reg_status: STATE_REGISTERED
[observe_step:521] Entering
[registration_step:2054] State: STATE_READY
[transaction_step:460] Entering
[lwm2m_step:488] Final timeoutP: 60
[lwm2m_step:490] Final state: STATE_READY
 -> State: STATE_READY

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
question
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@sbernard31 @rettichschnidi @sbertin-telular @aleparmi