Daily Usenet report

Jun 17 03:00:05 -- Jun 18 03:00:06

Unknown entries from news log file:

First 50 / 55 lines (90.9%)

Jun 17 03:01:18 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 17 12:40:49 twilightnode nnrpd[2106140]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 12:40:49 twilightnode nnrpd[2106140]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 12:40:49 twilightnode nnrpd[2106140]: o321.scanner.modat.io res <anonymous>
Jun 17 12:40:49 twilightnode nnrpd[2106140]: o321.scanner.modat.io no_match_user <anonymous> <localhost>
Jun 17 12:40:49 twilightnode nnrpd[2106140]: o321.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 12:40:49 twilightnode nnrpd[2106140]: o321.scanner.modat.io match_user <anonymous> <anonymous>
Jun 17 12:40:49 twilightnode nnrpd[2106140]: o321.scanner.modat.io no_post anonymous-read
Jun 17 12:53:17 twilightnode nnrpd[2106597]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 12:53:17 twilightnode nnrpd[2106597]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 12:53:17 twilightnode nnrpd[2106597]: o324.scanner.modat.io res <anonymous>
Jun 17 12:53:17 twilightnode nnrpd[2106597]: o324.scanner.modat.io no_match_user <anonymous> <localhost>
Jun 17 12:53:17 twilightnode nnrpd[2106597]: o324.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 12:53:17 twilightnode nnrpd[2106597]: o324.scanner.modat.io match_user <anonymous> <anonymous>
Jun 17 12:53:17 twilightnode nnrpd[2106597]: o324.scanner.modat.io no_post anonymous-read
Jun 17 14:12:58 twilightnode nnrpd[2110137]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 14:12:58 twilightnode nnrpd[2110137]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 14:12:58 twilightnode nnrpd[2110137]: 69.5.169.147 res <anonymous>
Jun 17 14:12:58 twilightnode nnrpd[2110137]: 69.5.169.147 no_match_user <anonymous> <localhost>
Jun 17 14:12:58 twilightnode nnrpd[2110137]: 69.5.169.147 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 14:12:58 twilightnode nnrpd[2110137]: 69.5.169.147 match_user <anonymous> <anonymous>
Jun 17 14:12:58 twilightnode nnrpd[2110137]: 69.5.169.147 no_post anonymous-read
Jun 17 14:16:23 twilightnode nnrpd[2110243]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 14:16:23 twilightnode nnrpd[2110243]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 14:16:23 twilightnode nnrpd[2110243]: 69.5.169.107 res <anonymous>
Jun 17 14:16:23 twilightnode nnrpd[2110243]: 69.5.169.107 no_match_user <anonymous> <localhost>
Jun 17 14:16:23 twilightnode nnrpd[2110243]: 69.5.169.107 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 14:16:23 twilightnode nnrpd[2110243]: 69.5.169.107 match_user <anonymous> <anonymous>
Jun 17 14:16:23 twilightnode nnrpd[2110243]: 69.5.169.107 no_post anonymous-read
Jun 17 19:29:44 twilightnode nnrpd[2123858]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 19:29:44 twilightnode nnrpd[2123858]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 19:29:44 twilightnode nnrpd[2123858]: 54.146.94.167.censys-scanner.com res <anonymous>
Jun 17 19:29:44 twilightnode nnrpd[2123858]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 17 19:29:44 twilightnode nnrpd[2123858]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 19:29:44 twilightnode nnrpd[2123858]: 54.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 17 19:29:44 twilightnode nnrpd[2123858]: 54.146.94.167.censys-scanner.com no_post anonymous-read
Jun 17 19:29:47 twilightnode nnrpd[2123860]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 19:29:47 twilightnode nnrpd[2123860]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 19:29:47 twilightnode nnrpd[2123860]: 54.146.94.167.censys-scanner.com res <anonymous>
Jun 17 19:29:47 twilightnode nnrpd[2123860]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 17 19:29:47 twilightnode nnrpd[2123860]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 19:29:47 twilightnode nnrpd[2123860]: 54.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 17 19:29:47 twilightnode nnrpd[2123860]: 54.146.94.167.censys-scanner.com no_post anonymous-read
Jun 17 19:29:53 twilightnode nnrpd[2123866]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 19:29:53 twilightnode nnrpd[2123866]: Auth strategy 'localhost' does not match client. Removing.
Jun 17 19:29:53 twilightnode nnrpd[2123866]: 54.146.94.167.censys-scanner.com res <anonymous>
Jun 17 19:29:53 twilightnode nnrpd[2123866]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 17 19:29:53 twilightnode nnrpd[2123866]: 54.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 17 19:29:53 twilightnode nnrpd[2123866]: 54.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 17 19:29:53 twilightnode nnrpd[2123866]: 54.146.94.167.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 5665 69.7%925.5 KB 73.0%
innfeed 1551 19.1%203.8 KB 16.1%
innd 829 10.2%128.9 KB 10.2%
nnrpd 86 1.1%9.1 KB 0.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 8132 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6000 34.6%
Positive hits 5738 33.1%
Do not exist 5616 32.4%
Cache misses 2 0.0%
TOTAL: 4 17356 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.006 0.0%4 0.000 1.500 3.000
article cleanup00:00:00.065 0.0%5633 0.000 0.012 0.091
article logging00:00:00.293 0.0%5664 0.000 0.052 0.211
article parse00:00:00.058 0.0%6801 0.000 0.009 0.231
article write00:00:04.771 0.0%4210 0.559 1.133 15.304
data move00:00:00.058 0.0%18703 0.000 0.003 0.028
hisgrep/artcncl00:00:00.005 0.0%4 0.000 1.250 2.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.055 0.0%17408 0.133 0.290 1.765
history sync00:00:00.069 0.0%413 0.000 0.167 1.500
history write00:00:03.459 0.0%5633 0.067 0.614 10.147
idle24:00:40.491100.0%18503 1399.200 4671.701 16235.216
nntp read00:00:00.506 0.0%18129 0.000 0.028 0.091
overview write00:00:02.276 0.0%4210 0.120 0.541 14.083
perl filter00:00:11.342 0.0%5633 0.990 2.013 31.182
python filter00:00:00.914 0.0%5633 0.000 0.162 2.636
site send00:00:00.138 0.0%4210 0.000 0.033 0.200
TOTAL: 1724:01:09.506100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%4192 0.000 0.004 0.500
article prepare00:00:00.003 0.0%24352 0.000 0.000 0.007
article read00:00:00.685 0.0%4188 0.000 0.164 1.600
backlog stats00:00:00.030 0.0%27893 0.000 0.001 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.236 0.0%15350 0.000 0.015 0.091
data write00:00:00.334 0.0%11215 0.000 0.030 0.145
idle23:50:13.196100.0%27893 958.989 3076.514 9032.194
status file00:00:00.225 0.0%303 0.000 0.743 3.000
TOTAL: 923:50:14.727100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:04.633100.0%10 43.000 463.300 2991.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%78 0.000 0.026 0.200
TOTAL: 300:00:04.635100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 11247 4140 5753 1354 36%48:09:58
2news.sklaffkom.se 46 138 42 31 65 30%13:49:00
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:01
TOTAL: 4 77 11385 4182 5784 1419 36%62:08:59
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.2 MB0.0 KB4.0 MB16.2 MB 75%3.0 KB
2news.sklaffkom.se104.8 KB0.0 KB143.9 KB248.7 KB 42%2.3 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 412.3 MB0.0 KB4.1 MB16.4 MB 74%3.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 17 03:00:05 - 03:59:59 62 1.5% 0.02155.6 KB 1.2% 0.04
Jun 17 04:00:00 - 04:59:59 116 2.8% 0.03312.7 KB 2.5% 0.09
Jun 17 05:00:00 - 05:59:59 106 2.5% 0.03264.9 KB 2.1% 0.07
Jun 17 06:00:00 - 06:59:59 114 2.7% 0.03378.3 KB 3.0% 0.11
Jun 17 07:00:00 - 07:59:59 138 3.3% 0.04375.5 KB 3.0% 0.10
Jun 17 08:00:00 - 08:59:59 171 4.1% 0.05510.9 KB 4.0% 0.14
Jun 17 09:00:00 - 09:59:59 194 4.6% 0.05523.2 KB 4.1% 0.15
Jun 17 10:00:00 - 10:59:59 172 4.1% 0.05493.9 KB 3.9% 0.14
Jun 17 11:00:00 - 11:59:59 167 4.0% 0.05415.2 KB 3.3% 0.12
Jun 17 12:00:00 - 12:59:59 156 3.7% 0.04408.1 KB 3.2% 0.11
Jun 17 13:00:00 - 13:59:59 183 4.3% 0.05480.2 KB 3.8% 0.13
Jun 17 14:00:00 - 14:59:59 192 4.6% 0.05571.0 KB 4.5% 0.16
Jun 17 15:00:00 - 15:59:59 236 5.6% 0.07634.3 KB 5.0% 0.18
Jun 17 16:00:00 - 16:59:59 217 5.2% 0.06659.0 KB 5.2% 0.18
Jun 17 17:00:00 - 17:59:59 204 4.8% 0.06595.2 KB 4.7% 0.17
Jun 17 18:00:00 - 18:59:59 266 6.3% 0.071.2 MB 9.9% 0.35
Jun 17 19:00:00 - 19:59:59 214 5.1% 0.06627.1 KB 5.0% 0.17
Jun 17 20:00:00 - 20:59:59 188 4.5% 0.05703.4 KB 5.6% 0.20
Jun 17 21:00:00 - 21:59:59 199 4.7% 0.06596.8 KB 4.7% 0.17
Jun 17 22:00:00 - 22:59:59 340 8.1% 0.09698.3 KB 5.5% 0.19
Jun 17 23:00:00 - 23:59:59 194 4.6% 0.05601.5 KB 4.8% 0.17
Jun 18 00:00:00 - 00:59:59 135 3.2% 0.04507.2 KB 4.0% 0.14
Jun 18 01:00:00 - 01:59:59 137 3.3% 0.04444.6 KB 3.5% 0.12
Jun 18 02:00:00 - 02:59:59 98 2.3% 0.03409.8 KB 3.2% 0.11
Jun 18 03:00:00 - 03:00:06 11 0.3% 1.8316.1 KB 0.1% 2.68
TOTAL: 24:00:01 4210 100.0% 0.0512.3 MB 100.0% 0.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1390 1318 0 0 0 0 0 0 72
2news.sklaffkom.se 65 65 0 0 0 0 0 0 0
TOTAL: 2 1455 1383 0 0 0 0 0 0 72

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.sport.calcio.milan 58
lada.talk 43
uk.d-i-y 41
fr.comp.os.linux.configuration 40
fido7.ru.military 38
dk.politik 36
nl.politiek 34
alt.prophecies.nostradamus 31
eternal-september.test 26
de.alt.talk.unmut 24
fido7.su.general 23
alt.recovery.aa 22
alt.usage.english 22
eternal-september.talk 22
alt.bestjobsusa.computer.jobs 21
hfx.general 21
de.rec.fahrrad 20
nl.actueel 20
fr.sci.maths 18
de.soc.recht.misc 17
TOTAL: 228 1383

Perl filter (innd) [Top 20]:

ReasonCount
User-issued cancel 18
Too many newsgroups 6
Too many newsgroups (meow) 6
EMP (phn path) 5
HTML Multipart 5
Binary: misplaced binary 1
TOTAL: 6 41

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 31
TOTAL: 1 31
TOTAL: 1 31

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4212 4210 0 0 0 0 99%23:58:36
2sklaffkom.se 4633 787 2290 1131 0 1 16%23:59:57
3weretis.net 55 0 42 0 0 0 0%05:30:34
4sklaff.dev 0 0 0 0 0 4204 0%23:57:55
5thebunker.nu 0 0 0 0 0 4210 0%23:59:57
6usenet.blueworldhosting.com 0 0 0 0 0 3873 0%23:59:57
TOTAL: 6 8900 4997 2332 1131 0 12288 56%125:26:56
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se12.3 MB0.0 KB12.3 MB0.1 KB/s3.0 KB23:58:36
2sklaffkom.se1.3 MB3.7 MB4.9 MB0.1 KB/s2.6 KB23:59:57
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:55
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s005:30:34
TOTAL: 613.6 MB3.7 MB17.3 MB0.0 KB/s2.9 KB125:26:56
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
154.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
269.5.169.107 1 00.0 KB 0 0 000:00:00
369.5.169.147 1 00.0 KB 0 0 000:00:00
4o321.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5o324.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 7 00.0 KB 0 0 000:00:04

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
2*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
3unresolved 2 00.0 KB 0 0 000:00:01
TOTAL: 3 7 00.0 KB 0 0 000:00:04

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
54.146.94.167.censys-scanner.com 0.058 0.025 0.00000:00:03
69.5.169.107 0.005 0.013 0.00000:00:00
69.5.169.147 0.005 0.013 0.00000:00:00
o324.scanner.modat.io 0.006 0.009 0.00000:00:00
o321.scanner.modat.io 0.006 0.009 0.00000:00:00
TOTAL: 5 0.080 0.069 0.00000:00:04

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
54.146.94.167.censys-scanner.com 1
TOTAL: 1 1

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
54.146.94.167.censys-scanner.com 1 2
69.5.169.107 1 1
TOTAL: 2 2 4
innreport from INN 2.7.3 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then