Janus 0.3 Bug with HTTP transport plugin: creating handle very slow after 1 videoroom and 4 textroom

[Ju Ju]

Hi,

As promised here the information regarding the bug of our janus http transport plugin

Same test as below show no issue using websocket.

After created 4 textroom and 1 videoroom janus start to become very slow (4 seconde ! ) to generate handleId for 1 more textroom attachment
If we go on create more it become even worse


janus log:
[Fri Jan 19 15:04:03 2018] Creating new session: 8292711769600944
[Fri Jan 19 15:04:07 2018] Creating new handle in session 8292711769600944: 4451202147885194
[Fri Jan 19 15:04:07 2018] [4451202147885194] Creating ICE agent (ICE Lite mode, controlled)
[Fri Jan 19 15:04:07 2018] [WARN] [4451202147885194] Still waiting for the DTLS stack for component 1 in stream 1...
[Fri Jan 19 15:04:07 2018] [4451202147885194] The DTLS handshake has been completed
[Fri Jan 19 15:04:07 2018] WebRTC media is now available


As you can see the peerco is up but it takes 4 secondes between the session creation and plugin attachement


proof it is not a netwok issue

1/ session creation request (get from client)
15:04:03.437511 IP 90.5.251.36.56028 > 193.252.113.200.8088: Flags [P.], seq 13675:14188, ack 9370, win 4096, options [nop,nop,TS val 353647334 ecr 650138997], length 513
{"janus":"
     create","transaction":"XWz7W8ptzgyw"}

2/ answer 200 ok from janus
15:04:03.437856 IP 193.252.113.200.8088 > 90.5.251.36.56028: Flags [P.], seq 9370:9566, ack 14188, win 882, options [nop,nop,TS val 650141150 ecr 353647334], length 196
"success",....
    0x0050:  2274 7261 6e73 6163 7469 6f6e 223a 2022  "transaction":."
    0x0060:  5857 7a37 5738 7074 7a67 7977 222c 0a20  XWz7W8ptzgyw",..
    0x0070:  2020 2264 6174 6122 3a20 7b0a 2020 2020  .."data":.{.....
    0x0080:  2020 2269 6422 3a20 3832 3932 3731 3137  .."id":.82927117
    0x0090:  3639 3630 3039 3434 0a20 2020 7d0a 7d    69600944....}

3/ plugin attachment
15:04:03.501717 IP 90.5.251.36.56028 > 193.252.113.200.8088: Flags [P.], seq 14188:14636, ack 9673, win 4096, options [nop,nop,TS val 353647402 ecr 650141150], length 448
  

    0x0030:  26c0 5dde 4745 5420 2f6a 616e 7573 2f38  &.].GET./janus/8
    0x0040:  3239 3237 3131 3736 3936 3030 3934 343f  292711769600944?
    0x0050:  7269 643d 3135 3136 3337 3036 3433 3435  rid=151637064345
    0x0060:  3726 6d61 7865 763d 3120 4854 5450 2f31  7&maxev=1.HTTP/1
    0x0070:  2e31 0d0a 486f 7374 3a20 6a61 6e75 732e  .1..Host:.janus.
    0x0080:  6f72 616e 6765 2e63 6f6d 3a38 3038 380d  orange.com:8088.
    0x0090:  0a43 6f6e 6e65 6374 696f 6e3a 206b 6565  .Connection:.kee
    0x00a0:  702d 616c 6976 650d 0a4f 7269 6769 6e3a  p-alive..Origin:
    0x00b0:  2068 7474 703a 2f2f 6a61 6e75 732e 6f72  .http://janus.or
    0x00c0:  616e 6765 2e63 6f6d 3a38 3038 300d 0a55  ange.com:8080..U
    0x00d0:  7365 722d 4167 656e 743a 204d 6f7a 696c  ser-Agent:.Mozil
    0x00e0:  6c61 2f35 2e30 2028 4d61 6369 6e74 6f73  la/5.0.(Macintos
    0x00f0:  683b 2049 6e74 656c 204d 6163 204f 5320  h;.Intel.Mac.OS.
    0x0100:  5820 3130 5f31 335f 3229 2041 7070 6c65  X.10_13_2).Apple
    0x0110:  5765 624b 6974 2f35 3337 2e33 3620 284b  WebKit/537.36.(K
    0x0120:  4854 4d4c 2c20 6c69 6b65 2047 6563 6b6f  HTML,.like.Gecko
    0x0130:  2920 4368 726f 6d65 2f36 332e 302e 3332  ).Chrome/63.0.32
    0x0140:  3339 2e31 3332 2053 6166 6172 692f 3533  39.132.Safari/53
    0x0150:  372e 3336 0d0a 4163 6365 7074 3a20 2a2f  7.36..Accept:.*/
    0x0160:  2a0d 0a52 6566 6572 6572 3a20 6874 7470  *..Referer:.http
    0x0170:  3a2f 2f6a 616e 7573 2e6f 7261 6e67 652e  ://janus.orange.
    0x0180:  636f 6d3a 3830 3830 2f74 6578 7472 6f6f  com:8080/textroo
    0x0190:  6d74 6573 742e 6874 6d6c 0d0a 4163 6365  mtest.html..Acce
    0x01a0:  7074 2d45 6e63 6f64 696e 673a 2067 7a69  pt-Encoding:.gzi
    0x01b0:  702c 2064 6566 6c61 7465 0d0a 4163 6365  p,.deflate..Acce
    0x01c0:  7074 2d4c 616e 6775 6167 653a 2066 722d  pt-Language:.fr-
    0x01d0:  4652 2c66 723b 713d 302e 392c 656e 2d55  FR,fr;q=0.9,en-U
    0x01e0:  533b 713d 302e 382c 656e 3b71 3d30 2e37  S;q=0.8,en;q=0.7

4/ answer from janus
15:04:07.432423 IP 193.252.113.200.8088 > 90.5.251.36.56291: Flags [P.], seq 1944:2139, ack 3862, win 303, options [nop,nop,TS val 650142149 ecr 353621529], length 195
    0x0000:  4500 00f7 ff87 4000 4006 b18a c1fc 71c8  E.....@.@.....q.
    0x0010:  5a05 fb24 1f98 dbe3 a45c c491 c7a2 3a0f  Z..$.....\....:.
    0x0020:  8018 012f 89d8 0000 0101 080a 26c0 61c5  .../........&.a.
    0x0030:  1513 d619 4854 5450 2f31 2e31 2032 3030  ....HTTP/1.1.200
    0x0040:  204f 4b0d 0a43 6f6e 6e65 6374 696f 6e3a  .OK..Connection:
    0x0050:  204b 6565 702d 416c 6976 650d 0a43 6f6e  .Keep-Alive..Con
    0x0060:  7465 6e74 2d4c 656e 6774 683a 2032 370d  tent-Length:.27.
    0x0070:  0a41 6363 6573 732d 436f 6e74 726f 6c2d  .Access-Control-
    0x0080:  4d61 782d 4167 653a 2038 3634 3030 0d0a  Max-Age:.86400..
    0x0090:  4163 6365 7373 2d43 6f6e 7472 6f6c 2d41  Access-Control-A
    0x00a0:  6c6c 6f77 2d4f 7269 6769 6e3a 202a 0d0a  llow-Origin:.*..
    0x00b0:  436f 6e74 656e 742d 5479 7065 3a20 6170  Content-Type:.ap
    0x00c0:  706c 6963 6174 696f 6e2f 6a73 6f6e 0d0a  plication/json..
    0x00d0:  4461 7465 3a20 4672 692c 2031 3920 4a61  Date:.Fri,.19.Ja
    0x00e0:  6e20 3230 3138 2031 343a 3034 3a30 3720  n.2018.14:04:07.
    0x00f0:  474d 540d 0a0d 0a                        GMT....



what is it doing during 4 secondes ?


I repeat : when using webesocket, this never happen

JJ-

[Ju Ju]

edit:

the issue happen when using the same client (so same IP src, just radnom SRC port) not when using several IP address