Daily Usenet report

Jul 18 06:01:01 -- Jul 19 06:01:01

Unknown entries from news log file:

First 50 / 181 lines (27.6%)

Jul 18 06:01:54 kennel innd: message repeated 2 times: [ ctlinnd command s]
Jul 18 20:00:03 kennel pgpverify[3629451]: pgpverify: verification failed
Jul 18 20:00:07 kennel pgpverify[3629578]: pgpverify: verification failed
Jul 18 20:01:08 kennel pgpverify[3629942]: pgpverify: verification failed
Jul 18 20:01:11 kennel pgpverify[3629959]: pgpverify: verification failed
Jul 18 20:02:12 kennel pgpverify[3630376]: pgpverify: verification failed
Jul 18 20:02:13 kennel pgpverify[3630384]: pgpverify: verification failed
Jul 18 20:03:13 kennel pgpverify[3630684]: pgpverify: verification failed
Jul 18 20:03:14 kennel pgpverify[3630693]: pgpverify: verification failed
Jul 18 20:04:15 kennel pgpverify[3631217]: pgpverify: verification failed
Jul 18 20:04:16 kennel pgpverify[3631228]: pgpverify: verification failed
Jul 18 20:05:17 kennel pgpverify[3631537]: pgpverify: verification failed
Jul 18 20:05:19 kennel pgpverify[3631546]: pgpverify: verification failed
Jul 18 20:06:19 kennel pgpverify[3631921]: pgpverify: verification failed
Jul 18 20:06:20 kennel pgpverify[3631929]: pgpverify: verification failed
Jul 18 20:07:21 kennel pgpverify[3632238]: pgpverify: verification failed
Jul 18 20:07:22 kennel pgpverify[3632246]: pgpverify: verification failed
Jul 18 20:08:22 kennel pgpverify[3632629]: pgpverify: verification failed
Jul 18 20:08:23 kennel pgpverify[3632637]: pgpverify: verification failed
Jul 18 20:09:24 kennel pgpverify[3633016]: pgpverify: verification failed
Jul 18 20:09:25 kennel pgpverify[3633024]: pgpverify: verification failed
Jul 18 20:10:25 kennel pgpverify[3633420]: pgpverify: verification failed
Jul 18 20:10:26 kennel pgpverify[3633429]: pgpverify: verification failed
Jul 18 20:11:27 kennel pgpverify[3633998]: pgpverify: verification failed
Jul 18 20:11:28 kennel pgpverify[3634008]: pgpverify: verification failed
Jul 18 20:12:28 kennel pgpverify[3634386]: pgpverify: verification failed
Jul 18 20:12:29 kennel pgpverify[3634394]: pgpverify: verification failed
Jul 18 20:13:30 kennel pgpverify[3634698]: pgpverify: verification failed
Jul 18 20:13:31 kennel pgpverify[3634706]: pgpverify: verification failed
Jul 18 20:14:31 kennel pgpverify[3635220]: pgpverify: verification failed
Jul 18 20:14:33 kennel pgpverify[3635231]: pgpverify: verification failed
Jul 18 20:15:33 kennel pgpverify[3635578]: pgpverify: verification failed
Jul 18 20:15:34 kennel pgpverify[3635586]: pgpverify: verification failed
Jul 18 20:16:34 kennel pgpverify[3635963]: pgpverify: verification failed
Jul 18 20:16:35 kennel pgpverify[3635971]: pgpverify: verification failed
Jul 18 20:17:36 kennel pgpverify[3636284]: pgpverify: verification failed
Jul 18 20:17:37 kennel pgpverify[3636395]: pgpverify: verification failed
Jul 18 20:18:38 kennel pgpverify[3636808]: pgpverify: verification failed
Jul 18 20:18:39 kennel pgpverify[3636816]: pgpverify: verification failed
Jul 18 20:19:39 kennel pgpverify[3637142]: pgpverify: verification failed
Jul 18 20:19:40 kennel pgpverify[3637151]: pgpverify: verification failed
Jul 18 20:20:41 kennel pgpverify[3637552]: pgpverify: verification failed
Jul 18 20:20:42 kennel pgpverify[3637561]: pgpverify: verification failed
Jul 18 20:21:42 kennel pgpverify[3637894]: pgpverify: verification failed
Jul 18 20:21:43 kennel pgpverify[3637902]: pgpverify: verification failed
Jul 18 20:22:44 kennel pgpverify[3638276]: pgpverify: verification failed
Jul 18 20:22:45 kennel pgpverify[3638284]: pgpverify: verification failed
Jul 18 20:23:45 kennel pgpverify[3638593]: pgpverify: verification failed
Jul 18 20:23:46 kennel pgpverify[3638601]: pgpverify: verification failed
Jul 18 20:24:47 kennel pgpverify[3639110]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 424038 97.6%53.3 MB 97.3%
innd 8295 1.9%1.2 MB 2.3%
innfeed 1397 0.3%208.6 KB 0.4%
controlchan 181 0.0%13.1 KB 0.0%
pgpverify 180 0.0%26.1 KB 0.0%
cnfsstat 144 0.0%23.2 KB 0.0%
nnrpd 38 0.0%4.4 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 8 434274 100.0%54.8 MB100.0%

History cache:

ReasonCount%Count
Negative hits 684001 45.1%
Positive hits 412860 27.2%
Do not exist 411659 27.1%
Cache misses 9070 0.6%
TOTAL: 4 1517590 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.023 0.0%49 0.000 0.469 5.333
article cleanup00:00:04.572 0.0%424076 0.005 0.011 0.018
article logging00:00:16.741 0.0%424233 0.017 0.039 0.056
article parse00:00:24.403 0.0%1180419 0.008 0.021 0.026
article write00:00:26.034 0.0%293479 0.053 0.089 0.120
artlog/artparse00:00:00.034 0.0%694 0.000 0.049 1.000
data move00:00:02.057 0.0%1272752 0.001 0.002 0.003
hisgrep/artcncl00:00:00.016 0.0%49 0.000 0.327 5.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:49.352 0.1%1528859 0.003 0.032 1.469
history sync00:00:00.028 0.0%323 0.000 0.087 0.500
history write00:00:25.101 0.0%414161 0.044 0.061 0.103
idle23:54:51.606 99.6%994792 56.838 86.542 170.074
nntp read00:00:37.435 0.0%994975 0.015 0.038 0.051
overview write00:00:01.602 0.0%293479 0.001 0.005 0.010
perl filter00:00:21.070 0.0%413489 0.025 0.051 0.067
python filter00:00:20.606 0.0%413489 0.026 0.050 0.067
site send00:00:11.088 0.0%659701 0.011 0.017 0.112
TOTAL: 24:00:32.58823:58:51.768 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.240 0.0%72579 0.000 0.003 0.016
article prepare00:00:00.000 0.0%596 0.000 0.000 0.000
article read00:00:00.058 0.0%452 0.000 0.128 1.000
backlog stats00:00:00.197 0.0%223345 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.871 0.0%143232 0.008 0.027 0.049
data write00:00:03.140 0.0%88879 0.008 0.035 0.086
idle23:55:36.685100.0%223345 80.048 385.667 1919.981
status file00:00:00.162 0.0%249 0.000 0.651 1.000
TOTAL: 23:55:52.12523:55:44.353100.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%8 0.000 0.000 0.000
TOTAL: 00:00:03.42200: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
group-admin@isc.org00018000
TOTAL00018000

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1hasname-out.feeder.erje.net 577 807226 285443 390851 130932 35%47:34:00
2hasname-out.news.weretis.net 4 13203 5212 7945 46 39%45:23:42
3usenet.blueworldhosting.com 718 8583 1546 6895 142 18%23:46:00
4news.nntp4.net 1 8347 485 7753 109 5%23:59:56
5localhost 1 0 0 0 0 0%00:00:00
TOTAL: 5 1301 837359 292686 413444 131229 34%140:43:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1hasname-out.feeder.erje.net2.6 GB108.1 MB1.1 GB3.9 GB 67%9.7 KB
2hasname-out.news.weretis.net15.0 MB0.0 KB76.1 KB15.1 MB 99%2.9 KB
3usenet.blueworldhosting.com3.7 MB0.0 KB747.1 KB4.4 MB 83%2.7 KB
4news.nntp4.net1.4 MB0.0 KB842.7 KB2.2 MB 62%3.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 52.6 GB108.1 MB1.2 GB3.9 GB 67%9.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 18 06:01:01 - 06:59:59 15248 5.2% 4.31111.8 MB 4.2% 32.36
Jul 18 07:00:00 - 07:59:59 13533 4.6% 3.76110.1 MB 4.1% 31.31
Jul 18 08:00:00 - 08:59:59 13492 4.6% 3.75112.0 MB 4.2% 31.84
Jul 18 09:00:00 - 09:59:59 11610 4.0% 3.23117.3 MB 4.4% 33.37
Jul 18 10:00:00 - 10:59:59 10537 3.6% 2.93118.8 MB 4.4% 33.80
Jul 18 11:00:00 - 11:59:59 10222 3.5% 2.8499.8 MB 3.7% 28.38
Jul 18 12:00:00 - 12:59:59 8156 2.8% 2.2793.8 MB 3.5% 26.68
Jul 18 13:00:00 - 13:59:59 11733 4.0% 3.26116.3 MB 4.3% 33.09
Jul 18 14:00:00 - 14:59:59 12328 4.2% 3.42116.1 MB 4.3% 33.03
Jul 18 15:00:00 - 15:59:59 11483 3.9% 3.19120.1 MB 4.5% 34.17
Jul 18 16:00:00 - 16:59:59 12373 4.2% 3.44114.7 MB 4.3% 32.63
Jul 18 17:00:00 - 17:59:59 11514 3.9% 3.20104.7 MB 3.9% 29.77
Jul 18 18:00:00 - 18:59:59 14425 4.9% 4.01149.7 MB 5.6% 42.57
Jul 18 19:00:00 - 19:59:59 16231 5.5% 4.51129.1 MB 4.8% 36.73
Jul 18 20:00:00 - 20:59:59 14968 5.1% 4.16100.4 MB 3.7% 28.55
Jul 18 21:00:00 - 21:59:59 10402 3.6% 2.89106.4 MB 4.0% 30.27
Jul 18 22:00:00 - 22:59:59 13231 4.5% 3.68119.3 MB 4.4% 33.92
Jul 18 23:00:00 - 23:59:59 14856 5.1% 4.13122.8 MB 4.6% 34.92
Jul 19 00:00:00 - 00:59:59 15486 5.3% 4.30131.1 MB 4.9% 37.29
Jul 19 01:00:00 - 01:59:59 12328 4.2% 3.42106.2 MB 4.0% 30.22
Jul 19 02:00:00 - 02:59:59 9140 3.1% 2.5488.2 MB 3.3% 25.08
Jul 19 03:00:00 - 03:59:59 11720 4.0% 3.26105.5 MB 3.9% 30.02
Jul 19 04:00:00 - 04:59:59 9631 3.3% 2.6897.6 MB 3.6% 27.76
Jul 19 05:00:00 - 05:59:59 7858 2.7% 2.1887.9 MB 3.3% 24.99
Jul 19 06:00:00 - 06:01:01 156 0.1% 2.561.7 MB 0.1% 29.00
TOTAL: 24:00:00 292661 100.0% 3.392.6 GB 100.0% 31.78
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
C1DEFAULT10.0 GB230.6 MB 2.3% 61 38.95 3.12
TOTAL: 1-10.0 GB230.6 MB 2.3% 61 38.95-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1hasname-out.feeder.erje.net131059119697 0 10539 0 0 0 0 823
2usenet.blueworldhosting.com 154 142 0 0 0 0 0 0 12
3news.nntp4.net 112 112 0 0 0 0 0 0 0
4hasname-out.news.weretis.net 52 44 0 0 0 0 0 0 8
TOTAL: 4131377119995 0 10539 0 0 0 0 843

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.friends 56900
alt.binaries.font 2646
alt.binaries.pwp 2512
alt.binaries.wtfnzb.beta 1570
alt.binaries.encrypted 1538
alt.binaries.wtfnzb.alpha 1530
alt.binaries.wtfnzb.kilo 1530
alt.binaries.wtfnzb.delta 1504
alt.binaries.wtfnzb.echo 1503
alt.binaries.wtfnzb.lima 1502
alt.binaries.wtfnzb.bravo 1493
alt.binaries.wtfnzb.golf 1483
alt.binaries.wtfnzb.hotel 1477
alt.binaries.wtfnzb.india 1469
alt.binaries.wtfnzb.foxtrot 1467
alt.binaries.wtfnzb.charlie 1465
alt.binaries.wtfnzb.juliet 1465
alt.binaries.wtfnzb.mike 1443
alt.binaries.wtfnzb.novemeber 1399
alt.binaries.ath 1385
TOTAL: 217 119995

INND no permission servers:

SystemConn
103.193.138.76 30
162.142.125.215 1
TOTAL: 2 31

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
hasname-out.feeder.erje.net 2
TOTAL: 1 2
Including strange strings
hasname-out.feeder.erje.net 135
usenet.blueworldhosting.com 12
hasname-out.news.weretis.net 8
TOTAL: 3 155
No colon-space in header field
hasname-out.feeder.erje.net 688
TOTAL: 1 688
TOTAL: 3 845

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1bbsgslincom 405 405 0 0 0 0100%21:44:12
2blueworldhosting 71595 39 70797 2 0 49 0%23:59:54
3nntp4 7644 1 7643 0 0 35 0%23:59:50
4erje 6645 0 6638 4 0 20 0%23:59:54
5weretis 2612 0 2609 2 0 0 0%23:59:07
TOTAL: 5 88901 445 87687 8 0 104 0%117:42:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1bbsgslincom1.3 MB0.0 KB1.3 MB0.0 KB/s3.3 KB21:44:12
2blueworldhosting458.0 KB57.0 KB515.1 KB0.0 KB/s12.6 KB23:59:54
3nntp46.1 KB0.0 KB6.1 KB0.0 KB/s6.1 KB23:59:50
4erje0.0 KB13.9 KB13.9 KB0.0 KB/s3.5 KB23:59:54
5weretis0.0 KB8.1 KB8.1 KB0.0 KB/s4.1 KB23:59:07
TOTAL: 51.8 MB79.0 KB1.8 MB0.0 KB/s4.1 KB117:42:57
Outgoing feeds (innfeed) by Volume

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
165.154.246.244 0.000 0.005 0.00000:00:01
206.168.34.74 0.000 0.004 0.00000:00:01
golden.monitoring.internet-measurement.com 0.001 0.003 0.00000:00:00
194.165.16.161 0.000 0.001 0.00000:00:00
198.235.24.251 0.001 0.003 0.00000:00:00
147.185.132.176 0.002 0.004 0.00000:00:00
azpdcg32jj1i.stretchoid.com 0.000 0.003 0.00000:00:00
TOTAL: 7 0.004 0.023 0.00000:00:03

NNRP no permission clients [Top 20]:

SystemConn
147.185.132.176 2
165.154.246.244 1
194.165.16.161 1
198.235.24.251 1
206.168.34.74 1
azpdcg32jj1i.stretchoid.com 1
golden.monitoring.internet-measurement.com 1
TOTAL: 7 8
innreport from INN 2.7.2 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then