Verb 6 client log
Wed Aug 15 16:56:38 2018 us=454909 TLS: soft reset sec=0 bytes=35574/-1 pkts=739/0
Wed Aug 15 16:56:38 2018 us=454909 TCP_CLIENT WRITE [14] to [AF_INET]x.x.x.x:3030: P_CONTROL_SOFT_RESET_V1 kid=2 [ ] pid=0 DATA len=0
Wed Aug 15 16:56:38 2018 us=454909 TCP_CLIENT READ [26] from [AF_INET]x.x.x.x:3030: P_CONTROL_SOFT_RESET_V1 kid=2 [ 0 ] pid=0 DATA len=0
Wed Aug 15 16:56:38 2018 us=454909 TCP_CLIENT WRITE [22] to [AF_INET]x.x.x.x:3030: P_ACK_V1 kid=2 [ 0 ]
Wed Aug 15 16:56:38 2018 us=454909 TCP_CLIENT WRITE [187] to [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ ] pid=1 DATA len=173
Wed Aug 15 16:56:38 2018 us=539601 TCP_CLIENT READ [1196] from [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ 1 ] pid=1 DATA len=1170
Wed Aug 15 16:56:38 2018 us=539601 TCP_CLIENT WRITE [22] to [AF_INET]x.x.x.x:3030: P_ACK_V1 kid=2 [ 1 ]
Wed Aug 15 16:56:38 2018 us=555225 TCP_CLIENT READ [1184] from [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ ] pid=2 DATA len=1170
Wed Aug 15 16:56:38 2018 us=555225 VERIFY OK: depth=1, C=US, ST=CA, L=SanFrancisco, O=OpenVPN, OU=changeme, CN=server, name=server, emailAddress=
mail@host.domain
Wed Aug 15 16:56:38 2018 us=555225 VERIFY KU OK
Wed Aug 15 16:56:38 2018 us=555225 Validating certificate extended key usage
Wed Aug 15 16:56:38 2018 us=555225 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Wed Aug 15 16:56:38 2018 us=555225 VERIFY EKU OK
Wed Aug 15 16:56:38 2018 us=555225 VERIFY OK: depth=0, C=US, ST=CA, L=SanFrancisco, O=OpenVPN, OU=changeme, CN=server, name=server, emailAddress=
mail@host.domain
Wed Aug 15 16:56:38 2018 us=555225 TCP_CLIENT READ [238] from [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ ] pid=3 DATA len=224
Wed Aug 15 16:56:38 2018 us=555225 TCP_CLIENT WRITE [22] to [AF_INET]x.x.x.x:3030: P_ACK_V1 kid=2 [ 2 ]
Wed Aug 15 16:56:38 2018 us=555225 TCP_CLIENT WRITE [1196] to [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ 3 ] pid=2 DATA len=1170
Wed Aug 15 16:56:38 2018 us=555225 TCP_CLIENT WRITE [1153] to [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ ] pid=3 DATA len=1139
Wed Aug 15 16:56:38 2018 us=570797 TCP_CLIENT READ [22] from [AF_INET]x.x.x.x:3030: P_ACK_V1 kid=2 [ 2 ]
Wed Aug 15 16:56:38 2018 us=624343 TCP_CLIENT READ [77] from [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ 3 ] pid=4 DATA len=51
Wed Aug 15 16:56:38 2018 us=624343 TCP_CLIENT WRITE [451] to [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ 4 ] pid=4 DATA len=425
Wed Aug 15 16:56:38 2018 us=639966 TCP_CLIENT READ [268] from [AF_INET]x.x.x.x:3030: P_CONTROL_V1 kid=2 [ 4 ] pid=5 DATA len=242
Wed Aug 15 16:56:38 2018 us=639966 Data Channel Encrypt: Cipher 'AES-256-GCM' initialized with 256 bit key
Wed Aug 15 16:56:38 2018 us=639966 Data Channel Decrypt: Cipher 'AES-256-GCM' initialized with 256 bit key
Wed Aug 15 16:56:38 2018 us=639966 TCP_CLIENT WRITE [22] to [AF_INET]x.x.x.x:3030: P_ACK_V1 kid=2 [ 5 ]
Wed Aug 15 16:56:38 2018 us=639966 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384, 1024 bit RSA
Wed Aug 15 16:56:47 2018 us=914298 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=37
Wed Aug 15 16:56:49 2018 us=25313 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=1 DATA len=40
Wed Aug 15 16:56:58 2018 us=242782 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=37
Wed Aug 15 16:56:59 2018 us=397259 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=1 DATA len=40
Wed Aug 15 16:57:08 2018 us=372024 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=37
Wed Aug 15 16:57:09 2018 us=556256 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=1 DATA len=40
Wed Aug 15 16:57:18 2018 us=641223 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=37
Wed Aug 15 16:57:19 2018 us=858815 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=1 DATA len=40
Wed Aug 15 16:57:22 2018 us=654504 TCP_CLIENT READ [153] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=152
Wed Aug 15 16:57:22 2018 us=654504 TUN WRITE [137]
Wed Aug 15 16:57:23 2018 us=661798 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=153
Wed Aug 15 16:57:23 2018 us=661798 TUN WRITE [137]
Wed Aug 15 16:57:25 2018 us=656101 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=153
Wed Aug 15 16:57:25 2018 us=656101 TUN WRITE [137]
Wed Aug 15 16:57:29 2018 us=672693 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=1 DATA len=153
Wed Aug 15 16:57:29 2018 us=672693 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=1 DATA len=40
Wed Aug 15 16:57:29 2018 us=672693 TUN WRITE [137]
Wed Aug 15 16:57:37 2018 us=688909 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=153
Wed Aug 15 16:57:37 2018 us=688909 TUN WRITE [137]
Wed Aug 15 16:57:40 2018 us=199360 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 16:57:48 2018 us=19707 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 16:57:50 2018 us=496274 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 16:57:53 2018 us=710032 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=153
Wed Aug 15 16:57:53 2018 us=710032 TUN WRITE [137]
Wed Aug 15 16:58:00 2018 us=663253 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 16:58:04 2018 us=765420 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 16:58:10 2018 us=559177 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 16:58:15 2018 us=75441 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 16:58:20 2018 us=476586 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 16:58:24 2018 us=932992 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 16:58:25 2018 us=704340 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=153
Wed Aug 15 16:58:25 2018 us=704340 TUN WRITE [137]
*****
It goes all the same until
*****
Wed Aug 15 17:40:36 2018 us=32573 TUN WRITE [137]
Wed Aug 15 17:40:40 2018 us=594968 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:40:45 2018 us=699512 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:40:50 2018 us=367114 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:40:55 2018 us=884631 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:00 2018 us=442820 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:06 2018 us=93160 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:08 2018 us=54515 TCP_CLIENT READ [154] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=153
Wed Aug 15 17:41:08 2018 us=54515 TUN WRITE [137]
Wed Aug 15 17:41:10 2018 us=234453 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:18 2018 us=606613 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:21 2018 us=72454 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:28 2018 us=723239 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:31 2018 us=221952 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:38 2018 us=890053 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:41 2018 us=406326 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:49 2018 us=681548 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:41:52 2018 us=198723 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:41:59 2018 us=906329 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:02 2018 us=330919 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:42:10 2018 us=4166 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:12 2018 us=398267 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:42:19 2018 us=930715 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:22 2018 us=233022 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:42:30 2018 us=191504 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:32 2018 us=522037 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:42:40 2018 us=245868 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:42 2018 us=732040 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:42:50 2018 us=233412 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37
Wed Aug 15 17:42:52 2018 us=742055 TCP_CLIENT WRITE [41] to [AF_INET]x.x.x.x:3030: P_DATA_V2 kid=2 DATA len=40
Wed Aug 15 17:43:00 2018 us=406550 TCP_CLIENT READ [38] from [AF_INET]x.x.x.x:3030: P_DATA_V1 kid=2 DATA len=37