Project

General

Profile

open-vpn-verbose.txt

openvpn --config /etc/openvpn/client/sepia.conf --cd /etc/openvpn/client --verb 11 - Josh Salomon, 10/27/2020 07:54 AM

Download (37.1 KB)

 
1
Tue Oct 27 09:52:22 2020 us=412501 WARNING: file 'sepia/tlsauth' is group or others accessible
2
Tue Oct 27 09:52:22 2020 us=412859 Current Parameter Settings:
3
Tue Oct 27 09:52:22 2020 us=412914   config = '/etc/openvpn/client/sepia.conf'
4
Tue Oct 27 09:52:22 2020 us=412950   mode = 0
5
Tue Oct 27 09:52:22 2020 us=412977   persist_config = DISABLED
6
Tue Oct 27 09:52:22 2020 us=413009   persist_mode = 1
7
Tue Oct 27 09:52:22 2020 us=413040   show_ciphers = DISABLED
8
Tue Oct 27 09:52:22 2020 us=413068   show_digests = DISABLED
9
Tue Oct 27 09:52:22 2020 us=413100   show_engines = DISABLED
10
Tue Oct 27 09:52:22 2020 us=413129   genkey = DISABLED
11
Tue Oct 27 09:52:22 2020 us=413157   key_pass_file = '[UNDEF]'
12
Tue Oct 27 09:52:22 2020 us=413186 NOTE: --mute triggered...
13
Tue Oct 27 09:52:22 2020 us=413235 273 variation(s) on previous 10 message(s) suppressed by --mute
14
Tue Oct 27 09:52:22 2020 us=413266 OpenVPN 2.4.9 x86_64-redhat-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Apr 19 2020
15
Tue Oct 27 09:52:22 2020 us=413328 library versions: OpenSSL 1.1.1g FIPS  21 Apr 2020, LZO 2.10
16
Tue Oct 27 09:52:22 2020 us=413874 PKCS#11: pkcs11_initialize - entered
17
Tue Oct 27 09:52:22 2020 us=414149 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
18
Tue Oct 27 09:52:22 2020 us=414234 PO_INIT maxevents=4 flags=0x00000002
19
Tue Oct 27 09:52:22 2020 us=420096 PRNG init md=SHA1 size=36
20
Tue Oct 27 09:52:22 2020 us=420327 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
21
Tue Oct 27 09:52:22 2020 us=420380 Outgoing Control Channel Authentication: HMAC KEY: 9fd3fab9 ce8513a5 25a55feb 731ca46c 185555b2
22
Tue Oct 27 09:52:22 2020 us=420412 Outgoing Control Channel Authentication: HMAC size=20 block_size=20
23
Tue Oct 27 09:52:22 2020 us=420443 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
24
Tue Oct 27 09:52:22 2020 us=420470 Incoming Control Channel Authentication: HMAC KEY: 587953b3 8323cf13 b763724d 5c8e2b77 b6a9d12c
25
Tue Oct 27 09:52:22 2020 us=420488 Incoming Control Channel Authentication: HMAC size=20 block_size=20
26
Tue Oct 27 09:52:22 2020 us=420507 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 28 bytes
27
Tue Oct 27 09:52:22 2020 us=420553 LZO compression initializing
28
Tue Oct 27 09:52:22 2020 us=420602 TLS: tls_session_init: entry
29
Tue Oct 27 09:52:22 2020 us=420631 PID packet_id_init seq_backtrack=64 time_backtrack=15
30
Tue Oct 27 09:52:22 2020 us=420760 PID packet_id_init seq_backtrack=64 time_backtrack=15
31
Tue Oct 27 09:52:22 2020 us=420827 TLS: tls_session_init: new session object, sid=264bc70d 15fd9b8c
32
Tue Oct 27 09:52:22 2020 us=420841 TLS: tls_session_init: entry
33
Tue Oct 27 09:52:22 2020 us=420857 PID packet_id_init seq_backtrack=64 time_backtrack=15
34
Tue Oct 27 09:52:22 2020 us=420919 PID packet_id_init seq_backtrack=64 time_backtrack=15
35
Tue Oct 27 09:52:22 2020 us=420964 TLS: tls_session_init: new session object, sid=d9475775 0e3f2ef3
36
Tue Oct 27 09:52:22 2020 us=420982 Control Channel MTU parms [ L:1622 D:1184 EF:66 EB:0 ET:0 EL:3 ]
37
Tue Oct 27 09:52:22 2020 us=421016 MTU DYNAMIC mtu=1450, flags=2, 1622 -> 1450
38
Tue Oct 27 09:52:22 2020 us=421131 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
39
Tue Oct 27 09:52:22 2020 us=440808 RESOLVE_REMOTE flags=0x0901 phase=1 rrs=0 sig=-1 status=0
40
Tue Oct 27 09:52:22 2020 us=440851 Data Channel MTU parms [ L:1622 D:1450 EF:122 EB:406 ET:0 EL:3 ]
41
Tue Oct 27 09:52:22 2020 us=440878 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 40 bytes
42
Tue Oct 27 09:52:22 2020 us=440887 calc_options_string_link_mtu: link-mtu 1622 -> 1542
43
Tue Oct 27 09:52:22 2020 us=440902 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 40 bytes
44
Tue Oct 27 09:52:22 2020 us=440910 calc_options_string_link_mtu: link-mtu 1622 -> 1542
45
Tue Oct 27 09:52:22 2020 us=440922 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client'
46
Tue Oct 27 09:52:22 2020 us=440930 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 0,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-server'
47
Tue Oct 27 09:52:22 2020 us=441257 TCP/UDP: Preserving recently used remote address: [AF_INET]8.43.84.129:1194
48
Tue Oct 27 09:52:22 2020 us=441284 Socket Buffers: R=[212992->212992] S=[212992->212992]
49
Tue Oct 27 09:52:22 2020 us=441293 UDP link local: (not bound)
50
Tue Oct 27 09:52:22 2020 us=441302 UDP link remote: [AF_INET]8.43.84.129:1194
51
Tue Oct 27 09:52:22 2020 us=441310 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
52
Tue Oct 27 09:52:22 2020 us=441325 TIMER: coarse timer wakeup 1 seconds
53
Tue Oct 27 09:52:22 2020 us=441340 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=264bc70d 15fd9b8c, stored-sid=00000000 00000000, stored-ip=[AF_INET]8.43.84.129:1194
54
Tue Oct 27 09:52:22 2020 us=441349 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
55
Tue Oct 27 09:52:22 2020 us=441356 ACK mark active outgoing ID 0
56
Tue Oct 27 09:52:22 2020 us=441365 TLS: Initial Handshake, sid=264bc70d 15fd9b8c
57
Tue Oct 27 09:52:22 2020 us=441374 ACK reliable_can_send active=1 current=1 : [1] 0
58
Tue Oct 27 09:52:22 2020 us=441382 ACK reliable_send ID 0 (size=4 to=2)
59
Tue Oct 27 09:52:22 2020 us=441400 ENCRYPT HMAC: c71c94dc a4ab7942 9b1712f4 cefe6d37 d8415dd5
60
Tue Oct 27 09:52:22 2020 us=441416 ENCRYPT TO: c71c94dc a4ab7942 9b1712f4 cefe6d37 d8415dd5 00000001 5f97d1b6 38264bc[more...]
61
Tue Oct 27 09:52:22 2020 us=441424 Reliable -> TCP/UDP
62
Tue Oct 27 09:52:22 2020 us=441432 NOTE: --mute triggered...
63
Tue Oct 27 09:52:22 2020 us=441449 10 variation(s) on previous 10 message(s) suppressed by --mute
64
Tue Oct 27 09:52:22 2020 us=441471 UDP WRITE [42] to [AF_INET]8.43.84.129:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=264bc70d 15fd9b8c tls_hmac=c71c94dc a4ab7942 9b1712f4 cefe6d37 d8415dd5 pid=[ #1 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ ] pid=0 DATA 
65
Tue Oct 27 09:52:22 2020 us=441528 UDP write returned 42
66
Tue Oct 27 09:52:22 2020 us=441545 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=264bc70d 15fd9b8c, stored-sid=00000000 00000000, stored-ip=[AF_INET]8.43.84.129:1194
67
Tue Oct 27 09:52:22 2020 us=441553 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
68
Tue Oct 27 09:52:22 2020 us=441561 ACK reliable_can_send active=1 current=0 : [1] 0
69
Tue Oct 27 09:52:22 2020 us=441618 SSL state (connect): before SSL initialization
70
Tue Oct 27 09:52:22 2020 us=441785 SSL state (connect): SSLv3/TLS write client hello
71
Tue Oct 27 09:52:22 2020 us=441804 ACK reliable_send_timeout 2 [1] 0
72
Tue Oct 27 09:52:22 2020 us=441813 TLS: tls_process: timeout set to 2
73
Tue Oct 27 09:52:22 2020 us=441824 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
74
Tue Oct 27 09:52:22 2020 us=441836 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
75
Tue Oct 27 09:52:22 2020 us=441875 NOTE: --mute triggered...
76
Tue Oct 27 09:52:22 2020 us=609911 6 variation(s) on previous 10 message(s) suppressed by --mute
77
Tue Oct 27 09:52:22 2020 us=610107 UDP READ [54] from [AF_INET]8.43.84.129:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=8a2724a1 05eadab3 tls_hmac=132e9a50 a183bc4c 07d53e23 2ec44f10 03549dfd pid=[ #1 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 0 sid=264bc70d 15fd9b8c ] pid=0 DATA 
78
Tue Oct 27 09:52:22 2020 us=610148 TLS: control channel, op=P_CONTROL_HARD_RESET_SERVER_V2, IP=[AF_INET]8.43.84.129:1194
79
Tue Oct 27 09:52:22 2020 us=610205 TLS: initial packet test, i=0 state=S_PRE_START, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=00000000 00000000, stored-ip=[AF_INET]8.43.84.129:1194
80
Tue Oct 27 09:52:22 2020 us=610258 TLS: initial packet test, i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
81
Tue Oct 27 09:52:22 2020 us=610333 TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
82
Tue Oct 27 09:52:22 2020 us=610394 TLS: Initial packet from [AF_INET]8.43.84.129:1194, sid=8a2724a1 05eadab3
83
Tue Oct 27 09:52:22 2020 us=610473 DECRYPT FROM: 132e9a50 a183bc4c 07d53e23 2ec44f10 03549dfd 00000001 5f97d1fe 408a272[more...]
84
Tue Oct 27 09:52:22 2020 us=610534 PID_TEST [0] [TLS_WRAP-0] [] 0:0 1603785214:1 t=1603785142[0] r=[0,64,15,0,1] sl=[0,0,64,528]
85
Tue Oct 27 09:52:22 2020 us=610576 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
86
Tue Oct 27 09:52:22 2020 us=610607 ACK reliable_schedule_now
87
Tue Oct 27 09:52:22 2020 us=610794 ACK received for pid 0, deleting from send buffer
88
Tue Oct 27 09:52:22 2020 us=610850 ACK read ID 0 (buf->len=0)
89
Tue Oct 27 09:52:22 2020 us=611009 ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1
90

    
91
Tue Oct 27 09:52:22 2020 us=611040 ACK mark active incoming ID 0
92
Tue Oct 27 09:52:22 2020 us=611059 ACK acknowledge ID 0 (ack->len=1)
93
Tue Oct 27 09:52:22 2020 us=611106 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
94
Tue Oct 27 09:52:22 2020 us=611127 NOTE: --mute triggered...
95
Tue Oct 27 09:52:22 2020 us=611235 18 variation(s) on previous 10 message(s) suppressed by --mute
96
Tue Oct 27 09:52:22 2020 us=611328 UDP WRITE [50] to [AF_INET]8.43.84.129:1194: P_ACK_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=0b7a7bc8 49d244ae 2b114bba 31ed3cd9 84ce163f pid=[ #2 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ 0 sid=8a2724a1 05eadab3 ]
97
Tue Oct 27 09:52:22 2020 us=611426 UDP write returned 50
98
Tue Oct 27 09:52:22 2020 us=611493 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
99
Tue Oct 27 09:52:22 2020 us=611531 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
100
Tue Oct 27 09:52:22 2020 us=611567 STATE S_START
101
Tue Oct 27 09:52:22 2020 us=611659 ACK reliable_can_send active=0 current=0 : [1]
102
Tue Oct 27 09:52:22 2020 us=611845 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
103
Tue Oct 27 09:52:22 2020 us=611928 STATE S_SENT_KEY
104
Tue Oct 27 09:52:22 2020 us=611979 BIO read tls_read_ciphertext 269 bytes
105
Tue Oct 27 09:52:22 2020 us=612012 ACK mark active outgoing ID 1
106
Tue Oct 27 09:52:22 2020 us=612040 Outgoing Ciphertext -> Reliable
107
Tue Oct 27 09:52:22 2020 us=612069 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
108
Tue Oct 27 09:52:22 2020 us=612100 ACK reliable_can_send active=1 current=1 : [2] 1
109
Tue Oct 27 09:52:22 2020 us=612130 ACK reliable_send ID 1 (size=273 to=2)
110
Tue Oct 27 09:52:22 2020 us=612190 ENCRYPT HMAC: 079242e7 17a2f30d f34fe249 01cccf41 2f521e95
111
Tue Oct 27 09:52:22 2020 us=612404 ENCRYPT TO: 079242e7 17a2f30d f34fe249 01cccf41 2f521e95 00000003 5f97d1b6 20264bc[more...]
112
Tue Oct 27 09:52:22 2020 us=612431 Reliable -> TCP/UDP
113
Tue Oct 27 09:52:22 2020 us=612450 NOTE: --mute triggered...
114
Tue Oct 27 09:52:22 2020 us=612480 9 variation(s) on previous 10 message(s) suppressed by --mute
115
Tue Oct 27 09:52:22 2020 us=612665 UDP WRITE [311] to [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=079242e7 17a2f30d f34fe249 01cccf41 2f521e95 pid=[ #3 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ ] pid=1 DATA 16030101 08010001 040303ee c7d5b6d2 d108141e cddbce6d a5ba68d8 b1dd6b1[more...]
116
Tue Oct 27 09:52:22 2020 us=612753 UDP write returned 311
117
Tue Oct 27 09:52:22 2020 us=612795 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
118
Tue Oct 27 09:52:22 2020 us=612816 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
119
Tue Oct 27 09:52:22 2020 us=612836 ACK reliable_can_send active=1 current=0 : [2] 1
120
Tue Oct 27 09:52:22 2020 us=612867 ACK reliable_send_timeout 2 [2] 1
121
Tue Oct 27 09:52:22 2020 us=612903 TLS: tls_process: timeout set to 2
122
Tue Oct 27 09:52:22 2020 us=612976 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
123
Tue Oct 27 09:52:22 2020 us=613008 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
124
Tue Oct 27 09:52:22 2020 us=613030 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
125
Tue Oct 27 09:52:22 2020 us=613067 I/O WAIT T?|T?|SR|Sw [1/132627]
126
Tue Oct 27 09:52:22 2020 us=784139 NOTE: --mute triggered...
127
Tue Oct 27 09:52:22 2020 us=784208 4 variation(s) on previous 10 message(s) suppressed by --mute
128
Tue Oct 27 09:52:22 2020 us=784469 UDP READ [1172] from [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=612691dd e8dbd55f 432ebe26 15c567a3 2141362c pid=[ #2 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 1 sid=264bc70d 15fd9b8c ] pid=1 DATA 16030300 39020000 35030317 dc43477b d6f30623 47ec458a d8ddd42d 
129
Tue Oct 27 09:52:22 2020 us=784486 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]8.43.84.129:1194
130
Tue Oct 27 09:52:22 2020 us=784543 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
131
Tue Oct 27 09:52:22 2020 us=784558 TLS: found match, session[0], sid=8a2724a1 05eadab3
132
Tue Oct 27 09:52:22 2020 us=784815 DECRYPT FROM: 612691dd e8dbd55f 432ebe26 15c567a3 2141362c 00000002 5f97d1fe 208a272[more...]
133
Tue Oct 27 09:52:22 2020 us=784839 PID_TEST [0] [TLS_WRAP-0] [0] 1603785214:1 1603785214:2 t=1603785142[0] r=[0,64,15,0,1] sl=[63,1,64,528]
134
Tue Oct 27 09:52:22 2020 us=784851 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
135
Tue Oct 27 09:52:22 2020 us=784859 ACK received for pid 1, deleting from send buffer
136
Tue Oct 27 09:52:22 2020 us=784867 ACK read ID 1 (buf->len=1118)
137
Tue Oct 27 09:52:22 2020 us=784875 ACK RWBS rel->size=8 rel->packet_id=00000001 id=00000001 ret=1
138

    
139
Tue Oct 27 09:52:22 2020 us=784882 ACK mark active incoming ID 1
140
Tue Oct 27 09:52:22 2020 us=784890 NOTE: --mute triggered...
141
Tue Oct 27 09:52:22 2020 us=785103 8 variation(s) on previous 10 message(s) suppressed by --mute
142
Tue Oct 27 09:52:22 2020 us=785120 VERIFY OK: depth=1, O=Redhat, CN=openvpnca-sepia
143
Tue Oct 27 09:52:22 2020 us=785321 VERIFY KU OK
144
Tue Oct 27 09:52:22 2020 us=785335 Validating certificate extended key usage
145
Tue Oct 27 09:52:22 2020 us=785344 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
146
Tue Oct 27 09:52:22 2020 us=785352 VERIFY EKU OK
147
Tue Oct 27 09:52:22 2020 us=785359 VERIFY OK: depth=0, O=Redhat, CN=openvpn-sepia
148
Tue Oct 27 09:52:22 2020 us=785378 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
149
Tue Oct 27 09:52:22 2020 us=785388 ACK reliable_can_send active=0 current=0 : [2]
150
Tue Oct 27 09:52:22 2020 us=785397 ACK write ID 1 (ack->len=1, n=1)
151
Tue Oct 27 09:52:22 2020 us=785412 ENCRYPT HMAC: e0c49598 0e3166df 3e001f12 54e6c7f3 baf5a3ea
152
Tue Oct 27 09:52:22 2020 us=785430 ENCRYPT TO: e0c49598 0e3166df 3e001f12 54e6c7f3 baf5a3ea 00000004 5f97d1b6 28264bc[more...]
153
Tue Oct 27 09:52:22 2020 us=785438 Dedicated ACK -> TCP/UDP
154
Tue Oct 27 09:52:22 2020 us=785445 ACK reliable_send_timeout 604800 [2]
155
Tue Oct 27 09:52:22 2020 us=785453 TLS: tls_process: timeout set to 60
156
Tue Oct 27 09:52:22 2020 us=785465 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
157
Tue Oct 27 09:52:22 2020 us=785476 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
158
Tue Oct 27 09:52:22 2020 us=785485 NOTE: --mute triggered...
159
Tue Oct 27 09:52:22 2020 us=785497 5 variation(s) on previous 10 message(s) suppressed by --mute
160
Tue Oct 27 09:52:22 2020 us=785521 UDP WRITE [50] to [AF_INET]8.43.84.129:1194: P_ACK_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=e0c49598 0e3166df 3e001f12 54e6c7f3 baf5a3ea pid=[ #4 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ 1 sid=8a2724a1 05eadab3 ]
161
Tue Oct 27 09:52:22 2020 us=785595 UDP write returned 50
162
Tue Oct 27 09:52:22 2020 us=785617 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
163
Tue Oct 27 09:52:22 2020 us=785627 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
164
Tue Oct 27 09:52:22 2020 us=785636 ACK reliable_can_send active=0 current=0 : [2]
165
Tue Oct 27 09:52:22 2020 us=785648 ACK reliable_send_timeout 604800 [2]
166
Tue Oct 27 09:52:22 2020 us=785658 TLS: tls_process: timeout set to 60
167
Tue Oct 27 09:52:22 2020 us=785671 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
168
Tue Oct 27 09:52:22 2020 us=785697 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
169
Tue Oct 27 09:52:22 2020 us=785709 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
170
Tue Oct 27 09:52:22 2020 us=785722 I/O WAIT T?|T?|SR|Sw [1/132627]
171
Tue Oct 27 09:52:22 2020 us=785735 NOTE: --mute triggered...
172
Tue Oct 27 09:52:22 2020 us=785750 4 variation(s) on previous 10 message(s) suppressed by --mute
173
Tue Oct 27 09:52:22 2020 us=785844 UDP READ [336] from [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=8c773015 7610c849 401ebc3f 6a93b4c3 dcee79f3 pid=[ #3 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ ] pid=2 DATA 1a656508 e93095f3 7b9503ff 0cf52d69 4e29235f 0ecd007c ed82711b 87e9628[more...]
174
Tue Oct 27 09:52:22 2020 us=785855 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]8.43.84.129:1194
175
Tue Oct 27 09:52:22 2020 us=785872 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
176
Tue Oct 27 09:52:22 2020 us=785883 TLS: found match, session[0], sid=8a2724a1 05eadab3
177
Tue Oct 27 09:52:22 2020 us=785998 DECRYPT FROM: 8c773015 7610c849 401ebc3f 6a93b4c3 dcee79f3 00000003 5f97d1fe 208a272[more...]
178
Tue Oct 27 09:52:22 2020 us=786019 PID_TEST [0] [TLS_WRAP-0] [00] 1603785214:2 1603785214:3 t=1603785142[0] r=[0,64,15,0,1] sl=[62,2,64,528]
179
Tue Oct 27 09:52:22 2020 us=786031 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
180
Tue Oct 27 09:52:22 2020 us=786040 ACK read ID 2 (buf->len=294)
181
Tue Oct 27 09:52:22 2020 us=786050 ACK RWBS rel->size=8 rel->packet_id=00000002 id=00000002 ret=1
182

    
183
Tue Oct 27 09:52:22 2020 us=786059 ACK mark active incoming ID 2
184
Tue Oct 27 09:52:22 2020 us=786068 ACK acknowledge ID 2 (ack->len=1)
185
Tue Oct 27 09:52:22 2020 us=786078 NOTE: --mute triggered...
186
Tue Oct 27 09:52:22 2020 us=786652 30 variation(s) on previous 10 message(s) suppressed by --mute
187
Tue Oct 27 09:52:22 2020 us=786738 UDP WRITE [180] to [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=d6cf123c 9e6b5241 78a42d11 15839497 80534813 pid=[ #5 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ 2 sid=8a2724a1 05eadab3 ] pid=2 DATA 16030300 46100000 424104f2 4614d2c3 00f0fb2a caacf971 53b494cc 
188
Tue Oct 27 09:52:22 2020 us=786772 UDP write returned 180
189
Tue Oct 27 09:52:22 2020 us=786791 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
190
Tue Oct 27 09:52:22 2020 us=786801 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
191
Tue Oct 27 09:52:22 2020 us=786811 ACK reliable_can_send active=1 current=0 : [3] 2
192
Tue Oct 27 09:52:22 2020 us=786825 ACK reliable_send_timeout 2 [3] 2
193
Tue Oct 27 09:52:22 2020 us=786834 TLS: tls_process: timeout set to 2
194
Tue Oct 27 09:52:22 2020 us=786848 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
195
Tue Oct 27 09:52:22 2020 us=786861 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
196
Tue Oct 27 09:52:22 2020 us=786879 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
197
Tue Oct 27 09:52:22 2020 us=786892 I/O WAIT T?|T?|SR|Sw [1/132627]
198
Tue Oct 27 09:52:22 2020 us=958933 NOTE: --mute triggered...
199
Tue Oct 27 09:52:22 2020 us=959082 4 variation(s) on previous 10 message(s) suppressed by --mute
200
Tue Oct 27 09:52:22 2020 us=959192 UDP READ [105] from [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=c225ed6f fc6ef487 cc5d18fe cbdeb7a8 56ce0c1e pid=[ #4 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 2 sid=264bc70d 15fd9b8c ] pid=3 DATA 14030300 01011603 03002852 9a80e66a d5883e7f add3488a 8013020f 
201
Tue Oct 27 09:52:22 2020 us=959219 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]8.43.84.129:1194
202
Tue Oct 27 09:52:22 2020 us=959258 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
203
Tue Oct 27 09:52:22 2020 us=959282 TLS: found match, session[0], sid=8a2724a1 05eadab3
204
Tue Oct 27 09:52:22 2020 us=959355 DECRYPT FROM: c225ed6f fc6ef487 cc5d18fe cbdeb7a8 56ce0c1e 00000004 5f97d1fe 208a272[more...]
205
Tue Oct 27 09:52:22 2020 us=959400 PID_TEST [0] [TLS_WRAP-0] [000] 1603785214:3 1603785214:4 t=1603785142[0] r=[0,64,15,0,1] sl=[61,3,64,528]
206
Tue Oct 27 09:52:22 2020 us=959443 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
207
Tue Oct 27 09:52:22 2020 us=959474 ACK received for pid 2, deleting from send buffer
208
Tue Oct 27 09:52:22 2020 us=959521 ACK read ID 3 (buf->len=51)
209
Tue Oct 27 09:52:22 2020 us=959549 ACK RWBS rel->size=8 rel->packet_id=00000003 id=00000003 ret=1
210

    
211
Tue Oct 27 09:52:22 2020 us=959579 ACK mark active incoming ID 3
212
Tue Oct 27 09:52:22 2020 us=959609 NOTE: --mute triggered...
213
Tue Oct 27 09:52:22 2020 us=959920 30 variation(s) on previous 10 message(s) suppressed by --mute
214
Tue Oct 27 09:52:22 2020 us=960248 UDP WRITE [581] to [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=38b67e4d c925428a 61e8bcf3 c56094ef 2a949ad4 pid=[ #6 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ 3 sid=8a2724a1 05eadab3 ] pid=3 DATA 17030302 0ad308b9 50e896e4 a2949189 c94616e3 1e5b6a7f 9ef569c0 
215
Tue Oct 27 09:52:22 2020 us=960336 UDP write returned 581
216
Tue Oct 27 09:52:22 2020 us=960379 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
217
Tue Oct 27 09:52:22 2020 us=960402 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
218
Tue Oct 27 09:52:22 2020 us=960423 ACK reliable_can_send active=1 current=0 : [4] 3
219
Tue Oct 27 09:52:22 2020 us=960450 ACK reliable_send_timeout 2 [4] 3
220
Tue Oct 27 09:52:22 2020 us=960469 TLS: tls_process: timeout set to 2
221
Tue Oct 27 09:52:22 2020 us=960497 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
222
Tue Oct 27 09:52:22 2020 us=960525 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
223
Tue Oct 27 09:52:22 2020 us=960547 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
224
Tue Oct 27 09:52:22 2020 us=960573 I/O WAIT T?|T?|SR|Sw [1/132627]
225
Tue Oct 27 09:52:24 2020 us=94795 NOTE: --mute triggered...
226
Tue Oct 27 09:52:24 2020 us=94954 19 variation(s) on previous 10 message(s) suppressed by --mute
227
Tue Oct 27 09:52:24 2020 us=95380 UDP WRITE [569] to [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=04e0257c 06960f83 4694e1a7 ca253d2f 90658e88 pid=[ #7 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ ] pid=3 DATA 17030302 0ad308b9 50e896e4 a2949189 c94616e3 1e5b6a7f 9ef569c0 d2e5830[more...]
228
Tue Oct 27 09:52:24 2020 us=95552 UDP write returned 569
229
Tue Oct 27 09:52:24 2020 us=95637 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
230
Tue Oct 27 09:52:24 2020 us=95743 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
231
Tue Oct 27 09:52:24 2020 us=95817 ACK reliable_can_send active=1 current=0 : [4] 3
232
Tue Oct 27 09:52:24 2020 us=95860 ACK reliable_send_timeout 4 [4] 3
233
Tue Oct 27 09:52:24 2020 us=95880 TLS: tls_process: timeout set to 4
234
Tue Oct 27 09:52:24 2020 us=95911 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
235
Tue Oct 27 09:52:24 2020 us=95939 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
236
Tue Oct 27 09:52:24 2020 us=95963 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
237
Tue Oct 27 09:52:24 2020 us=95989 I/O WAIT T?|T?|SR|Sw [1/132627]
238
Tue Oct 27 09:52:24 2020 us=168967 NOTE: --mute triggered...
239
Tue Oct 27 09:52:24 2020 us=169082 4 variation(s) on previous 10 message(s) suppressed by --mute
240
Tue Oct 27 09:52:24 2020 us=169277 UDP READ [302] from [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=62048a6e 3e1165f5 96a37d37 d0afc685 650b81ab pid=[ #5 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 3 sid=264bc70d 15fd9b8c ] pid=4 DATA 17030300 f3529a80 e66ad588 3f4f1187 f2f659b6 140e0e2f a700e95c 
241
Tue Oct 27 09:52:24 2020 us=169304 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]8.43.84.129:1194
242
Tue Oct 27 09:52:24 2020 us=169343 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
243
Tue Oct 27 09:52:24 2020 us=169366 TLS: found match, session[0], sid=8a2724a1 05eadab3
244
Tue Oct 27 09:52:24 2020 us=169528 DECRYPT FROM: 62048a6e 3e1165f5 96a37d37 d0afc685 650b81ab 00000005 5f97d1fe 208a272[more...]
245
Tue Oct 27 09:52:24 2020 us=169568 PID_TEST [0] [TLS_WRAP-0] [2222] 1603785214:4 1603785214:5 t=1603785144[0] r=[-2,64,15,0,1] sl=[60,4,64,528]
246
Tue Oct 27 09:52:24 2020 us=169593 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
247
Tue Oct 27 09:52:24 2020 us=169613 ACK received for pid 3, deleting from send buffer
248
Tue Oct 27 09:52:24 2020 us=169631 ACK read ID 4 (buf->len=248)
249
Tue Oct 27 09:52:24 2020 us=169650 ACK RWBS rel->size=8 rel->packet_id=00000004 id=00000004 ret=1
250

    
251
Tue Oct 27 09:52:24 2020 us=169722 ACK mark active incoming ID 4
252
Tue Oct 27 09:52:24 2020 us=169746 NOTE: --mute triggered...
253
Tue Oct 27 09:52:24 2020 us=169844 24 variation(s) on previous 10 message(s) suppressed by --mute
254
Tue Oct 27 09:52:24 2020 us=169908 UDP WRITE [50] to [AF_INET]8.43.84.129:1194: P_ACK_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=cd335090 88a7698a 7d5c9bf8 2f4c6b66 d0763b13 pid=[ #8 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ 4 sid=8a2724a1 05eadab3 ]
255
Tue Oct 27 09:52:24 2020 us=170002 UDP write returned 50
256
Tue Oct 27 09:52:24 2020 us=170044 TLS: tls_multi_process: i=0 state=S_GOT_KEY, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
257
Tue Oct 27 09:52:24 2020 us=170065 TLS: tls_process: chg=0 ks=S_GOT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
258
Tue Oct 27 09:52:24 2020 us=170083 STATE S_ACTIVE
259
Tue Oct 27 09:52:24 2020 us=170114 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 2432 bit RSA
260
Tue Oct 27 09:52:24 2020 us=170157 [openvpn-sepia] Peer Connection Initiated with [AF_INET]8.43.84.129:1194
261
Tue Oct 27 09:52:24 2020 us=170183 ACK reliable_can_send active=0 current=0 : [4]
262
Tue Oct 27 09:52:24 2020 us=170208 TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800
263
Tue Oct 27 09:52:24 2020 us=170228 ACK reliable_can_send active=0 current=0 : [4]
264
Tue Oct 27 09:52:24 2020 us=170248 ACK reliable_send_timeout 604800 [4]
265
Tue Oct 27 09:52:24 2020 us=170266 TLS: tls_process: timeout set to 58
266
Tue Oct 27 09:52:24 2020 us=170294 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
267
Tue Oct 27 09:52:24 2020 us=170320 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
268
Tue Oct 27 09:52:24 2020 us=170342 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
269
Tue Oct 27 09:52:24 2020 us=170394 I/O WAIT T?|T?|SR|Sw [1/132627]
270
Tue Oct 27 09:52:25 2020 us=258749 PO_WAIT[0,0] fd=3 rev=0x00000001 rwflags=0x0001 arg=0x55f4b769f1d0 
271
Tue Oct 27 09:52:25 2020 us=258862 NOTE: --mute triggered...
272
Tue Oct 27 09:52:25 2020 us=258925 3 variation(s) on previous 10 message(s) suppressed by --mute
273
Tue Oct 27 09:52:25 2020 us=259050 UDP READ [50] from [AF_INET]8.43.84.129:1194: P_ACK_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=d8e3266c 19ee0bc5 9de8a3e6 857b5c27 7b60d61c pid=[ #6 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 3 sid=264bc70d 15fd9b8c ]
274
Tue Oct 27 09:52:25 2020 us=259113 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]8.43.84.129:1194
275
Tue Oct 27 09:52:25 2020 us=259167 TLS: initial packet test, i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
276
Tue Oct 27 09:52:25 2020 us=259193 TLS: found match, session[0], sid=8a2724a1 05eadab3
277
Tue Oct 27 09:52:25 2020 us=259242 DECRYPT FROM: d8e3266c 19ee0bc5 9de8a3e6 857b5c27 7b60d61c 00000006 5f97d1fe 288a272[more...]
278
Tue Oct 27 09:52:25 2020 us=259282 PID_TEST [0] [TLS_WRAP-0] [13333] 1603785214:5 1603785214:6 t=1603785145[0] r=[-3,64,15,0,1] sl=[59,5,64,528]
279
Tue Oct 27 09:52:25 2020 us=259307 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
280
Tue Oct 27 09:52:25 2020 us=259365 BIO write tls_write_plaintext_const 13 bytes
281
Tue Oct 27 09:52:25 2020 us=259392 SENT CONTROL [openvpn-sepia]: 'PUSH_REQUEST' (status=1)
282
Tue Oct 27 09:52:25 2020 us=259412 TIMER: coarse timer wakeup 0 seconds
283
Tue Oct 27 09:52:25 2020 us=259443 TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
284
Tue Oct 27 09:52:25 2020 us=259465 TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800
285
Tue Oct 27 09:52:25 2020 us=259486 ACK reliable_can_send active=0 current=0 : [4]
286
Tue Oct 27 09:52:25 2020 us=259512 BIO read tls_read_ciphertext 42 bytes
287
Tue Oct 27 09:52:25 2020 us=259531 ACK mark active outgoing ID 4
288
Tue Oct 27 09:52:25 2020 us=259549 Outgoing Ciphertext -> Reliable
289
Tue Oct 27 09:52:25 2020 us=259567 TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800
290
Tue Oct 27 09:52:25 2020 us=259586 ACK reliable_can_send active=1 current=1 : [5] 4
291
Tue Oct 27 09:52:25 2020 us=259605 ACK reliable_send ID 4 (size=46 to=2)
292
Tue Oct 27 09:52:25 2020 us=259629 NOTE: --mute triggered...
293
Tue Oct 27 09:52:25 2020 us=259712 12 variation(s) on previous 10 message(s) suppressed by --mute
294
Tue Oct 27 09:52:25 2020 us=259796 UDP WRITE [84] to [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=264bc70d 15fd9b8c tls_hmac=e3f800e9 5c4dc373 5eef0259 033ee60c c6ec76b4 pid=[ #9 / time = (1603785142) Tue Oct 27 09:52:22 2020 ] [ ] pid=4 DATA 17030300 25d308b9 50e896e4 a3bbe157 aa1e45f9 2e26fa8a 88251f3a af02024[more...]
295
Tue Oct 27 09:52:25 2020 us=259894 UDP write returned 84
296
Tue Oct 27 09:52:25 2020 us=259924 TIMER: coarse timer wakeup 5 seconds
297
Tue Oct 27 09:52:25 2020 us=259956 TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
298
Tue Oct 27 09:52:25 2020 us=259976 TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800
299
Tue Oct 27 09:52:25 2020 us=259996 ACK reliable_can_send active=1 current=0 : [5] 4
300
Tue Oct 27 09:52:25 2020 us=260021 ACK reliable_send_timeout 2 [5] 4
301
Tue Oct 27 09:52:25 2020 us=260041 TLS: tls_process: timeout set to 2
302
Tue Oct 27 09:52:25 2020 us=260068 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d9475775 0e3f2ef3, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
303
Tue Oct 27 09:52:25 2020 us=260095 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC]
304
Tue Oct 27 09:52:25 2020 us=260117 PO_CTL rwflags=0x0001 ev=3 arg=0x55f4b769f1d0
305
Tue Oct 27 09:52:25 2020 us=260135 NOTE: --mute triggered...
306
Tue Oct 27 09:52:25 2020 us=493551 5 variation(s) on previous 10 message(s) suppressed by --mute
307
Tue Oct 27 09:52:25 2020 us=493894 UDP READ [50] from [AF_INET]8.43.84.129:1194: P_ACK_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=464f5037 96419f8c 27c6a687 1d3a8a2f 06b76789 pid=[ #7 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ 4 sid=264bc70d 15fd9b8c ]
308
Tue Oct 27 09:52:25 2020 us=493966 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]8.43.84.129:1194
309
Tue Oct 27 09:52:25 2020 us=494016 TLS: initial packet test, i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
310
Tue Oct 27 09:52:25 2020 us=494041 TLS: found match, session[0], sid=8a2724a1 05eadab3
311
Tue Oct 27 09:52:25 2020 us=494090 DECRYPT FROM: 464f5037 96419f8c 27c6a687 1d3a8a2f 06b76789 00000007 5f97d1fe 288a272[more...]
312
Tue Oct 27 09:52:25 2020 us=494131 PID_TEST [0] [TLS_WRAP-0] [013333] 1603785214:6 1603785214:7 t=1603785145[0] r=[-3,64,15,0,1] sl=[58,6,64,528]
313
Tue Oct 27 09:52:25 2020 us=494156 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
314
Tue Oct 27 09:52:25 2020 us=494177 ACK received for pid 4, deleting from send buffer
315
Tue Oct 27 09:52:25 2020 us=494215 TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
316
Tue Oct 27 09:52:25 2020 us=494238 TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800
317
Tue Oct 27 09:52:25 2020 us=494258 ACK reliable_can_send active=0 current=0 : [5]
318
Tue Oct 27 09:52:25 2020 us=494294 NOTE: --mute triggered...
319
Tue Oct 27 09:52:25 2020 us=494337 10 variation(s) on previous 10 message(s) suppressed by --mute
320
Tue Oct 27 09:52:25 2020 us=494409 UDP READ [83] from [AF_INET]8.43.84.129:1194: P_CONTROL_V1 kid=0 sid=8a2724a1 05eadab3 tls_hmac=1b7e51d1 60ba4b26 0e068890 8b53ced8 db05731d pid=[ #8 / time = (1603785214) Tue Oct 27 09:53:34 2020 ] [ ] pid=5 DATA 17030300 24529a80 e66ad588 403fe0cc 129eeca9 9d13b603 7cd2dedf f6558ac[more...]
321
Tue Oct 27 09:52:25 2020 us=494431 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]8.43.84.129:1194
322
Tue Oct 27 09:52:25 2020 us=494466 TLS: initial packet test, i=0 state=S_ACTIVE, mysid=264bc70d 15fd9b8c, rec-sid=8a2724a1 05eadab3, rec-ip=[AF_INET]8.43.84.129:1194, stored-sid=8a2724a1 05eadab3, stored-ip=[AF_INET]8.43.84.129:1194
323
Tue Oct 27 09:52:25 2020 us=494488 TLS: found match, session[0], sid=8a2724a1 05eadab3
324
Tue Oct 27 09:52:25 2020 us=494546 DECRYPT FROM: 1b7e51d1 60ba4b26 0e068890 8b53ced8 db05731d 00000008 5f97d1fe 208a272[more...]
325
Tue Oct 27 09:52:25 2020 us=494576 PID_TEST [0] [TLS_WRAP-0] [0013333] 1603785214:7 1603785214:8 t=1603785145[0] r=[-3,64,15,0,1] sl=[57,7,64,528]
326
Tue Oct 27 09:52:25 2020 us=494599 TLS: received control channel packet s#=0 sid=8a2724a1 05eadab3
327
Tue Oct 27 09:52:25 2020 us=494618 ACK read ID 5 (buf->len=41)
328
Tue Oct 27 09:52:25 2020 us=494638 ACK RWBS rel->size=8 rel->packet_id=00000005 id=00000005 ret=1
329

    
330
Tue Oct 27 09:52:25 2020 us=494706 ACK mark active incoming ID 5
331
Tue Oct 27 09:52:25 2020 us=494731 ACK acknowledge ID 5 (ack->len=1)
332
Tue Oct 27 09:52:25 2020 us=494753 NOTE: --mute triggered...
333
Tue Oct 27 09:52:25 2020 us=494806 17 variation(s) on previous 10 message(s) suppressed by --mute
334
Tue Oct 27 09:52:25 2020 us=494830 AUTH: Received control message: AUTH_FAILED
335
Tue Oct 27 09:52:25 2020 us=494921 PID packet_id_free
336
Tue Oct 27 09:52:25 2020 us=494967 SSL alert (write): warning: close notify
337
Tue Oct 27 09:52:25 2020 us=495041 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55f4b8aa6e30, ptr=(nil), ad=0x55f4b8aa6e98, idx=1, argl=0, argp=0x7f58354b5683
338
Tue Oct 27 09:52:25 2020 us=495106 PID packet_id_free
339
Tue Oct 27 09:52:25 2020 us=495130 PID packet_id_free
340
Tue Oct 27 09:52:25 2020 us=495150 PID packet_id_free
341
Tue Oct 27 09:52:25 2020 us=495186 PID packet_id_free
342
Tue Oct 27 09:52:25 2020 us=495206 PID packet_id_free
343
Tue Oct 27 09:52:25 2020 us=495224 PID packet_id_free
344
Tue Oct 27 09:52:25 2020 us=495241 PID packet_id_free
345
Tue Oct 27 09:52:25 2020 us=495277 NOTE: --mute triggered...
346
Tue Oct 27 09:52:25 2020 us=495340 1 variation(s) on previous 10 message(s) suppressed by --mute
347
Tue Oct 27 09:52:25 2020 us=495363 TCP/UDP: Closing socket
348
Tue Oct 27 09:52:25 2020 us=495409 PID packet_id_free
349
Tue Oct 27 09:52:25 2020 us=495441 SIGTERM[soft,auth-failure] received, process exiting
350
Tue Oct 27 09:52:25 2020 us=495471 PKCS#11: pkcs11_terminate - entered
351
Tue Oct 27 09:52:25 2020 us=495492 PKCS#11: pkcs11h_terminate entry
352
Tue Oct 27 09:52:25 2020 us=495511 PKCS#11: Terminating openssl
353
Tue Oct 27 09:52:25 2020 us=495529 PKCS#11: _pkcs11h_openssl_terminate
354
Tue Oct 27 09:52:25 2020 us=495550 PKCS#11: Removing providers
355
Tue Oct 27 09:52:25 2020 us=495569 PKCS#11: Releasing sessions
356
Tue Oct 27 09:52:25 2020 us=495587 PKCS#11: Terminating slotevent
357
Tue Oct 27 09:52:25 2020 us=495605 PKCS#11: _pkcs11h_slotevent_terminate entry
358
Tue Oct 27 09:52:25 2020 us=495623 PKCS#11: _pkcs11h_slotevent_terminate return
359
Tue Oct 27 09:52:25 2020 us=495640 PKCS#11: Marking as uninitialized
360
Tue Oct 27 09:52:25 2020 us=495717 NOTE: --mute triggered...