Daily Usenet report

Jun 1 15:00:04 -- Jun 2 15:00:08

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 1 15:03:09 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Jun 1 22:15:13 colo-sc-1 pgpverify[29848]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 16485 80.6%2.0 MB 78.3%
innd 2221 10.9%318.3 KB 12.2%
innfeed 1145 5.6%164.3 KB 6.3%
cnfsstat 580 2.8%80.1 KB 3.1%
controlchan 13 0.1%2.5 KB 0.1%
nnrpd 10 0.0%1.0 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 8 20456 100.0%2.5 MB100.0%

History cache:

ReasonCount%Count
Negative hits 49479 47.5%
Positive hits 38691 37.1%
Do not exist 15644 15.0%
Cache misses 340 0.3%
TOTAL: 4 104154 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:19.593 0.0%345 0.000 56.791 995.000
article cleanup00:00:00.119 0.0%15776 0.000 0.008 0.047
article logging00:00:00.579 0.0%16064 0.000 0.036 0.685
article parse00:00:00.266 0.0%29049 0.000 0.009 0.035
article write00:00:05.660 0.0%15062 0.000 0.376 25.978
artlog/artcncl00:00:00.009 0.0%342 0.000 0.026 0.500
data move00:00:00.060 0.0%111043 0.000 0.001 0.007
hisgrep/artcncl00:00:00.005 0.0%343 0.000 0.015 0.017
hishave/artcncl00:00:12.188 0.0%345 0.000 35.328 581.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:18:28.680 1.3%104405 0.002 10.619 78.935
history sync00:00:00.019 0.0%389 0.000 0.049 1.000
history write00:00:07.496 0.0%15748 0.000 0.476 9.875
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:30:39.559 98.6%105730 453.376 800.525 2222.565
nntp read00:00:00.787 0.0%110914 0.000 0.007 0.017
overview write00:00:00.054 0.0%15062 0.000 0.004 0.035
perl filter00:00:38.944 0.0%15748 0.832 2.473 26.372
python filter00:00:00.441 0.0%15748 0.000 0.028 0.100
site send00:00:02.479 0.0%45187 0.021 0.055 0.108
TOTAL: 23:51:05.45623:50:36.938100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%15219 0.000 0.002 0.023
article prepare00:00:00.000 0.0%663 0.000 0.000 0.000
article read00:00:00.229 0.0%594 0.000 0.386 16.833
backlog stats00:00:00.050 0.0%68116 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.354 0.0%45921 0.000 0.008 0.038
data write00:00:00.853 0.0%31438 0.000 0.027 0.069
idle23:48:10.211100.0%68116 517.560 1258.004 2772.866
status file00:00:00.067 0.0%207 0.000 0.324 1.000
TOTAL: 23:48:14.69423:48:11.800100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:00.000 0.0%0 0.000 0.000 0.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%3 0.000 0.000 0.000
TOTAL: 00:00:10.46700:00:00.000 0.0%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reserve 2
TOTAL: 10 167

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 6 25250 8583 16537 130 33%46:06:47
2news-out.aioe.org 4 11794 3949 7833 12 33%22:43:38
3news.nntp4.net 3 15383 1838 13029 516 11%23:51:40
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 14 52427 14370 37399 658 27%92:42:05
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net27.8 MB41.2 KB272.1 KB28.1 MB 98%3.3 KB
2news-out.aioe.org11.3 MB11.8 KB21.8 KB11.3 MB 99%2.9 KB
3news.nntp4.net4.7 MB10.1 KB2.6 MB7.3 MB 64%3.2 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 443.8 MB63.1 KB2.9 MB46.8 MB 93%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 1 15:00:04 - 15:59:59 792 5.2% 0.222.7 MB 6.0% 0.78
Jun 1 16:00:00 - 16:59:59 916 6.1% 0.252.6 MB 5.6% 0.73
Jun 1 17:00:00 - 17:59:59 754 5.0% 0.212.1 MB 4.5% 0.59
Jun 1 18:00:00 - 18:59:59 828 5.5% 0.232.5 MB 5.5% 0.72
Jun 1 19:00:00 - 19:59:59 787 5.2% 0.222.1 MB 4.5% 0.59
Jun 1 20:00:00 - 20:59:59 678 4.5% 0.192.0 MB 4.3% 0.56
Jun 1 21:00:00 - 21:59:59 599 4.0% 0.171.8 MB 4.0% 0.52
Jun 1 22:00:00 - 22:59:59 723 4.8% 0.202.7 MB 5.8% 0.76
Jun 1 23:00:00 - 23:59:59 515 3.4% 0.141.7 MB 3.7% 0.48
Jun 2 00:00:00 - 00:59:59 435 2.9% 0.121.7 MB 3.7% 0.49
Jun 2 01:00:00 - 01:59:59 385 2.5% 0.111.5 MB 3.3% 0.44
Jun 2 02:00:00 - 02:59:59 327 2.2% 0.091.2 MB 2.6% 0.34
Jun 2 03:00:00 - 03:59:59 331 2.2% 0.091.1 MB 2.4% 0.31
Jun 2 04:00:00 - 04:59:59 310 2.0% 0.09975.2 KB 2.1% 0.27
Jun 2 05:00:00 - 05:59:59 631 4.2% 0.182.7 MB 6.0% 0.78
Jun 2 06:00:00 - 06:59:59 537 3.5% 0.151.3 MB 2.9% 0.37
Jun 2 07:00:00 - 07:59:59 614 4.1% 0.171.5 MB 3.3% 0.44
Jun 2 08:00:00 - 08:59:59 708 4.7% 0.201.9 MB 4.2% 0.55
Jun 2 09:00:00 - 09:59:59 596 3.9% 0.171.5 MB 3.2% 0.42
Jun 2 10:00:00 - 10:59:59 663 4.4% 0.181.9 MB 4.2% 0.54
Jun 2 11:00:00 - 11:59:59 624 4.1% 0.171.6 MB 3.5% 0.46
Jun 2 12:00:00 - 12:59:59 731 4.8% 0.202.1 MB 4.6% 0.60
Jun 2 13:00:00 - 13:59:59 848 5.6% 0.242.3 MB 5.0% 0.65
Jun 2 14:00:00 - 14:59:59 799 5.3% 0.222.3 MB 5.1% 0.67
Jun 2 15:00:00 - 15:00:08 8 0.1% 1.0019.9 KB 0.0% 2.49
TOTAL: 24:00:04 15139 100.0% 0.1845.9 MB 100.0% 0.54
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB228.9 MB 22.4% 1 0.23 52.68
ONEDEFAULT1024.0 MB388.4 MB 37.9% 3 0.23 52.49
THREEDEFAULT1024.0 MB232.8 MB 22.7% 1 0.22 54.30
TWODEFAULT1024.0 MB230.3 MB 22.5% 1 0.23 53.62
TOTAL: 4-4.0 GB1.1 GB 26.4% 6 0.23-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 572 31 0 2 1 0 0 0 538
2hasname-out.news.weretis.net 297 6 0 18 0 0 0 0 273
3news-out.aioe.org 134 0 0 8 0 0 0 0 126
41 1 0 0 0 0 0 0 0 1
TOTAL: 4 1004 37 0 28 1 0 0 0 938

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.questions 10
alt.personals.sex 5
panix.test 3
wolfsburg.talk 2
panix.chat.politics 2
alt.blah 2
news-service.test 1
muenster.test 1
alt.religion.jewish 1
alt.french 1
newshosting.test 1
mozilla.dev.tech.layout 1
sybase.public.ng.dnews.test 1
alt.sex.telephone.alt 1
ruhr.test 1
alt.soc.germans 1
manse.test 1
nashville.test 1
soc.culture.germany 1
TOTAL: 19 37

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 467
EMP (phn path) 112
EMP (phl) 29
User-issued spam cancel 24
HTML Multipart 13
Binary: misplaced binary 2
TOTAL: 6 647

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 154
news-out.aioe.org 122
news.nntp4.net 14
TOTAL: 3 290
TOTAL: 1 290

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 574 574 0 0 0 0100%21:51:57
2nntp4 13470 8 13202 0 0 152 0%23:51:53
3aioe 10879 8 10593 1 0 31 0%23:51:53
4weretis 6146 0 6013 6 0 0 0%23:51:54
TOTAL: 4 31069 590 29808 7 0 183 1%93:27:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom2.7 MB0.0 KB2.7 MB0.0 KB/s4.8 KB21:51:57
2nntp443.2 KB0.0 KB43.2 KB0.0 KB/s5.4 KB23:51:53
3aioe16.1 KB1.9 KB18.0 KB0.0 KB/s2.0 KB23:51:53
4weretis0.0 KB32.4 KB32.4 KB0.0 KB/s5.4 KB23:51:54
TOTAL: 42.7 MB34.3 KB2.8 MB0.0 KB/s4.8 KB93:27:37
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
? 0.000 0.003 0.00000:00:07
scanner-21.ch1.censys-scanner.com 0.001 0.003 0.00000:00:03
TOTAL: 2 0.001 0.006 0.00000:00:10

NNRP no permission clients:

SystemConn
scanner-21.ch1.censys-scanner.com 1
TOTAL: 1 1

NNRP gethostbyaddr failures [Top 20]:

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