Daily Usenet report

May 20 15:00:03 -- May 21 15:00:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 20 15:01:00 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
May 21 06:30:03 colo-sc-1 pgpverify[18528]: pgpverify: verification failed
May 21 06:30:07 colo-sc-1 pgpverify[18542]: pgpverify: verification failed
May 21 06:45:02 colo-sc-1 pgpverify[20411]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 12183 73.9%1.5 MB 71.2%
innd 2466 15.0%337.5 KB 15.8%
innfeed 1234 7.5%190.9 KB 8.9%
cnfsstat 576 3.5%85.7 KB 4.0%
nnrpd 9 0.1%0.9 KB 0.0%
controlchan 4 0.0%0.3 KB 0.0%
pgpverify 3 0.0%0.6 KB 0.0%
overchan 1 0.0%0.2 KB 0.0%
TOTAL: 8 16476 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 31205 42.3%
Negative hits 30795 41.8%
Do not exist 11721 15.9%
Cache misses 36 0.0%
TOTAL: 4 73757 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.274 0.0%68 0.000 4.029 82.000
article cleanup00:00:00.085 0.0%11810 0.000 0.007 0.038
article logging00:00:00.318 0.0%12114 0.000 0.026 0.068
article parse00:00:00.174 0.0%20694 0.000 0.008 0.047
article write00:00:01.145 0.0%10790 0.000 0.106 6.055
artlog/artcncl00:00:00.001 0.0%65 0.000 0.015 0.111
data move00:00:00.040 0.0%81574 0.000 0.000 0.004
hisgrep/artcncl00:00:00.001 0.0%63 0.000 0.016 0.500
hishave/artcncl00:00:00.200 0.0%68 0.000 2.941 82.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:47.624 0.1%74220 0.032 1.450 49.676
history sync00:00:00.019 0.0%363 0.000 0.052 1.000
history write00:00:01.953 0.0%11802 0.000 0.165 9.039
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle23:58:33.758 99.8%81396 517.621 1060.418 3280.339
nntp read00:00:00.519 0.0%81682 0.000 0.006 0.021
overview write00:00:00.041 0.0%10790 0.000 0.004 0.125
perl filter00:00:16.616 0.0%11802 0.806 1.408 22.873
python filter00:00:00.348 0.0%11802 0.000 0.029 0.107
site send00:00:01.881 0.0%32372 0.026 0.058 0.111
TOTAL: 24:00:54.81224:00:44.997100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%10776 0.000 0.002 0.038
article prepare00:00:00.000 0.0%580 0.000 0.000 0.000
article read00:00:00.016 0.0%439 0.000 0.036 1.000
backlog stats00:00:00.033 0.0%64956 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.254 0.0%38092 0.000 0.007 0.030
data write00:00:00.713 0.0%27404 0.000 0.026 0.080
idle23:59:04.191100.0%64956 674.032 1329.272 3117.093
status file00:00:03.470 0.0%245 0.000 14.163 1263.000
TOTAL: 23:59:10.42623:59:08.699100.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%2 0.000 0.000 0.000
TOTAL: 00:00:00.07800: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.de000300
TOTAL000300

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 9 20811 7213 13330 268 34%48:30:11
2news-out.aioe.org 2 10398 3404 6932 62 32%23:29:20
3news.nntp4.net 2 8647 73 8035 539 0%18:36:52
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 14 39856 10690 28297 869 26%90:36:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net22.3 MB6.7 KB499.7 KB22.8 MB 97%3.1 KB
2news-out.aioe.org8.9 MB4.8 KB114.1 KB9.0 MB 98%2.7 KB
3news.nntp4.net245.5 KB0.0 KB1.3 MB1.6 MB 15%2.7 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 431.4 MB11.4 KB1.9 MB33.4 MB 94%3.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 20 15:00:03 - 15:59:59 656 6.1% 0.181.7 MB 5.4% 0.48
May 20 16:00:00 - 16:59:59 678 6.3% 0.191.9 MB 6.0% 0.54
May 20 17:00:00 - 17:59:59 546 5.1% 0.151.6 MB 5.0% 0.45
May 20 18:00:00 - 18:59:59 565 5.2% 0.161.5 MB 4.7% 0.43
May 20 19:00:00 - 19:59:59 695 6.4% 0.192.4 MB 7.4% 0.67
May 20 20:00:00 - 20:59:59 501 4.6% 0.141.4 MB 4.6% 0.41
May 20 21:00:00 - 21:59:59 505 4.7% 0.141.5 MB 4.8% 0.43
May 20 22:00:00 - 22:59:59 414 3.8% 0.121.2 MB 3.9% 0.36
May 20 23:00:00 - 23:59:59 256 2.4% 0.07911.0 KB 2.8% 0.25
May 21 00:00:00 - 00:59:59 242 2.2% 0.07978.8 KB 3.0% 0.27
May 21 01:00:00 - 01:59:59 307 2.8% 0.09982.3 KB 3.0% 0.27
May 21 02:00:00 - 02:59:59 207 1.9% 0.06666.7 KB 2.1% 0.19
May 21 03:00:00 - 03:59:59 219 2.0% 0.06894.0 KB 2.8% 0.25
May 21 04:00:00 - 04:59:59 258 2.4% 0.07870.7 KB 2.7% 0.24
May 21 05:00:00 - 05:59:59 305 2.8% 0.08768.2 KB 2.4% 0.21
May 21 06:00:00 - 06:59:59 333 3.1% 0.091008.2 KB 3.1% 0.28
May 21 07:00:00 - 07:59:59 388 3.6% 0.111.1 MB 3.4% 0.31
May 21 08:00:00 - 08:59:59 500 4.6% 0.141.3 MB 4.2% 0.38
May 21 09:00:00 - 09:59:59 538 5.0% 0.151.5 MB 4.7% 0.43
May 21 10:00:00 - 10:59:59 564 5.2% 0.161.5 MB 4.7% 0.43
May 21 11:00:00 - 11:59:59 424 3.9% 0.121.2 MB 3.8% 0.34
May 21 12:00:00 - 12:59:59 463 4.3% 0.131.2 MB 3.7% 0.34
May 21 13:00:00 - 13:59:59 594 5.5% 0.171.8 MB 5.7% 0.51
May 21 14:00:00 - 15:00:01 626 5.8% 0.171.9 MB 6.1% 0.55
TOTAL: 23:59:58 10784 100.0% 0.1231.7 MB 100.0% 0.38
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB35.6 MB 3.5% 7 0.16 75.89
ONEDEFAULT1024.0 MB210.3 MB 20.5% 9 0.16 75.27
THREEDEFAULT1024.0 MB45.4 MB 4.4% 7 0.16 76.52
TWODEFAULT1024.0 MB36.0 MB 3.5% 7 0.16 75.77
TOTAL: 4-4.0 GB327.4 MB 8.0% 30 0.16-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 687 14 0 3 1 0 0 0 669
2hasname-out.news.weretis.net 530 1 0 3 0 0 0 0 526
3news-out.aioe.org 117 1 0 2 0 0 0 0 114
TOTAL: 3 1334 16 0 8 1 0 0 0 1309

Unwanted newsgroups [Top 20]:

NewsgroupCount
nyc.jobs 5
panix.chat.politics 5
alt.finals.suicide 2
greenend.test 1
mailing.freebsd.questions 1
mozilla.dev.apps.seamonkey 1
panix.questions 1
TOTAL: 7 16

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 520
EMP (md5) 207
EMP (phn nph) 165
EMP (phl) 103
HTML Multipart 5
Scoring filter 3
TOTAL: 6 1003

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 255
news-out.aioe.org 50
news.nntp4.net 1
TOTAL: 3 306
TOTAL: 1 306

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 392 392 0 0 0 0100%19:05:29
2aioe 9561 26 7113 2 0 1 0%23:59:55
3nntp4 13259 1 10688 0 0 1 0%23:59:54
4weretis 3670 0 3560 18 0 0 0%23:59:49
TOTAL: 4 26882 419 21361 20 0 2 1%91:05:07
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.9 MB0.0 KB1.9 MB0.0 KB/s4.9 KB19:05:29
2aioe94.2 KB6.9 KB101.1 KB0.0 KB/s3.6 KB23:59:55
3nntp43.5 KB0.0 KB3.5 KB0.0 KB/s3.5 KB23:59:54
4weretis0.0 KB77.5 KB77.5 KB0.0 KB/s4.3 KB23:59:49
TOTAL: 42.0 MB84.3 KB2.0 MB0.0 KB/s4.8 KB91:05:07
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
192.241.213.215 0.002 0.002 0.00000:00:00
scanner-09.ch1.censys-scanner.com 0.001 0.001 0.00000:00:00
TOTAL: 2 0.003 0.003 0.00000:00:00

NNRP no permission clients:

SystemConn
scanner-09.ch1.censys-scanner.com 1
192.241.213.215 1
TOTAL: 2 2