[SQS-Script] Completion-Propagation/DMCA/Retention check

Share your scripts or request scripts with specific features.
Forum rules
Please keep the forum clean - one topic per script. Questions not related to a specific script should be posted in Support forum.
Post Reply
JackD
Posts: 16
Joined: 15 Jul 2016, 18:17

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by JackD » 16 Jul 2016, 11:29

Hi,

This log is edited to contain just the juicy bits. There are two releases logged, one from yesterday, and another from today (note; i've observed a second issue).

I've inserted notes to help as you read through, and trimmed the log.
kloaknet wrote:the 501 msg is acutally an error message in the NNTP commands, so the 501 is not a confirmation you are 'logged in'.
Agreed, not on providers that actually use the username and password, which is why I included the heads up about NNTP over customer cable network as compared to a USP. So as a test, I changed the username and you can see those results too.

I have suspicions that this ISP (not to be confused with USP) is not using protocol response codes for auth flow that USPs do, be interesting to see what you think though ;)

===============
At this point in time the server username and password fields are set up correctly (both blank) and completion 0.24 script has no changes.

Code: Select all

Fri Jul 15 18:00:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] lock_file()
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] Old completion.lock file overwritten
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] Found paused item in queue: "Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued"
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] nzbget_paused()
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] Waiting for NZBGet to end downloading
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] Downloading for NZBGet paused
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] Ignoring priority of items older than AgeLimit of 4 hours
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] get_nzb_status(nzb=[2881, 'Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued', 1468573931, 1000, u'SickGear-281537-2.7', 100])
Fri Jul 15 18:00:01 2016	INFO	Completion: Checking: "Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued"
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] get_nzb_data(fname=<redacted_path_to>\Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued)
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] No par files in release, all articles will be checked
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] NZB contains 341 articles, 341 rar articles, 0 par2 articles
Fri Jul 15 18:00:01 2016	INFO	Completion: [V] 341 articles will be checked
Fri Jul 15 18:00:01 2016	INFO	Completion: Maximum failed articles limit for NZB: 0.0%
Fri Jul 15 18:00:01 2016	INFO	Completion: Maximum failed articles limit for highest level news server: 0%
Fri Jul 15 18:00:01 2016	INFO	Completion: [E] check_failure_status(rar_msg_ids=[[u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87000.1@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87003.2@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87005.3@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87006.4@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/
Fri Jul 15 18:00:01 2016	INFO	Completion: 1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87107.49@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87108.50@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87110.51@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87112.52@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87114.53@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius
Fri Jul 15 18:00:01 2016	INFO	Completion: quarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87202.98@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87204.99@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87205.100@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87207.101@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87209.102@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.
Fri Jul 15 18:00:01 2016	INFO	Completion: 146@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87294.147@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87296.148@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87298.149@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87299.150@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, [
Fri Jul 15 18:00:01 2016	INFO	Completion: 2E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87385.195@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87387.196@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87388.197@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87390.198@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87391.199@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264
Fri Jul 15 18:00:01 2016	INFO	Completion: ', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87480.244@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87481.245@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87483.246@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87484.247@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binari
Fri Jul 15 18:00:02 2016	INFO	Completion: .x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87565.292@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87567.293@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87568.294@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87570.295@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87572.296@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] 
Fri Jul 15 18:00:02 2016	INFO	Completion: ATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [3/3] - "_newzNZB.nfo" yEnc (1/1)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87660.1@PRiVATE', -1]], failed_limit=0.0)
Fri Jul 15 18:00:02 2016	INFO	Completion: [V] all active news servers BEFORE filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Fri Jul 15 18:00:02 2016	INFO	Completion: [V] all active news servers AFTER filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Fri Jul 15 18:00:02 2016	INFO	Completion: [V] Using Non SSL connection for server: news.<redacted_ISP_hostname>.com
Fri Jul 15 18:00:02 2016	INFO	Completion: Using server: news.<redacted_ISP_hostname>.com
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 3 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 4 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 8 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 9 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 0 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
<<< truncated the repeated message >>>>
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:00:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:00:03 2016	WARNING	Completion: [V] Socket: 14 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
<<< truncated the repeated message >>>>
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	WARNING	Completion: [V] Socket: 13 news.<redacted_ISP_hostname>.com, Not covered NNTP server reply code: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
<<< truncated the repeated message >>>>
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:00:03 2016	ERROR	Completion: Ending script due to looping

Code: Select all

Fri Jul 15 18:05:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Fri Jul 15 18:05:01 2016	INFO	Completion: [V] lock_file()
Fri Jul 15 18:05:01 2016	INFO	Completion: [V] Script is already running, check canceled

Code: Select all

Fri Jul 15 18:10:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Fri Jul 15 18:10:01 2016	INFO	Completion: [V] lock_file()
Fri Jul 15 18:10:01 2016	INFO	Completion: [V] Script is already running, check canceled
After noting the above error loop "Not covered NNTP server reply code", the "501" is added to script, NG reloaded

Code: Select all

Fri Jul 15 18:13:00 2016	INFO	Reloading...
Fri Jul 15 18:13:01 2016	INFO	nzbget 17.0-testing server-mode
Waited for next schedule...

Code: Select all

Fri Jul 15 18:20:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] lock_file()
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] Old completion.lock file overwritten
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] Found paused item in queue: "Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued"
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] nzbget_paused()
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] Waiting for NZBGet to end downloading
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] Downloading for NZBGet paused
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] Ignoring priority of items older than AgeLimit of 4 hours
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] get_nzb_status(nzb=[2881, 'Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued', 1468573931, 1000, u'SickGear-281537-2.7', 100])
Fri Jul 15 18:20:01 2016	INFO	Completion: Checking: "Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued"
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] get_nzb_data(fname=<redacted_path_to>\Aquarius.US.S02E07.WEB-DL.x264-FUM.nzb.queued)
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] No par files in release, all articles will be checked
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] NZB contains 341 articles, 341 rar articles, 0 par2 articles
Fri Jul 15 18:20:01 2016	INFO	Completion: [V] 341 articles will be checked
Fri Jul 15 18:20:01 2016	INFO	Completion: Maximum failed articles limit for NZB: 0.0%
Fri Jul 15 18:20:01 2016	INFO	Completion: Maximum failed articles limit for highest level news server: 0%
Fri Jul 15 18:20:01 2016	INFO	Completion: [E] check_failure_status(rar_msg_ids=[[u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87000.1@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87003.2@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87005.3@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87006.4@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/
Fri Jul 15 18:20:01 2016	INFO	Completion: 1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87107.49@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87108.50@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87110.51@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87112.52@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87114.53@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius
Fri Jul 15 18:20:01 2016	INFO	Completion: quarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87202.98@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87204.99@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87205.100@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87207.101@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87209.102@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.
Fri Jul 15 18:20:01 2016	INFO	Completion: 146@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87294.147@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87296.148@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87298.149@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87299.150@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, [
Fri Jul 15 18:20:01 2016	INFO	Completion: 2E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87385.195@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87387.196@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87388.197@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87390.198@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87391.199@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264
Fri Jul 15 18:20:01 2016	INFO	Completion: ', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87480.244@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87481.245@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87483.246@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87484.247@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binari
Fri Jul 15 18:20:02 2016	INFO	Completion: .x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87565.292@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87567.293@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87568.294@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87570.295@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [1/3] - "Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv].mp4" yEnc (1/339)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87572.296@PRiVATE', -1], [u'[PRiVATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] 
Fri Jul 15 18:20:02 2016	INFO	Completion: ATE] Aquarius.US.S02E07.WEB-DL.x264-FUM[ettv] [newzNZB.iNFO] [3/3] - "_newzNZB.nfo" yEnc (1/1)', 0, ['alt.binaries.inner-sanctum'], '1468573925.87660.1@PRiVATE', -1]], failed_limit=0.0)
Fri Jul 15 18:20:02 2016	INFO	Completion: [V] all active news servers BEFORE filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Fri Jul 15 18:20:02 2016	INFO	Completion: [V] all active news servers AFTER filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Fri Jul 15 18:20:02 2016	INFO	Completion: [V] Using Non SSL connection for server: news.<redacted_ISP_hostname>.com
Fri Jul 15 18:20:02 2016	INFO	Completion: Using server: news.<redacted_ISP_hostname>.com
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87000.1@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87003.2@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87005.3@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87006.4@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87008.5@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87009.6@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87012.7@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87016.8@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87017.9@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87019.10@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87000.1@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87021.11@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87022.12@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87024.13@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87025.14@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.inner-sanctum
On posting i recieved from phpBB...
"Your message contains 106264 characters. The maximum number of allowed characters is 60000."
Therefore, this message is split into two...
Last edited by JackD on 16 Jul 2016, 11:36, edited 1 time in total.

JackD
Posts: 16
Joined: 15 Jul 2016, 18:17

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by JackD » 16 Jul 2016, 11:32

Part 2/2 follows...

Code: Select all

Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87012.7@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87026.15@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87016.8@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87028.16@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87017.9@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87030.17@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87031.18@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87021.11@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87033.19@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87022.12@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87034.20@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87003.2@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87035.21@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87025.14@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87037.22@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87005.3@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87039.23@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['211', '80702405', '204898706', '285601110', 'alt.binaries.inner-sanctum']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87060.24@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87006.4@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87062.25@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87008.5@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87063.26@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87009.6@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87065.27@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87030.17@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87069.28@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87019.10@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87072.29@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87033.19@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87075.30@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87035.21@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87079.31@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87024.13@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87081.32@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87060.24@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87082.33@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87062.25@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87084.34@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87063.26@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87086.35@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87065.27@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87088.36@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87026.15@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87089.37@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87028.16@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87090.38@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87069.28@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87092.39@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87031.18@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87094.40@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87034.20@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87095.41@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87037.22@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87096.42@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['223', '0', '<1468573925.87039.23@PRiVATE>']
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: STAT <1468573925.87098.43@PRiVATE>
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Fri Jul 15 18:20:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
...The above two code blocks are where I had to split - grr, treat as one where it shows the download unpaused and completed as expect, good times :)

Today, at this point, an intentional username "admin" was set.
As expected, auth failed because this is not the required <blank>, lock file is found, and check cancelled.

Code: Select all

Sat Jul 16 10:00:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Sat Jul 16 10:00:01 2016	INFO	Completion: [V] lock_file()
Sat Jul 16 10:00:01 2016	INFO	Completion: [V] Script is already running, check canceled
Sat Jul 16 10:05:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Sat Jul 16 10:05:01 2016	INFO	Completion: [V] lock_file()
Sat Jul 16 10:05:01 2016	INFO	Completion: [V] Script is already running, check canceled
Sat Jul 16 10:10:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Sat Jul 16 10:10:00 2016	INFO	Completion: [V] lock_file()
Sat Jul 16 10:10:01 2016	INFO	Completion: [V] Script is already running, check canceled
Following below, Username is set to the correct <blank> and NG reloaded, it seems blank in the UI is substituted with "Name" and "Password" as string values sent to the server. With my 501 change still in place, checking continues as normal.

Code: Select all

Sat Jul 16 10:12:37 2016	INFO	Reloading...
Sat Jul 16 10:12:38 2016	INFO	nzbget 17.0-testing server-mode

Code: Select all

Sat Jul 16 10:15:00 2016	INFO	Executing scheduler-script Completion\Completion.py for Task4
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] lock_file()
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] Old completion.lock file overwritten
However, a new issue is observed where this news server does not have the group and now there has been a infinite period of rechecking over and over even though there is no group. I suspect some logic is needed to handle the reply case ['411', 'No', 'Such', 'Group'...].

Code: Select all

Sat Jul 16 10:15:01 2016	INFO	Completion: [V] Found paused item in queue: "Big.Brother.UK.S17E40.HDTV.x264-LiNKLE.nzb.queued"
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] nzbget_paused()
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] Waiting for NZBGet to end downloading
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] Downloading for NZBGet paused
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] Ignoring priority of items older than AgeLimit of 4 hours
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] get_nzb_status(nzb=[2885, 'Big.Brother.UK.S17E40.HDTV.x264-LiNKLE.nzb.queued', 1468628900, 890, u'SickGear-72549-17.40', 100])
Sat Jul 16 10:15:01 2016	INFO	Completion: Checking: "Big.Brother.UK.S17E40.HDTV.x264-LiNKLE.nzb.queued"
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] get_nzb_data(fname=<redacted_path_to>\Big.Brother.UK.S17E40.HDTV.x264-LiNKLE.nzb.queued)
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] NZB contains 1172 articles, 1055 rar articles, 117 par2 articles
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] 176 articles will be checked
Sat Jul 16 10:15:01 2016	INFO	Completion: Maximum failed articles limit for NZB: 11.0%
Sat Jul 16 10:15:01 2016	INFO	Completion: Maximum failed articles limit for highest level news server: 0%
Sat Jul 16 10:15:01 2016	INFO	Completion: [E] check_failure_status(rar_msg_ids=[[u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[01/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.nfo" yEnc (1/1)', 0, ['alt.binaries.teevee'], '1468628878.23406.1@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[03/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r00" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628878.25796.6@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[03/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r00" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628878.28532.12@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[03/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r00" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628878.31247.18@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17
Sat Jul 16 10:15:01 2016	INFO	Completion: 8883.61732.20@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[12/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r09" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628883.69182.26@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[13/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r10" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628883.82141.5@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[13/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r10" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628883.99575.11@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[13/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r10" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628884.07655.17@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.
Sat Jul 16 10:15:01 2016	INFO	Completion: 1468628888.30999.20@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[22/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r19" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628888.34178.26@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[23/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r20" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628888.41906.5@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[23/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r20" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628888.67273.11@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[23/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r20" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628888.76560.17@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Br
Sat Jul 16 10:15:01 2016	INFO	Completion: e'], '1468628892.69633.20@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[32/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r29" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628892.81552.26@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[33/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r30" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628892.86527.5@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[33/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r30" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628893.02827.11@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ Big.Brother.UK.S17E40.HDTV.x264-LiNKLE ]-[33/49] - "big.brother.uk.s17e40.hdtv.x264-linkle.r30" yEnc (1/27)', 0, ['alt.binaries.teevee'], '1468628893.10805.17@reader.easyusenet.nl', -1], [u'[332450]-[FULL]-[#a.b.teevee@EFNet]-[ 
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] all active news servers BEFORE filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Sat Jul 16 10:15:01 2016	INFO	Completion: [V] all active news servers AFTER filtering on NZBGet ServerX.Group: [['0', '0', 'news.<redacted_ISP_hostname>.com', '119', '', '', False, '15']]
Sat Jul 16 10:15:02 2016	INFO	Completion: [V] Using Non SSL connection for server: news.<redacted_ISP_hostname>.com
Sat Jul 16 10:15:02 2016	INFO	Completion: Using server: news.<redacted_ISP_hostname>.com
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['200', 'Welcome', 'to', '<redacted>', '<ISP_name>']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO USER
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['381', 'PASS', 'required']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: AUTHINFO PASS
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['501', 'user', 'Name|pass', 'Password']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 0 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 0 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 4 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 4 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 9 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 9 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 10 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 10 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 12 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 12 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 14 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 14 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 1 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 1 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 2 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 2 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 3 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 3 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 5 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 5 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 6 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 6 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 7 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 7 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:02 2016	WARNING	Completion: [V] Socket: 8 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:02 2016	INFO	Completion: [E] Socket: 8 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee

Code: Select all

<<< truncated the repeated message >>>>
Sat Jul 16 10:15:03 2016	WARNING	Completion: [V] Socket: 11 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:03 2016	INFO	Completion: [E] Socket: 11 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
Sat Jul 16 10:15:03 2016	INFO	Completion: [E] Slow reply from server, waiting 13 ms to avoid looping
Sat Jul 16 10:15:03 2016	WARNING	Completion: [V] Socket: 13 news.<redacted_ISP_hostname>.com, NNTP reply: ['411', 'No', 'Such', 'Group', '"alt.binaries.teevee"']
Sat Jul 16 10:15:03 2016	INFO	Completion: [E] Socket: 13 news.<redacted_ISP_hostname>.com, Send: group alt.binaries.teevee
The above has repeated for over an hour now, rendering the script unusable until 411's are handled ;)

Hope that helps.

JackD
SickGear
---------------------------

kloaknet
Posts: 335
Joined: 23 Jul 2014, 08:52

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by kloaknet » 16 Jul 2016, 19:45

Thanks for posting (and filtering out relevant parts) all the logs. The AUTHINFO part should work as follows on your news server:
4. AUTHINFO

AUTHINFO is used to authenticate a user. In all cases, clients
MUST provide this information when requested by the server.
Servers are not required to accept authentication information that
is volunteered by the client. Clients MUST accommodate servers
that reject any authentication information volunteered by the
client.


4.1. AUTHINFO USER/PASS

AUTHINFO USER username

AUTHINFO PASS password

These commands permit the client to use a plain-text password to
authenticate. A compliant implementation MUST NOT implement this
mechanism without also implementing support for a strong encryption
layer or a stronger authentication mechanism. Use of this
mechanism is deprecated as it exposes the user's password to all
parties on the network between the client and the server.

The client begins by issuing the AUTHINFO USER username command.
If the server is willing to accept this form of authentication and
a password is required, the server responds with a 350 response.
The client continues by sending the AUTHINFO PASS password command.
If the username/password combination is valid or no password is
required, the server responds with a 250 response and the client
MAY then retry the command which resulted in a 450 response.

If the server returns 501, this means that the authenticator
invocation was syntactically incorrect, or that this form of
AUTHINFO is not supported.

If the requested authenticator capability is not found or there is
some other unspecified server program error, the server MUST return
the 503 response code.


4.1.2. Responses

250 Authorization accepted
350 Continue with authorization sequence
450 Authorization required for this command
452 Authorization rejected
501 Command not supported or Command Syntax Error
503 Program error, function not performed
https://tools.ietf.org/id/draft-newman- ... uth-00.txt


As your server asks for a PASS (381) after USER command, and then replies with a 501 and it looks like stating that the pass sending went wrong it is something with how blanks are send. It looks like there should atleast be a space behind PASS and USER. Or otherwise I should receive a 250 directly after AUTHINFO USER...

pfff I can't test it, but maybe with your python skills you see something in line 615 and 623 and understand why the last space (when the remaining is empty) in string 'username' and ' password' is not send.

about the 411, nice find, but :oops: if you dont have a.b.teevee on your provider, then you will prolly miss a lot of nzbs. Other thing is, is when an article is not there, a 430 is filled, and that works, and the 411 is in the same check on line 588. So it is actually odd the 411 causes and endless loop. May also try just to run with checking only 5 or 10% of all the articles. A newsserver replies a lot slower when it cannot find the article, and assuming failure is random, it will be enough to not check 100%

hugbug
Developer & Admin
Posts: 7541
Joined: 09 Sep 2008, 11:58
Location: Germany

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by hugbug » 17 Jul 2016, 00:06

I think the problem here is that the script tries to authenticate immediately after successully connecting to the server. Instead it should send "AUTHINFO USER" only after becoming response "480 Authentication required" (which can be received at any time, after any command).
Or at least don't try to authenicate if the user name and password are both empty.

kloaknet
Posts: 335
Joined: 23 Jul 2014, 08:52

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by kloaknet » 17 Jul 2016, 08:01

thanks hugbug for joining the bug hunt :) .

Ill add the 480 check
ill try to figure out what to send after the 20X messages
i might understand now also why the 411 causes a loop

will be continued...

edit:

@JackD, do you have the join group option disabled for you ISP in NZBget? or does normal nzb file grabbing just work with join group enabled?

kloaknet
Posts: 335
Joined: 23 Jul 2014, 08:52

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by kloaknet » 17 Jul 2016, 16:20

find attached a version that might work, JackD, please test.

it now sends articles before login, and will only answer with login info when requested by server.
it will only use the join group option when requested by the server
when a 411 occurs, it marks the article as failed.
Attachments
Completion.py
test version
(64.49 KiB) Downloaded 120 times

JackD
Posts: 16
Joined: 15 Jul 2016, 18:17

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by JackD » 17 Jul 2016, 22:51

That's an improvement. Here is a set up that can apply to other cases than what I observed on mine...

Server1-x are set up for completion checking in the COMPLETION/"Servers" settings.
Serverx-y are block fill servers in the NG/NEWS-SERVERS settings.

Case, when a 411 (group not found) is raised - the nzb is marked total error, and a signal sent back to the SickGear client which then looks for an alternative release on another nzb indexer. In my case, I happen to have an alternative indexer that did send another nzb posted in another group that did "complete" on server1. This is fine.

The concern is a likely scenario where no other nzb is made available.

If the raised response added value than simply an error, e.g error but other enabled servers could fill (note: this would only raise if there are actually other *enabled* servers, else it would just be an error as is the case now).

This would mean that COMPLETION/Servers list is iterated as usual, an error_others_exist is raised, and clients like SickGear can wait for an alternative release for say 4 hours, if none found, the original nzb could be resent to NG which then uses other servers instead of marking dupe - this would then complete on the block accounts instead.

This allows all avenues to be exhausted before using a block account.

In conclusion, an nzb is attempted, another nzb may be forthcoming which may complete on server1-x, but if not, test the first nzb on the remaining block servers before giving up.

I do appreciate that this may be too lofty an ambition but the case is worth consideration ;)
kloaknet wrote:@JackD, do you have the join group option disabled for you ISP in NZBget? or does normal nzb file grabbing just work with join group enabled?
All mine are not enabled (default).
---

In another news, I notice a possible logic error in the code where...

Code: Select all

if duplicate and VERBOSE:
will prevent operational logic for "if duplicate" being performed if VERBOSE is not enabled, maybe this is intentional.

edit: Also,

Code: Select all

if server_reply in ('412'):
should be...

Code: Select all

elif server_reply in ('412'):
to prevent the erroneous...
...Not covered NNTP server reply code: ['430', 'No', 'Such', 'Article']
edit2: Also,

Code: Select all

print ('[WARNING]"' + nzb[1] + 
should have space before quote...

Code: Select all

print ('[WARNING] "' + nzb[1] + 
so that NG will parse the warning label properly in the ui.

Hope that helps.

JackD
SickGear

kloaknet
Posts: 335
Joined: 23 Jul 2014, 08:52

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by kloaknet » 18 Jul 2016, 14:45

JackD, thanks for reporting back, and spotting some other minor issues :-)

1. - Script currently works best if you use all your servers active in NZBget, normal and fill accounts.
2. - When SickGear pushes dupes, even though there is a similar nzb paused, it will check the dupe too (with dupemode and checkdupes enabled) when the first nzb fails. I know from Sonarr, it doesn't push dupes, only when it gets the failure status a new nzb is send. I basically wrote the script without considering Sonarr/SickGear and the likes, and focussed in covering all those nice features in NZBget for the RSS feeds and filters. The ForceFailure option isn't really nice, but it works for Sonarr.
3. - I don't know about the 'error_others_exist' in NZBget, I can only work with the failure options/statuses in nzbget: https://github.com/nzbget/nzbget/wiki/A ... d-"history". I can't trigger and in between status for queued/ downloading and Good/Bad/Failure. Maybe it is an idea to trigger SickGear to send a new nzb when the nzb is paused? But that might become messy and SickGear keeps sending nzbs.
3. - A solution that might help you out by updating the completion.py script, is to include a list of fill servers, next to the Servers option there is now. This list of fill servers will then only be used when the AgeLimit is passed, and in the final check it will use those listed fill servers too for the check. (this approach saves using the fill servers I think).

Please let me know what you think about the fill server list addition, note that I dont expect it to have the addition ready in like 2 weeks if you like the fill servers idea.

JackD
Posts: 16
Joined: 15 Jul 2016, 18:17

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by JackD » 21 Jul 2016, 18:02

Kloaknet,

1) Ahh, I was concerned that that might be the case

2) That makes sense. Currently, SickGear tries not to push dupes, it too may send a different nzb in response to a failure status.

3) The 'error_others_exist' is not actually in NzbGet, it is just a hypothetical error code used as I threw down the idea to consider, but yeah, on reflection of your thoughts, i'm not sure either, but like i said, it was just idea germination, which leads us to a result, which is your number 4 (3.- solution)

4) Sounds the best route for all client types. That the user sets, as usual, the period for "primary" servers, and then the script uses the secondary list of servers e.g. fill servers at the end of period 1 (before giving up). With dupe using/checking, at least a client can send another nzb inside the initial time frame too. So yeah, that sounds like a good balance to cover everything :)

Thanks for your efforts :)

kloaknet
Posts: 335
Joined: 23 Jul 2014, 08:52

Re: [SQS-Script] Completion-Propagation/DMCA/Retention check

Post by kloaknet » 30 Jul 2016, 14:22

Script has been updated, and now has an option to use typical (fill) servers only for the last check, when items are older than X hours prior to when they will be marked bad / failed.

download can be found in first post, via http://forum.nzbget.net/viewtopic.php?f=8&t=1736

Post Reply

Who is online

Users browsing this forum: No registered users and 3 guests