goldy Does not work under Windows (Cygwin)

Does not work under Windows (Cygwin)

Open mifritscher opened this issue 6 years ago • 3 comments

I'm using goldy-client as client (https://github.com/fortitudepub/goldy-client)

On Linux, both goldy and goldy-client do work. But unter Windows, goldy-client does work (connects to goldy on Linux just fine), but goldy under Windows does not work - tried goldy-client both under Linux and Windows.

Output of client:

2019-08-06 15:07:39.377352 INFO  Goldy 0.2 starting up
2019-08-06 15:07:39.377494 DEBUG Binded UDP 127.0.0.1:1193
2019-08-06 15:07:39.377597 DEBUG Loaded server cacert file
2019-08-06 15:07:39.377659 DEBUG Seeded random number generator
2019-08-06 15:07:39.377703 INFO  Proxy is ready, listening for connections on UDP 127.0.0.1:1193
2019-08-06 15:07:39.377747 INFO  main_loop - start
2019-08-06 15:07:39.377760 DEBUG start_listen_io - 3
2019-08-06 15:07:52.937361 DEBUG global_cb fds: 3,3 revents: 0x01 count: 0
2019-08-06 15:07:52.937500 DEBUG connect_to_new_client: connected on fd 6
2019-08-06 15:07:52.937593 INFO  (127.0.0.1:43106) Client connected
2019-08-06 15:07:52.937786 INFO  Created socket to backend UDP 172.25.30.225:11194
2019-08-06 15:07:52.937809 DEBUG global_cb - session_start - client_fd 6
2019-08-06 15:07:52.937824 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:07:52.937837 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 0

2019-08-06 15:07:52.937849 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.937859 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.937870 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 1

2019-08-06 15:07:52.937880 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.937890 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.937901 DEBUG mbedtls_debug [2] ssl_cli.c:0717: => write client hello

2019-08-06 15:07:52.937932 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:07:52.937961 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.937973 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 401, out_left: 401

2019-08-06 15:07:52.938040 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 401 (-0xfffffe6f)

2019-08-06 15:07:52.938054 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:07:52.938065 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:07:52.938076 DEBUG mbedtls_debug [2] ssl_cli.c:1049: <= write client hello

2019-08-06 15:07:52.938107 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:07:52.938119 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.938129 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.938140 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:07:52.938150 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.938161 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.938172 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.938196 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:07:52.938210 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:07:52.968410 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:07:52.968470 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:07:52.968480 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.968492 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.968510 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:07:52.968522 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.968531 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.968543 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.968571 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 60 (-0xffffffc4)

2019-08-06 15:07:52.968581 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:07:52.968590 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.968598 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 60, nb_want: 60

2019-08-06 15:07:52.968605 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:07:52.968620 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:07:52.968629 DEBUG mbedtls_debug [2] ssl_cli.c:1449: received hello verify request

2019-08-06 15:07:52.968638 DEBUG mbedtls_debug [2] ssl_cli.c:1450: <= parse server hello

2019-08-06 15:07:52.968646 DEBUG mbedtls_debug [2] ssl_cli.c:1326: => parse hello verify request

2019-08-06 15:07:52.968658 DEBUG mbedtls_debug [2] ssl_cli.c:1385: <= parse hello verify request

2019-08-06 15:07:52.968667 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 1

2019-08-06 15:07:52.968675 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.968682 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.968689 DEBUG mbedtls_debug [2] ssl_cli.c:0717: => write client hello

2019-08-06 15:07:52.968713 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:07:52.968745 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.968762 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 433, out_left: 433

2019-08-06 15:07:52.968872 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 433 (-0xfffffe4f)

2019-08-06 15:07:52.968889 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:07:52.968905 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:07:52.968920 DEBUG mbedtls_debug [2] ssl_cli.c:1049: <= write client hello

2019-08-06 15:07:52.968940 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:07:52.968956 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.968972 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.968988 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:07:52.969003 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.969018 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.969035 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.969056 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:07:52.969077 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:07:52.976433 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:07:52.976480 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:07:52.976503 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.976519 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.976533 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:07:52.976549 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.976565 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.976580 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.976604 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 112 (-0xffffff90)

2019-08-06 15:07:52.976619 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:07:52.976634 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.976648 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 112, nb_want: 112

2019-08-06 15:07:52.976662 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:07:52.976687 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:07:52.976704 DEBUG mbedtls_debug [2] ssl_cli.c:1671: server hello, total extension length: 15

2019-08-06 15:07:52.976720 DEBUG mbedtls_debug [2] ssl_cli.c:1859: <= parse server hello

2019-08-06 15:07:52.976734 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 3

2019-08-06 15:07:52.976748 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.976762 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.976776 DEBUG mbedtls_debug [2] ssl_tls.c:4223: => parse certificate

2019-08-06 15:07:52.976790 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.976804 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.976818 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.976840 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 1594 (-0xfffff9c6)

2019-08-06 15:07:52.976855 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:07:52.976869 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.976884 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 1594, nb_want: 1594

2019-08-06 15:07:52.976898 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:07:52.976933 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:07:52.976982 DEBUG mbedtls_debug [2] ssl_tls.c:4490: <= parse certificate

2019-08-06 15:07:52.976999 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 4

2019-08-06 15:07:52.977013 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:52.977027 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:52.977041 DEBUG mbedtls_debug [2] ssl_cli.c:2198: => parse server key exchange

2019-08-06 15:07:52.977054 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:52.977068 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:52.977083 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:52.977104 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:07:52.977121 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:07:53.000585 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:07:53.000662 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 4

2019-08-06 15:07:53.000730 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.000774 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.000817 DEBUG mbedtls_debug [2] ssl_cli.c:2198: => parse server key exchange

2019-08-06 15:07:53.000841 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:53.000861 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:53.000882 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:53.000917 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 422 (-0xfffffe5a)

2019-08-06 15:07:53.000935 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:07:53.000951 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:53.000966 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 422, nb_want: 422

2019-08-06 15:07:53.000982 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:07:53.001006 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:07:53.001032 DEBUG mbedtls_debug [2] ssl_cli.c:1919: ECDH curve: secp521r1

2019-08-06 15:07:53.001050 DEBUG mbedtls_debug [2] ssl_cli.c:2141: Server used SignatureAlgorithm 1

2019-08-06 15:07:53.001067 DEBUG mbedtls_debug [2] ssl_cli.c:2142: Server used HashAlgorithm 6

2019-08-06 15:07:53.001460 DEBUG mbedtls_debug [2] ssl_cli.c:2516: <= parse server key exchange

2019-08-06 15:07:53.001491 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 5

2019-08-06 15:07:53.001510 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.001527 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.001545 DEBUG mbedtls_debug [2] ssl_cli.c:2556: => parse certificate request

2019-08-06 15:07:53.001563 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:53.001579 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:53.001597 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:53.001624 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 25 (-0xffffffe7)

2019-08-06 15:07:53.001643 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:07:53.001662 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:53.001679 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 25, nb_want: 25

2019-08-06 15:07:53.001696 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:07:53.001714 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:07:53.001730 DEBUG mbedtls_debug [2] ssl_cli.c:2674: <= parse certificate request

2019-08-06 15:07:53.001748 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 6

2019-08-06 15:07:53.001765 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.001783 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.001801 DEBUG mbedtls_debug [2] ssl_cli.c:2689: => parse server hello done

2019-08-06 15:07:53.001823 DEBUG mbedtls_debug [2] ssl_cli.c:2721: <= parse server hello done

2019-08-06 15:07:53.001843 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 7

2019-08-06 15:07:53.001861 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.001878 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.001895 DEBUG mbedtls_debug [2] ssl_tls.c:4107: => write certificate

2019-08-06 15:07:53.001912 DEBUG mbedtls_debug [2] ssl_tls.c:4124: <= skip write certificate

2019-08-06 15:07:53.001928 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 8

2019-08-06 15:07:53.001945 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.001962 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.001980 DEBUG mbedtls_debug [2] ssl_cli.c:2732: => write client key exchange

2019-08-06 15:07:53.014365 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:07:53.014392 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014400 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 159, out_left: 159

2019-08-06 15:07:53.014444 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 159 (-0xffffff61)

2019-08-06 15:07:53.014452 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:07:53.014461 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:07:53.014470 DEBUG mbedtls_debug [2] ssl_cli.c:2977: <= write client key exchange

2019-08-06 15:07:53.014480 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 9

2019-08-06 15:07:53.014489 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014498 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.014506 DEBUG mbedtls_debug [2] ssl_cli.c:3026: => write certificate verify

2019-08-06 15:07:53.014515 DEBUG mbedtls_debug [2] ssl_tls.c:0501: => derive keys

2019-08-06 15:07:53.014525 DEBUG mbedtls_debug [2] ssl_tls.c:1067: => calc verify sha384

2019-08-06 15:07:53.014536 DEBUG mbedtls_debug [2] ssl_tls.c:1073: <= calc verify

2019-08-06 15:07:53.014609 DEBUG mbedtls_debug [2] ssl_tls.c:0957: <= derive keys

2019-08-06 15:07:53.014616 DEBUG mbedtls_debug [2] ssl_cli.c:3047: <= skip write certificate verify

2019-08-06 15:07:53.014635 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 10

2019-08-06 15:07:53.014643 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014651 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.014660 DEBUG mbedtls_debug [2] ssl_tls.c:4506: => write change cipher spec

2019-08-06 15:07:53.014668 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:07:53.014677 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014686 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 14, out_left: 14

2019-08-06 15:07:53.014706 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 14 (-0xfffffff2)

2019-08-06 15:07:53.014714 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:07:53.014723 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:07:53.014731 DEBUG mbedtls_debug [2] ssl_tls.c:4520: <= write change cipher spec

2019-08-06 15:07:53.014739 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 11

2019-08-06 15:07:53.014747 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014755 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.014763 DEBUG mbedtls_debug [2] ssl_tls.c:5031: => write finished

2019-08-06 15:07:53.014771 DEBUG mbedtls_debug [2] ssl_tls.c:4905: => calc  finished tls sha384

2019-08-06 15:07:53.014791 DEBUG mbedtls_debug [2] ssl_tls.c:4935: <= calc  finished

2019-08-06 15:07:53.014799 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:07:53.014809 DEBUG mbedtls_debug [2] ssl_tls.c:1258: => encrypt buf

2019-08-06 15:07:53.014819 DEBUG mbedtls_debug [2] ssl_tls.c:1560: <= encrypt buf

2019-08-06 15:07:53.014827 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014836 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 61, out_left: 61

2019-08-06 15:07:53.014855 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 61 (-0xffffffc3)

2019-08-06 15:07:53.014862 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:07:53.014870 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:07:53.014879 DEBUG mbedtls_debug [2] ssl_tls.c:5140: <= write finished

2019-08-06 15:07:53.014887 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 12

2019-08-06 15:07:53.014895 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:07:53.014903 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:07:53.014912 DEBUG mbedtls_debug [2] ssl_tls.c:4529: => parse change cipher spec

2019-08-06 15:07:53.014920 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:07:53.014928 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:07:53.014936 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:07:53.014950 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:07:53.014958 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:08:26.232762 DEBUG global_cb fds: 3,3 revents: 0x01 count: 1
2019-08-06 15:08:26.232989 DEBUG connect_to_new_client: connected on fd 8
2019-08-06 15:08:26.233031 DEBUG session_free - sc=f51bfa80
2019-08-06 15:08:26.233154 DEBUG mbedtls_debug [2] ssl_tls.c:7055: => free

2019-08-06 15:08:26.233324 DEBUG mbedtls_debug [2] ssl_tls.c:7120: <= free

2019-08-06 15:08:26.233366 INFO  (127.0.0.1:43106) Session closed
2019-08-06 15:08:26.233448 INFO  (127.0.0.1:41531) Client connected
2019-08-06 15:08:26.233549 INFO  Created socket to backend UDP 172.25.30.225:11194
2019-08-06 15:08:26.233588 DEBUG global_cb - session_start - client_fd 8
2019-08-06 15:08:26.233624 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:08:26.233653 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 0

2019-08-06 15:08:26.233682 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.233708 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.233736 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 1

2019-08-06 15:08:26.233763 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.233792 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.233821 DEBUG mbedtls_debug [2] ssl_cli.c:0717: => write client hello

2019-08-06 15:08:26.233901 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:08:26.233978 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.234013 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 401, out_left: 401

2019-08-06 15:08:26.234266 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 401 (-0xfffffe6f)

2019-08-06 15:08:26.234307 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:08:26.234335 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:08:26.234366 DEBUG mbedtls_debug [2] ssl_cli.c:1049: <= write client hello

2019-08-06 15:08:26.234396 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:08:26.234424 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.234451 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.234477 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:08:26.234506 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.234535 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.234565 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.234613 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:08:26.234652 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:08:26.238718 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:08:26.238751 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:08:26.238763 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.238774 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.238784 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:08:26.238793 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.238803 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.238812 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.238830 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 60 (-0xffffffc4)

2019-08-06 15:08:26.238842 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:08:26.238853 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.238864 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 60, nb_want: 60

2019-08-06 15:08:26.238875 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:08:26.238889 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:08:26.238900 DEBUG mbedtls_debug [2] ssl_cli.c:1449: received hello verify request

2019-08-06 15:08:26.238912 DEBUG mbedtls_debug [2] ssl_cli.c:1450: <= parse server hello

2019-08-06 15:08:26.238923 DEBUG mbedtls_debug [2] ssl_cli.c:1326: => parse hello verify request

2019-08-06 15:08:26.238937 DEBUG mbedtls_debug [2] ssl_cli.c:1385: <= parse hello verify request

2019-08-06 15:08:26.238949 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 1

2019-08-06 15:08:26.238961 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.238972 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.238984 DEBUG mbedtls_debug [2] ssl_cli.c:0717: => write client hello

2019-08-06 15:08:26.239014 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:08:26.239047 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.239060 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 433, out_left: 433

2019-08-06 15:08:26.239112 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 433 (-0xfffffe4f)

2019-08-06 15:08:26.239125 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:08:26.239134 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:08:26.239144 DEBUG mbedtls_debug [2] ssl_cli.c:1049: <= write client hello

2019-08-06 15:08:26.239156 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:08:26.239166 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.239177 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.239189 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:08:26.239199 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.239211 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.239223 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.239239 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:08:26.239254 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:08:26.247057 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:08:26.247094 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 2

2019-08-06 15:08:26.247113 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.247123 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.247133 DEBUG mbedtls_debug [2] ssl_cli.c:1410: => parse server hello

2019-08-06 15:08:26.247142 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.247152 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.247161 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.247178 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 112 (-0xffffff90)

2019-08-06 15:08:26.247190 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:08:26.247202 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.247212 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 112, nb_want: 112

2019-08-06 15:08:26.247221 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:08:26.247238 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:08:26.247250 DEBUG mbedtls_debug [2] ssl_cli.c:1671: server hello, total extension length: 15

2019-08-06 15:08:26.247260 DEBUG mbedtls_debug [2] ssl_cli.c:1859: <= parse server hello

2019-08-06 15:08:26.247270 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 3

2019-08-06 15:08:26.247279 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.247288 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.247297 DEBUG mbedtls_debug [2] ssl_tls.c:4223: => parse certificate

2019-08-06 15:08:26.247307 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.247316 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.247325 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.247340 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 1594 (-0xfffff9c6)

2019-08-06 15:08:26.247350 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:08:26.247359 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.247369 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 1594, nb_want: 1594

2019-08-06 15:08:26.247379 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:08:26.247401 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:08:26.247427 DEBUG mbedtls_debug [2] ssl_tls.c:4490: <= parse certificate

2019-08-06 15:08:26.247439 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 4

2019-08-06 15:08:26.247447 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.247457 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.247466 DEBUG mbedtls_debug [2] ssl_cli.c:2198: => parse server key exchange

2019-08-06 15:08:26.247475 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.247485 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.247495 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.247509 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:08:26.247521 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:08:26.265289 DEBUG mbedtls_debug [2] ssl_tls.c:6335: => handshake

2019-08-06 15:08:26.265330 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 4

2019-08-06 15:08:26.265344 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.265354 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.265363 DEBUG mbedtls_debug [2] ssl_cli.c:2198: => parse server key exchange

2019-08-06 15:08:26.265373 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.265382 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.265391 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.265410 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 422 (-0xfffffe5a)

2019-08-06 15:08:26.265419 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:08:26.265428 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.265437 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 422, nb_want: 422

2019-08-06 15:08:26.265446 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:08:26.265460 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:08:26.265474 DEBUG mbedtls_debug [2] ssl_cli.c:1919: ECDH curve: secp521r1

2019-08-06 15:08:26.265483 DEBUG mbedtls_debug [2] ssl_cli.c:2141: Server used SignatureAlgorithm 1

2019-08-06 15:08:26.265492 DEBUG mbedtls_debug [2] ssl_cli.c:2142: Server used HashAlgorithm 6

2019-08-06 15:08:26.265659 DEBUG mbedtls_debug [2] ssl_cli.c:2516: <= parse server key exchange

2019-08-06 15:08:26.265669 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 5

2019-08-06 15:08:26.265679 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.265688 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.265697 DEBUG mbedtls_debug [2] ssl_cli.c:2556: => parse certificate request

2019-08-06 15:08:26.265706 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.265715 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.265724 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.265737 DEBUG mbedtls_debug [2] ssl_tls.c:2315: ssl->f_recv(_timeout)() returned 25 (-0xffffffe7)

2019-08-06 15:08:26.265745 DEBUG mbedtls_debug [2] ssl_tls.c:2403: <= fetch input

2019-08-06 15:08:26.265754 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.265763 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 25, nb_want: 25

2019-08-06 15:08:26.265771 DEBUG mbedtls_debug [2] ssl_tls.c:2276: <= fetch input

2019-08-06 15:08:26.265782 DEBUG mbedtls_debug [2] ssl_tls.c:3753: <= read record

2019-08-06 15:08:26.265791 DEBUG mbedtls_debug [2] ssl_cli.c:2674: <= parse certificate request

2019-08-06 15:08:26.265800 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 6

2019-08-06 15:08:26.265809 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.265818 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.265828 DEBUG mbedtls_debug [2] ssl_cli.c:2689: => parse server hello done

2019-08-06 15:08:26.265839 DEBUG mbedtls_debug [2] ssl_cli.c:2721: <= parse server hello done

2019-08-06 15:08:26.265848 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 7

2019-08-06 15:08:26.265857 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.265865 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.265874 DEBUG mbedtls_debug [2] ssl_tls.c:4107: => write certificate

2019-08-06 15:08:26.265883 DEBUG mbedtls_debug [2] ssl_tls.c:4124: <= skip write certificate

2019-08-06 15:08:26.265892 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 8

2019-08-06 15:08:26.265901 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.265909 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.265919 DEBUG mbedtls_debug [2] ssl_cli.c:2732: => write client key exchange

2019-08-06 15:08:26.280887 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:08:26.280923 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.280937 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 159, out_left: 159

2019-08-06 15:08:26.280984 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 159 (-0xffffff61)

2019-08-06 15:08:26.280995 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:08:26.281003 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:08:26.281012 DEBUG mbedtls_debug [2] ssl_cli.c:2977: <= write client key exchange

2019-08-06 15:08:26.281020 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 9

2019-08-06 15:08:26.281028 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281036 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.281044 DEBUG mbedtls_debug [2] ssl_cli.c:3026: => write certificate verify

2019-08-06 15:08:26.281051 DEBUG mbedtls_debug [2] ssl_tls.c:0501: => derive keys

2019-08-06 15:08:26.281060 DEBUG mbedtls_debug [2] ssl_tls.c:1067: => calc verify sha384

2019-08-06 15:08:26.281069 DEBUG mbedtls_debug [2] ssl_tls.c:1073: <= calc verify

2019-08-06 15:08:26.281147 DEBUG mbedtls_debug [2] ssl_tls.c:0957: <= derive keys

2019-08-06 15:08:26.281155 DEBUG mbedtls_debug [2] ssl_cli.c:3047: <= skip write certificate verify

2019-08-06 15:08:26.281174 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 10

2019-08-06 15:08:26.281181 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281188 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.281196 DEBUG mbedtls_debug [2] ssl_tls.c:4506: => write change cipher spec

2019-08-06 15:08:26.281206 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:08:26.281213 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281221 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 14, out_left: 14

2019-08-06 15:08:26.281245 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 14 (-0xfffffff2)

2019-08-06 15:08:26.281252 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:08:26.281260 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:08:26.281268 DEBUG mbedtls_debug [2] ssl_tls.c:4520: <= write change cipher spec

2019-08-06 15:08:26.281275 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 11

2019-08-06 15:08:26.281282 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281290 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.281298 DEBUG mbedtls_debug [2] ssl_tls.c:5031: => write finished

2019-08-06 15:08:26.281305 DEBUG mbedtls_debug [2] ssl_tls.c:4905: => calc  finished tls sha384

2019-08-06 15:08:26.281325 DEBUG mbedtls_debug [2] ssl_tls.c:4935: <= calc  finished

2019-08-06 15:08:26.281333 DEBUG mbedtls_debug [2] ssl_tls.c:2701: => write record

2019-08-06 15:08:26.281342 DEBUG mbedtls_debug [2] ssl_tls.c:1258: => encrypt buf

2019-08-06 15:08:26.281351 DEBUG mbedtls_debug [2] ssl_tls.c:1560: <= encrypt buf

2019-08-06 15:08:26.281358 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281366 DEBUG mbedtls_debug [2] ssl_tls.c:2435: message length: 61, out_left: 61

2019-08-06 15:08:26.281384 DEBUG mbedtls_debug [2] ssl_tls.c:2441: ssl->f_send() returned 61 (-0xffffffc3)

2019-08-06 15:08:26.281391 DEBUG mbedtls_debug [2] ssl_tls.c:2460: <= flush output

2019-08-06 15:08:26.281400 DEBUG mbedtls_debug [2] ssl_tls.c:2850: <= write record

2019-08-06 15:08:26.281408 DEBUG mbedtls_debug [2] ssl_tls.c:5140: <= write finished

2019-08-06 15:08:26.281415 DEBUG mbedtls_debug [2] ssl_cli.c:3279: client state: 12

2019-08-06 15:08:26.281422 DEBUG mbedtls_debug [2] ssl_tls.c:2416: => flush output

2019-08-06 15:08:26.281429 DEBUG mbedtls_debug [2] ssl_tls.c:2428: <= flush output

2019-08-06 15:08:26.281437 DEBUG mbedtls_debug [2] ssl_tls.c:4529: => parse change cipher spec

2019-08-06 15:08:26.281444 DEBUG mbedtls_debug [2] ssl_tls.c:3728: => read record

2019-08-06 15:08:26.281452 DEBUG mbedtls_debug [2] ssl_tls.c:2208: => fetch input

2019-08-06 15:08:26.281461 DEBUG mbedtls_debug [2] ssl_tls.c:2269: in_left: 0, nb_want: 13

2019-08-06 15:08:26.281474 DEBUG mbedtls_debug [2] ssl_tls.c:6345: <= handshake

2019-08-06 15:08:26.281482 DEBUG mbedtls handshake return SSL - Connection requires a read call
2019-08-06 15:09:26.265656 DEBUG session_inactivity_timer_handler - sc=f51cb720 timeout: now=1565096966.266 - last_activity=1565096906.265 (duration=60.000) > timeout=60
2019-08-06 15:09:26.265720 INFO  (127.0.0.1:41531) Session timeout
2019-08-06 15:09:26.265736 DEBUG session_free - sc=f51cb720
2019-08-06 15:09:26.265793 DEBUG mbedtls_debug [2] ssl_tls.c:7055: => free

2019-08-06 15:09:26.265874 DEBUG mbedtls_debug [2] ssl_tls.c:7120: <= free

2019-08-06 15:09:26.265890 INFO  (127.0.0.1:41531) Session closed

Output of server:

2019-08-06 15:07:32.033800 INFO  Goldy 0.2 starting up
2019-08-06 15:07:32.037800 DEBUG Binded UDP 0.0.0.0:11194
2019-08-06 15:07:32.037800 DEBUG Loaded server certificate file
2019-08-06 15:07:32.039800 DEBUG Loaded private key file
2019-08-06 15:07:32.039800 DEBUG Seeded random number generator
2019-08-06 15:07:32.039800 INFO  Proxy is ready, listening for connections on UDP 0.0.0.0:11194
2019-08-06 15:07:32.039800 INFO  main_loop - start
2019-08-06 15:07:32.039800 DEBUG start_listen_io - 3
2019-08-06 15:07:50.029800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 0
2019-08-06 15:07:50.029800 DEBUG connect_to_new_client: connected on fd 6
2019-08-06 15:07:50.029800 INFO  (172.25.30.222:43059) Client connected
2019-08-06 15:07:50.029800 DEBUG global_cb - session_start - client_fd 6
2019-08-06 15:07:50.029800 DEBUG (172.25.30.222:43059) DTLS handshake requested hello verification
2019-08-06 15:07:50.029800 DEBUG session_deferred_free - hello verification 54c0 6
2019-08-06 15:07:50.029800 DEBUG session_free - sc=54c0
2019-08-06 15:07:50.052800 INFO  (172.25.30.222:43059) Session closed
2019-08-06 15:07:50.056800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 1
2019-08-06 15:07:50.056800 DEBUG connect_to_new_client: connected on fd 6
2019-08-06 15:07:50.056800 INFO  (172.25.30.222:43059) Client connected
2019-08-06 15:07:50.056800 DEBUG global_cb - session_start - client_fd 6
2019-08-06 15:07:50.102800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 2
2019-08-06 15:07:50.102800 DEBUG connect_to_new_client: connected on fd 8
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Client connected
2019-08-06 15:07:50.102800 DEBUG global_cb - session_start - client_fd 8
2019-08-06 15:07:50.102800 DEBUG connect_to_new_client: connected on fd 9
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Client connected
2019-08-06 15:07:50.102800 DEBUG global_cb - session_start - client_fd 9
2019-08-06 15:07:50.102800 DEBUG connect_to_new_client: connected on fd 10
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Client connected
2019-08-06 15:07:50.102800 DEBUG global_cb - session_start - client_fd 10
2019-08-06 15:07:50.102800 ERROR (172.25.30.222:43059) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:07:50.102800 DEBUG session_deferred_free - session_cb - ssl handshake 5d740 10
2019-08-06 15:07:50.102800 DEBUG session_free - sc=5d740
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Session closed
2019-08-06 15:07:50.102800 ERROR (172.25.30.222:43059) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:07:50.102800 DEBUG session_deferred_free - session_cb - ssl handshake 54660 9
2019-08-06 15:07:50.102800 DEBUG session_free - sc=54660
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Session closed
2019-08-06 15:07:50.102800 ERROR (172.25.30.222:43059) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:07:50.102800 DEBUG session_deferred_free - session_cb - ssl handshake 4b4f0 8
2019-08-06 15:07:50.102800 DEBUG session_free - sc=4b4f0
2019-08-06 15:07:50.102800 INFO  (172.25.30.222:43059) Session closed
2019-08-06 15:08:23.322800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 3
2019-08-06 15:08:23.322800 DEBUG connect_to_new_client: connected on fd 8
2019-08-06 15:08:23.322800 INFO  (172.25.30.222:40460) Client connected
2019-08-06 15:08:23.322800 DEBUG global_cb - session_start - client_fd 8
2019-08-06 15:08:23.322800 DEBUG (172.25.30.222:40460) DTLS handshake requested hello verification
2019-08-06 15:08:23.322800 DEBUG session_deferred_free - hello verification 4b4f0 8
2019-08-06 15:08:23.322800 DEBUG session_free - sc=4b4f0
2019-08-06 15:08:23.323800 INFO  (172.25.30.222:40460) Session closed
2019-08-06 15:08:23.326800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 4
2019-08-06 15:08:23.326800 DEBUG connect_to_new_client: connected on fd 8
2019-08-06 15:08:23.326800 INFO  (172.25.30.222:40460) Client connected
2019-08-06 15:08:23.326800 DEBUG global_cb - session_start - client_fd 8
2019-08-06 15:08:23.369800 DEBUG global_cb fds: 3,3 revents: 0x01 count: 5
2019-08-06 15:08:23.369800 DEBUG connect_to_new_client: connected on fd 9
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Client connected
2019-08-06 15:08:23.369800 DEBUG global_cb - session_start - client_fd 9
2019-08-06 15:08:23.369800 DEBUG connect_to_new_client: connected on fd 10
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Client connected
2019-08-06 15:08:23.369800 DEBUG global_cb - session_start - client_fd 10
2019-08-06 15:08:23.369800 DEBUG connect_to_new_client: connected on fd 11
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Client connected
2019-08-06 15:08:23.369800 DEBUG global_cb - session_start - client_fd 11
2019-08-06 15:08:23.369800 ERROR (172.25.30.222:40460) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:08:23.369800 DEBUG session_deferred_free - session_cb - ssl handshake 69850 11
2019-08-06 15:08:23.369800 DEBUG session_free - sc=69850
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Session closed
2019-08-06 15:08:23.369800 ERROR (172.25.30.222:40460) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:08:23.369800 DEBUG session_deferred_free - session_cb - ssl handshake 60770 10
2019-08-06 15:08:23.369800 DEBUG session_free - sc=60770
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Session closed
2019-08-06 15:08:23.369800 ERROR (172.25.30.222:40460) session_cb - ssl handshake: SSL - Processing of the ClientHello handshake message failed (-30976)
2019-08-06 15:08:23.369800 DEBUG session_deferred_free - session_cb - ssl handshake 57690 9
2019-08-06 15:08:23.369800 DEBUG session_free - sc=57690
2019-08-06 15:08:23.369800 INFO  (172.25.30.222:40460) Session closed
2019-08-06 15:08:50.061800 DEBUG session_inactivity_timer_handler - sc=54c0 timeout: now=1565096930.062 - last_activity=1565096870.057 (duration=60.005) > timeout=60
2019-08-06 15:08:50.061800 INFO  (172.25.30.222:43059) Session timeout
2019-08-06 15:08:50.061800 DEBUG session_free - sc=54c0
2019-08-06 15:08:50.061800 INFO  (172.25.30.222:43059) Session closed
2019-08-06 15:09:23.331800 DEBUG session_inactivity_timer_handler - sc=4b4f0 timeout: now=1565096963.332 - last_activity=1565096903.327 (duration=60.005) > timeout=60
2019-08-06 15:09:23.331800 INFO  (172.25.30.222:40460) Session timeout
2019-08-06 15:09:23.331800 DEBUG session_free - sc=4b4f0
2019-08-06 15:09:23.331800 INFO  (172.25.30.222:40460) Session closed

Packetdumps: https://mifritscher.de/austausch/zft/goldy_ok.pcapng https://mifritscher.de/austausch/zft/goldy_nok.pcapng

It seems to simply abort on the first encrypted handshake packet.

Aug 06 '19 13:08 mifritscher

P.S. You can find the windows binary under https://mifritscher.de/austausch/zft/goldy.zip .

Aug 06 '19 13:08 mifritscher

Upgrading to mbedtls 2.7.1 didn't solve the issue.

Raising the debug level on mbedtls, I got:

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:6850: => handshake

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_srv.c:4009: server state: 0

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2574: => flush output

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2586: <= flush output

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_srv.c:4009: server state: 1

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2574: => flush output

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2586: <= flush output

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_srv.c:1217: => parse client hello

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2358: => fetch input

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2419: in_left: 159, nb_want: 5

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2426: <= fetch input

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1249: dumping 'record header' (13 bytes)

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1249: 0000:  16 fe fd 00 00 00 00 00 00 00 02 00 92           .............

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [3] ssl_srv.c:1261: client hello v3, message type: 22

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [3] ssl_srv.c:1270: client hello v3, message len.: 146

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [3] ssl_srv.c:1273: client hello v3, protocol version: [254:253]

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2358: => fetch input

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2419: in_left: 159, nb_want: 159

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:2426: <= fetch input

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: dumping 'record contents' (146 bytes)

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0000:  10 00 00 86 00 02 00 00 00 00 00 86 85 04 01 4c  ...............L

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0010:  62 4e ff 27 bd 24 0c e2 03 94 9b aa 68 44 ac b1  bN.'.$......hD..

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0020:  db 80 1a ca cc 7b 13 9a c3 8d 0f f7 ec d7 fe f8  .....{..........

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0030:  94 66 fe b0 44 2d a5 9d f4 08 9e e4 17 9f a5 0c  .f..D-..........

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0040:  00 70 41 62 7f bb 30 63 9f b8 16 67 4d 7e f8 62  .pAb..0c...gM~.b

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0050:  01 95 5a cc fb 10 6c 00 7f 28 3b bb f7 ac 54 07  ..Z...l..(;...T.

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0060:  e6 90 86 4e 87 d3 8e 8b 81 88 7a 21 e6 be e0 71  ...N......z!...q

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0070:  91 b4 76 ed ca ce e0 f8 27 c1 06 fc c1 6d 87 a0  ..v.....'....m..

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0080:  89 7f 99 51 dd 2c a5 fd d3 a8 6d cf f0 84 19 7e  ...Q.,....m....~

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [4] ssl_srv.c:1355: 0090:  a8 06                                            ..

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [3] ssl_srv.c:1373: client hello v3, handshake type: 16

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [1] ssl_srv.c:1377: bad client hello message

2019-08-07 15:12:38.205000 DEBUG mbedtls_debug [2] ssl_tls.c:6860: <= handshake

Aug 07 '19 13:08 mifritscher

If I understand it right, it wants to have MBEDTLS_SSL_HS_CLIENT_HELLO = 1, but it got a MBEDTLS_SSL_HS_CLIENT_KEY_EXCHANGE = 16. Can it be that there are packets being mixed up?

Aug 07 '19 13:08 mifritscher

goldy goldy copied to clipboard

Does not work under Windows (Cygwin)

goldy
goldy copied to clipboard