Hi all, first post. Thank you Greenbone for this forum and the excellent software you produce.
Moving from 20.08 version, using Ubuntu 20.04 for the OS. We have a fairly large setup in our organisation so am testing thoroughly before we move over completely.
Noticing with the 22.4.0 (freshly installed under ubuntu 22.04) with initial tests that I’m getting quite a few NVT timeouts now on scans; ones which would previously take a few minutes are taking a long longer and very often hitting up against those timeouts.
Because some of the servers in our org are affected by the scans (we’ve a lot of 3rd party software), this matters somewhat and my initial tests were hurting departments which would normally be able to work around the scans but can’t tolerate the length of time they were taking during the tests.
I’ve noticed one main difference so far - on the 20.08 scan testing against 1.3.6.1.4.1.25623.1.0.117823, an apache log4j2, one which repeatedly timed out, I noticed that it seems to do 2 lots of tests against the same port. Using tcpdump against port 80 whilst the NVT runs - 20.08 instance:
GET /?param=${jndi:ldap://192.168.1.1:25252/a} HTTP/1.1
GET /?param=${jndi:ldap://scanner_2008:25252/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#192.168.1.1:25252/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#scanner_2008:25252/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#192.168.1.1:25252/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#scanner_2008:25252/a} HTTP/1.1
… and 22.4.0:
GET /?param=${jndi:ldap://192.168.1.2:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://scanner_2240:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#192.168.1.2:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#scanner_2240:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#192.168.1.2:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#scanner_2240:16204/a} HTTP/1.1
GET /?param=${jndi:ldap://192.168.1.2:26271/a} HTTP/1.1
GET /?param=${jndi:ldap://scanner_2240:26271/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#192.168.1.2:26271/a} HTTP/1.1
GET /?param=${jndi:ldap://127.0.0.1#scanner_2240:26271/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#192.168.1.2:26271/a} HTTP/1.1
GET /?param=${jndi:ldap://localhost#scanner_2240:26271/a} HTTP/1.1
I haven’t yet checked the other NVTs which are taking longer than usual, just this one so far.
Any ideas? Did I overlook something important during the configuration?
Thanks
Just an extra bit of info - the logs from the scanner side. It does seem to do 2 sets of scanning against port 80, almost as if that port was registered twice somewhere.
Thanks
libgvm util: DEBUG:2022-10-01 10h53.07 utc:36006: mqtt_reset: start
libgvm util: DEBUG:2022-10-01 10h53.07 utc:36006: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util: DEBUG:2022-10-01 10h53.07 utc:36007: mqtt_reset: start
libgvm util: DEBUG:2022-10-01 10h53.07 utc:36007: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: family is 17
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: family is 17
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: family is 17
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is lo
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is 255.0.0.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: netmask is 255.0.0.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is ens160
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: netmask is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is ens192
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: netmask is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is lo
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is ens160
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is ffff:ffff:ffff:ffff::
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: interface name is ens192
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: address is ffff:ffff:ffff:ffff::
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and ::ffff:127.0.0.1
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and ::ffff:192.168.1.2
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and ::ffff:192.168.2.100
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and ::1
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and fe80::669c:ac84:1bbe:d8ed
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: comparing addresses ::ffff:192.168.1.100 and fe80::f9fa:4f39:8273:8b5d
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: source address is ::ffff:192.168.1.2
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: #0: for dev ens160, The dest is 0 and the mask is 0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: #1: for dev ens192, The dest is 0 and the mask is 0
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: #2: for dev ens160, The dest is B0800A and the mask is FFFFFF
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: #3: for dev ens160, The dest is FEA9 and the mask is FFFF
lib misc: DEBUG:2022-10-01 10h53.07 utc:36007: #4: for dev ens192, The dest is 7AA8C0 and the mask is FFFFFF
lib misc: DEBUG:2022-10-01 10h53.12 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.12 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.12 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.12 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.17 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.17 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.17 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.17 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.22 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.22 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.22 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.22 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.27 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.27 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.27 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.27 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.32 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.32 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.32 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.32 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.37 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.37 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.37 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.37 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.42 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.42 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.42 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.42 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.47 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.47 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.47 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.47 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.52 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.52 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.52 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.52 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h53.57 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h53.57 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h53.57 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h53.57 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.02 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.02 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.02 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.02 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.07 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.07 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.07 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.07 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.12 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.12 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.12 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.12 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.17 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.17 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.17 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.17 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.22 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.22 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.22 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.22 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.27 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.27 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.27 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.27 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.32 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.32 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.32 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.32 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.37 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.37 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.37 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.37 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.42 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.42 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.42 utc:36007: [36007] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.42 utc:36007: [36007] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.47 utc:36007: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.47 utc:36007: [36007] release_connection_fd: fd > 0 fd=22
libgvm util: DEBUG:2022-10-01 10h54.47 utc:36183: mqtt_reset: start
libgvm util: DEBUG:2022-10-01 10h54.47 utc:36183: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: family is 17
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: family is 17
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: family is 17
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is lo
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is 255.0.0.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: netmask is 255.0.0.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is ens160
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: netmask is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is ens192
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: netmask is 255.255.255.0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is lo
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is ens160
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is ffff:ffff:ffff:ffff::
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: AF_INET6 family
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: interface name is ens192
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: address is ffff:ffff:ffff:ffff::
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and ::ffff:127.0.0.1
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and ::ffff:192.168.1.2
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and ::ffff:192.168.2.100
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and ::1
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and fe80::669c:ac84:1bbe:d8ed
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: comparing addresses ::ffff:192.168.1.100 and fe80::f9fa:4f39:8273:8b5d
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: source address is ::ffff:192.168.1.2
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: #0: for dev ens160, The dest is 0 and the mask is 0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: #1: for dev ens192, The dest is 0 and the mask is 0
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: #2: for dev ens160, The dest is B0800A and the mask is FFFFFF
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: #3: for dev ens160, The dest is FEA9 and the mask is FFFF
lib misc: DEBUG:2022-10-01 10h54.47 utc:36183: #4: for dev ens192, The dest is 7AA8C0 and the mask is FFFFFF
lib misc: DEBUG:2022-10-01 10h54.52 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.52 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.52 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.52 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h54.57 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h54.57 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h54.57 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h54.57 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.02 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.02 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.02 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.02 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.07 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.07 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.07 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.07 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.12 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.12 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.12 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.12 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.17 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.17 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.17 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.17 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.22 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.22 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.22 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.22 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.27 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.27 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.27 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.27 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.32 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.32 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.32 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.32 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.37 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.37 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.37 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.37 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.42 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.42 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.42 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.42 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.47 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.47 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.47 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.47 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.52 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.52 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.52 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.52 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h55.57 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h55.57 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h55.57 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h55.57 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.02 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.02 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h56.02 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h56.02 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.07 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.07 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h56.07 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h56.07 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.12 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.12 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h56.12 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h56.12 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.17 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.17 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h56.17 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h56.17 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.22 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.22 utc:36183: [36183] release_connection_fd: fd > 0 fd=22
lib misc: DEBUG:2022-10-01 10h56.22 utc:36183: [36183] open_stream_connection: TCP:80 transport:1 timeout:10 priority: ‘NORMAL:+ARCFOUR-128:%COMPAT’
lib misc: DEBUG:2022-10-01 10h56.22 utc:36183: [36183] connect : Operation now in progress
lib misc: DEBUG:2022-10-01 10h56.27 utc:36183: close_stream_connection TCP:80 (fd=1000000)
lib misc: DEBUG:2022-10-01 10h56.27 utc:36183: [36183] release_connection_fd: fd > 0 fd=22