Forums / Support / bb waiting forever to retrieve posts- Status 'Starting'

bb waiting forever to retrieve posts- Status 'Starting'

First of all: don't know how describe it in a better way and I don't know if I can find the reason in an errorlog.

First the PC enviroment:
I use BB 1.97 b88 (but the problem did happen even with earöier builds) on Win7pro. It connects over a VPNconnection to giganews (port 563).
Sometimes I change in BB the number ob download connections and bandwidth throtlle between 2 and 20 connections and 15000Kbs - 22000Kbs (and any number in between - depending on the speed I want to achieve).
Timeout 10 secs; delay after error 6 secs, delay 10 secs after rejection; remember Ip address on.
In the same time I use utorrent from the same PC. Since the Pc is located in the basement I am controlling it remotely over a VNC connection. Av Software is now AVG Suite 2012.
I let BB run with a *.lst for 24/7 in an endless loop with auto, loop and autonext option on.

Problem:
Sometime it seems BB endlessly waits for postings and does not go on. In the queue-tab there are a number of connections with the status 'Starting' and that's it. First one or two connections and over the time all connections stay in this status. Even after minutes or hours, bb does not go on.
in the meantime: the PC connection to the internet did not break away, since the VPN connection is still active and utorrent still transfers data (I can see the network activity in the windows taskmanager).
When I manually press the red stop-button from BB, wait for few seconds an press the green start-button, bb goes on and retrieves the postings. then it runs with no problems for some time. When I log on to this PC again after some hours/days, it can happen, that bb again waits endlessly for postings with the Status 'Starting'.
and so on....
 

BinaryBoy's reply to Shinji #15620 @

Ok. I'll look into this. When it has a problem of any kind, it's supposed to timeout and try again so it shouldn't be doing this.
 

Shinji's reply to BinaryBoy #15622 @

Hello.

I just encountered the problem again. The Log-Tab shows:
====
14:46:06: [5496] Server closed connection. 0 bytes available. No error.
14:46:06: [5304] Server closed connection. 0 bytes available. No error.
14:46:06: [5480] Server closed connection. 0 bytes available. No error.

Then I press Stop and Go:
Stopping all jobs for this session.
Restarting downloads if necessary.
Downloading filename "[Coalgirls]_Mobile_Suit_Gundam_Unicorn_03_(1920x1080_Blu-ray_FLAC)_[4DA12357].mkv.147" part 6 of 78. <1324640018.85380.6@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[Coalgirls]_Mobile_Suit_Gundam_Unicorn_03_(1920x1080_Blu-ray_FLAC)_[4DA12357].mkv.121" part 14 of 78. <1324639882.30340.14@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[Coalgirls]_Mobile_Suit_Gundam_Unicorn_03_(1920x1080_Blu-ray_FLAC)_[4DA12357].mkv.006" part 54 of 78. <1324639271.79956.54@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[Coalgirls]_Mobile_Suit_Gundam_Unicorn_03_(1920x1080_Blu-ray_FLAC)_[4DA12357].mkv.125" part 17 of 78. <1324639901.92137.17@eu.news.astraweb.com> in from news-europe.giganews.com
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Compression ENABLED
Compression ENABLED
Compression ENABLED
...
 

BinaryBoy's reply to Shinji #15623 @

No solution to this yet but based on the information you showed and some poking around in the code, it appears that Binary Boy can sometimes fail to timeout after a connection is lost. This could potentially happen if the connection is lost at certain moments when no data is expected from the server. I'll continue to look into this.
 

BinaryBoy's reply to BinaryBoy #15624 @

The latest build attempts to fix this, assuming this is caused by the loss of connection while sending data to the server. If it doesn't work, look on the Log tab for the "Server closed connection" message. It will display a state number. Let me know what that number is.
 

Shinji's reply to BinaryBoy #15627 @

The Problem appeared again. BB1.97 b90

No message "Server closed connection" to be seen.

Here is the Log-tab:
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 6 of 39. <1326301060.70498.6@eu.news.astraweb.com> in from news-europe.giganews.com
Connection failed with error 11004 - The requested name is valid and was found in the database, but it does not have the correct associated data being resolved for..
Failed to connect to server "news-europe.giganews.com" port 563. Will retry.
Connection failed with error 11004 - The requested name is valid and was found in the database, but it does not have the correct associated data being resolved for..
Failed to connect to server "news-europe.giganews.com" port 563. Will retry.
Connection failed with error 11004 - The requested name is valid and was found in the database, but it does not have the correct associated data being resolved for..
Failed to connect to server "news-europe.giganews.com" port 563. Will retry.
Connection failed with error 11004 - The requested name is valid and was found in the database, but it does not have the correct associated data being resolved for..
Failed to connect to server "news-europe.giganews.com" port 563. Will retry.
...<and so on>...
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
...<and so on>...
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
...<and so on>...
Stopping all jobs for this session. <<<< here I pressed Stop
Restarting downloads if necessary. <<<< here I pressed Start
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.001" part 35 of 39. <1326301058.53738.35@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.007" part 6 of 8. <1326301067.69685.6@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 2 of 39. <1326301060.48599.2@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 3 of 39. <1326301060.50773.3@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 4 of 39. <1326301060.67222.4@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 5 of 39. <1326301060.68349.5@eu.news.astraweb.com> in from news-europe.giganews.com
Downloading filename "[HorribleSubs]_Ano_Natsu_de_Matteru_-_01_[480p].mkv.003" part 6 of 39. <1326301060.70498.6@eu.news.astraweb.com> in from news-europe.giganews.com
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Connected to news-europe.giganews.com (216.196.109.144).
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
Compression ENABLED
Compression ENABLED
Compression ENABLED
Compression ENABLED
Compression ENABLED
Compression ENABLED
Compression ENABLED

<And here BB downloads again without problem.>
 

BinaryBoy's reply to Shinji #15628 @

Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
200 News.GigaNews.Com
...<and so on>...
Stopping all jobs for this session. <<<< here I pressed Stop
Restarting downloads if necessary. <<<< here I pressed Start

When you pressed stop, was it still repeating those connection attempts or was it stalled out?

Also... do you have a rough idea of how long it takes for this to happen?
 

Shinji's reply to BinaryBoy #15629 @

Hello.

When I pressed stop ist endlessly running this
200 News.GigaNews.Com
Warning: Certificate has expired.
Certificate issuer: GeoTrust Inc.
Certificate signed by news-europe.giganews.com
stuff.
------------------
No there is no fixed time until the BB rund into the Problem that it waits forever.
Sometimes only some hours, sometime almost a week or more.
 

BinaryBoy's reply to Shinji #15630 @

Ok. Thanks for the info. Sounds like two different problems. One is stalling and the second is looping. I've created an account on that server and will keep testing.
 

Shinji's reply to BinaryBoy #15631 @

Hello.

I don't know if my following suggestions are already build in BB and I just did not find them, but I gues they could be helpfull in searchin for this and other future problems:
1. wouldn't it be nice to be able to copy the complete Log-Tab from inside BB to a file so someone does not have to copy every line individually copy/paste for every line is a bit time consuming. :)
2. Since the timing could help to see if BB does do something or not: how about inserting the local time in front of every line in the Log-Tab. I guess the date (day) would be to much, but the hour, minute, second may be helpful.

I searched the options in the BB Settings, but I can't find switches to activate this. So I guess they are not build in.

Thanks!
 

BinaryBoy's reply to Shinji #15632 @

If you click the text area on the Log tab, then press ctrl-A, you can select all then ctrl-c to copy all. Yes, a timestamp could be useful. I'll keep that in mind. Thanks.
 

BinaryBoy's reply to Shinji #15633 @

P.S. The looping problem might be fixed in the latest build. http://binaryboy.com/patch.php
 

Shinji's reply to BinaryBoy #15634 @

Hello.

I will now install the new build91 and test it. Thanks in advance!

I thought it through and I think I found a new look at this problem (or better at the puzzling Log-tab-lines):
Since I do not sit in front of the PC running BB (watching BB to do its work) and just look at it from time to time the appearance of endlessly waiting (Status: 'Starting') a download from a posting in the Queue-Tab can happen any time.
When it happens then it is for a single download connection.
The other connections do run on and the corresponding line in the log-tab gets flushed out by the new lines from the other connections.
Then it happens to an other connection and so 2 connections keep waiting forever, while the others (sometimes I increased up to 20) go on and flush the Log-tab-lines away. This may add on.
Over time some downloads are stuck in the status 'Starting' and wait endlessly, while the other connections flushed their log-tab-lines away and the queue-tab runs empty -except for the stuck postings. Since the queue is not completely empty, BB does not go on and waits endlessly for the stuck connections while the messages in the log-tab have noting to do with this stuck connections, since the correctly downloaded postings flushed everything away and the log tab only shows, that there are no more new postings to be loaded.
Then it looks like the queue-tab only has (for example) 5 endlessly 'Starting' postings and when I change to the Log-tab then I cant see the corresponding errors/lines, because the other connections had no problem and ended with not problem. So there may no more error to be seen in the log-tab.

I don't know how to solve this, but I guess this may help understanding why the copy/pasted lines may not help in searching the real problem for the stuck connections.
 

BinaryBoy's reply to Shinji #15636 @

There's always the -log switch. (Click Start, type binboy.exe -log and that will write to log.txt in Documents\Binary Boy\Logs

Unfortunately that will slow down the program a bit. For the next build, the Stop button will display some information on the Log tab about the state of the connection. That might help track down the stalling problem if it still exists.

Has it gone into a repeated loop again?
 

Shinji's reply to BinaryBoy #15637 @

Hello.

First: BB did not go into endless loop again. It held some postings in the 'Starting' phase, but after some time bb retried the download and got the files.

Second: I activated the -log switch. And bb did crash since then 4 times.
It looks like BB does something in the background, the BB-Program-window gets locked for many seconds.
Most of the time BB recovers and works along, but sometimes it seems that BB does not recovers fast enough and Windows 7pro32Bit thinks BB crashed.
Problemsignatur of win7:
Problemereignisname: APPCRASH
Anwendungsname: binboy.exe
Anwendungsversion: 1.97.0.91
Anwendungszeitstempel: 4f16bfd0
Fehlermodulname: binboy.exe
Fehlermodulversion: 1.97.0.91
Fehlermodulzeitstempel: 4f16bfd0
Ausnahmecode: c0000005
Ausnahmeoffset: 000db8d3
Betriebsystemversion: 6.1.7601.2.1.0.256.48
Gebietsschema-ID: 1031
Zusatzinformation 1: 0a9e
Zusatzinformation 2: 0a9e372d3b4ad19135b953a78882e789
Zusatzinformation 3: 0a9e
Zusatzinformation 4: 0a9e372d3b4ad19135b953a78882e789
=============
last lines from the binaryBoy log.txt:
19:46:13: Currently selected newsgroup is: "alt.binaries.dvd.anime"
19:46:13: GetToIdle(): Already in idle state. Returning success.
19:46:13: [2216] MetaReturn(0): Executing 45
19:46:13: SelectGroup2(): Sending GROUP alt.binaries.multimedia.japanese
19:46:13: [2216] Sending: GROUP alt.binaries.multimedia.japanese
19:46:13: [2216] CBBNNTP::send_until_blocked()
19:46:13: [2216] send_until_blocked(): Sent 40.
19:46:13: [2216] send_until_blocked(): Finished. Returning
19:46:13: After: 6 open, 6 active, 6 m_nslots
19:46:13: HandleSlotsChange entered.
19:46:13: HandleSlotsChange exiting.
19:46:13: CDownloader::FillAllSlots(): Exiting at end of function
19:46:13: [2216] ONCLOSE_SOCKET_:
19:46:13: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:13: Server closed connection. State: 20. 0 bytes available. No error.
19:46:14: [2172] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:14: Server closed connection. State: 20. 90 bytes available. No error.
19:46:14: [2224] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:14: Server closed connection. State: 20. 90 bytes available. No error.
19:46:14: [1468] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:14: Server closed connection. State: 20. 90 bytes available. No error.
19:46:14: [1788] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:14: Server closed connection. State: 20. 90 bytes available. No error.
19:46:14: [1684] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 3
19:46:14: Server closed connection. State: 3. 0 bytes available. No error.
19:46:14: [2252] ONCLOSE_SOCKET_:
19:46:14: CBBNNTP::OnClose(0): OnClose during state: 20
19:46:14: Server closed connection. State: 20. 0 bytes available. No error.
19:46:14: OnRead called 0.
19:46:14: OnRead: ReadIncoming returned 78 bytes read.
19:46:14: [-1] OnRead(): 78 read. Returning Success
19:46:14: [-1] MetaReturn(0): Executing 46
19:46:14: [-1] Response: 211 6767662 19111217 25878878 alt.binaries.multimedia.japanese
19:46:14: SelectGroup3(): Newsgroup entered successfully.
19:46:14: SelectGroup3(): Currently selected group set to "alt.binaries.multimedia.japanese"
19:46:14: [-1] MetaReturn(0): Executing 30
19:46:14: GetArticle2(-1): Requesting ARTICLE 25717607
19:46:14: [-1] Sending: ARTICLE 25717607
19:46:14: [-1] CBBNNTP::send_until_blocked()
====<here BB crashed>======

I will deactivate the -log again and try again. I hope it does not crash again.

Bye.
 

BinaryBoy's reply to Shinji #15638 @

Thanks for the info. It appears BB was notified of more incoming data even after the connection was closed so it continued working. That's probably the root of the problem. I'll have BB deal with that more gracefully and also will look into that send_until_blocked function.
 

Shinji's reply to BinaryBoy #15640 @

So I tested b92 and now build93. BB did not get stucked in the 'Starting' state.
This problem seems to be fixed.
Thank you!
 

BinaryBoy's reply to Shinji #15642 @

Great!
 

nikkil's reply to Shinji #16047 @

So far, everything is working as it should be. I think it was just a glitch.