[1.1.0RC2] Very, very slow initial connexion to Usenet serve

Questions and bug reports for Beta releases should be posted here.
Forum rules
Help us help you:
  • Tell us what system you run SABnzbd on.
  • Adhere to the forum rules.
  • Do you experience problems during downloading?
    Check your connection in Status and Interface settings window.
    Use Test Server in Config > Servers.
    We will probably ask you to do a test using only basic settings.
  • Do you experience problems during repair or unpacking?
    Enable +Debug logging in the Status and Interface settings window and share the relevant parts of the log here using [ code ] sections.
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

OK, a hypothesis:

Facts:
- The Happy-Eyeballs test does a socket connect (and disconnect) for each IP address, for each number of connection you specified in SAB
- A newsserver only allows a maximum number of connections

So if you set to the maximum number of connections in SAB, the happy-eyeballs test "eats" those (or even: more than) allowed connections. The newsservers waits until those connections have been closed, possibly causing the 20 sec - 1 minute delay. = Hypothesis

So, EvilFlander and Mastyman:
- what is the max number of connections that is allowed by your newsprovider?
- can you lower to 1/3 of that in SABnzbd?


PS: I wish I could reproduce this problem on my own system, but I have no newsserver with multiple IP addresses with 50 connections.
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

yep news.newsgroup.ninja, priority 0, 50 connections, SSL Port 563
i was going to cancel it in a few days and go back to supernews because it was taking so long to start the download, incorrectly assumed it was them, since i havent seen this on supernews or my block providers
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

sander wrote: So, EvilFlander and Mastyman:
- what is the max number of connections that is allowed by your newsprovider?
- can you lower to 1/3 of that in SABnzbd?
Sure

i turned back on "Allow Load Balancing with optimisation for IPv6" and reduced the connections to 17 for newsgroup ninja
it took 15 seconds after the NZB was added to start

i dont believe it has anything to do with the amount of connections, as i tried this a few days ago as well, made no difference to the start delay


changed it back to "Allow Load Balancing" and its back to instant download on nzb add.
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

Another test, please:
turn off SSL (and thus set port to 119), with 50 connections and "Allow Load Balancing with optimisation for IPv6": what is the result.
turn off SSL (and thus set port to 119), with 10 connections and "Allow Load Balancing with optimisation for IPv6": what is the result.

I don't think it's SSL related, but just to be sure.

with SSL on or off, use 10 connections and "Allow Load Balancing with optimisation for IPv6": what is the delay, and how does the download speed compare to the speed with 50 connections? (I do not expect a lower download speed)
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

sander wrote:Another test, please:
turn off SSL (and thus set port to 119), with 50 connections and "Allow Load Balancing with optimisation for IPv6": what is the result.

About 12 seconds for download to start

turn off SSL (and thus set port to 119), with 10 connections and "Allow Load Balancing with optimisation for IPv6": what is the result.

About 6 seconds to start

so definitely alot faster with SSL off and only 10 connections compared to 50,
but still far slower than SSL on, 50 connections and "Allow Load Balancing" only which is 1-2 seconds.
EvilFlanders
Newbie
Newbie
Posts: 23
Joined: May 25th, 2016, 4:02 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by EvilFlanders »

Unfortunately I've installed 1.1.0RC3 in between...

Here is what I have with RC3, measured in seconds to start a new download after a forced disconnect:

"Allow Load Balancing with optimisation for IPv6": used to be around 20 seconds in RC2, is now 5 to 6 seconds with SSL, under 2 seconds without SSL.
"Allow Load Balancing": used to be around 2 seconds in RC2, is now under 2 seconds with SSL, under 1 second without SSL.
"Prevent Load balancing": instantaneous (under 1 second) either RC2 or RC3, SSL or no SSL.

- Overall I can notice that the delay has been vastly reduced in RC3, particularly with the worst setup.
- Changing the number of connections from 30 (with a max of 30) to 10 shaves about a second to the 5-6 seconds (with "Allow Load Balancing with optimisation for IPv6" and SSL activated), and does not affect noticeably "Allow Load Balancing" (which can be confirmed with mastyman's result since it takes him the same time with 50 connections).
- Turning off SSL and reverting to port 119 does improve things quite noticeably, particularly with the worst setup.

As for the download speed, I've tried with 10 connections, either SSL or no SSL, "Allow Load Balancing with optimisation for IPv6" or else, and it does not seem to affect anything. I use power line TCP adapters, and it makes my bandwidth quite unstable. Yet, I could not notice much difference, varying between 16.8 and 18 Mbytes/sec.
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

i know on my connection, i have ipv6 turned off on my routers wan, since not support by ISP

sab still reports the following in the log:

2016-08-21 11:16:47,963::DEBUG::[__init__:1153] Test IPv6: IPv6 test successful. Enabling IPv6
2016-08-21 11:16:47,963::DEBUG::[__init__:284] External IPv6 test result: True

maybe the delay with having "Allow Load Balancing with optimisation for IPv6" enabled is because i dont have ipv6 on the wan side, yet sab seems to think so?

heres what a ipv6 test site shows for my connection

http://imgur.com/a/PfsTj
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

@mastyman: that is strange, but I'm quite sure it is not the cause; happey-eyebalss can handle that.

If you want, we can analyze it in a seperate thread. Could be useful for SABnzbd. Do you want that? Can you run a python program on your system?
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

im not fussed personally since its all good for me now, but im happy to help if its of value to you guys, i can install python no issues - and follow your instructions
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

Good news:

I could borrow an Astraweb account from a friendly person, and was able to reproduce the delay with 20 connections.
I programmed HappyEyeballs in a different way (only doing a socket.connect() to determine the quickest IP address if that was not yet done in the past 100 seconds), and the connection setup is now blazingly fast .... :) :) :)

Proof in the logging below

I'll do some more testing, and if everything is OK, I'll upload the code to SAB's github.

Code: Select all

2016-08-22 20:28:04,827::INFO::[downloader:445] [email protected]: Initiating connection
Host not yet known. Find entry
2016-08-22 20:28:04,866::INFO::[happyeyeballs:148] Quickest IP address for ssl.astraweb.com (port 563, ssl True, preferipv6 True) is 91.208.207.167
2016-08-22 20:28:04,867::DEBUG::[happyeyeballs:150] Happy Eyeballs lookup and port connect took 33 ms
Determined new result for (u'ssl.astraweb.com', 563, True, True) with result: ('91.208.207.167', 1471890484)
2016-08-22 20:28:04,867::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.167 as server
2016-08-22 20:28:04,869::INFO::[downloader:445] [email protected]: Initiating connection
existing result recent enough
2016-08-22 20:28:04,869::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.167 as server
2016-08-22 20:28:04,870::INFO::[downloader:445] [email protected]: Initiating connection
existing result recent enough
2016-08-22 20:28:04,871::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.167 as server
2016-08-22 20:28:04,871::INFO::[downloader:445] [email protected]: Initiating connection
existing result recent enough

EvilFlanders
Newbie
Newbie
Posts: 23
Joined: May 25th, 2016, 4:02 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by EvilFlanders »

Good job! Indeed, every millisecond instead of every half/full second!
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

@EvilFlanders and @mastyman,

1.1.0 RC4 is out and contains my patch "happy eyeballs with caching". Description in the release notes: "Faster initial connections to servers with multiple IP-addresses (HappyEyeBalls)"

So could you please download & install 1.1.0 RC4 (see https://forums.sabnzbd.org/viewtopic.php?f=8&t=21343), run it, and set:
- "Allow Load Balancing with optimisation for IPv6"
- Logging to +Debug

Then download something. Check that everything is fast. Check SABnzbd.log for files like this:

Code: Select all

2016-08-29 22:30:42,994::INFO::[SABnzbd:1231] SABnzbd.py-1.1.0RC4 (rev=34c24acb20e4257f21df20b936819c0386374b3d)

2016-08-29 22:32:50,442::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,578::INFO::[happyeyeballs:138] Quickest IP address for ssl.astraweb.com (port 563, ssl True, preferipv6 True) is 91.208.207.43
2016-08-29 22:32:50,579::DEBUG::[happyeyeballs:140] Happy Eyeballs lookup and port connect took 152 ms
2016-08-29 22:32:50,579::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,581::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,581::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,583::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,584::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,585::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,586::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,587::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,588::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,588::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,588::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,589::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,589::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,590::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,591::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,592::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,592::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,593::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,593::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,594::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,594::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,595::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,595::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,596::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,596::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,597::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,597::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,598::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,598::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,599::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,599::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,600::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,600::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,600::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,601::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,601::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,602::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,602::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:32:50,602::DEBUG::[downloader:121] For server ssl.astraweb.com, using IP 91.208.207.43 as server
2016-08-29 22:32:50,889::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,889::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,892::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,893::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,894::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,895::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,895::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,896::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,896::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,897::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,897::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,952::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,953::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,957::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,958::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,959::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,959::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,960::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:50,960::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:32:51,128::INFO::[downloader:647] Connecting [email protected] finished


2016-08-29 22:33:03,490::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:03,592::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:03,693::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:03,795::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:03,897::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:03,998::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,099::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,201::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,302::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,404::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,505::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,606::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,708::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,809::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:04,911::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:05,012::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:05,114::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:05,215::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:05,317::INFO::[downloader:734] Thread [email protected]: forcing disconnect
2016-08-29 22:33:05,419::INFO::[downloader:734] Thread [email protected]: forcing disconnect
Post back your results.
EvilFlanders
Newbie
Newbie
Posts: 23
Joined: May 25th, 2016, 4:02 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by EvilFlanders »

I've benchmarked both settings, from the initial log of importing the nzb to the last connection.
It takes "Allow Load Balancing with optimisation for IPv6" 5.26 seconds.
And it takes "Prevents Load Balancing" 5.22 seconds.

So, I'd say, it's pretty fast!

Code: Select all

2016-08-29 22:52:55,153::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,374::INFO::[happyeyeballs:138] Quickest IP address for news-eu.newshosting.com (port 563, ssl True, preferipv6 True) is 81.171.92.238
2016-08-29 22:52:55,375::DEBUG::[happyeyeballs:140] Happy Eyeballs lookup and port connect took 159 ms
2016-08-29 22:52:55,375::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,376::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,376::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,377::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,377::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,378::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,378::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,379::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,380::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,381::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,381::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,382::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,382::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,383::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,383::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,384::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,384::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,385::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,385::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,386::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,386::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,387::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,387::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,388::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,388::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,390::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,390::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,391::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,391::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,392::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,392::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,393::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,393::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,394::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,394::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,395::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,395::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,396::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,396::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,397::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,397::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,398::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,398::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,399::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,399::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,400::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,400::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,401::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,401::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,402::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,402::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:55,403::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:55,403::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:56,564::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,565::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,565::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,565::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,565::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,566::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,566::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,566::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,566::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,566::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,567::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,567::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,567::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,567::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,568::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,568::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,568::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,568::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,568::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,569::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,569::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,569::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,569::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,569::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,570::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,812::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:56,881::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:57,339::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:57,339::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:57,340::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:57,340::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:57,340::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-29 22:52:57,340::DEBUG::[downloader:121] For server news-eu.newshosting.com, using IP 81.171.92.238 as server
2016-08-29 22:52:57,862::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:57,862::INFO::[downloader:647] Connecting [email protected] finished
2016-08-29 22:52:57,863::INFO::[downloader:647] Connecting [email protected] finished
mastyman
Jr. Member
Jr. Member
Posts: 57
Joined: February 27th, 2012, 9:57 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by mastyman »

yep its fine now, with load balancing ipv6 enabled. huge improvement,

Code: Select all

2016-08-30 09:26:29,877::INFO::[downloader:445] [email protected]: Initiating connection
2016-08-30 09:26:30,825::INFO::[happyeyeballs:138] Quickest IP address for news.newsgroup.ninja (port 563, ssl True, preferipv6 True) is 69.16.179.22
2016-08-30 09:26:30,825::DEBUG::[happyeyeballs:140] Happy Eyeballs lookup and port connect took 948 ms
User avatar
sander
Release Testers
Release Testers
Posts: 9033
Joined: January 22nd, 2008, 2:22 pm

Re: [1.1.0RC2] Very, very slow initial connexion to Usenet s

Post by sander »

Cool.

Thank you for your report!
Post Reply