Daily Usenet report

Oct 1 03:00:07 -- Oct 2 03:00:09

Log entries by program:

Program nameLines%LinesSize%Size
inn 16438 81.6%2.0 MB 85.1%
innd 2705 13.4%278.2 KB 11.4%
innfeed 909 4.5%73.6 KB 3.0%
nnrpd 58 0.3%5.6 KB 0.2%
controlchan 23 0.1%4.9 KB 0.2%
pgpverify 2 0.0%0.1 KB 0.0%
TOTAL: 6 20135 100.0%2.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 16150 51.4%
Negative hits 15186 48.3%
Positive hits 52 0.2%
Cache misses 25 0.1%
TOTAL: 4 31413 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:11.015 0.0%59 0.000 186.695 1357.000
article cleanup00:00:00.319 0.0%16180 0.000 0.020 1.014
article logging00:00:01.076 0.0%16386 0.000 0.066 2.353
article parse00:00:00.428 0.0%32688 0.000 0.013 0.583
article write00:11:05.225 0.0%13565 13.650 49.040 194.341
artlog/artcncl00:00:00.001 0.0%59 0.000 0.017 0.091
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.824 0.0%47772 0.000 0.017 1.778
hisgrep/artcncl00:00:00.645 0.0%46 0.000 14.022 55.500
hishave/artcncl00:00:00.029 0.0%59 0.000 0.492 24.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:54.812 0.0%31440 0.000 1.743 20.377
history sync00:00:00.092 0.0%408 0.000 0.225 12.000
history write00:02:42.483 0.0%16180 0.669 10.042 110.236
hiswrite/artcncl00:00:00.054 0.0%13 0.000 4.154 13.000
idle5124095576053:59:27.488100.0%59202 749.639311589879966797.875131762457669352928.000
nntp read00:00:04.670 0.0%46296 0.000 0.101 3.361
overview write00:07:35.280 0.0%13565 2.295 33.563 1188.115
perl filter00:01:31.121 0.0%16180 0.733 5.632 79.986
python filter00:00:04.274 0.0%16218 0.000 0.264 4.877
site send00:00:05.728 0.0%40500 0.000 0.141 3.427
TOTAL: 5124095576054:29:25.6325124095576054:23:37.472100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.847 0.0%13386 0.000 0.213 10.353
article prepare00:00:00.001 0.0%144 0.000 0.007 0.009
article read00:00:07.487 0.0%36 0.000 207.972 6982.000
backlog stats00:00:00.039 0.0%18020 0.000 0.002 0.123
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.322 0.0%13482 0.000 0.024 0.877
data write00:00:00.129 0.0%109 0.000 1.183 20.000
idle5124095576054:22:23.744100.0%18020 2933.0051023681691109641.750802032351030833152.000
status file00:00:09.656 0.0%472 0.000 20.458 404.000
TOTAL: 5124095576054:22:52.4165124095576054:22:44.224100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:11.083 62.7%21 0.000 527.762 9822.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%48 0.000 0.000 0.000
TOTAL: 00:00:17.68300:00:11.083 62.7%----

Control commands to INND:

CommandNumber
flush 291
flushlogs 2
go 2
logmode 2
lowmark 1
mode 139
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 447

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
hun-mnt@news.sztaki.hu001101
us-control@lists.killfile.org000100
TOTAL001201

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 4 16149 13520 31 2598 83%24:20:40
2localhost 33 38 38 0 0 100%00:00:04
TOTAL: 2 37 16187 13558 31 2598 83%24:20:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org36.9 MB0.0 KB7.5 MB44.5 MB 83%2.8 KB
2localhost178.9 KB0.0 KB0.0 KB178.9 KB100%4.7 KB
TOTAL: 237.1 MB0.0 KB7.5 MB44.7 MB 83%2.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 03:00:07 - 03:59:59 515 3.8% 0.141.3 MB 3.4% 0.36
Oct 1 04:00:00 - 04:59:59 500 3.7% 0.141.2 MB 3.2% 0.34
Oct 1 05:00:00 - 05:59:59 680 5.0% 0.191.6 MB 4.2% 0.44
Oct 1 06:00:00 - 06:59:59 678 5.0% 0.191.9 MB 5.1% 0.54
Oct 1 07:00:00 - 07:59:59 630 4.6% 0.171.8 MB 4.8% 0.50
Oct 1 08:00:00 - 08:59:59 683 5.0% 0.191.8 MB 4.9% 0.52
Oct 1 09:00:00 - 09:59:59 776 5.7% 0.222.0 MB 5.3% 0.56
Oct 1 10:00:00 - 10:59:59 717 5.3% 0.202.0 MB 5.5% 0.58
Oct 1 11:00:00 - 11:59:59 760 5.6% 0.212.0 MB 5.5% 0.58
Oct 1 12:00:00 - 12:59:59 801 5.9% 0.222.1 MB 5.6% 0.59
Oct 1 13:00:00 - 13:59:59 758 5.6% 0.212.0 MB 5.4% 0.57
Oct 1 14:00:00 - 14:59:59 760 5.6% 0.212.0 MB 5.5% 0.58
Oct 1 15:00:00 - 15:59:59 796 5.9% 0.222.0 MB 5.3% 0.56
Oct 1 16:00:00 - 16:59:59 748 5.5% 0.211.9 MB 5.2% 0.55
Oct 1 17:00:00 - 17:59:59 625 4.6% 0.171.9 MB 5.1% 0.54
Oct 1 18:00:00 - 18:59:59 651 4.8% 0.182.1 MB 5.8% 0.61
Oct 1 19:00:00 - 19:59:59 391 2.9% 0.111.2 MB 3.2% 0.34
Oct 1 20:00:00 - 20:59:59 284 2.1% 0.081.0 MB 2.7% 0.29
Oct 1 21:00:00 - 21:59:59 309 2.3% 0.091.1 MB 2.8% 0.30
Oct 1 22:00:00 - 22:59:59 294 2.2% 0.08958.8 KB 2.5% 0.27
Oct 1 23:00:00 - 23:59:59 257 1.9% 0.07781.6 KB 2.1% 0.22
Oct 2 00:00:00 - 00:59:59 308 2.3% 0.09826.4 KB 2.2% 0.23
Oct 2 01:00:00 - 01:59:59 322 2.4% 0.09921.0 KB 2.4% 0.26
Oct 2 02:00:00 - 03:00:09 322 2.4% 0.09817.5 KB 2.2% 0.23
TOTAL: 24:00:02 13565 100.0% 0.1637.1 MB 100.0% 0.44
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news-out.aioe.org 2814 2303 0 0 0 0 0 0 511
TOTAL: 1 2814 2303 0 0 0 0 0 0 511

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.soc.politique 410
nl.politiek 230
lada.talk 50
bit.listserv.ibm-main 43
fr.rec.photo 42
nl.taal 41
dk.livssyn.kristendom 40
it-alt.sport.calcio.juventus 39
hun.politika 37
alt.computer.workshop 34
aus.politics 34
dk.politik 32
fr.rec.bricolage 29
muc.lists.netbsd.source-changes 25
nz.general 25
hr.soc.politika 24
es.humanidades.literatura 24
alt.politics 24
scot.general 23
fr.bio.medecine 22
TOTAL: 462 2303

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 291
HTML Multipart 12
EMP (md5) 2
TOTAL: 3 305

INND misc events:

EventsCount
CCreader 1
TOTAL: 1 1

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 206
TOTAL: 1 206
TOTAL: 1 206

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1aioe 36 31 0 5 0 5 86%01:21:12
2giganews 0 0 0 0 0 13196 0%23:59:50
3eternal-september 0 0 0 0 0 11599 0%23:59:02
TOTAL: 3 36 31 0 5 0 24800 86%49:20:04
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1aioe70.9 KB12.3 KB83.2 KB0.0 KB/s2.3 KB01:21:12
2giganews0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
3eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:02
TOTAL: 370.9 KB12.3 KB83.2 KB0.0 KB/s2.3 KB49:20:04
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1192.241.236.80 1 00.0 KB 0 0 000:00:09
2167.172.116.155 1 00.0 KB 0 0 000:00:00
389.248.168.176 1 00.0 KB 0 0 000:00:00
4uucp.scinet-ftn.org 1 00.0 KB 0 1 000:00:00
5worker-18.sfj.censys-scanner.com 1 00.0 KB 0 0 000:00:00
6122.228.19.80 1 00.0 KB 0 0 000:00:05
7192.35.168.250 4 00.0 KB 0 0 000:00:01
TOTAL: 7 10 00.0 KB 0 1 000:00:17

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 8 00.0 KB 0 0 000:00:16
2*.scinet-ftn.org 1 00.0 KB 0 1 000:00:00
3*.sfj.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 10 00.0 KB 0 1 000:00:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
192.241.236.80 0.024 0.006 0.00000:00:09
122.228.19.80 0.933 7.970 0.00000:00:05
192.35.168.250 0.711 0.334 0.00000:00:01
uucp.scinet-ftn.org 0.159 0.136 0.00000:00:00
worker-18.sfj.censys-scanner.com 0.352 0.249 0.00000:00:00
89.248.168.176 0.249 0.068 0.00000:00:00
167.172.116.155 0.339 0.161 0.00000:00:00
? 0.302 0.176 0.00000:00:00
TOTAL: 8 3.069 9.100 0.00000:00:17

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP unrecognized commands (by host):

SystemConn
122.228.19.80 5
192.35.168.250 3
TOTAL: 2 8

NNRP unrecognized commands (by command):

CommandCount
^V^C^A 1
Host: 192.171.60.59:119 1
Connection: keep-alive 1
GET / HTTP/1.1 1
^A 1
Accept: */* 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
EHLO www.censys.io 1
TOTAL: 8 8

NNRP client timeouts [Top 20]:

SystemConnPeer
192.35.168.250 1 2
worker-18.sfj.censys-scanner.com 1 1
TOTAL: 2 2 3