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

Does not work under Windows (Cygwin) #8

Open
mifritscher opened this issue Aug 6, 2019 · 3 comments
Open

Does not work under Windows (Cygwin) #8

mifritscher opened this issue Aug 6, 2019 · 3 comments

Comments

@mifritscher
Copy link

mifritscher commented Aug 6, 2019 •
edited
Loading

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.
@mifritscher
Copy link
Author

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

@mifritscher
Copy link
Author

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

@mifritscher
Copy link
Author

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?

@mifritscher mifritscher mentioned this issue Aug 7, 2019
Open
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@mifritscher