Daily Usenet report

Dec 29 15:00:01 -- Dec 30 15:00:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 29 15:01:15 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 15784 73.2%1.9 MB 70.9%
innd 3954 18.3%540.2 KB 19.9%
innfeed 1190 5.5%162.8 KB 6.0%
cnfsstat 576 2.7%82.6 KB 3.0%
nnrpd 45 0.2%4.8 KB 0.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 21550 100.0%2.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 42992 44.2%
Positive hits 39005 40.1%
Do not exist 15206 15.6%
Cache misses 37 0.0%
TOTAL: 4 97240 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.633 0.0%77 0.000 47.182 362.778
article cleanup00:00:00.098 0.0%15350 0.000 0.006 0.057
article logging00:00:00.441 0.0%15688 0.000 0.028 0.079
article parse00:00:00.253 0.0%27179 0.000 0.009 0.036
article write00:00:01.204 0.0%12874 0.000 0.094 2.670
artlog/artcncl00:00:00.000 0.0%77 0.000 0.000 0.000
data move00:00:00.035 0.0%107465 0.000 0.000 0.004
hisgrep/artcncl00:00:00.004 0.0%75 0.000 0.053 3.000
hishave/artcncl00:00:00.027 0.0%77 0.000 0.351 4.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:19.087 0.0%98033 0.000 0.195 8.765
history sync00:00:00.016 0.0%364 0.000 0.044 0.500
history write00:00:03.964 0.0%15348 0.000 0.258 10.679
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:59:44.438 99.9%108226 434.203 798.186 2410.635
nntp read00:00:00.892 0.0%108129 0.000 0.008 0.067
overview write00:00:00.045 0.0%12874 0.000 0.003 0.047
perl filter00:00:21.172 0.0%15348 0.778 1.379 18.405
python filter00:00:00.397 0.0%15348 0.000 0.026 0.082
site send00:00:02.187 0.0%38622 0.019 0.057 0.117
TOTAL: 24:00:53.55924:00:37.893100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.029 0.0%12842 0.000 0.002 0.026
article prepare00:00:00.000 0.0%752 0.000 0.000 0.000
article read00:00:00.025 0.0%526 0.000 0.048 0.500
backlog stats00:00:00.051 0.0%74012 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.366 0.0%44603 0.000 0.008 0.026
data write00:00:00.954 0.0%31930 0.000 0.030 0.076
idle23:57:26.546100.0%74012 625.649 1165.305 3433.366
status file00:00:00.809 0.0%235 0.000 3.443 136.400
TOTAL: 23:57:30.68323:57:28.780100.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%11 0.000 0.000 0.000
TOTAL: 00:00:01.40900:00:00.000 0.0%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.news.weretis.net 9 26757 7291 18306 1160 27%48:39:12
2news-out.aioe.org 4 12611 5058 7537 16 40%23:53:45
3news.nntp4.net 1 14903 494 13108 1301 3%23:38:04
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 15 54271 12843 38951 2477 23%96:11:01
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.news.weretis.net22.7 MB0.8 KB2.3 MB25.0 MB 90%3.0 KB
2news-out.aioe.org13.0 MB0.0 KB29.9 KB13.0 MB 99%2.6 KB
3news.nntp4.net1.5 MB0.9 KB3.2 MB4.7 MB 32%2.7 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 437.2 MB1.7 KB5.5 MB42.7 MB 87%2.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 29 15:00:01 - 15:59:59 680 5.3% 0.192.1 MB 5.7% 0.60
Dec 29 16:00:00 - 16:59:59 749 5.8% 0.212.2 MB 6.0% 0.64
Dec 29 17:00:00 - 17:59:59 883 6.9% 0.252.6 MB 7.1% 0.75
Dec 29 18:00:00 - 18:59:59 809 6.3% 0.222.0 MB 5.4% 0.57
Dec 29 19:00:00 - 19:59:59 693 5.4% 0.192.0 MB 5.3% 0.56
Dec 29 20:00:00 - 20:59:59 683 5.3% 0.192.1 MB 5.5% 0.58
Dec 29 21:00:00 - 21:59:59 642 5.0% 0.182.0 MB 5.5% 0.58
Dec 29 22:00:00 - 22:59:59 551 4.3% 0.151.7 MB 4.6% 0.48
Dec 29 23:00:00 - 23:59:59 511 4.0% 0.141.7 MB 4.5% 0.47
Dec 30 00:00:00 - 00:59:59 340 2.6% 0.091.1 MB 3.1% 0.33
Dec 30 01:00:00 - 01:59:59 286 2.2% 0.08925.3 KB 2.4% 0.26
Dec 30 02:00:00 - 02:59:59 299 2.3% 0.081.0 MB 2.8% 0.29
Dec 30 03:00:00 - 03:59:59 245 1.9% 0.07789.9 KB 2.1% 0.22
Dec 30 04:00:00 - 04:59:59 256 2.0% 0.07804.7 KB 2.1% 0.22
Dec 30 05:00:00 - 05:59:59 257 2.0% 0.07839.2 KB 2.2% 0.23
Dec 30 06:00:00 - 06:59:59 295 2.3% 0.08946.0 KB 2.5% 0.26
Dec 30 07:00:00 - 07:59:59 360 2.8% 0.10956.9 KB 2.5% 0.27
Dec 30 08:00:00 - 08:59:59 535 4.2% 0.151.3 MB 3.6% 0.38
Dec 30 09:00:00 - 09:59:59 589 4.6% 0.161.5 MB 3.9% 0.41
Dec 30 10:00:00 - 10:59:59 697 5.4% 0.191.7 MB 4.6% 0.49
Dec 30 11:00:00 - 11:59:59 561 4.4% 0.161.6 MB 4.4% 0.47
Dec 30 12:00:00 - 12:59:59 589 4.6% 0.161.7 MB 4.5% 0.48
Dec 30 13:00:00 - 13:59:59 682 5.3% 0.191.9 MB 5.0% 0.53
Dec 30 14:00:00 - 14:59:59 680 5.3% 0.191.8 MB 4.9% 0.51
Dec 30 15:00:00 - 15:00:01 2 0.0% 2.005.3 KB 0.0% 5.29
TOTAL: 24:00:00 12874 100.0% 0.1537.2 MB 100.0% 0.44
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB743.2 MB 72.6% 4 0.19 64.52
ONEDEFAULT1024.0 MB908.6 MB 88.7% 6 0.19 64.57
THREEDEFAULT1024.0 MB751.5 MB 73.4% 4 0.19 65.31
TWODEFAULT1024.0 MB743.6 MB 72.6% 4 0.19 64.89
TOTAL: 4-4.0 GB3.1 GB 76.8% 18 0.19-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1hasname-out.news.weretis.net 1435 3 0 1 0 0 0 0 1431
2news.nntp4.net 1304 7 0 1 1 0 0 0 1295
3news-out.aioe.org 94 0 0 0 0 0 0 0 94
TOTAL: 3 2833 10 0 2 1 0 0 0 2820

Unwanted newsgroups [Top 20]:

NewsgroupCount
wolfsburg.test 2
alt.religion.jewish 1
alt.french 1
panix.chat.politics 1
alt.ufo 1
alt.soc.germans 1
alt.bestjobsusa.philly.jobs 1
soc.culture.germany 1
list.postfix.users 1
TOTAL: 9 10

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) 1733
EMP (md5) 595
EMP (phn nph) 70
EMP (phl) 44
User-issued spam cancel 23
HTML Multipart 9
Binary: misplaced binary 2
HTML post 2
Scoring filter 2
Too many newsgroups 1
TOTAL: 10 2481

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
hasname-out.news.weretis.net 260
news-out.aioe.org 77
news.nntp4.net 2
TOTAL: 3 339
TOTAL: 1 339

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 451 451 0 0 0 0100%20:44:17
2aioe 10175 25 7541 6 0 0 0%23:50:00
3weretis 5664 5 5547 39 0 0 0%23:58:02
4nntp4 14862 0 12272 0 0 1 0%23:50:00
TOTAL: 4 31152 481 25360 45 0 1 1%92:22:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.6 MB0.0 KB1.6 MB0.0 KB/s3.6 KB20:44:17
2aioe120.4 KB55.4 KB175.8 KB0.0 KB/s5.7 KB23:50:00
3weretis18.2 KB158.8 KB177.0 KB0.0 KB/s4.0 KB23:58:02
4nntp40.0 KB0.0 KB0.0 KB0.0 KB/s023:50:00
TOTAL: 41.7 MB214.2 KB1.9 MB0.0 KB/s3.7 KB92:22:19
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news.nntp4.net 0.003 0.003 0.00000:00:01
sh-phx-us-gp1-wk107.internet-census.org 0.009 0.013 0.00000:00:00
104.152.52.199 0.001 0.002 0.00000:00:00
TOTAL: 3 0.013 0.018 0.00000:00:01

NNRP no permission clients:

SystemConn
sh-phx-us-gp1-wk107.internet-census.org 8
news.nntp4.net 2
104.152.52.199 1
TOTAL: 3 11