Mon Jan 6 09:29:49 2020 us=695463 WARNING: file '/etc/openvpn/keys/2019ca/server2019.key' is group or others accessible Mon Jan 6 09:29:49 2020 us=695599 Current Parameter Settings: Mon Jan 6 09:29:49 2020 us=695608 config = '/etc/openvpn/old_openvpn19.conf' Mon Jan 6 09:29:49 2020 us=695619 mode = 1 Mon Jan 6 09:29:49 2020 us=695625 persist_config = DISABLED Mon Jan 6 09:29:49 2020 us=695631 persist_mode = 1 Mon Jan 6 09:29:49 2020 us=695636 show_ciphers = DISABLED Mon Jan 6 09:29:49 2020 us=695641 show_digests = DISABLED Mon Jan 6 09:29:49 2020 us=695647 show_engines = DISABLED Mon Jan 6 09:29:49 2020 us=695652 genkey = DISABLED Mon Jan 6 09:29:49 2020 us=695658 key_pass_file = '[UNDEF]' Mon Jan 6 09:29:49 2020 us=695663 show_tls_ciphers = DISABLED Mon Jan 6 09:29:49 2020 us=695669 connect_retry_max = 0 Mon Jan 6 09:29:49 2020 us=695674 Connection profiles [0]: Mon Jan 6 09:29:49 2020 us=695680 proto = tcp-server Mon Jan 6 09:29:49 2020 us=695686 local = 'test.atnas.eu' Mon Jan 6 09:29:49 2020 us=695691 local_port = '443' Mon Jan 6 09:29:49 2020 us=695697 remote = '[UNDEF]' Mon Jan 6 09:29:49 2020 us=695702 remote_port = '443' Mon Jan 6 09:29:49 2020 us=695707 remote_float = DISABLED Mon Jan 6 09:29:49 2020 us=695713 bind_defined = DISABLED Mon Jan 6 09:29:49 2020 us=695719 NOTE: --mute triggered... Mon Jan 6 09:29:49 2020 us=695742 283 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:49 2020 us=695749 OpenVPN 2.4.4 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on May 14 2019 Mon Jan 6 09:29:49 2020 us=695766 library versions: OpenSSL 1.1.1 11 Sep 2018, LZO 2.08 Mon Jan 6 09:29:49 2020 us=695839 PO_INIT maxevents=1 flags=0x00000002 Mon Jan 6 09:29:49 2020 us=696008 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:2000 Mon Jan 6 09:29:49 2020 us=696049 PKCS#11: pkcs11_initialize - entered Mon Jan 6 09:29:49 2020 us=696129 PKCS#11: pkcs11_initialize - return 0-'CKR_OK' Mon Jan 6 09:29:49 2020 us=696720 Diffie-Hellman initialized with 4096 bit key Mon Jan 6 09:29:49 2020 us=697395 PRNG init md=SHA1 size=36 Mon Jan 6 09:29:49 2020 us=697440 TLS-Auth MTU parms [ L:1624 D:1210 EF:40 EB:0 ET:0 EL:3 ] Mon Jan 6 09:29:49 2020 us=697450 MTU DYNAMIC mtu=1450, flags=2, 1624 -> 1450 Mon Jan 6 09:29:49 2020 us=697606 GETADDRINFO flags=0x0423 ai_family=0 ai_socktype=1 Mon Jan 6 09:29:49 2020 us=698992 ROUTE_GATEWAY xxx.xxx.xxx.xxx/255.255.252.0 IFACE=eth0 Mon Jan 6 09:29:49 2020 us=703057 TUN/TAP device tun0 opened Mon Jan 6 09:29:49 2020 us=703109 TUN/TAP TX queue length set to 100 Mon Jan 6 09:29:49 2020 us=703128 do_ifconfig, tt->did_ifconfig_ipv6_setup=0 Mon Jan 6 09:29:49 2020 us=703143 /sbin/ip link set dev tun0 up mtu 1500 Mon Jan 6 09:29:49 2020 us=703633 PKCS#11: __pkcs11h_forkFixup entry pid=22456, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=703719 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=710359 /sbin/ip addr add dev tun0 local 10.98.1.1 peer 10.98.1.2 Mon Jan 6 09:29:49 2020 us=710806 PKCS#11: __pkcs11h_forkFixup entry pid=22457, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=710868 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=716340 /sbin/ip route add 10.198.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=716762 PKCS#11: __pkcs11h_forkFixup entry pid=22458, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=716827 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=718017 /sbin/ip route add 10.99.10.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=718413 PKCS#11: __pkcs11h_forkFixup entry pid=22459, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=718489 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=721607 /sbin/ip route add 192.168.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=722087 PKCS#11: __pkcs11h_forkFixup entry pid=22460, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=722148 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=723194 /sbin/ip route add 10.99.11.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=723647 PKCS#11: __pkcs11h_forkFixup entry pid=22461, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=723729 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=726982 /sbin/ip route add 10.99.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=727429 PKCS#11: __pkcs11h_forkFixup entry pid=22463, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=727488 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=733843 /sbin/ip route add 10.99.33.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=734246 PKCS#11: __pkcs11h_forkFixup entry pid=22465, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=734315 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=735236 /sbin/ip route add 10.99.2.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=735588 PKCS#11: __pkcs11h_forkFixup entry pid=22466, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=735647 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=738689 /sbin/ip route add 10.99.3.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=739046 PKCS#11: __pkcs11h_forkFixup entry pid=22468, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=739103 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=744129 /sbin/ip route add 10.99.99.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=744509 PKCS#11: __pkcs11h_forkFixup entry pid=22470, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=744581 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=745498 /sbin/ip route add 10.99.5.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=745860 PKCS#11: __pkcs11h_forkFixup entry pid=22471, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=745918 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=746816 /sbin/ip route add 10.99.113.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=747123 PKCS#11: __pkcs11h_forkFixup entry pid=22472, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=747176 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=751125 /sbin/ip route add 10.88.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=751473 PKCS#11: __pkcs11h_forkFixup entry pid=22473, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=751529 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=752528 /sbin/ip route add 10.99.55.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=752866 PKCS#11: __pkcs11h_forkFixup entry pid=22474, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=752938 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=753865 /sbin/ip route add 10.66.99.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=756169 PKCS#11: __pkcs11h_forkFixup entry pid=22475, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=756234 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=757146 /sbin/ip route add 10.99.4.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=757464 PKCS#11: __pkcs11h_forkFixup entry pid=22476, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=757518 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=760309 /sbin/ip route add 10.77.55.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=760638 PKCS#11: __pkcs11h_forkFixup entry pid=22477, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=760694 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=765021 /sbin/ip route add 10.75.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=766391 PKCS#11: __pkcs11h_forkFixup entry pid=22478, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=766461 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=770205 /sbin/ip route add 10.99.6.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=770599 PKCS#11: __pkcs11h_forkFixup entry pid=22480, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=770670 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=771755 /sbin/ip route add 10.98.1.0/24 via 10.98.1.2 Mon Jan 6 09:29:49 2020 us=773306 PKCS#11: __pkcs11h_forkFixup entry pid=22481, activate_slotevent=1 Mon Jan 6 09:29:49 2020 us=773372 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:29:49 2020 us=774433 Data Channel MTU parms [ L:1624 D:1450 EF:124 EB:406 ET:0 EL:3 ] Mon Jan 6 09:29:49 2020 us=774474 STREAM: RESET Mon Jan 6 09:29:49 2020 us=774482 STREAM: INIT maxlen=1627 Mon Jan 6 09:29:49 2020 us=774490 Could not determine IPv4/IPv6 protocol. Using AF_INET Mon Jan 6 09:29:49 2020 us=774513 Socket Buffers: R=[87380->87380] S=[16384->16384] Mon Jan 6 09:29:49 2020 us=774541 Listening for incoming TCP connection on [AF_INET]46.232.250.141:443 Mon Jan 6 09:29:49 2020 us=774553 TCPv4_SERVER link local (bound): [AF_INET]46.232.250.141:443 Mon Jan 6 09:29:49 2020 us=774560 TCPv4_SERVER link remote: [AF_UNSPEC] Mon Jan 6 09:29:49 2020 us=774580 MULTI: multi_init called, r=256 v=256 Mon Jan 6 09:29:49 2020 us=774611 IFCONFIG POOL: base=10.98.1.4 size=62, ipv6=0 Mon Jan 6 09:29:49 2020 us=774626 EP_INIT maxevents=104 flags=0x00000000 Mon Jan 6 09:29:49 2020 us=774638 MULTI: TCP INIT maxclients=100 maxevents=104 Mon Jan 6 09:29:49 2020 us=774665 Initialization Sequence Completed Mon Jan 6 09:29:49 2020 us=774678 SCHEDULE: schedule_find_least NULL Mon Jan 6 09:29:49 2020 us=774685 EP_CTL fd=7 rwflags=0x0001 ev=0x00000001 arg=0x00000001 Mon Jan 6 09:29:49 2020 us=774696 EP_CTL fd=6 rwflags=0x0001 ev=0x00000001 arg=0x00000002 Mon Jan 6 09:29:49 2020 us=774706 EP_CTL fd=3 rwflags=0x0001 ev=0x00000001 arg=0x00000004 Mon Jan 6 09:29:56 2020 us=871309 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x00000001 Mon Jan 6 09:29:56 2020 us=871498 MULTI: REAP range 0 -> 16 Mon Jan 6 09:29:56 2020 us=871543 MULTI: multi_create_instance called Mon Jan 6 09:29:56 2020 us=871699 PO_INIT maxevents=4 flags=0x00000002 Mon Jan 6 09:29:56 2020 us=871731 Re-using SSL/TLS context Mon Jan 6 09:29:56 2020 us=871780 LZO compression initializing Mon Jan 6 09:29:56 2020 us=871832 TLS: tls_session_init: entry Mon Jan 6 09:29:56 2020 us=871906 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:56 2020 us=872160 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:56 2020 us=872204 TLS: tls_session_init: new session object, sid=06095dc4 d3c8a4da Mon Jan 6 09:29:56 2020 us=872220 TLS: tls_session_init: entry Mon Jan 6 09:29:56 2020 us=872252 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:56 2020 us=872375 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:56 2020 us=872393 TLS: tls_session_init: new session object, sid=b9f93e50 a9869492 Mon Jan 6 09:29:56 2020 us=872411 Control Channel MTU parms [ L:1624 D:1210 EF:40 EB:0 ET:0 EL:3 ] Mon Jan 6 09:29:56 2020 us=872465 MTU DYNAMIC mtu=1450, flags=2, 1624 -> 1450 Mon Jan 6 09:29:56 2020 us=872485 Data Channel MTU parms [ L:1624 D:1450 EF:124 EB:406 ET:0 EL:3 ] Mon Jan 6 09:29:56 2020 us=872557 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 40 bytes Mon Jan 6 09:29:56 2020 us=872578 calc_options_string_link_mtu: link-mtu 1624 -> 1544 Mon Jan 6 09:29:56 2020 us=872638 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 40 bytes Mon Jan 6 09:29:56 2020 us=872650 calc_options_string_link_mtu: link-mtu 1624 -> 1544 Mon Jan 6 09:29:56 2020 us=872668 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_SERVER,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server' Mon Jan 6 09:29:56 2020 us=872680 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client' Mon Jan 6 09:29:56 2020 us=872693 STREAM: RESET Mon Jan 6 09:29:56 2020 us=872703 STREAM: INIT maxlen=1627 Mon Jan 6 09:29:56 2020 us=872759 TCP connection established with [AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:56 2020 us=872773 TCPv4_SERVER link local: (not bound) Mon Jan 6 09:29:56 2020 us=872785 TCPv4_SERVER link remote: [AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:56 2020 us=872844 xxx.xxx.xxx.xxx:57577 SENT PING Mon Jan 6 09:29:56 2020 us=872857 xxx.xxx.xxx.xxx:57577 TIMER: coarse timer wakeup 1 seconds Mon Jan 6 09:29:56 2020 us=872895 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=06095dc4 d3c8a4da, stored-sid=00000000 00000000, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:56 2020 us=872938 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:56 2020 us=872950 xxx.xxx.xxx.xxx:57577 ACK mark active outgoing ID 0 Mon Jan 6 09:29:56 2020 us=872966 xxx.xxx.xxx.xxx:57577 TLS: Initial Handshake, sid=06095dc4 d3c8a4da Mon Jan 6 09:29:56 2020 us=872984 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=1 : [1] 0 Mon Jan 6 09:29:56 2020 us=873148 xxx.xxx.xxx.xxx:57577 SSL state (accept): before SSL initialization Mon Jan 6 09:29:56 2020 us=873183 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:56 2020 us=873195 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=1 : [1] 0 Mon Jan 6 09:29:56 2020 us=873208 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 0 [1] 0 Mon Jan 6 09:29:56 2020 us=873219 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 1 Mon Jan 6 09:29:56 2020 us=873241 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:56 2020 us=873266 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:56 2020 us=873295 xxx.xxx.xxx.xxx:57577 RANDOM USEC=210371 Mon Jan 6 09:29:56 2020 us=873321 xxx.xxx.xxx.xxx:57577 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 09:29:58 2020 us=35105] pri=0 Mon Jan 6 09:29:56 2020 us=873339 xxx.xxx.xxx.xxx:57577 MULTI TCP: instance added: xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:56 2020 us=873359 xxx.xxx.xxx.xxx:57577 MULTI TCP: multi_tcp_action a=TA_INITIAL p=0 Mon Jan 6 09:29:56 2020 us=873370 xxx.xxx.xxx.xxx:57577 MULTI TCP: multi_tcp_dispatch a=TA_INITIAL mi=0x55d8f1be65d0 Mon Jan 6 09:29:56 2020 us=873387 xxx.xxx.xxx.xxx:57577 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:56 2020 us=873398 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=836232 xxx.xxx.xxx.xxx:57577 21 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=836342 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [14] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=b3f8a1da 92eb0866 [ ] pid=0 DATA Mon Jan 6 09:29:57 2020 us=836366 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_CONTROL_HARD_RESET_CLIENT_V2, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=836388 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_PRE_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=00000000 00000000, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=836406 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=836422 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=836436 xxx.xxx.xxx.xxx:57577 TLS: Initial packet from [AF_INET]xxx.xxx.xxx.xxx:57577, sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=836448 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=836457 xxx.xxx.xxx.xxx:57577 ACK reliable_schedule_now Mon Jan 6 09:29:57 2020 us=836467 xxx.xxx.xxx.xxx:57577 ACK read ID 0 (buf->len=0) Mon Jan 6 09:29:57 2020 us=836476 xxx.xxx.xxx.xxx:57577 ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1 Mon Jan 6 09:29:57 2020 us=836484 xxx.xxx.xxx.xxx:57577 ACK mark active incoming ID 0 Mon Jan 6 09:29:57 2020 us=836493 xxx.xxx.xxx.xxx:57577 ACK acknowledge ID 0 (ack->len=1) Mon Jan 6 09:29:57 2020 us=836509 xxx.xxx.xxx.xxx:57577 TIMER: coarse timer wakeup 1 seconds Mon Jan 6 09:29:57 2020 us=836537 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=836611 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=836623 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=1 : [1] 0 Mon Jan 6 09:29:57 2020 us=836635 xxx.xxx.xxx.xxx:57577 ACK reliable_send ID 0 (size=4 to=2) Mon Jan 6 09:29:57 2020 us=836652 xxx.xxx.xxx.xxx:57577 ACK write ID 0 (ack->len=1, n=1) Mon Jan 6 09:29:57 2020 us=836661 xxx.xxx.xxx.xxx:57577 Reliable -> TCP/UDP Mon Jan 6 09:29:57 2020 us=836670 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [1] 0 Mon Jan 6 09:29:57 2020 us=836678 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=836691 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=836703 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=836719 xxx.xxx.xxx.xxx:57577 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 09:29:59 2020 us=35217] pri=1319308813 Mon Jan 6 09:29:57 2020 us=836730 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=836740 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_SOCKET_WRITE Mon Jan 6 09:29:57 2020 us=836748 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=836774 xxx.xxx.xxx.xxx:57577 10 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=836791 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [26] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=06095dc4 d3c8a4da [ 0 sid=b3f8a1da 92eb0866 ] pid=0 DATA Mon Jan 6 09:29:57 2020 us=836800 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 26 offset=18 Mon Jan 6 09:29:57 2020 us=836839 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 28 Mon Jan 6 09:29:57 2020 us=836855 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=836882 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=836904 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=0 : [1] 0 Mon Jan 6 09:29:57 2020 us=836912 xxx.xxx.xxx.xxx:57577 Incoming Ciphertext -> TLS Mon Jan 6 09:29:57 2020 us=836937 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=836946 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=0 : [1] 0 Mon Jan 6 09:29:57 2020 us=836960 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [1] 0 Mon Jan 6 09:29:57 2020 us=836969 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=836981 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=836993 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=837003 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=837012 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF Mon Jan 6 09:29:57 2020 us=837025 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 09:29:59 2020 us=35217] pri=2118337516 Mon Jan 6 09:29:57 2020 us=871689 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=871794 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:57 2020 us=871815 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=871832 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=1627 Mon Jan 6 09:29:57 2020 us=871893 xxx.xxx.xxx.xxx:57577 STREAM: ADD length_added=24 Mon Jan 6 09:29:57 2020 us=871979 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=871995 xxx.xxx.xxx.xxx:57577 4 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=872024 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [22] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=b3f8a1da 92eb0866 [ 0 sid=06095dc4 d3c8a4da ] Mon Jan 6 09:29:57 2020 us=872053 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=872086 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_PRE_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=872104 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=872122 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=872136 xxx.xxx.xxx.xxx:57577 ACK received for pid 0, deleting from send buffer Mon Jan 6 09:29:57 2020 us=872168 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=872184 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=872225 xxx.xxx.xxx.xxx:57577 STATE S_START Mon Jan 6 09:29:57 2020 us=872252 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [1] Mon Jan 6 09:29:57 2020 us=872290 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=872304 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [1] Mon Jan 6 09:29:57 2020 us=872321 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 604800 [1] Mon Jan 6 09:29:57 2020 us=872346 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 59 Mon Jan 6 09:29:57 2020 us=872369 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=872386 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=872399 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=872409 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=872419 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_UNDEF Mon Jan 6 09:29:57 2020 us=950994 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=951091 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:57 2020 us=951113 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=951144 xxx.xxx.xxx.xxx:57577 7 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=951276 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [174] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=b3f8a1da 92eb0866 [ ] pid=1 DATA 16030100 9b010000 970303c5 39ce44ef 084ff872 b9b88164 11ae179e 3c83c8f[more...] Mon Jan 6 09:29:57 2020 us=951301 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=951329 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=951345 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=951361 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=951376 xxx.xxx.xxx.xxx:57577 ACK read ID 1 (buf->len=160) Mon Jan 6 09:29:57 2020 us=951401 xxx.xxx.xxx.xxx:57577 ACK RWBS rel->size=8 rel->packet_id=00000001 id=00000001 ret=1 Mon Jan 6 09:29:57 2020 us=951415 xxx.xxx.xxx.xxx:57577 ACK mark active incoming ID 1 Mon Jan 6 09:29:57 2020 us=951461 xxx.xxx.xxx.xxx:57577 ACK acknowledge ID 1 (ack->len=1) Mon Jan 6 09:29:57 2020 us=951500 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=951517 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=951530 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [1] Mon Jan 6 09:29:57 2020 us=951549 xxx.xxx.xxx.xxx:57577 BIO write tls_write_ciphertext 160 bytes Mon Jan 6 09:29:57 2020 us=951558 xxx.xxx.xxx.xxx:57577 Incoming Ciphertext -> TLS Mon Jan 6 09:29:57 2020 us=951651 xxx.xxx.xxx.xxx:57577 SSL state (accept): before SSL initialization Mon Jan 6 09:29:57 2020 us=951954 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS read client hello Mon Jan 6 09:29:57 2020 us=952013 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write server hello Mon Jan 6 09:29:57 2020 us=952355 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write certificate Mon Jan 6 09:29:57 2020 us=967699 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write key exchange Mon Jan 6 09:29:57 2020 us=967789 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write certificate request Mon Jan 6 09:29:57 2020 us=967816 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write server done Mon Jan 6 09:29:57 2020 us=967834 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=967904 xxx.xxx.xxx.xxx:57577 24 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=968146 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [1196] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=06095dc4 d3c8a4da [ 1 sid=b3f8a1da 92eb0866 ] pid=1 DATA 16030300 3d020000 3903034b 0932a796 6c2454ed 3bfbdfec e712f601 89c5689[more...] Mon Jan 6 09:29:57 2020 us=968158 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 1196 offset=18 Mon Jan 6 09:29:57 2020 us=968215 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 1198 Mon Jan 6 09:29:57 2020 us=968232 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=968239 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=968247 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=0 : [2] 1 Mon Jan 6 09:29:57 2020 us=968260 xxx.xxx.xxx.xxx:57577 BIO read tls_read_ciphertext 1170 bytes Mon Jan 6 09:29:57 2020 us=968266 xxx.xxx.xxx.xxx:57577 ACK mark active outgoing ID 2 Mon Jan 6 09:29:57 2020 us=968272 xxx.xxx.xxx.xxx:57577 Outgoing Ciphertext -> Reliable Mon Jan 6 09:29:57 2020 us=968278 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=968285 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=2 current=1 : [3] 1 2 Mon Jan 6 09:29:57 2020 us=968291 xxx.xxx.xxx.xxx:57577 ACK reliable_send ID 2 (size=1174 to=3) Mon Jan 6 09:29:57 2020 us=968297 xxx.xxx.xxx.xxx:57577 Reliable -> TCP/UDP Mon Jan 6 09:29:57 2020 us=968304 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [3] 1 2 Mon Jan 6 09:29:57 2020 us=968310 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=968320 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=968329 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=968338 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_SOCKET_WRITE Mon Jan 6 09:29:57 2020 us=968345 MULTI TCP: multi_tcp_action a=TA_SOCKET_WRITE p=1 Mon Jan 6 09:29:57 2020 us=968351 MULTI TCP: multi_tcp_wait_lite a=TA_SOCKET_WRITE mi=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=968358 PO_CTL rwflags=0x0002 ev=9 arg=0x55d8f1475168 Mon Jan 6 09:29:57 2020 us=968364 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=968395 xxx.xxx.xxx.xxx:57577 7 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=968635 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [1184] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=06095dc4 d3c8a4da [ ] pid=2 DATA 01090116 15726574 65702e73 616e7461 40676d61 696c2e63 6f6d8209 00a2f6f[more...] Mon Jan 6 09:29:57 2020 us=968645 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 1184 offset=30 Mon Jan 6 09:29:57 2020 us=968658 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 1186 Mon Jan 6 09:29:57 2020 us=968670 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=968690 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=968697 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=2 current=0 : [3] 1 2 Mon Jan 6 09:29:57 2020 us=968707 xxx.xxx.xxx.xxx:57577 BIO read tls_read_ciphertext 1170 bytes Mon Jan 6 09:29:57 2020 us=968713 xxx.xxx.xxx.xxx:57577 ACK mark active outgoing ID 3 Mon Jan 6 09:29:57 2020 us=968719 xxx.xxx.xxx.xxx:57577 Outgoing Ciphertext -> Reliable Mon Jan 6 09:29:57 2020 us=968725 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=968732 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=3 current=1 : [4] 1 2 3 Mon Jan 6 09:29:57 2020 us=968739 xxx.xxx.xxx.xxx:57577 ACK reliable_send ID 3 (size=1174 to=4) Mon Jan 6 09:29:57 2020 us=968745 xxx.xxx.xxx.xxx:57577 Reliable -> TCP/UDP Mon Jan 6 09:29:57 2020 us=968751 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [4] 1 2 3 Mon Jan 6 09:29:57 2020 us=968757 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=968766 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=968775 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=968782 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_SOCKET_WRITE Mon Jan 6 09:29:57 2020 us=968788 MULTI TCP: multi_tcp_action a=TA_SOCKET_WRITE p=1 Mon Jan 6 09:29:57 2020 us=968794 MULTI TCP: multi_tcp_wait_lite a=TA_SOCKET_WRITE mi=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=968800 PO_CTL rwflags=0x0002 ev=9 arg=0x55d8f1475168 Mon Jan 6 09:29:57 2020 us=968806 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=968814 xxx.xxx.xxx.xxx:57577 7 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=969084 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [1184] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=06095dc4 d3c8a4da [ ] pid=3 DATA 9fb3dd15 6e377035 d0ff7f86 9bdf29e2 48569861 828b6498 ff56903f 23600f6[more...] Mon Jan 6 09:29:57 2020 us=969098 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 1184 offset=30 Mon Jan 6 09:29:57 2020 us=969135 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 1186 Mon Jan 6 09:29:57 2020 us=969152 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=969159 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=969166 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=3 current=0 : [4] 1 2 3 Mon Jan 6 09:29:57 2020 us=969177 xxx.xxx.xxx.xxx:57577 BIO read tls_read_ciphertext 556 bytes Mon Jan 6 09:29:57 2020 us=969186 xxx.xxx.xxx.xxx:57577 ACK mark active outgoing ID 4 Mon Jan 6 09:29:57 2020 us=969195 xxx.xxx.xxx.xxx:57577 Outgoing Ciphertext -> Reliable Mon Jan 6 09:29:57 2020 us=969204 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=969215 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=4 current=1 : [5] 1 2 3 4 Mon Jan 6 09:29:57 2020 us=969244 xxx.xxx.xxx.xxx:57577 ACK reliable_send ID 4 (size=560 to=5) Mon Jan 6 09:29:57 2020 us=969251 xxx.xxx.xxx.xxx:57577 Reliable -> TCP/UDP Mon Jan 6 09:29:57 2020 us=969258 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [5] 1 2 3 4 Mon Jan 6 09:29:57 2020 us=969265 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=969274 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=969284 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=969292 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_SOCKET_WRITE Mon Jan 6 09:29:57 2020 us=969298 MULTI TCP: multi_tcp_action a=TA_SOCKET_WRITE p=1 Mon Jan 6 09:29:57 2020 us=969305 MULTI TCP: multi_tcp_wait_lite a=TA_SOCKET_WRITE mi=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=969311 PO_CTL rwflags=0x0002 ev=9 arg=0x55d8f1475168 Mon Jan 6 09:29:57 2020 us=969317 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=969327 xxx.xxx.xxx.xxx:57577 7 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=969421 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [570] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=06095dc4 d3c8a4da [ ] pid=4 DATA 00360c2f 12ef2f6f b30c0dba 6b8efd06 42b80f9b e8fba848 ccbbcd6e 4b6d07a[more...] Mon Jan 6 09:29:57 2020 us=969428 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 570 offset=30 Mon Jan 6 09:29:57 2020 us=969452 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 572 Mon Jan 6 09:29:57 2020 us=969463 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=969470 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=969477 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=4 current=0 : [5] 1 2 3 4 Mon Jan 6 09:29:57 2020 us=969486 xxx.xxx.xxx.xxx:57577 ACK output sequence broken: [5] 1 2 3 4 Mon Jan 6 09:29:57 2020 us=969493 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 2 [5] 1 2 3 4 Mon Jan 6 09:29:57 2020 us=969498 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 2 Mon Jan 6 09:29:57 2020 us=969507 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=969516 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=969538 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=969545 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF Mon Jan 6 09:29:57 2020 us=988478 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=988556 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:57 2020 us=988570 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:57 2020 us=988582 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=1627 Mon Jan 6 09:29:57 2020 us=988601 xxx.xxx.xxx.xxx:57577 STREAM: ADD length_added=24 Mon Jan 6 09:29:57 2020 us=988611 xxx.xxx.xxx.xxx:57577 STREAM: ADD returned TRUE, buf_len=22, residual_len=0 Mon Jan 6 09:29:57 2020 us=988621 xxx.xxx.xxx.xxx:57577 STREAM: GET FINAL len=22 Mon Jan 6 09:29:57 2020 us=988630 xxx.xxx.xxx.xxx:57577 STREAM: RESET Mon Jan 6 09:29:57 2020 us=988638 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:57 2020 us=988649 xxx.xxx.xxx.xxx:57577 1 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:57 2020 us=988676 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [22] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=b3f8a1da 92eb0866 [ 1 sid=06095dc4 d3c8a4da ] Mon Jan 6 09:29:57 2020 us=988688 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=988709 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=988753 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=988765 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:57 2020 us=988776 xxx.xxx.xxx.xxx:57577 ACK received for pid 1, deleting from send buffer Mon Jan 6 09:29:57 2020 us=988806 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:57 2020 us=988821 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:57 2020 us=988837 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=3 current=0 : [5] 2 3 4 Mon Jan 6 09:29:57 2020 us=988892 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 3 [5] 2 3 4 Mon Jan 6 09:29:57 2020 us=988903 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 3 Mon Jan 6 09:29:57 2020 us=988917 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=988930 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:57 2020 us=988953 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=988964 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:57 2020 us=989004 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_UNDEF Mon Jan 6 09:29:58 2020 us=20904 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=21024 MULTI: REAP range 32 -> 48 Mon Jan 6 09:29:58 2020 us=21047 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:58 2020 us=21091 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=21126 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=1627 Mon Jan 6 09:29:58 2020 us=21152 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:58 2020 us=21168 xxx.xxx.xxx.xxx:57577 5 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:58 2020 us=21252 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [22] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=b3f8a1da 92eb0866 [ 2 sid=06095dc4 d3c8a4da ] Mon Jan 6 09:29:58 2020 us=21269 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21307 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21363 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=21377 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=21387 xxx.xxx.xxx.xxx:57577 ACK received for pid 2, deleting from send buffer Mon Jan 6 09:29:58 2020 us=21401 xxx.xxx.xxx.xxx:57577 TIMER: coarse timer wakeup 1 seconds Mon Jan 6 09:29:58 2020 us=21420 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21430 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=21441 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=2 current=0 : [5] 3 4 Mon Jan 6 09:29:58 2020 us=21479 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 3 [5] 3 4 Mon Jan 6 09:29:58 2020 us=21504 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 3 Mon Jan 6 09:29:58 2020 us=21705 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=21777 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=21796 xxx.xxx.xxx.xxx:57577 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 09:30:00 2020 us=150823] pri=2118337516 Mon Jan 6 09:29:58 2020 us=21807 STREAM: ADD length_added=0 Mon Jan 6 09:29:58 2020 us=21816 STREAM: ADD returned TRUE, buf_len=22, residual_len=0 Mon Jan 6 09:29:58 2020 us=21824 STREAM: RESIDUAL FULLY FORMED [YES], len=0 Mon Jan 6 09:29:58 2020 us=21833 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_SOCKET_READ_RESIDUAL Mon Jan 6 09:29:58 2020 us=21841 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ_RESIDUAL p=1 Mon Jan 6 09:29:58 2020 us=21849 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ_RESIDUAL mi=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=21857 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:58 2020 us=21866 xxx.xxx.xxx.xxx:57577 3 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:58 2020 us=21883 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [22] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=b3f8a1da 92eb0866 [ 3 sid=06095dc4 d3c8a4da ] Mon Jan 6 09:29:58 2020 us=21893 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_ACK_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21909 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21919 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=21929 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=21938 xxx.xxx.xxx.xxx:57577 ACK received for pid 3, deleting from send buffer Mon Jan 6 09:29:58 2020 us=21954 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=21966 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=21979 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=1 current=0 : [5] 4 Mon Jan 6 09:29:58 2020 us=22002 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 4 [5] 4 Mon Jan 6 09:29:58 2020 us=22015 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 4 Mon Jan 6 09:29:58 2020 us=22034 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=22050 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=22061 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:58 2020 us=22070 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:58 2020 us=22078 MULTI TCP: multi_tcp_post TA_SOCKET_READ_RESIDUAL -> TA_UNDEF Mon Jan 6 09:29:58 2020 us=22091 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 09:30:00 2020 us=150823] pri=1260209504 Mon Jan 6 09:29:58 2020 us=31749 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=31825 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:58 2020 us=31875 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=31895 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=1627 Mon Jan 6 09:29:58 2020 us=31920 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:58 2020 us=31938 xxx.xxx.xxx.xxx:57577 5 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:58 2020 us=32254 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [1196] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=b3f8a1da 92eb0866 [ 4 sid=06095dc4 d3c8a4da ] pid=2 DATA 1603030c 9c0b000c 98000c95 00067c30 82067830 820460a0 03020102 0201023[more...] Mon Jan 6 09:29:58 2020 us=32303 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=32334 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=32351 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=32368 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=32384 xxx.xxx.xxx.xxx:57577 ACK received for pid 4, deleting from send buffer Mon Jan 6 09:29:58 2020 us=32397 xxx.xxx.xxx.xxx:57577 ACK read ID 2 (buf->len=1170) Mon Jan 6 09:29:58 2020 us=32410 xxx.xxx.xxx.xxx:57577 ACK RWBS rel->size=8 rel->packet_id=00000002 id=00000002 ret=1 Mon Jan 6 09:29:58 2020 us=32420 xxx.xxx.xxx.xxx:57577 ACK mark active incoming ID 2 Mon Jan 6 09:29:58 2020 us=32428 xxx.xxx.xxx.xxx:57577 ACK acknowledge ID 2 (ack->len=1) Mon Jan 6 09:29:58 2020 us=32468 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=32480 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=32505 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=32527 xxx.xxx.xxx.xxx:57577 BIO write tls_write_ciphertext 1170 bytes Mon Jan 6 09:29:58 2020 us=32541 xxx.xxx.xxx.xxx:57577 Incoming Ciphertext -> TLS Mon Jan 6 09:29:58 2020 us=32593 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=32610 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=32626 xxx.xxx.xxx.xxx:57577 ACK write ID 2 (ack->len=1, n=1) Mon Jan 6 09:29:58 2020 us=32636 xxx.xxx.xxx.xxx:57577 Dedicated ACK -> TCP/UDP Mon Jan 6 09:29:58 2020 us=32645 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 604800 [5] Mon Jan 6 09:29:58 2020 us=32654 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 58 Mon Jan 6 09:29:58 2020 us=32662 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:58 2020 us=32687 xxx.xxx.xxx.xxx:57577 18 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:58 2020 us=32705 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [22] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=06095dc4 d3c8a4da [ 2 sid=b3f8a1da 92eb0866 ] Mon Jan 6 09:29:58 2020 us=32714 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 22 offset=35 Mon Jan 6 09:29:58 2020 us=32753 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 24 Mon Jan 6 09:29:58 2020 us=32768 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=32779 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=32792 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=32807 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 604800 [5] Mon Jan 6 09:29:58 2020 us=32821 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 58 Mon Jan 6 09:29:58 2020 us=32841 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=32862 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=32877 STREAM: SET NEXT, buf=[538,427] next=[965,757] len=1184 maxlen=1627 Mon Jan 6 09:29:58 2020 us=32887 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF Mon Jan 6 09:29:58 2020 us=32902 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=32911 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:58 2020 us=32941 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=32955 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=757 Mon Jan 6 09:29:58 2020 us=32973 xxx.xxx.xxx.xxx:57577 STREAM: ADD length_added=757 Mon Jan 6 09:29:58 2020 us=32986 xxx.xxx.xxx.xxx:57577 STREAM: ADD returned TRUE, buf_len=1184, residual_len=0 Mon Jan 6 09:29:58 2020 us=32999 xxx.xxx.xxx.xxx:57577 STREAM: GET FINAL len=1184 Mon Jan 6 09:29:58 2020 us=33011 xxx.xxx.xxx.xxx:57577 STREAM: RESET Mon Jan 6 09:29:58 2020 us=33021 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER read returned 1184 Mon Jan 6 09:29:58 2020 us=33323 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [1184] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=b3f8a1da 92eb0866 [ ] pid=3 DATA 1d4cb711 cf09bc0a 554ec92c 5261205e f5027cf0 f36f03bd 65cf6159 b93548b[more...] Mon Jan 6 09:29:58 2020 us=33339 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=33356 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=33366 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=33377 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=33386 xxx.xxx.xxx.xxx:57577 ACK read ID 3 (buf->len=1170) Mon Jan 6 09:29:58 2020 us=33395 xxx.xxx.xxx.xxx:57577 ACK RWBS rel->size=8 rel->packet_id=00000003 id=00000003 ret=1 Mon Jan 6 09:29:58 2020 us=33403 xxx.xxx.xxx.xxx:57577 ACK mark active incoming ID 3 Mon Jan 6 09:29:58 2020 us=33445 xxx.xxx.xxx.xxx:57577 ACK acknowledge ID 3 (ack->len=1) Mon Jan 6 09:29:58 2020 us=33472 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=33487 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=33498 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=33509 xxx.xxx.xxx.xxx:57577 BIO write tls_write_ciphertext 1170 bytes Mon Jan 6 09:29:58 2020 us=33518 xxx.xxx.xxx.xxx:57577 Incoming Ciphertext -> TLS Mon Jan 6 09:29:58 2020 us=33728 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=1 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=33743 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=33754 xxx.xxx.xxx.xxx:57577 ACK write ID 3 (ack->len=1, n=1) Mon Jan 6 09:29:58 2020 us=33763 xxx.xxx.xxx.xxx:57577 Dedicated ACK -> TCP/UDP Mon Jan 6 09:29:58 2020 us=33771 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 604800 [5] Mon Jan 6 09:29:58 2020 us=33780 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 58 Mon Jan 6 09:29:58 2020 us=33793 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=33801 xxx.xxx.xxx.xxx:57577 NOTE: --mute triggered... Mon Jan 6 09:29:58 2020 us=33821 xxx.xxx.xxx.xxx:57577 13 variation(s) on previous 20 message(s) suppressed by --mute Mon Jan 6 09:29:58 2020 us=33838 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER WRITE [22] to [AF_INET]xxx.xxx.xxx.xxx:57577: P_ACK_V1 kid=0 sid=06095dc4 d3c8a4da [ 3 sid=b3f8a1da 92eb0866 ] Mon Jan 6 09:29:58 2020 us=33846 xxx.xxx.xxx.xxx:57577 STREAM: WRITE 22 offset=35 Mon Jan 6 09:29:58 2020 us=33869 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER write returned 24 Mon Jan 6 09:29:58 2020 us=33901 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=33915 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=33929 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=33971 xxx.xxx.xxx.xxx:57577 ACK reliable_send_timeout 604800 [5] Mon Jan 6 09:29:58 2020 us=33983 xxx.xxx.xxx.xxx:57577 TLS: tls_process: timeout set to 58 Mon Jan 6 09:29:58 2020 us=33995 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=34011 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=34028 STREAM: SET NEXT, buf=[536,0] next=[536,1627] len=-1 maxlen=1627 Mon Jan 6 09:29:58 2020 us=34042 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF Mon Jan 6 09:29:58 2020 us=34061 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=34073 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0 Mon Jan 6 09:29:58 2020 us=34084 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x55d8f1be65d0 Mon Jan 6 09:29:58 2020 us=34095 xxx.xxx.xxx.xxx:57577 STREAM: GET NEXT len=1627 Mon Jan 6 09:29:58 2020 us=34113 xxx.xxx.xxx.xxx:57577 STREAM: ADD length_added=1543 Mon Jan 6 09:29:58 2020 us=34125 xxx.xxx.xxx.xxx:57577 STREAM: ADD returned TRUE, buf_len=1184, residual_len=357 Mon Jan 6 09:29:58 2020 us=34136 xxx.xxx.xxx.xxx:57577 STREAM: GET FINAL len=1184 Mon Jan 6 09:29:58 2020 us=34148 xxx.xxx.xxx.xxx:57577 STREAM: RESET Mon Jan 6 09:29:58 2020 us=34158 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER read returned 1184 Mon Jan 6 09:29:58 2020 us=34605 xxx.xxx.xxx.xxx:57577 TCPv4_SERVER READ [1184] from [AF_INET]xxx.xxx.xxx.xxx:57577: P_CONTROL_V1 kid=0 sid=b3f8a1da 92eb0866 [ ] pid=4 DATA 14792810 f54fb939 680ff32e f745a880 ebadc5cc f117b45c dc49835a 03c74e2[more...] Mon Jan 6 09:29:58 2020 us=34632 xxx.xxx.xxx.xxx:57577 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=34650 xxx.xxx.xxx.xxx:57577 TLS: initial packet test, i=0 state=S_START, mysid=06095dc4 d3c8a4da, rec-sid=b3f8a1da 92eb0866, rec-ip=[AF_INET]xxx.xxx.xxx.xxx:57577, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=34661 xxx.xxx.xxx.xxx:57577 TLS: found match, session[0], sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=34672 xxx.xxx.xxx.xxx:57577 TLS: received control channel packet s#=0 sid=b3f8a1da 92eb0866 Mon Jan 6 09:29:58 2020 us=34681 xxx.xxx.xxx.xxx:57577 ACK read ID 4 (buf->len=1170) Mon Jan 6 09:29:58 2020 us=34690 xxx.xxx.xxx.xxx:57577 ACK RWBS rel->size=8 rel->packet_id=00000004 id=00000004 ret=1 Mon Jan 6 09:29:58 2020 us=34699 xxx.xxx.xxx.xxx:57577 ACK mark active incoming ID 4 Mon Jan 6 09:29:58 2020 us=34708 xxx.xxx.xxx.xxx:57577 ACK acknowledge ID 4 (ack->len=1) Mon Jan 6 09:29:58 2020 us=34725 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=0 state=S_START, mysid=06095dc4 d3c8a4da, stored-sid=b3f8a1da 92eb0866, stored-ip=[AF_INET]xxx.xxx.xxx.xxx:57577 Mon Jan 6 09:29:58 2020 us=34735 xxx.xxx.xxx.xxx:57577 TLS: tls_process: chg=0 ks=S_START lame=S_UNDEF to_link->len=0 wakeup=604800 Mon Jan 6 09:29:58 2020 us=34743 xxx.xxx.xxx.xxx:57577 ACK reliable_can_send active=0 current=0 : [5] Mon Jan 6 09:29:58 2020 us=34754 xxx.xxx.xxx.xxx:57577 BIO write tls_write_ciphertext 1170 bytes Mon Jan 6 09:29:58 2020 us=34763 xxx.xxx.xxx.xxx:57577 Incoming Ciphertext -> TLS Mon Jan 6 09:29:58 2020 us=34778 xxx.xxx.xxx.xxx:57577 SSL state (accept): SSLv3/TLS write server done Mon Jan 6 09:29:58 2020 us=35147 xxx.xxx.xxx.xxx:57577 VERIFY ERROR: depth=0, error=CRL has expired: C=IT, ST=BZ, L=Truden, O=My Org, OU=Office, CN=pier, emailAddress=xxxx.xxxx@gmail.com Mon Jan 6 09:29:58 2020 us=35180 xxx.xxx.xxx.xxx:57577 SSL alert (write): fatal: certificate expired Mon Jan 6 09:29:58 2020 us=35219 xxx.xxx.xxx.xxx:57577 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55d8f1bd66c0, ptr=(nil), ad=0x55d8f1bd6728, idx=1, argl=0, argp=0x7f3f35d87842 Mon Jan 6 09:29:58 2020 us=35299 xxx.xxx.xxx.xxx:57577 OpenSSL: error:1417C086:SSL routines:tls_process_client_certificate:certificate verify failed Mon Jan 6 09:29:58 2020 us=35324 xxx.xxx.xxx.xxx:57577 TLS_ERROR: BIO read tls_read_plaintext error Mon Jan 6 09:29:58 2020 us=35334 xxx.xxx.xxx.xxx:57577 TLS Error: TLS object -> incoming plaintext read error Mon Jan 6 09:29:58 2020 us=35342 xxx.xxx.xxx.xxx:57577 TLS Error: TLS handshake failed Mon Jan 6 09:29:58 2020 us=35351 xxx.xxx.xxx.xxx:57577 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35398 xxx.xxx.xxx.xxx:57577 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55d8f1c02130, ptr=(nil), ad=0x55d8f1c02198, idx=1, argl=0, argp=0x7f3f35d87842 Mon Jan 6 09:29:58 2020 us=35453 xxx.xxx.xxx.xxx:57577 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35473 xxx.xxx.xxx.xxx:57577 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35487 xxx.xxx.xxx.xxx:57577 TLS: tls_session_init: entry Mon Jan 6 09:29:58 2020 us=35503 xxx.xxx.xxx.xxx:57577 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:58 2020 us=35544 xxx.xxx.xxx.xxx:57577 PID packet_id_init seq_backtrack=64 time_backtrack=15 Mon Jan 6 09:29:58 2020 us=35564 xxx.xxx.xxx.xxx:57577 TLS: tls_session_init: new session object, sid=db7896a6 c73c8a91 Mon Jan 6 09:29:58 2020 us=35585 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b9f93e50 a9869492, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=35605 xxx.xxx.xxx.xxx:57577 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Mon Jan 6 09:29:58 2020 us=35620 xxx.xxx.xxx.xxx:57577 Fatal TLS error (check_tls_errors_co), restarting Mon Jan 6 09:29:58 2020 us=35636 xxx.xxx.xxx.xxx:57577 SIGUSR1[soft,tls-error] received, client-instance restarting Mon Jan 6 09:29:58 2020 us=35657 MULTI: multi_close_instance called Mon Jan 6 09:29:58 2020 us=35678 EP_DEL ev=9 Mon Jan 6 09:29:58 2020 us=35730 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35759 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35769 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35778 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35797 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35813 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35834 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35847 PID packet_id_free Mon Jan 6 09:29:58 2020 us=35943 TCP/UDP: Closing socket Mon Jan 6 09:29:58 2020 us=36044 PID packet_id_free Mon Jan 6 09:29:58 2020 us=36080 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_UNDEF Mon Jan 6 09:29:58 2020 us=36097 SCHEDULE: schedule_find_least NULL Mon Jan 6 09:30:03 2020 us=450260 MULTI: REAP range 0 -> 256 Mon Jan 6 09:30:03 2020 us=450473 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55d8f1bd42e0, ptr=(nil), ad=0x55d8f1bd4348, idx=1, argl=0, argp=0x7f3f35d87842 Mon Jan 6 09:30:03 2020 us=450527 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55d8f1bca4d0, ptr=(nil), ad=0x55d8f1bca538, idx=1, argl=0, argp=0x7f3f35d87842 Mon Jan 6 09:30:03 2020 us=450554 PKCS#11: __pkcs11h_openssl_ex_data_free entered - parent=0x55d8f1bd4110, ptr=(nil), ad=0x55d8f1bd4178, idx=1, argl=0, argp=0x7f3f35d87842 Mon Jan 6 09:30:03 2020 us=450578 TCP/UDP: Closing socket Mon Jan 6 09:30:03 2020 us=450626 /sbin/ip route del 10.198.1.0/24 Mon Jan 6 09:30:03 2020 us=451449 PKCS#11: __pkcs11h_forkFixup entry pid=22484, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=451564 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=452979 /sbin/ip route del 10.99.10.0/24 Mon Jan 6 09:30:03 2020 us=453466 PKCS#11: __pkcs11h_forkFixup entry pid=22485, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=453738 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=455325 /sbin/ip route del 192.168.1.0/24 Mon Jan 6 09:30:03 2020 us=455848 PKCS#11: __pkcs11h_forkFixup entry pid=22486, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=455989 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=457471 /sbin/ip route del 10.99.11.0/24 Mon Jan 6 09:30:03 2020 us=458002 PKCS#11: __pkcs11h_forkFixup entry pid=22487, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=458086 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=459543 /sbin/ip route del 10.99.1.0/24 Mon Jan 6 09:30:03 2020 us=460204 PKCS#11: __pkcs11h_forkFixup entry pid=22488, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=460291 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=461697 /sbin/ip route del 10.99.33.0/24 Mon Jan 6 09:30:03 2020 us=462176 PKCS#11: __pkcs11h_forkFixup entry pid=22489, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=462274 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=463595 /sbin/ip route del 10.99.2.0/24 Mon Jan 6 09:30:03 2020 us=464151 PKCS#11: __pkcs11h_forkFixup entry pid=22490, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=464260 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=465587 /sbin/ip route del 10.99.3.0/24 Mon Jan 6 09:30:03 2020 us=466035 PKCS#11: __pkcs11h_forkFixup entry pid=22491, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=466114 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=467168 /sbin/ip route del 10.99.99.0/24 Mon Jan 6 09:30:03 2020 us=467588 PKCS#11: __pkcs11h_forkFixup entry pid=22492, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=467670 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=468824 /sbin/ip route del 10.99.5.0/24 Mon Jan 6 09:30:03 2020 us=469201 PKCS#11: __pkcs11h_forkFixup entry pid=22493, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=469272 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=470398 /sbin/ip route del 10.99.113.0/24 Mon Jan 6 09:30:03 2020 us=470801 PKCS#11: __pkcs11h_forkFixup entry pid=22494, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=470867 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=472007 /sbin/ip route del 10.88.1.0/24 Mon Jan 6 09:30:03 2020 us=472386 PKCS#11: __pkcs11h_forkFixup entry pid=22495, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=472461 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=473486 /sbin/ip route del 10.99.55.0/24 Mon Jan 6 09:30:03 2020 us=473911 PKCS#11: __pkcs11h_forkFixup entry pid=22496, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=473975 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=474970 /sbin/ip route del 10.66.99.0/24 Mon Jan 6 09:30:03 2020 us=475316 PKCS#11: __pkcs11h_forkFixup entry pid=22497, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=475378 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=476504 /sbin/ip route del 10.99.4.0/24 Mon Jan 6 09:30:03 2020 us=476869 PKCS#11: __pkcs11h_forkFixup entry pid=22498, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=476946 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=477955 /sbin/ip route del 10.77.55.0/24 Mon Jan 6 09:30:03 2020 us=478312 PKCS#11: __pkcs11h_forkFixup entry pid=22499, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=478375 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=479337 /sbin/ip route del 10.75.1.0/24 Mon Jan 6 09:30:03 2020 us=479735 PKCS#11: __pkcs11h_forkFixup entry pid=22500, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=479804 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=480940 /sbin/ip route del 10.99.6.0/24 Mon Jan 6 09:30:03 2020 us=481327 PKCS#11: __pkcs11h_forkFixup entry pid=22501, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=481410 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=482407 /sbin/ip route del 10.98.1.0/24 Mon Jan 6 09:30:03 2020 us=482767 PKCS#11: __pkcs11h_forkFixup entry pid=22502, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=482839 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=483851 Closing TUN/TAP interface Mon Jan 6 09:30:03 2020 us=483997 /sbin/ip addr del dev tun0 local 10.98.1.1 peer 10.98.1.2 Mon Jan 6 09:30:03 2020 us=484349 PKCS#11: __pkcs11h_forkFixup entry pid=22503, activate_slotevent=1 Mon Jan 6 09:30:03 2020 us=484411 PKCS#11: __pkcs11h_forkFixup return Mon Jan 6 09:30:03 2020 us=496076 PID packet_id_free Mon Jan 6 09:30:03 2020 us=496137 SIGINT[hard,] received, process exiting Mon Jan 6 09:30:03 2020 us=496269 PKCS#11: pkcs11_terminate - entered Mon Jan 6 09:30:03 2020 us=496293 PKCS#11: pkcs11h_terminate entry Mon Jan 6 09:30:03 2020 us=496300 PKCS#11: Terminating openssl Mon Jan 6 09:30:03 2020 us=496306 PKCS#11: _pkcs11h_openssl_terminate Mon Jan 6 09:30:03 2020 us=496329 PKCS#11: Removing providers Mon Jan 6 09:30:03 2020 us=496337 PKCS#11: Releasing sessions Mon Jan 6 09:30:03 2020 us=496342 PKCS#11: Terminating slotevent Mon Jan 6 09:30:03 2020 us=496348 PKCS#11: _pkcs11h_slotevent_terminate entry Mon Jan 6 09:30:03 2020 us=496353 PKCS#11: _pkcs11h_slotevent_terminate return Mon Jan 6 09:30:03 2020 us=496358 PKCS#11: Marking as uninitialized Mon Jan 6 09:30:03 2020 us=496366 PKCS#11: pkcs11_terminate - return