Daily Usenet report for csiph.com

Feb 17 04:15:00 -- Feb 18 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 17 12:10:02 csiph pgpverify[52772]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 43635 60.5%8.2 MB 71.7%
innd 23966 33.2%2.5 MB 22.3%
innfeed 3215 4.5%554.1 KB 4.8%
nnrpd 1273 1.8%140.4 KB 1.2%
nocem 86 0.1%8.7 KB 0.1%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 72180 100.0%11.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 430683 56.5%
Negative hits 289200 37.9%
Do not exist 42446 5.6%
Cache misses 542 0.1%
TOTAL: 4 762871 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.692 0.0%265 0.000 2.611 26.000
article cleanup00:00:00.298 0.0%42883 0.000 0.007 0.034
article logging00:00:04.690 0.0%43357 0.000 0.108 2.235
article parse00:00:02.744 0.0%142355 0.004 0.019 0.404
article write00:03:14.762 0.2%23797 0.189 8.184 70.964
artlog/artcncl00:00:00.000 0.0%246 0.000 0.000 0.000
artlog/artparse00:00:00.003 0.0%138 0.000 0.022 0.333
data move00:00:00.296 0.0%700682 0.000 0.000 0.003
hisgrep/artcncl00:00:00.156 0.0%259 0.000 0.602 15.000
hishave/artcncl00:00:00.055 0.0%265 0.000 0.208 2.389
hishave/artparse00:00:00.001 0.0%137 0.000 0.007 0.100
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:12.116 0.2%767347 0.002 0.172 1.480
history sync00:00:03.471 0.0%361 0.000 9.615 80.000
history write00:03:33.490 0.2%42640 0.648 5.007 36.542
hiswrite/artcncl00:00:00.018 0.0%6 1.000 3.000 3.400
hiswrite/artparse00:00:01.052 0.0%137 0.000 7.679 50.000
idle23:44:15.879 98.8%683220 78.703 125.078 376.649
nntp read00:00:03.384 0.0%714928 0.002 0.005 0.011
overview write00:04:16.409 0.3%23797 0.956 10.775 133.783
perl filter00:01:51.642 0.1%42454 1.532 2.630 6.753
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.436 0.0%47676 0.000 0.009 0.027
TOTAL: 24:01:02.86323:59:41.594 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.096 0.0%23775 0.000 0.004 0.032
article prepare00:00:00.006 0.0%41356 0.000 0.000 0.003
article read00:00:01.106 0.0%7268 0.000 0.152 1.696
backlog stats00:00:00.238 0.0%450664 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.349 0.0%340678 0.002 0.007 0.015
data write00:00:01.971 0.0%325165 0.002 0.006 0.014
idle23:53:43.186100.0%450664 119.511 190.881 483.258
status file00:00:00.327 0.0%293 0.000 1.116 12.000
TOTAL: 23:53:59.44523:53:49.279100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:01.513 0.0%2533 0.000 0.597 37.000
idle68:19:15.710 99.8%3201 0.000 76837.148900205.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.022 0.0%6585 0.000 0.003 0.500
readart00:00:00.959 0.0%69 0.000 13.899 70.000
TOTAL: 68:27:43.40868:19:18.204 99.8%----

Control commands to INND:

CommandNumber
L 1
flush 5
flushlogs 2
go 2
logmode 2
mode 144
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 164

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.feeder.erje.net 1152 135308 8317 116195 10796 6%94:57:30
2csiph-out.news.weretis.net 3 49668 4037 44835 796 8%47:44:27
3feeder.eternal-september.org 7 38108 3763 29694 4651 9%25:20:55
4newsfeed.neva.ru 143 55206 1661 50222 3323 3%71:49:51
5news-out.aioe.org 3 24004 1589 22384 31 6%24:09:56
6news.redatomik.org 1 22178 1149 21016 13 5%23:56:05
7newsfeed.fu-berlin.de 45 24914 1031 23441 442 4%31:40:46
8news-out.mixmin.net 1 26125 974 24791 360 3%23:59:40
9newsfeeds.xmission.com 3 20332 543 19780 9 2%20:52:21
10xanadu-bbs.net 7 1718 237 1473 8 13%38:32:59
11nntp.pasdenom.info 4 22424 134 22284 6 0%24:07:31
12news.fcku.it 1 9549 117 9388 44 1%23:59:59
13news.freedyn.net 4 26593 107 26323 163 0%24:09:44
14news.swapon.de 2 2254 56 2189 9 2%23:44:48
15news.bbs.geek.nz 1 16414 17 16396 1 0%23:59:31
16localhost 47 13 13 0 0 100%00:00:14
TOTAL: 16 1424 474808 23745 430411 20652 5%523:06:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net25.0 MB2.3 MB60.1 MB87.4 MB 28%4.7 KB
2csiph-out.news.weretis.net13.3 MB0.0 KB11.0 MB24.3 MB 54%5.1 KB
3feeder.eternal-september.org9.8 MB193.2 KB59.3 MB69.3 MB 14%8.4 KB
4news-out.mixmin.net4.6 MB23.6 KB1.1 MB5.6 MB 81%4.3 KB
5news-out.aioe.org4.4 MB0.0 KB74.4 KB4.4 MB 98%2.8 KB
6newsfeed.neva.ru4.0 MB446.2 KB30.2 MB34.6 MB 11%7.1 KB
7news.redatomik.org3.0 MB0.0 KB1.2 MB4.2 MB 71%3.7 KB
8newsfeed.fu-berlin.de2.2 MB0.0 KB1.2 MB3.4 MB 64%2.4 KB
9newsfeeds.xmission.com1.8 MB0.0 KB390.7 KB2.2 MB 82%4.1 KB
10xanadu-bbs.net651.1 KB0.0 KB280.4 KB931.5 KB 69%3.8 KB
11news.freedyn.net471.6 KB0.0 KB1.8 MB2.3 MB 20%8.6 KB
12nntp.pasdenom.info419.7 KB0.0 KB22.9 KB442.6 KB 94%3.2 KB
13news.fcku.it244.2 KB0.0 KB910.3 KB1.1 MB 21%7.2 KB
14news.swapon.de150.2 KB0.0 KB19.0 KB169.2 KB 88%2.6 KB
15news.bbs.geek.nz47.9 KB0.0 KB1.4 KB49.3 KB 97%2.7 KB
16localhost19.8 KB0.0 KB0.0 KB19.8 KB100%1.5 KB
TOTAL: 1670.0 MB3.0 MB167.5 MB240.5 MB 29%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 17 04:15:00 - 04:59:59 284 1.2% 0.11846.8 KB 1.2% 0.31
Feb 17 05:00:00 - 05:59:59 645 2.7% 0.182.0 MB 2.8% 0.56
Feb 17 06:00:00 - 06:59:59 722 3.0% 0.202.5 MB 3.6% 0.72
Feb 17 07:00:00 - 07:59:59 652 2.7% 0.181.9 MB 2.7% 0.55
Feb 17 08:00:00 - 08:59:59 1044 4.4% 0.292.7 MB 3.9% 0.77
Feb 17 09:00:00 - 09:59:59 1103 4.6% 0.313.2 MB 4.6% 0.92
Feb 17 10:00:00 - 10:59:59 1243 5.2% 0.353.5 MB 5.0% 1.00
Feb 17 11:00:00 - 11:59:59 1116 4.7% 0.312.9 MB 4.2% 0.83
Feb 17 12:00:00 - 12:59:59 1086 4.6% 0.303.4 MB 4.8% 0.96
Feb 17 13:00:00 - 13:59:59 1143 4.8% 0.323.0 MB 4.2% 0.84
Feb 17 14:00:00 - 14:59:59 1163 4.9% 0.323.1 MB 4.5% 0.89
Feb 17 15:00:00 - 15:59:59 1244 5.2% 0.353.7 MB 5.2% 1.04
Feb 17 16:00:00 - 16:59:59 1280 5.4% 0.363.8 MB 5.4% 1.08
Feb 17 17:00:00 - 17:59:59 1369 5.8% 0.383.8 MB 5.5% 1.09
Feb 17 18:00:00 - 18:59:59 1339 5.6% 0.373.8 MB 5.4% 1.08
Feb 17 19:00:00 - 19:59:59 1303 5.5% 0.363.9 MB 5.6% 1.11
Feb 17 20:00:00 - 20:59:59 1338 5.6% 0.373.9 MB 5.6% 1.11
Feb 17 21:00:00 - 21:59:59 1258 5.3% 0.353.7 MB 5.3% 1.06
Feb 17 22:00:00 - 22:59:59 1127 4.7% 0.313.4 MB 4.8% 0.96
Feb 17 23:00:00 - 23:59:59 900 3.8% 0.252.8 MB 4.1% 0.81
Feb 18 00:00:00 - 00:59:59 681 2.9% 0.191.9 MB 2.7% 0.54
Feb 18 01:00:00 - 01:59:59 596 2.5% 0.172.5 MB 3.5% 0.70
Feb 18 02:00:00 - 02:59:59 555 2.3% 0.151.8 MB 2.6% 0.51
Feb 18 03:00:00 - 03:59:59 500 2.1% 0.141.6 MB 2.4% 0.47
Feb 18 04:00:00 - 04:15:00 99 0.4% 0.11336.0 KB 0.5% 0.37
TOTAL: 24:00:00 23790 100.0% 0.2870.1 MB 100.0% 0.83
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 9364 433 0 191 4 0 0 0 8736
2feeder.eternal-september.org 4666 58 0 15 0 0 0 0 4593
3newsfeed.neva.ru 3297 252 0 33 0 0 0 0 3012
4csiph-out.news.weretis.net 837 189 0 0 0 0 0 0 648
5newsfeed.fu-berlin.de 783 33 0 0 0 0 0 0 750
6news-out.mixmin.net 360 1 0 5 0 0 0 0 354
7news.freedyn.net 163 16 0 0 0 0 0 0 147
8news.fcku.it 43 0 0 0 0 0 0 0 43
9news-out.aioe.org 31 21 0 0 0 0 0 0 10
10news.swapon.de 17 0 0 0 0 0 0 0 17
11news.redatomik.org 17 0 0 0 0 0 0 0 17
12newsfeeds.xmission.com 15 4 0 0 0 0 0 0 11
13nntp.pasdenom.info 6 4 0 0 0 0 0 0 2
14xanadu-bbs.net 4 0 0 0 0 0 0 0 4
TOTAL: 14 19603 1011 0 244 4 0 0 018344

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.auto 106
lada.talk 95
ut.test 94
it.annunci.immobiliari 43
gmane.linux.alsa.devel 42
it.annunci.commerciali 41
it.annunci.varie 41
it-alt.sport.calcio.juventus 37
mpc.lists.freebsd.ports.bugs 34
alt.politics.scorched-earth 24
spline.eisfair 23
mozilla.support.thunderbird 21
mozilla.dev.platform 19
cakewalk.audio 18
alt.windows7.general 18
alt.courts.pretrial-services 16
it-alt.comp.software.mozilla 16
mpc.lists.nanog 14
eagle.userchat.eng 14
it-alt.comp.software.openoffice 13
TOTAL: 124 1011

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

GroupsCount
nz.politics.announce 3
alt.culture.china 1
TOTAL: 2 4

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15182
EMP (phn path) 1567
EMP (phl) 553
EMP (phn nph) 71
Too many newsgroups 57
HTML Multipart 22
Scoring filter (11) 19
Scoring filter (8) 19
Scoring filter (10) 15
Binary Payload 12
Scoring filter (9) 7
Scoring filter (21) 5
HTML post 4
Scoring filter (15) 4
Scoring filter (18) 3
Scoring filter (14) 2
User-issued cancel 2
Binary Image: misplaced jpg 1
Scoring filter (13) 1
Angle-bracket bot 1
TOTAL: 21 17548

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com7908888
TOTAL: 17908888

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 185
TOTAL: 1 185
Huge articles
csiph-out.news.weretis.net 67
feeder.eternal-september.org 31
news.freedyn.net 10
news.fcku.it 10
newsfeeds.xmission.com 6
news-out.mixmin.net 6
newsfeed.fu-berlin.de 4
news.redatomik.org 2
xanadu-bbs.net 1
TOTAL: 9 137
Including strange strings
newsfeed.fu-berlin.de 335
csiph-out.feeder.erje.net 64
feeder.eternal-september.org 29
csiph-out.news.weretis.net 11
newsfeed.neva.ru 11
news.swapon.de 8
news.redatomik.org 6
newsfeeds.xmission.com 5
news-out.mixmin.net 3
news-out.aioe.org 1
TOTAL: 10 473
TOTAL: 3 795

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 39375 5525 17401 94 1 0 14%23:56:51
2news.freedyn.net 24818 1133 22487 5 1 0 4%23:50:06
3xanadu-bbs 27323 893 22770 1 0 0 3%23:59:54
4fu-berlin.de 22476 676 21800 0 0 0 3%23:52:09
5usenet.pasdenom.info 26304 253 21998 1401 0 21 0%23:59:55
6xmission 28463 106 22297 7 0 0 0%23:59:55
7aioe 21443 66 19888 6 0 0 0%23:50:05
8news.redatomik.org 22973 41 21807 12 0 0 0%23:56:43
9weretis.net 18263 23 17305 2 0 0 0%23:54:10
10news.mixmin.net 22435 20 21146 0 0 0 0%23:50:06
11eternal-september 21156 19 18937 0 0 0 0%23:59:31
12news.swapon.de 2209 14 2128 0 0 0 0%23:50:06
13neva.ru 22475 12 20999 9 0 0 0%23:59:54
14erje 15482 1 15317 26 0 0 0%23:59:01
15news.fcku.it 2667 0 2507 0 0 0 0%22:58:10
16au2pb 0 0 0 0 0 23005 0%23:50:07
17bwh 0 0 0 0 0 22997 0%23:50:07
TOTAL: 17 317862 8782 268787 1563 2 46023 2%405:36:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.bbs.geek.nz14.8 MB481.5 KB15.3 MB0.2 KB/s2.8 KB23:56:51
2news.freedyn.net6.8 MB19.9 KB6.8 MB0.1 KB/s6.1 KB23:50:06
3xanadu-bbs6.1 MB5.6 KB6.1 MB0.1 KB/s7.0 KB23:59:54
4fu-berlin.de5.5 MB0.0 KB5.5 MB0.1 KB/s8.3 KB23:52:09
5usenet.pasdenom.info662.1 KB6.9 MB7.6 MB0.1 KB/s4.7 KB23:59:55
6xmission243.2 KB31.0 KB274.2 KB0.0 KB/s2.4 KB23:59:55
7aioe156.2 KB27.4 KB183.6 KB0.0 KB/s2.5 KB23:50:05
8news.redatomik.org87.9 KB86.2 KB174.1 KB0.0 KB/s3.3 KB23:56:43
9news.mixmin.net78.6 KB0.0 KB78.6 KB0.0 KB/s3.9 KB23:50:06
10weretis.net52.8 KB20.7 KB73.5 KB0.0 KB/s2.9 KB23:54:10
11eternal-september42.1 KB0.0 KB42.1 KB0.0 KB/s2.2 KB23:59:31
12news.swapon.de21.7 KB0.0 KB21.7 KB0.0 KB/s1.5 KB23:50:06
13neva.ru19.4 KB15.6 KB35.0 KB0.0 KB/s1.7 KB23:59:54
14erje3.1 KB60.3 KB63.4 KB0.0 KB/s2.3 KB23:59:01
15news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s022:58:10
16au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:07
17bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:07
TOTAL: 1734.6 MB7.6 MB42.2 MB0.0 KB/s4.2 KB405:36:50
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1tempe0.bbox.io 31 2339.7 KB 7 1 107:56:33
2dhcp-089-099-203-254.chello.nl 21 2341.2 KB 4 0 004:36:15
368-185-181-64.dhcp.mdsn.wi.charter.com 31 1629.2 KB 15 12 1205:06:55
4194-73-239-109.cust.centrio.cz 12 716.6 KB 6 0 003:32:54
TOTAL: 4 95 69126.7 KB 32 13 1368:27:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.chello.nl 21 2341.2 KB 4 0 004:36:15
2*.bbox.io 31 2339.7 KB 7 1 107:56:33
3*.dhcp.mdsn.wi.charter.com 31 1629.2 KB 15 12 1205:06:55
4*.cust.centrio.cz 12 716.6 KB 6 0 003:32:54
5unresolved 92 00.0 KB 0 0 047:15:02
6*.137.22.98.dynamic.ip.windstream.net 1 00.0 KB 0 0 000:00:00
TOTAL: 6 188 69126.7 KB 32 13 1368:27:41

NNRP auth users [Top 20]:

UserConn
ohlandl 12
ctomczyk 9
kev009 1
TOTAL: 3 22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.239.174.218 6.905 1.405 0.00044:34:47
tempe0.bbox.io 2.895 0.465 0.00307:56:33
68-185-181-64.dhcp.mdsn.wi.charter.com 3.341 0.587 0.04705:06:55
dhcp-089-099-203-254.chello.nl 1.931 0.326 0.00004:36:15
194-73-239-109.cust.centrio.cz 1.128 0.191 0.03403:32:54
50.96.157.204 0.141 0.203 0.00002:40:15
TOTAL: 6 16.341 3.177 0.08468:27:41

Curious NNRP server explorers [Top 20]:

SystemConn
216.239.174.218 91
h201.137.22.98.dynamic.ip.windstream.net 1
50.96.157.204 1
TOTAL: 3 93

NNRP client timeouts [Top 20]:

SystemConnPeer
216.239.174.218 87 0
tempe0.bbox.io 12 0
194-73-239-109.cust.centrio.cz 7 0
68-185-181-64.dhcp.mdsn.wi.charter.com 1 0
TOTAL: 4 107 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 60 87.0%
2bit 5 7.2%
3pl 4 5.8%
TOTAL: 3 69100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 56
2bit.listserv.ibm-main 5
3pl.comp.lang.javascript 3
4comp.lang.javascript 3
5comp.sys.ibm.as400.misc 1
6pl.comp.www.nowe-strony 1
TOTAL: 6 69