Post by NeedHelp6969 » Thu Feb 22, 2018 6:07 am
Fermi wrote:-snip-
parityboy wrote:-snip-
df wrote:-snip-
I raised the verbosity log level to 7 with "verb 7," and this is the new log with my errors:
Code: Select all
Wed Feb 21 17:32:39 2018 us=892443 Current Parameter Settings:
Wed Feb 21 17:32:39 2018 us=892555 config = '-snip-/cstorm_linux-netherlands_udp.ovpn'
Wed Feb 21 17:32:39 2018 us=892606 mode = 0
Wed Feb 21 17:32:39 2018 us=892649 NOTE: --mute triggered...
Wed Feb 21 17:32:39 2018 us=892710 349 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:32:39 2018 us=892753 OpenVPN 2.4.4 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Dec 30 2017
Wed Feb 21 17:32:39 2018 us=892859 library versions: OpenSSL 1.0.2l 25 May 2017, LZO 2.08
Enter Auth Username: -snip-
Enter Auth Password: *
Wed Feb 21 17:33:32 2018 us=278384 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:33:32 2018 us=279354 PRNG init md=SHA1 size=36
Wed Feb 21 17:33:32 2018 us=279421 LZO compression initializing
Wed Feb 21 17:33:32 2018 us=279516 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279630 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279686 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279781 NOTE: --mute triggered...
Wed Feb 21 17:33:32 2018 us=279826 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:32 2018 us=279860 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:33:32 2018 us=279922 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:33:32 2018 us=280062 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:33:57 2018 us=418487 RESOLVE_REMOTE flags=0x0901 phase=1 rrs=0 sig=-1 status=0
Wed Feb 21 17:33:57 2018 us=418576 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:33:57 2018 us=418659 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:33:57 2018 us=418698 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:33:57 2018 us=418758 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:33:57 2018 us=418794 NOTE: --mute triggered...
Wed Feb 21 17:33:57 2018 us=418842 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:57 2018 us=418879 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:33:57 2018 us=418917 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:33:57 2018 us=418971 TCP/UDP: Preserving recently used remote address: [AF_INET]185.107.80.85:443
Wed Feb 21 17:33:57 2018 us=419028 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:33:57 2018 us=419072 UDP link local: (not bound)
Wed Feb 21 17:33:57 2018 us=419112 UDP link remote: [AF_INET]185.107.80.85:443
Wed Feb 21 17:33:57 2018 us=419236 UDP WRITE [14] to [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:33:57 2018 us=593166 UDP READ [26] from [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Wed Feb 21 17:33:57 2018 us=593262 TLS: Initial packet from [AF_INET]185.107.80.85:443, sid=68a9b54c a590ad57
Wed Feb 21 17:33:57 2018 us=593404 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 0 ]
Wed Feb 21 17:33:57 2018 us=593899 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Wed Feb 21 17:33:57 2018 us=593990 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:33:57 2018 us=767681 UDP READ [1200] from [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 1 ] pid=1 DATA len=1174
Wed Feb 21 17:33:57 2018 us=768019 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 1 ]
Wed Feb 21 17:33:57 2018 us=768493 NOTE: --mute triggered...
Wed Feb 21 17:33:57 2018 us=771117 3 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:57 2018 us=771162 VERIFY OK: depth=1, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=cryptostorm_is, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:33:57 2018 us=771833 VERIFY OK: nsCertType=SERVER
Wed Feb 21 17:33:57 2018 us=771863 VERIFY OK: depth=0, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=server, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:33:57 2018 us=771994 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 3 ]
Wed Feb 21 17:33:57 2018 us=772460 UDP READ [47] from [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=33
Wed Feb 21 17:33:57 2018 us=798171 UDP WRITE [368] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 4 ] pid=2 DATA len=342
Wed Feb 21 17:33:57 2018 us=971990 NOTE: --mute triggered...
Wed Feb 21 17:34:11 2018 us=908652 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:11 2018 us=908757 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 2048 bit RSA
Wed Feb 21 17:34:11 2018 us=908872 [server] Peer Connection Initiated with [AF_INET]185.107.80.85:443
Wed Feb 21 17:34:13 2018 us=8289 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
Wed Feb 21 17:34:13 2018 us=8464 UDP WRITE [83] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=69
Wed Feb 21 17:34:15 2018 us=208432 UDP WRITE [83] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=69
Wed Feb 21 17:34:15 2018 us=213919 UDP READ [22] from [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 4 ]
Wed Feb 21 17:34:15 2018 us=214091 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=214220 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=214265 PUSH: Received control message: 'PUSH_REPLY,persist-key,persist-tun,redirect-gateway def1,dhcp-option DNS 185.107.80.84,route-gateway 10.33.0.1,topology subnet,ping 20,ping-restart 60,ifconfig 10.33.66.197 255.255.0.0'
Wed Feb 21 17:34:15 2018 us=214484 OPTIONS IMPORT: timers and/or timeouts modified
Wed Feb 21 17:34:15 2018 us=214523 OPTIONS IMPORT: --persist options modified
Wed Feb 21 17:34:15 2018 us=214557 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=214610 4 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=214646 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:34:15 2018 us=214682 MTU DYNAMIC mtu=1400, flags=2, 1602 -> 1400
Wed Feb 21 17:34:15 2018 us=214720 Data Channel MTU parms [ L:1602 D:1400 EF:102 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:34:15 2018 us=214785 Client pre_master: af8a0454 7837a294 ac1cf81f baca0203 bdc84cf2 3db2affc a37b00d7 f0add86c d52af53d 4bd6a31d ffc48cc8 be57c525
Wed Feb 21 17:34:15 2018 us=214839 Client random1: 7c369187 e17bdbf1 a0c46b88 b00bd4ad bbd91a6e 8cd55a08 b93eec7c 743e7c0a
Wed Feb 21 17:34:15 2018 us=214892 Client random2: 5afc0c7b a07720a1 b020ee43 7010824d 9eac36a3 90049dc2 3573fcad 51566b67
Wed Feb 21 17:34:15 2018 us=214925 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=215230 25 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=215270 Outgoing Data Channel: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Feb 21 17:34:15 2018 us=215322 Outgoing Data Channel: CIPHER KEY: a2658f1f 26cfb339 54bcf3e9 acf64102 a6a25abf 95b22f53 da402cc9 b13451cd
Wed Feb 21 17:34:15 2018 us=215362 Outgoing Data Channel: CIPHER block_size=16 iv_size=16
Wed Feb 21 17:34:15 2018 us=215423 Outgoing Data Channel: Using 512 bit message hash 'SHA512' for HMAC authentication
Wed Feb 21 17:34:15 2018 us=215496 Outgoing Data Channel: HMAC KEY: c62e8a06 53480372 20f4cb97 856705a1 25d417a9 a3bdb53f 47d7b1b1 8f71c67c 426b96c4 b6761250 4faa3964 ebb80819 bc80def9 665e8636 26cb5448 e8b72fee
Wed Feb 21 17:34:15 2018 us=215532 Outgoing Data Channel: HMAC size=64 block_size=64
Wed Feb 21 17:34:15 2018 us=215572 Incoming Data Channel: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Feb 21 17:34:15 2018 us=215626 Incoming Data Channel: CIPHER KEY: 81ce3d6d a1c26ee5 3d484ed7 6700388e bc6ffada 08199dad 85d0887f 44b56ee3
Wed Feb 21 17:34:15 2018 us=215665 Incoming Data Channel: CIPHER block_size=16 iv_size=16
Wed Feb 21 17:34:15 2018 us=215717 Incoming Data Channel: Using 512 bit message hash 'SHA512' for HMAC authentication
Wed Feb 21 17:34:15 2018 us=215789 Incoming Data Channel: HMAC KEY: 9cf3f960 bc382d3b a456c638 6abf1dfe 0bd080a8 6bbdc050 115b5bd4 f1cf506f 14d7be24 58774907 1463c7ac 448dbdfa 2e470ab8 a46222e4 9859c562 a46fdaef
Wed Feb 21 17:34:15 2018 us=215826 Incoming Data Channel: HMAC size=64 block_size=64
Wed Feb 21 17:34:15 2018 us=216160 ROUTE_GATEWAY 192.168.1.254/255.255.255.0 IFACE=wlo1 HWADDR=ee:5f:80:89:24:c7
Wed Feb 21 17:34:15 2018 us=224850 TUN/TAP device tun0 opened
Wed Feb 21 17:34:15 2018 us=224956 TUN/TAP TX queue length set to 100
Wed Feb 21 17:34:15 2018 us=225013 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Wed Feb 21 17:34:15 2018 us=225065 /sbin/ip link set dev tun0 up mtu 1500
Wed Feb 21 17:34:15 2018 us=229428 /sbin/ip addr add dev tun0 10.33.66.197/16 broadcast 10.33.255.255
Wed Feb 21 17:34:15 2018 us=231303 /sbin/ip route add 185.107.80.85/32 via 192.168.1.254
Wed Feb 21 17:34:15 2018 us=232851 /sbin/ip route add 0.0.0.0/1 via 10.33.0.1
Wed Feb 21 17:34:15 2018 us=235098 /sbin/ip route add 128.0.0.0/1 via 10.33.0.1
Wed Feb 21 17:34:15 2018 us=242308 Initialization Sequence Completed
Wed Feb 21 17:34:15 2018 us=242404 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 7 ]
Wed Feb 21 17:34:35 2018 us=583641 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:34:35 2018 us=583838 SENT PING
Wed Feb 21 17:34:35 2018 us=583912 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=870497 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:34:55 2018 us=870664 SENT PING
Wed Feb 21 17:34:55 2018 us=870742 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=903137 UDP READ [113] from [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=903242 TLS: tls_pre_decrypt, key_id=0, IP=[AF_INET]185.107.80.85:443
Wed Feb 21 17:34:55 2018 us=903339 PID_TEST [0] [SSL-0] [] 0:0 0:2 t=1519259695[0] r=[0,64,15,0,1] sl=[0,0,64,528]
Wed Feb 21 17:34:55 2018 us=903368 RECEIVED PING PACKET
Wed Feb 21 17:35:15 2018 us=144976 NOTE: --mute triggered...
Wed Feb 21 17:35:15 2018 us=145166 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:35:15 2018 us=145228 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=663258 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:35:35 2018 us=663424 SENT PING
Wed Feb 21 17:35:35 2018 us=663496 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=676778 UDP READ [113] from [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=676918 TLS: tls_pre_decrypt, key_id=0, IP=[AF_INET]185.107.80.85:443
Wed Feb 21 17:35:35 2018 us=677002 PID_TEST [0] [SSL-0] [EE] 0:2 0:4 t=1519259735[0] r=[0,64,15,0,1] sl=[62,2,64,528]
Wed Feb 21 17:35:35 2018 us=677038 RECEIVED PING PACKET
Wed Feb 21 17:35:56 2018 us=69848 NOTE: --mute triggered...
Wed Feb 21 17:35:56 2018 us=70036 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:35:56 2018 us=70097 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:36:16 2018 us=818135 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:36:16 2018 us=818303 SENT PING
Wed Feb 21 17:36:16 2018 us=818424 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:36:35 2018 us=960159 [server] Inactivity timeout (--ping-restart), restarting
Wed Feb 21 17:36:35 2018 us=960346 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960741 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960814 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960855 NOTE: --mute triggered...
Wed Feb 21 17:36:35 2018 us=960939 5 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:35 2018 us=960978 TCP/UDP: Closing socket
Wed Feb 21 17:36:35 2018 us=961043 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=961100 SIGUSR1[soft,ping-restart] received, process restarting
Wed Feb 21 17:36:35 2018 us=961172 Restart pause, 5 second(s)
Wed Feb 21 17:36:40 2018 us=961418 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:36:40 2018 us=961544 Re-using SSL/TLS context
Wed Feb 21 17:36:40 2018 us=961601 LZO compression initializing
Wed Feb 21 17:36:40 2018 us=961720 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961822 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961871 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961954 NOTE: --mute triggered...
Wed Feb 21 17:36:40 2018 us=961999 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:40 2018 us=962034 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:36:40 2018 us=962089 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:36:40 2018 us=962136 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:36:40 2018 us=962202 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:36:40 2018 us=962239 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:36:40 2018 us=962297 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:36:40 2018 us=962332 NOTE: --mute triggered...
Wed Feb 21 17:36:40 2018 us=962378 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:40 2018 us=962415 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:36:40 2018 us=962452 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:36:40 2018 us=962506 TCP/UDP: Preserving recently used remote address: [AF_INET]185.107.80.85:443
Wed Feb 21 17:36:40 2018 us=962595 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:36:40 2018 us=962634 UDP link local: (not bound)
Wed Feb 21 17:36:40 2018 us=962675 UDP link remote: [AF_INET]185.107.80.85:443
Wed Feb 21 17:36:40 2018 us=962757 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Wed Feb 21 17:36:40 2018 us=962797 SENT PING
Wed Feb 21 17:36:40 2018 us=962887 UDP WRITE [14] to [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:36:42 2018 us=10261 UDP READ [26] from [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Wed Feb 21 17:36:42 2018 us=10382 TLS: Initial packet from [AF_INET]185.107.80.85:443, sid=fbe05bf0 12ea22ff
Wed Feb 21 17:36:42 2018 us=10519 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 0 ]
Wed Feb 21 17:36:42 2018 us=11441 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:36:44 2018 us=332306 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:36:46 2018 us=119833 NOTE: --mute triggered...
Wed Feb 21 17:36:49 2018 us=238730 8 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:49 2018 us=238826 VERIFY OK: depth=1, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=cryptostorm_is, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:36:49 2018 us=239615 VERIFY OK: nsCertType=SERVER
Wed Feb 21 17:36:49 2018 us=239656 VERIFY OK: depth=0, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=server, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:36:49 2018 us=269625 UDP WRITE [368] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 2 ] pid=2 DATA len=342
Wed Feb 21 17:36:51 2018 us=683523 UDP WRITE [356] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=342
Wed Feb 21 17:36:55 2018 us=442426 UDP WRITE [356] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=342
Wed Feb 21 17:36:55 2018 us=549018 NOTE: --mute triggered...
Wed Feb 21 17:36:58 2018 us=55169 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:58 2018 us=55281 TLS Error: TLS key negotiation failed to occur within 17 seconds (check your network connectivity)
Wed Feb 21 17:36:58 2018 us=55328 TLS Error: TLS handshake failed
Wed Feb 21 17:36:58 2018 us=55365 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55662 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55702 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55749 NOTE: --mute triggered...
Wed Feb 21 17:36:58 2018 us=55882 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:58 2018 us=55921 TCP/UDP: Closing socket
Wed Feb 21 17:36:58 2018 us=55985 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=56041 SIGUSR1[soft,tls-error] received, process restarting
Wed Feb 21 17:36:58 2018 us=56114 Restart pause, 5 second(s)
Wed Feb 21 17:37:03 2018 us=56368 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:37:03 2018 us=56492 Re-using SSL/TLS context
Wed Feb 21 17:37:03 2018 us=56552 LZO compression initializing
Wed Feb 21 17:37:03 2018 us=56627 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56730 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56812 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56886 NOTE: --mute triggered...
Wed Feb 21 17:37:03 2018 us=56930 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:03 2018 us=56964 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:37:03 2018 us=57011 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:37:03 2018 us=57054 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:37:03 2018 us=57121 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:37:03 2018 us=57158 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:37:03 2018 us=57215 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:37:03 2018 us=57250 NOTE: --mute triggered...
Wed Feb 21 17:37:03 2018 us=57295 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:03 2018 us=57333 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:37:03 2018 us=57375 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:37:03 2018 us=57426 TCP/UDP: Preserving recently used remote address: [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:03 2018 us=57510 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:37:03 2018 us=57550 UDP link local: (not bound)
Wed Feb 21 17:37:03 2018 us=57589 UDP link remote: [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:03 2018 us=57666 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Wed Feb 21 17:37:03 2018 us=57706 SENT PING
Wed Feb 21 17:37:03 2018 us=57793 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:03 2018 us=57982 TUN READ [42]
Wed Feb 21 17:37:03 2018 us=58025 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:05 2018 us=70542 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:05 2018 us=70764 TUN READ [42]
Wed Feb 21 17:37:05 2018 us=70810 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:09 2018 us=95596 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:09 2018 us=95820 TUN READ [42]
Wed Feb 21 17:37:09 2018 us=95867 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:17 2018 us=161499 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:17 2018 us=161714 TUN READ [42]
Wed Feb 21 17:37:17 2018 us=161761 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:20 2018 us=192477 TLS Error: TLS key negotiation failed to occur within 17 seconds (check your network connectivity)
Wed Feb 21 17:37:20 2018 us=192586 TLS Error: TLS handshake failed
Wed Feb 21 17:37:20 2018 us=192634 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192738 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192777 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192864 NOTE: --mute triggered...
Wed Feb 21 17:37:20 2018 us=192998 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:20 2018 us=193037 TCP/UDP: Closing socket
Wed Feb 21 17:37:20 2018 us=193107 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=193154 SIGUSR1[soft,tls-error] received, process restarting
Wed Feb 21 17:37:20 2018 us=193225 Restart pause, 5 second(s)
Wed Feb 21 17:37:25 2018 us=193491 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:37:25 2018 us=193613 Re-using SSL/TLS context
Wed Feb 21 17:37:25 2018 us=193665 LZO compression initializing
Wed Feb 21 17:37:25 2018 us=193751 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193853 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193907 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193976 NOTE: --mute triggered...
Wed Feb 21 17:37:25 2018 us=194020 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:25 2018 us=194056 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:37:25 2018 us=194104 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:37:25 2018 us=194237 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:38:45 2018 us=278630 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:38:45 2018 us=278747 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:38:45 2018 us=278834 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:38:45 2018 us=278874 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:38:45 2018 us=278936 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:38:45 2018 us=278974 NOTE: --mute triggered...
Wed Feb 21 17:38:45 2018 us=279020 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:38:45 2018 us=279058 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:38:45 2018 us=279096 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:38:45 2018 us=279206 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:40:05 2018 us=358965 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:40:10 2018 us=359395 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:41:30 2018 us=436137 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:41:35 2018 us=436550 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:42:55 2018 us=506826 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:43:00 2018 us=507244 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:44:20 2018 us=575635 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:44:20 2018 us=575743 Could not determine IPv4/IPv6 protocol
Wed Feb 21 17:44:20 2018 us=575794 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575882 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575920 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575954 NOTE: --mute triggered...
Wed Feb 21 17:44:20 2018 us=576037 6 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:44:20 2018 us=576076 SIGUSR1[soft,init_instance] received, process restarting
Wed Feb 21 17:44:20 2018 us=576143 Restart pause, 5 second(s)
Wed Feb 21 17:44:25 2018 us=576313 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:44:25 2018 us=576431 Re-using SSL/TLS context
Wed Feb 21 17:44:25 2018 us=576495 LZO compression initializing
Wed Feb 21 17:44:25 2018 us=576558 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576642 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576698 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576769 NOTE: --mute triggered...
Wed Feb 21 17:44:25 2018 us=576821 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:44:25 2018 us=576851 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:44:25 2018 us=576890 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:44:25 2018 us=576986 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:45:45 2018 us=644058 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:45:45 2018 us=644175 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:45:45 2018 us=644257 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:45:45 2018 us=644299 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:45:45 2018 us=644369 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:45:45 2018 us=644408 NOTE: --mute triggered...
Wed Feb 21 17:45:45 2018 us=644456 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:45:45 2018 us=644498 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:45:45 2018 us=644538 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:45:45 2018 us=644649 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:47:05 2018 us=709290 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:47:10 2018 us=709727 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:48:30 2018 us=777377 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:48:35 2018 us=777796 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:49:55 2018 us=847929 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:50:00 2018 us=848358 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:51:20 2018 us=916209 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:51:20 2018 us=916318 Could not determine IPv4/IPv6 protocol
Wed Feb 21 17:51:20 2018 us=916380 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916470 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916510 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916539 NOTE: --mute triggered...
Wed Feb 21 17:51:20 2018 us=916624 6 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:51:20 2018 us=916663 SIGUSR1[soft,init_instance] received, process restarting
Wed Feb 21 17:51:20 2018 us=916732 Restart pause, 5 second(s)
Wed Feb 21 17:51:25 2018 us=916978 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:51:25 2018 us=917100 Re-using SSL/TLS context
Wed Feb 21 17:51:25 2018 us=917156 LZO compression initializing
Wed Feb 21 17:51:25 2018 us=917236 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917336 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917384 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917455 NOTE: --mute triggered...
Wed Feb 21 17:51:25 2018 us=917500 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:51:25 2018 us=917534 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:51:25 2018 us=917587 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:51:25 2018 us=917710 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:52:45 2018 us=985807 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.org:443 (Temporary failure in name resolution)
Wed Feb 21 17:52:45 2018 us=985925 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:52:45 2018 us=986010 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:52:45 2018 us=986052 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:52:45 2018 us=986104 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:52:45 2018 us=986140 NOTE: --mute triggered...
Wed Feb 21 17:52:45 2018 us=986187 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:52:45 2018 us=986224 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:52:45 2018 us=986261 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:52:45 2018 us=986373 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:54:06 2018 us=55004 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.org:443 (Temporary failure in name resolution)
Wed Feb 21 17:54:11 2018 us=55339 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
I also just realized I left my token in the email when posting a previous reply, which was really dumb of me, but thanks parityboy for removing it.
I don't know if this issue is client-side or server-side, but I am using a laptop on a wireless network with Debian Stretch Stable (backported openvpn. I also tested the stable openvpn, and it has the same issues).
My networking on Debian also has absolutely no problems; I used my wifi to play some games and do stuff on the web and it never drops. It just seems to be OpenVPN.
Also, @df, is your server based in Iceland?
Thanks.
[quote="Fermi"]
-snip-
[/quote]
[quote="parityboy"]
-snip-
[/quote]
[quote="df"]
-snip-
[/quote]
I raised the verbosity log level to 7 with "verb 7," and this is the new log with my errors:
[code]
Wed Feb 21 17:32:39 2018 us=892443 Current Parameter Settings:
Wed Feb 21 17:32:39 2018 us=892555 config = '-snip-/cstorm_linux-netherlands_udp.ovpn'
Wed Feb 21 17:32:39 2018 us=892606 mode = 0
Wed Feb 21 17:32:39 2018 us=892649 NOTE: --mute triggered...
Wed Feb 21 17:32:39 2018 us=892710 349 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:32:39 2018 us=892753 OpenVPN 2.4.4 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Dec 30 2017
Wed Feb 21 17:32:39 2018 us=892859 library versions: OpenSSL 1.0.2l 25 May 2017, LZO 2.08
Enter Auth Username: -snip-
Enter Auth Password: *
Wed Feb 21 17:33:32 2018 us=278384 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:33:32 2018 us=279354 PRNG init md=SHA1 size=36
Wed Feb 21 17:33:32 2018 us=279421 LZO compression initializing
Wed Feb 21 17:33:32 2018 us=279516 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279630 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279686 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:33:32 2018 us=279781 NOTE: --mute triggered...
Wed Feb 21 17:33:32 2018 us=279826 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:32 2018 us=279860 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:33:32 2018 us=279922 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:33:32 2018 us=280062 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:33:57 2018 us=418487 RESOLVE_REMOTE flags=0x0901 phase=1 rrs=0 sig=-1 status=0
Wed Feb 21 17:33:57 2018 us=418576 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:33:57 2018 us=418659 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:33:57 2018 us=418698 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:33:57 2018 us=418758 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:33:57 2018 us=418794 NOTE: --mute triggered...
Wed Feb 21 17:33:57 2018 us=418842 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:57 2018 us=418879 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:33:57 2018 us=418917 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:33:57 2018 us=418971 TCP/UDP: Preserving recently used remote address: [AF_INET]185.107.80.85:443
Wed Feb 21 17:33:57 2018 us=419028 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:33:57 2018 us=419072 UDP link local: (not bound)
Wed Feb 21 17:33:57 2018 us=419112 UDP link remote: [AF_INET]185.107.80.85:443
Wed Feb 21 17:33:57 2018 us=419236 UDP WRITE [14] to [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:33:57 2018 us=593166 UDP READ [26] from [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Wed Feb 21 17:33:57 2018 us=593262 TLS: Initial packet from [AF_INET]185.107.80.85:443, sid=68a9b54c a590ad57
Wed Feb 21 17:33:57 2018 us=593404 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 0 ]
Wed Feb 21 17:33:57 2018 us=593899 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Wed Feb 21 17:33:57 2018 us=593990 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:33:57 2018 us=767681 UDP READ [1200] from [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 1 ] pid=1 DATA len=1174
Wed Feb 21 17:33:57 2018 us=768019 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 1 ]
Wed Feb 21 17:33:57 2018 us=768493 NOTE: --mute triggered...
Wed Feb 21 17:33:57 2018 us=771117 3 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:33:57 2018 us=771162 VERIFY OK: depth=1, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=cryptostorm_is, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:33:57 2018 us=771833 VERIFY OK: nsCertType=SERVER
Wed Feb 21 17:33:57 2018 us=771863 VERIFY OK: depth=0, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=server, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:33:57 2018 us=771994 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 3 ]
Wed Feb 21 17:33:57 2018 us=772460 UDP READ [47] from [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=33
Wed Feb 21 17:33:57 2018 us=798171 UDP WRITE [368] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 4 ] pid=2 DATA len=342
Wed Feb 21 17:33:57 2018 us=971990 NOTE: --mute triggered...
Wed Feb 21 17:34:11 2018 us=908652 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:11 2018 us=908757 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 2048 bit RSA
Wed Feb 21 17:34:11 2018 us=908872 [server] Peer Connection Initiated with [AF_INET]185.107.80.85:443
Wed Feb 21 17:34:13 2018 us=8289 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
Wed Feb 21 17:34:13 2018 us=8464 UDP WRITE [83] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=69
Wed Feb 21 17:34:15 2018 us=208432 UDP WRITE [83] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=4 DATA len=69
Wed Feb 21 17:34:15 2018 us=213919 UDP READ [22] from [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 4 ]
Wed Feb 21 17:34:15 2018 us=214091 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=214220 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=214265 PUSH: Received control message: 'PUSH_REPLY,persist-key,persist-tun,redirect-gateway def1,dhcp-option DNS 185.107.80.84,route-gateway 10.33.0.1,topology subnet,ping 20,ping-restart 60,ifconfig 10.33.66.197 255.255.0.0'
Wed Feb 21 17:34:15 2018 us=214484 OPTIONS IMPORT: timers and/or timeouts modified
Wed Feb 21 17:34:15 2018 us=214523 OPTIONS IMPORT: --persist options modified
Wed Feb 21 17:34:15 2018 us=214557 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=214610 4 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=214646 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:34:15 2018 us=214682 MTU DYNAMIC mtu=1400, flags=2, 1602 -> 1400
Wed Feb 21 17:34:15 2018 us=214720 Data Channel MTU parms [ L:1602 D:1400 EF:102 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:34:15 2018 us=214785 Client pre_master: af8a0454 7837a294 ac1cf81f baca0203 bdc84cf2 3db2affc a37b00d7 f0add86c d52af53d 4bd6a31d ffc48cc8 be57c525
Wed Feb 21 17:34:15 2018 us=214839 Client random1: 7c369187 e17bdbf1 a0c46b88 b00bd4ad bbd91a6e 8cd55a08 b93eec7c 743e7c0a
Wed Feb 21 17:34:15 2018 us=214892 Client random2: 5afc0c7b a07720a1 b020ee43 7010824d 9eac36a3 90049dc2 3573fcad 51566b67
Wed Feb 21 17:34:15 2018 us=214925 NOTE: --mute triggered...
Wed Feb 21 17:34:15 2018 us=215230 25 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:34:15 2018 us=215270 Outgoing Data Channel: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Feb 21 17:34:15 2018 us=215322 Outgoing Data Channel: CIPHER KEY: a2658f1f 26cfb339 54bcf3e9 acf64102 a6a25abf 95b22f53 da402cc9 b13451cd
Wed Feb 21 17:34:15 2018 us=215362 Outgoing Data Channel: CIPHER block_size=16 iv_size=16
Wed Feb 21 17:34:15 2018 us=215423 Outgoing Data Channel: Using 512 bit message hash 'SHA512' for HMAC authentication
Wed Feb 21 17:34:15 2018 us=215496 Outgoing Data Channel: HMAC KEY: c62e8a06 53480372 20f4cb97 856705a1 25d417a9 a3bdb53f 47d7b1b1 8f71c67c 426b96c4 b6761250 4faa3964 ebb80819 bc80def9 665e8636 26cb5448 e8b72fee
Wed Feb 21 17:34:15 2018 us=215532 Outgoing Data Channel: HMAC size=64 block_size=64
Wed Feb 21 17:34:15 2018 us=215572 Incoming Data Channel: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Feb 21 17:34:15 2018 us=215626 Incoming Data Channel: CIPHER KEY: 81ce3d6d a1c26ee5 3d484ed7 6700388e bc6ffada 08199dad 85d0887f 44b56ee3
Wed Feb 21 17:34:15 2018 us=215665 Incoming Data Channel: CIPHER block_size=16 iv_size=16
Wed Feb 21 17:34:15 2018 us=215717 Incoming Data Channel: Using 512 bit message hash 'SHA512' for HMAC authentication
Wed Feb 21 17:34:15 2018 us=215789 Incoming Data Channel: HMAC KEY: 9cf3f960 bc382d3b a456c638 6abf1dfe 0bd080a8 6bbdc050 115b5bd4 f1cf506f 14d7be24 58774907 1463c7ac 448dbdfa 2e470ab8 a46222e4 9859c562 a46fdaef
Wed Feb 21 17:34:15 2018 us=215826 Incoming Data Channel: HMAC size=64 block_size=64
Wed Feb 21 17:34:15 2018 us=216160 ROUTE_GATEWAY 192.168.1.254/255.255.255.0 IFACE=wlo1 HWADDR=ee:5f:80:89:24:c7
Wed Feb 21 17:34:15 2018 us=224850 TUN/TAP device tun0 opened
Wed Feb 21 17:34:15 2018 us=224956 TUN/TAP TX queue length set to 100
Wed Feb 21 17:34:15 2018 us=225013 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Wed Feb 21 17:34:15 2018 us=225065 /sbin/ip link set dev tun0 up mtu 1500
Wed Feb 21 17:34:15 2018 us=229428 /sbin/ip addr add dev tun0 10.33.66.197/16 broadcast 10.33.255.255
Wed Feb 21 17:34:15 2018 us=231303 /sbin/ip route add 185.107.80.85/32 via 192.168.1.254
Wed Feb 21 17:34:15 2018 us=232851 /sbin/ip route add 0.0.0.0/1 via 10.33.0.1
Wed Feb 21 17:34:15 2018 us=235098 /sbin/ip route add 128.0.0.0/1 via 10.33.0.1
Wed Feb 21 17:34:15 2018 us=242308 Initialization Sequence Completed
Wed Feb 21 17:34:15 2018 us=242404 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 7 ]
Wed Feb 21 17:34:35 2018 us=583641 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:34:35 2018 us=583838 SENT PING
Wed Feb 21 17:34:35 2018 us=583912 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=870497 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:34:55 2018 us=870664 SENT PING
Wed Feb 21 17:34:55 2018 us=870742 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=903137 UDP READ [113] from [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:34:55 2018 us=903242 TLS: tls_pre_decrypt, key_id=0, IP=[AF_INET]185.107.80.85:443
Wed Feb 21 17:34:55 2018 us=903339 PID_TEST [0] [SSL-0] [] 0:0 0:2 t=1519259695[0] r=[0,64,15,0,1] sl=[0,0,64,528]
Wed Feb 21 17:34:55 2018 us=903368 RECEIVED PING PACKET
Wed Feb 21 17:35:15 2018 us=144976 NOTE: --mute triggered...
Wed Feb 21 17:35:15 2018 us=145166 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:35:15 2018 us=145228 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=663258 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:35:35 2018 us=663424 SENT PING
Wed Feb 21 17:35:35 2018 us=663496 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=676778 UDP READ [113] from [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:35:35 2018 us=676918 TLS: tls_pre_decrypt, key_id=0, IP=[AF_INET]185.107.80.85:443
Wed Feb 21 17:35:35 2018 us=677002 PID_TEST [0] [SSL-0] [EE] 0:2 0:4 t=1519259735[0] r=[0,64,15,0,1] sl=[62,2,64,528]
Wed Feb 21 17:35:35 2018 us=677038 RECEIVED PING PACKET
Wed Feb 21 17:35:56 2018 us=69848 NOTE: --mute triggered...
Wed Feb 21 17:35:56 2018 us=70036 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:35:56 2018 us=70097 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:36:16 2018 us=818135 TLS: tls_pre_encrypt: key_id=0
Wed Feb 21 17:36:16 2018 us=818303 SENT PING
Wed Feb 21 17:36:16 2018 us=818424 UDP WRITE [113] to [AF_INET]185.107.80.85:443: P_DATA_V1 kid=0 DATA len=112
Wed Feb 21 17:36:35 2018 us=960159 [server] Inactivity timeout (--ping-restart), restarting
Wed Feb 21 17:36:35 2018 us=960346 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960741 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960814 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=960855 NOTE: --mute triggered...
Wed Feb 21 17:36:35 2018 us=960939 5 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:35 2018 us=960978 TCP/UDP: Closing socket
Wed Feb 21 17:36:35 2018 us=961043 PID packet_id_free
Wed Feb 21 17:36:35 2018 us=961100 SIGUSR1[soft,ping-restart] received, process restarting
Wed Feb 21 17:36:35 2018 us=961172 Restart pause, 5 second(s)
Wed Feb 21 17:36:40 2018 us=961418 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:36:40 2018 us=961544 Re-using SSL/TLS context
Wed Feb 21 17:36:40 2018 us=961601 LZO compression initializing
Wed Feb 21 17:36:40 2018 us=961720 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961822 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961871 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:36:40 2018 us=961954 NOTE: --mute triggered...
Wed Feb 21 17:36:40 2018 us=961999 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:40 2018 us=962034 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:36:40 2018 us=962089 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:36:40 2018 us=962136 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:36:40 2018 us=962202 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:36:40 2018 us=962239 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:36:40 2018 us=962297 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:36:40 2018 us=962332 NOTE: --mute triggered...
Wed Feb 21 17:36:40 2018 us=962378 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:40 2018 us=962415 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:36:40 2018 us=962452 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:36:40 2018 us=962506 TCP/UDP: Preserving recently used remote address: [AF_INET]185.107.80.85:443
Wed Feb 21 17:36:40 2018 us=962595 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:36:40 2018 us=962634 UDP link local: (not bound)
Wed Feb 21 17:36:40 2018 us=962675 UDP link remote: [AF_INET]185.107.80.85:443
Wed Feb 21 17:36:40 2018 us=962757 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Wed Feb 21 17:36:40 2018 us=962797 SENT PING
Wed Feb 21 17:36:40 2018 us=962887 UDP WRITE [14] to [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:36:42 2018 us=10261 UDP READ [26] from [AF_INET]185.107.80.85:443: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Wed Feb 21 17:36:42 2018 us=10382 TLS: Initial packet from [AF_INET]185.107.80.85:443, sid=fbe05bf0 12ea22ff
Wed Feb 21 17:36:42 2018 us=10519 UDP WRITE [22] to [AF_INET]185.107.80.85:443: P_ACK_V1 kid=0 [ 0 ]
Wed Feb 21 17:36:42 2018 us=11441 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:36:44 2018 us=332306 UDP WRITE [109] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=95
Wed Feb 21 17:36:46 2018 us=119833 NOTE: --mute triggered...
Wed Feb 21 17:36:49 2018 us=238730 8 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:49 2018 us=238826 VERIFY OK: depth=1, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=cryptostorm_is, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:36:49 2018 us=239615 VERIFY OK: nsCertType=SERVER
Wed Feb 21 17:36:49 2018 us=239656 VERIFY OK: depth=0, C=CA, ST=QC, L=Montreal, O=Katana Holdings Limite / cryptostorm_darknet, OU=Tech Ops, CN=server, emailAddress=certadmin@cryptostorm.is
Wed Feb 21 17:36:49 2018 us=269625 UDP WRITE [368] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ 2 ] pid=2 DATA len=342
Wed Feb 21 17:36:51 2018 us=683523 UDP WRITE [356] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=342
Wed Feb 21 17:36:55 2018 us=442426 UDP WRITE [356] to [AF_INET]185.107.80.85:443: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=342
Wed Feb 21 17:36:55 2018 us=549018 NOTE: --mute triggered...
Wed Feb 21 17:36:58 2018 us=55169 2 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:58 2018 us=55281 TLS Error: TLS key negotiation failed to occur within 17 seconds (check your network connectivity)
Wed Feb 21 17:36:58 2018 us=55328 TLS Error: TLS handshake failed
Wed Feb 21 17:36:58 2018 us=55365 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55662 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55702 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=55749 NOTE: --mute triggered...
Wed Feb 21 17:36:58 2018 us=55882 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:36:58 2018 us=55921 TCP/UDP: Closing socket
Wed Feb 21 17:36:58 2018 us=55985 PID packet_id_free
Wed Feb 21 17:36:58 2018 us=56041 SIGUSR1[soft,tls-error] received, process restarting
Wed Feb 21 17:36:58 2018 us=56114 Restart pause, 5 second(s)
Wed Feb 21 17:37:03 2018 us=56368 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:37:03 2018 us=56492 Re-using SSL/TLS context
Wed Feb 21 17:37:03 2018 us=56552 LZO compression initializing
Wed Feb 21 17:37:03 2018 us=56627 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56730 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56812 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:03 2018 us=56886 NOTE: --mute triggered...
Wed Feb 21 17:37:03 2018 us=56930 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:03 2018 us=56964 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:37:03 2018 us=57011 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:37:03 2018 us=57054 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:37:03 2018 us=57121 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:37:03 2018 us=57158 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:37:03 2018 us=57215 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:37:03 2018 us=57250 NOTE: --mute triggered...
Wed Feb 21 17:37:03 2018 us=57295 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:03 2018 us=57333 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:37:03 2018 us=57375 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:37:03 2018 us=57426 TCP/UDP: Preserving recently used remote address: [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:03 2018 us=57510 Socket Buffers: R=[212992->212992] S=[212992->212992]
Wed Feb 21 17:37:03 2018 us=57550 UDP link local: (not bound)
Wed Feb 21 17:37:03 2018 us=57589 UDP link remote: [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:03 2018 us=57666 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Wed Feb 21 17:37:03 2018 us=57706 SENT PING
Wed Feb 21 17:37:03 2018 us=57793 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:03 2018 us=57982 TUN READ [42]
Wed Feb 21 17:37:03 2018 us=58025 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:05 2018 us=70542 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:05 2018 us=70764 TUN READ [42]
Wed Feb 21 17:37:05 2018 us=70810 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:09 2018 us=95596 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:09 2018 us=95820 TUN READ [42]
Wed Feb 21 17:37:09 2018 us=95867 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:17 2018 us=161499 UDP WRITE [14] to [AF_INET]213.163.64.209:443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Wed Feb 21 17:37:17 2018 us=161714 TUN READ [42]
Wed Feb 21 17:37:17 2018 us=161761 Recursive routing detected, drop tun packet to [AF_INET]213.163.64.209:443
Wed Feb 21 17:37:20 2018 us=192477 TLS Error: TLS key negotiation failed to occur within 17 seconds (check your network connectivity)
Wed Feb 21 17:37:20 2018 us=192586 TLS Error: TLS handshake failed
Wed Feb 21 17:37:20 2018 us=192634 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192738 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192777 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=192864 NOTE: --mute triggered...
Wed Feb 21 17:37:20 2018 us=192998 10 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:20 2018 us=193037 TCP/UDP: Closing socket
Wed Feb 21 17:37:20 2018 us=193107 PID packet_id_free
Wed Feb 21 17:37:20 2018 us=193154 SIGUSR1[soft,tls-error] received, process restarting
Wed Feb 21 17:37:20 2018 us=193225 Restart pause, 5 second(s)
Wed Feb 21 17:37:25 2018 us=193491 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:37:25 2018 us=193613 Re-using SSL/TLS context
Wed Feb 21 17:37:25 2018 us=193665 LZO compression initializing
Wed Feb 21 17:37:25 2018 us=193751 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193853 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193907 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:37:25 2018 us=193976 NOTE: --mute triggered...
Wed Feb 21 17:37:25 2018 us=194020 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:37:25 2018 us=194056 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:37:25 2018 us=194104 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:37:25 2018 us=194237 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:38:45 2018 us=278630 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:38:45 2018 us=278747 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:38:45 2018 us=278834 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:38:45 2018 us=278874 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:38:45 2018 us=278936 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:38:45 2018 us=278974 NOTE: --mute triggered...
Wed Feb 21 17:38:45 2018 us=279020 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:38:45 2018 us=279058 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:38:45 2018 us=279096 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:38:45 2018 us=279206 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:40:05 2018 us=358965 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:40:10 2018 us=359395 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:41:30 2018 us=436137 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:41:35 2018 us=436550 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:42:55 2018 us=506826 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:43:00 2018 us=507244 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:44:20 2018 us=575635 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.nu:443 (Temporary failure in name resolution)
Wed Feb 21 17:44:20 2018 us=575743 Could not determine IPv4/IPv6 protocol
Wed Feb 21 17:44:20 2018 us=575794 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575882 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575920 PID packet_id_free
Wed Feb 21 17:44:20 2018 us=575954 NOTE: --mute triggered...
Wed Feb 21 17:44:20 2018 us=576037 6 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:44:20 2018 us=576076 SIGUSR1[soft,init_instance] received, process restarting
Wed Feb 21 17:44:20 2018 us=576143 Restart pause, 5 second(s)
Wed Feb 21 17:44:25 2018 us=576313 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:44:25 2018 us=576431 Re-using SSL/TLS context
Wed Feb 21 17:44:25 2018 us=576495 LZO compression initializing
Wed Feb 21 17:44:25 2018 us=576558 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576642 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576698 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:44:25 2018 us=576769 NOTE: --mute triggered...
Wed Feb 21 17:44:25 2018 us=576821 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:44:25 2018 us=576851 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:44:25 2018 us=576890 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:44:25 2018 us=576986 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:45:45 2018 us=644058 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:45:45 2018 us=644175 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:45:45 2018 us=644257 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:45:45 2018 us=644299 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:45:45 2018 us=644369 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:45:45 2018 us=644408 NOTE: --mute triggered...
Wed Feb 21 17:45:45 2018 us=644456 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:45:45 2018 us=644498 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:45:45 2018 us=644538 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:45:45 2018 us=644649 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:47:05 2018 us=709290 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:47:10 2018 us=709727 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:48:30 2018 us=777377 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:48:35 2018 us=777796 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:49:55 2018 us=847929 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:50:00 2018 us=848358 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:51:20 2018 us=916209 RESOLVE: Cannot resolve host address: linux-netherlands.cstorm.pw:443 (Temporary failure in name resolution)
Wed Feb 21 17:51:20 2018 us=916318 Could not determine IPv4/IPv6 protocol
Wed Feb 21 17:51:20 2018 us=916380 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916470 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916510 PID packet_id_free
Wed Feb 21 17:51:20 2018 us=916539 NOTE: --mute triggered...
Wed Feb 21 17:51:20 2018 us=916624 6 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:51:20 2018 us=916663 SIGUSR1[soft,init_instance] received, process restarting
Wed Feb 21 17:51:20 2018 us=916732 Restart pause, 5 second(s)
Wed Feb 21 17:51:25 2018 us=916978 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Feb 21 17:51:25 2018 us=917100 Re-using SSL/TLS context
Wed Feb 21 17:51:25 2018 us=917156 LZO compression initializing
Wed Feb 21 17:51:25 2018 us=917236 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917336 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917384 PID packet_id_init seq_backtrack=64 time_backtrack=15
Wed Feb 21 17:51:25 2018 us=917455 NOTE: --mute triggered...
Wed Feb 21 17:51:25 2018 us=917500 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:51:25 2018 us=917534 Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Wed Feb 21 17:51:25 2018 us=917587 MTU DYNAMIC mtu=1400, flags=2, 1622 -> 1400
Wed Feb 21 17:51:25 2018 us=917710 GETADDRINFO flags=0x0901 ai_family=0 ai_socktype=2
Wed Feb 21 17:52:45 2018 us=985807 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.org:443 (Temporary failure in name resolution)
Wed Feb 21 17:52:45 2018 us=985925 Data Channel MTU parms [ L:1622 D:1400 EF:122 EB:406 ET:0 EL:3 ]
Wed Feb 21 17:52:45 2018 us=986010 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:52:45 2018 us=986052 calc_options_string_link_mtu: link-mtu 1622 -> 1602
Wed Feb 21 17:52:45 2018 us=986104 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 100 bytes
Wed Feb 21 17:52:45 2018 us=986140 NOTE: --mute triggered...
Wed Feb 21 17:52:45 2018 us=986187 1 variation(s) on previous 3 message(s) suppressed by --mute
Wed Feb 21 17:52:45 2018 us=986224 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-client'
Wed Feb 21 17:52:45 2018 us=986261 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1602,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA512,keysize 256,key-method 2,tls-server'
Wed Feb 21 17:52:45 2018 us=986373 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
Wed Feb 21 17:54:06 2018 us=55004 RESOLVE: Cannot resolve host address: linux-netherlands.cryptostorm.org:443 (Temporary failure in name resolution)
Wed Feb 21 17:54:11 2018 us=55339 GETADDRINFO flags=0x0903 ai_family=0 ai_socktype=2
[/code]
I also just realized I left my token in the email when posting a previous reply, which was really dumb of me, but thanks parityboy for removing it.
I don't know if this issue is client-side or server-side, but I am using a laptop on a wireless network with Debian Stretch Stable (backported openvpn. I also tested the stable openvpn, and it has the same issues).
My networking on Debian also has absolutely no problems; I used my wifi to play some games and do stuff on the web and it never drops. It just seems to be OpenVPN.
Also, @df, is your server based in Iceland?
Thanks.