Daily Usenet report for csiph.com

Dec 15 04:15:00 -- Dec 16 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 15 11:51:40 csiph pgpverify[45507]: pgpverify: verification failed
Dec 15 11:51:40 csiph controlchan[25015]: skipping newgroup m control@usenet.org.uk (pgpverify failed) in <makegroup-uk.radio.amateur.moderated-20151215114905$7fc9@weathertop.principate.org.uk>

Log entries by program:

Program nameLines%LinesSize%Size
inn 46247 48.5%7.1 MB 61.2%
nnrpd 31874 33.4%2.6 MB 23.0%
innd 15410 16.2%1.5 MB 13.4%
innfeed 1840 1.9%283.0 KB 2.4%
controlchan 2 0.0%0.1 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 6 95374 100.0%11.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 262943 49.6%
Negative hits 221764 41.8%
Do not exist 45498 8.6%
Cache misses 176 0.0%
TOTAL: 4 530381 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.504 0.0%194 0.000 2.598 23.000
article cleanup00:00:00.275 0.0%43284 0.000 0.006 0.023
article logging00:00:00.591 0.0%43836 0.000 0.013 0.067
article parse00:00:05.648 0.0%494794 0.003 0.011 0.075
article write00:01:17.756 0.1%28246 0.175 2.753 17.839
artlog/artcncl00:00:00.002 0.0%156 0.000 0.013 0.333
artlog/artparse00:00:00.020 0.0%2262 0.000 0.009 0.333
data move00:00:00.470 0.0%954593 0.000 0.000 0.002
hisgrep/artcncl00:00:00.123 0.0%194 0.000 0.634 16.000
hishave/artcncl00:00:00.001 0.0%194 0.000 0.005 0.500
hishave/artparse00:00:00.009 0.0%2262 0.000 0.004 0.031
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.266 0.0%528577 0.001 0.012 0.275
history sync00:00:02.405 0.0%352 0.000 6.832 215.500
history write00:00:43.591 0.1%43281 0.072 1.007 13.356
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:01.549 0.0%2261 0.000 0.685 6.600
idle23:55:28.884 99.6%927190 12.712 92.892 350.242
nntp read00:00:02.988 0.0%930329 0.002 0.003 0.007
overview write00:01:08.651 0.1%28246 0.504 2.430 20.179
perl filter00:01:12.727 0.1%43281 1.029 1.680 3.030
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.476 0.0%56493 0.000 0.008 0.024
TOTAL: 24:00:50.09924:00:12.936100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.077 0.0%28222 0.000 0.003 0.019
article prepare00:00:00.000 0.0%4684 0.000 0.000 0.000
article read00:00:00.172 0.0%946 0.000 0.182 15.000
backlog stats00:00:00.100 0.0%299164 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.308 0.0%235564 0.001 0.006 0.011
data write00:00:01.562 0.0%212555 0.001 0.007 0.015
idle23:55:11.255100.0%299164 130.638 287.840 739.330
status file00:00:00.081 0.0%175 0.000 0.463 1.000
TOTAL: 23:55:18.69223:55:14.555100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.086 0.0%2262 0.032 0.038 0.049
idle20:41:41.482 99.9%33701 0.000 2210.661600127.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.411 0.0%68434 0.000 0.006 0.333
readart00:00:00.079 0.0%103 0.000 0.767 72.000
TOTAL: 20:42:41.08720:41:42.058 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 146
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 165

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1au2pb.net 3 31281 9230 20985 1066 29%24:08:47
2feeder.eternal-september.org 8 41262 5872 33895 1495 14%25:51:22
3news-out.mixmin.net 4 33046 3782 26866 2398 11%24:17:44
4newsfeeds.xmission.com 1 29404 2739 26125 540 9%23:35:06
5xanadu-bbs.net 7 45277 2582 34375 8320 5%27:38:59
6newsfeed.neva.ru 143 62563 2405 56986 3172 3%71:39:55
7newsfeed.fu-berlin.de 65 33375 1376 31759 240 4%36:01:37
8news.swapon.de 2 2523 210 2306 7 8%24:38:21
9nntp.pasdenom.info 4 29391 139 29198 54 0%24:08:21
10localhost 26 0 0 0 0 0%00:00:00
TOTAL: 10 263 308122 28335 262495 17292 9%282:00:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1au2pb.net21.3 MB11.0 KB6.0 MB27.3 MB 77%2.7 KB
2feeder.eternal-september.org16.9 MB0.0 KB25.2 MB42.1 MB 40%5.8 KB
3news-out.mixmin.net13.4 MB12.1 KB5.3 MB18.8 MB 71%3.1 KB
4newsfeeds.xmission.com9.1 MB12.1 KB5.4 MB14.5 MB 62%4.5 KB
5xanadu-bbs.net7.8 MB0.0 KB645.1 MB652.9 MB 1%61.3 KB
6newsfeed.neva.ru7.6 MB0.0 KB15.9 MB23.5 MB 32%4.3 KB
7newsfeed.fu-berlin.de3.0 MB0.0 KB598.9 KB3.6 MB 83%2.3 KB
8news.swapon.de556.4 KB0.0 KB141.2 KB697.6 KB 79%3.2 KB
9nntp.pasdenom.info349.7 KB0.0 KB578.6 KB928.3 KB 37%4.8 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1080.0 MB35.2 KB704.3 MB784.3 MB 10%17.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 15 04:15:00 - 04:59:59 487 1.7% 0.181.6 MB 1.9% 0.59
Dec 15 05:00:00 - 05:59:59 703 2.5% 0.202.3 MB 2.9% 0.67
Dec 15 06:00:00 - 06:59:59 611 2.2% 0.172.0 MB 2.5% 0.57
Dec 15 07:00:00 - 07:59:59 807 2.9% 0.222.1 MB 2.6% 0.60
Dec 15 08:00:00 - 08:59:59 1251 4.4% 0.353.6 MB 4.5% 1.01
Dec 15 09:00:00 - 09:59:59 1224 4.3% 0.343.1 MB 3.8% 0.87
Dec 15 10:00:00 - 10:59:59 1378 4.9% 0.383.5 MB 4.4% 1.01
Dec 15 11:00:00 - 11:59:59 1176 4.2% 0.332.9 MB 3.7% 0.83
Dec 15 12:00:00 - 12:59:59 1389 4.9% 0.393.6 MB 4.5% 1.02
Dec 15 13:00:00 - 13:59:59 1346 4.8% 0.373.3 MB 4.2% 0.94
Dec 15 14:00:00 - 14:59:59 1394 4.9% 0.393.4 MB 4.2% 0.95
Dec 15 15:00:00 - 15:59:59 1510 5.3% 0.424.5 MB 5.6% 1.28
Dec 15 16:00:00 - 16:59:59 1743 6.2% 0.485.0 MB 6.3% 1.43
Dec 15 17:00:00 - 17:59:59 1656 5.9% 0.464.8 MB 6.1% 1.38
Dec 15 18:00:00 - 18:59:59 1830 6.5% 0.514.6 MB 5.8% 1.31
Dec 15 19:00:00 - 19:59:59 1579 5.6% 0.444.7 MB 5.9% 1.34
Dec 15 20:00:00 - 20:59:59 1375 4.9% 0.383.7 MB 4.6% 1.05
Dec 15 21:00:00 - 21:59:59 1578 5.6% 0.444.6 MB 5.8% 1.31
Dec 15 22:00:00 - 22:59:59 1305 4.6% 0.364.3 MB 5.4% 1.22
Dec 15 23:00:00 - 23:59:59 972 3.4% 0.272.8 MB 3.5% 0.78
Dec 16 00:00:00 - 00:59:59 860 3.0% 0.242.5 MB 3.1% 0.71
Dec 16 01:00:00 - 01:59:59 750 2.7% 0.212.4 MB 3.0% 0.68
Dec 16 02:00:00 - 02:59:59 554 2.0% 0.151.6 MB 2.0% 0.45
Dec 16 03:00:00 - 03:59:59 605 2.1% 0.172.2 MB 2.8% 0.64
Dec 16 04:00:00 - 04:15:00 160 0.6% 0.18635.3 KB 0.8% 0.71
TOTAL: 24:00:00 28243 100.0% 0.3379.7 MB 100.0% 0.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1xanadu-bbs.net 8319 396 0 0 0 0 0 0 7923
2newsfeed.neva.ru 3202 1453 0 0 0 0 0 0 1749
3news-out.mixmin.net 2421 4 0 1 0 0 0 0 2416
4feeder.eternal-september.org 1509 222 0 0 0 0 0 0 1287
5au2pb.net 1083 1006 0 1 0 0 0 0 76
6newsfeed.fu-berlin.de 658 50 0 0 0 0 0 0 608
7newsfeeds.xmission.com 556 400 0 1 0 0 0 0 155
8nntp.pasdenom.info 59 50 0 0 0 0 0 0 9
9news.swapon.de 41 0 0 0 0 0 0 0 41
TOTAL: 9 17848 3581 0 3 0 0 0 014264

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 909
lada.talk 186
ut.test 151
alt.politics 95
mailing.freebsd.svn-ports-all 87
mailing.freebsd.pkg-fallout 82
mailing.freebsd.svn-ports-head 82
lada.auto 80
mailing.freebsd.ports-bugs 63
mailing.freebsd.svn-src-all 62
fa.freebsd.cvs-all 62
it-alt.sport.calcio.juventus 61
pld.cvs.commit 57
mailing.freebsd.svn-src-head 50
mailing.freebsd.questions 42
gmane.linux.alsa.devel 34
mozilla.dev.tree-alerts 33
alt.politics.scorched-earth 30
mailing.openssl.dev 29
mailing.openbsd.source-changes 29
TOTAL: 266 3581

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 7302
EMP (phn path) 2853
EMP (phl) 849
PFM bot misc 156
Too many newsgroups 86
EMP (phn nph) 77
HTML Multipart 64
Binary: misplaced rar 19
HTML post 18
User-issued spam cancel 8
Binary Image: misplaced jpg 7
HTML Image Tags 2
Binary: misplaced par2 1
Binary: misplaced zip 1
Too many newsgroups (meow) 1
NewsAgent 1
TOTAL: 16 11445

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 1994
feeder.eternal-september.org 170
newsfeeds.xmission.com 41
au2pb.net 30
news-out.mixmin.net 24
nntp.pasdenom.info 5
newsfeed.fu-berlin.de 2
news.swapon.de 1
TOTAL: 8 2267
Including strange strings
newsfeed.fu-berlin.de 440
news.swapon.de 34
feeder.eternal-september.org 30
news-out.mixmin.net 20
au2pb.net 20
newsfeeds.xmission.com 4
newsfeed.neva.ru 2
xanadu-bbs.net 2
TOTAL: 8 552
TOTAL: 2 2819

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1usenet.pasdenom.info 32735 193 27388 522 0 3 0%23:59:57
2au2pb 19554 147 18802 81 0 0 0%23:59:52
3xanadu-bbs 27307 75 25541 61 0 12 0%23:59:57
4fu-berlin.de 26435 20 26400 15 0 0 0%23:59:53
5xmission 29715 10 25178 2 0 0 0%23:59:57
6eternal-september 23122 8 21562 7 0 0 0%23:59:57
7news.mixmin.net 25480 6 24390 0 0 0 0%23:59:57
8neva.ru 26703 3 25528 24 0 0 0%23:59:57
9news.swapon.de 2316 0 2280 2 0 0 0%23:57:02
TOTAL: 9 213367 462 197069 714 0 15 0%215:56:29
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1usenet.pasdenom.info804.6 KB1.1 MB1.9 MB0.0 KB/s2.7 KB23:59:57
2au2pb654.5 KB327.4 KB981.9 KB0.0 KB/s4.3 KB23:59:52
3xanadu-bbs520.8 KB135.5 KB656.2 KB0.0 KB/s4.8 KB23:59:57
4fu-berlin.de109.8 KB19.0 KB128.8 KB0.0 KB/s3.7 KB23:59:53
5xmission32.8 KB4.1 KB36.9 KB0.0 KB/s3.1 KB23:59:57
6eternal-september32.3 KB35.1 KB67.4 KB0.0 KB/s4.5 KB23:59:57
7news.mixmin.net28.6 KB0.0 KB28.6 KB0.0 KB/s4.8 KB23:59:57
8neva.ru15.4 KB177.0 KB192.4 KB0.0 KB/s7.1 KB23:59:57
9news.swapon.de0.0 KB8.3 KB8.3 KB0.0 KB/s4.2 KB23:57:02
TOTAL: 92.1 MB1.8 MB3.9 MB0.0 KB/s3.4 KB215:56:29
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 288 101258.2 KB 94 0 004:23:07
2tempe0.bbox.io 14 22.4 KB 2 0 004:11:20
TOTAL: 2 302 103260.6 KB 96 0 020:42:37

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 317 101258.2 KB 94 0 012:15:24
2*.bbox.io 14 22.4 KB 2 0 004:11:20
3*.dynamic.upc.nl 2 00.0 KB 0 0 000:00:43
4*.chello.nl 4 00.0 KB 0 0 001:39:53
5*.tampfl.dsl-w.verizon.net 3 00.0 KB 0 0 001:03:31
6*.nielsenmedia.com 3 00.0 KB 0 0 001:31:43
TOTAL: 6 343 103260.6 KB 96 0 020:42:37

NNRP auth users [Top 20]:

UserConn
moussa 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.246.166.29 0.388 0.205 0.00007:52:14
202.89.176.220 30.378 6.395 0.74804:23:07
tempe0.bbox.io 1.378 0.263 0.00004:11:20
dhcp-089-099-203-254.chello.nl 0.294 0.055 0.00001:39:53
challenger.nielsenmedia.com 0.250 0.054 0.00001:31:43
pool-71-98-160-84.tampfl.dsl-w.verizon.net 0.266 0.025 0.00001:03:31
92-108-162-83.dynamic.upc.nl 0.199 0.023 0.00000:00:43
69.160.37.54 1.943 0.417 0.00000:00:02
TOTAL: 8 35.096 7.437 0.74820:42:37

Curious NNRP server explorers [Top 20]:

SystemConn
69.160.37.54 25
dhcp-089-099-203-254.chello.nl 4
216.246.166.29 4
challenger.nielsenmedia.com 3
pool-71-98-160-84.tampfl.dsl-w.verizon.net 3
92-108-162-83.dynamic.upc.nl 2
TOTAL: 6 41

NNRP client timeouts [Top 20]:

SystemConnPeer
tempe0.bbox.io 8 0
challenger.nielsenmedia.com 3 0
dhcp-089-099-203-254.chello.nl 3 0
pool-71-98-160-84.tampfl.dsl-w.verizon.net 2 0
TOTAL: 4 16 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 103100.0%
TOTAL: 1 103100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1comp.os.vms 26
2comp.arch 22
3comp.sys.ibm.pc.games.action 20
4comp.arch.embedded 10
5comp.arch.fpga 7
6comp.ai.philosophy 6
7comp.sys.ibm.pc.hardware.storage 4
8comp.ai.neural-nets 3
9comp.sys.ibm.ps2.hardware 3
10comp.sys.ibm.pc.games.rpg 1
11comp.sys.ibm.pc.games.strategic 1
TOTAL: 11 103