Daily Usenet report for csiph.com

May 5 04:15:00 -- May 6 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 5 20:45:03 csiph pgpverify[51703]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 72520 58.1%7.2 MB 43.5%
inn 32690 26.2%6.9 MB 41.9%
innd 16341 13.1%1.9 MB 11.4%
innfeed 3217 2.6%534.4 KB 3.2%
nocem 69 0.1%6.8 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 124842 100.0%16.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 384600 56.5%
Negative hits 263959 38.8%
Do not exist 31696 4.7%
Cache misses 493 0.1%
TOTAL: 4 680748 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.116 0.0%230 0.000 4.852 36.222
article cleanup00:00:00.266 0.0%31260 0.000 0.009 0.047
article logging00:00:04.227 0.0%31791 0.000 0.133 1.315
article parse00:00:06.353 0.0%89258 0.003 0.071 0.988
article write00:02:29.192 0.2%22006 0.211 6.780 50.058
artlog/artcncl00:00:00.017 0.0%213 0.000 0.080 0.500
artlog/artparse00:00:00.256 0.0%736 0.000 0.348 2.500
data move00:00:00.261 0.0%647604 0.000 0.000 0.008
hisgrep/artcncl00:00:00.421 0.0%230 0.000 1.830 18.000
hishave/artcncl00:00:00.136 0.0%230 0.000 0.591 10.500
hishave/artparse00:00:00.002 0.0%735 0.000 0.003 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:35.475 0.2%685097 0.002 0.227 1.455
history sync00:00:02.420 0.0%338 0.000 7.160 60.000
history write00:02:59.538 0.2%31258 0.089 5.744 40.255
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:04.735 0.0%735 0.000 6.442 34.000
idle23:44:19.470 98.9%640938 74.555 133.335 402.726
nntp read00:00:03.349 0.0%659622 0.002 0.005 0.013
overview write00:05:14.302 0.4%22006 1.675 14.283 128.551
perl filter00:01:10.521 0.1%31110 1.428 2.267 15.923
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.418 0.0%44073 0.000 0.009 0.025
TOTAL: 24:00:46.22323:59:12.475 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.058 0.0%22012 0.000 0.003 0.018
article prepare00:00:00.003 0.0%20068 0.000 0.000 0.006
article read00:00:01.772 0.0%3053 0.000 0.580 25.182
backlog stats00:00:00.217 0.0%395577 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.145 0.0%309429 0.002 0.007 0.013
data write00:00:01.780 0.0%293090 0.003 0.006 0.013
idle23:54:15.560100.0%395577 138.006 217.544 672.029
status file00:00:00.615 0.0%284 0.000 2.165 206.000
TOTAL: 23:54:32.27823:54:22.150100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle26:46:20.834 99.6%80671 79.056 1194.740600055.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.944 0.0%162358 0.000 0.006 0.500
readart00:02:08.429 0.1%10275 0.000 12.499 148.000
TOTAL: 26:53:31.55826:48:30.207 99.7%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 3 44779 6659 37377 743 14%47:55:51
2csiph-out.feeder.erje.net 1440 128006 4251 118467 5288 3%118:48:41
3feeder.eternal-september.org 1 27380 3034 23397 949 11%23:59:55
4news-out.aioe.org 12 22814 1980 20755 79 8%24:27:06
5news-out.mixmin.net 3 24072 1655 21644 773 6%24:08:28
6newsfeed.neva.ru 143 30004 1212 27323 1469 4%71:41:06
7newsfeed.fu-berlin.de 33 23334 1070 21945 319 4%30:36:36
8newsfeeds.xmission.com 3 21623 883 20734 6 4%25:03:52
9news.freedyn.net 3 24752 393 24082 277 1%24:09:46
10news.redatomik.org 1 14519 347 14169 3 2%18:13:03
11xanadu-bbs.net 11 1014 247 756 11 24%25:37:57
12news.fcku.it 2 9250 206 8967 77 2%23:59:08
13news.swapon.de 2 2148 71 2076 1 3%26:02:35
14nntp.pasdenom.info 2 20804 42 20759 3 0%23:58:58
15news.bbs.geek.nz 2 21601 3 21598 0 0%23:59:44
16localhost 38 3 3 0 0 100%00:00:01
TOTAL: 16 1699 416103 22056 384049 9998 5%532:42:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.weretis.net19.0 MB11.9 KB4.2 MB23.2 MB 81%3.2 KB
2csiph-out.feeder.erje.net16.7 MB0.0 KB52.0 MB68.7 MB 24%7.4 KB
3feeder.eternal-september.org8.1 MB9.7 KB7.1 MB15.3 MB 53%3.9 KB
4news-out.mixmin.net6.1 MB7.2 KB2.0 MB8.1 MB 75%3.4 KB
5news-out.aioe.org4.9 MB2.5 KB189.3 KB5.1 MB 96%2.5 KB
6newsfeed.neva.ru2.9 MB0.0 KB5.9 MB8.8 MB 32%3.3 KB
7newsfeeds.xmission.com2.8 MB0.0 KB198.8 KB3.0 MB 93%3.4 KB
8newsfeed.fu-berlin.de2.4 MB4.2 KB848.0 KB3.2 MB 74%2.4 KB
9news.freedyn.net1.2 MB0.0 KB1.0 MB2.2 MB 54%3.4 KB
10news.redatomik.org1005.3 KB7.3 KB11.7 KB1.0 MB 98%2.9 KB
11xanadu-bbs.net726.9 KB0.0 KB172.2 KB899.1 KB 80%3.5 KB
12news.fcku.it460.7 KB0.0 KB1.6 MB2.1 MB 21%7.5 KB
13news.swapon.de195.3 KB0.0 KB9.2 KB204.5 KB 95%2.8 KB
14nntp.pasdenom.info95.7 KB0.0 KB7.9 KB103.6 KB 92%2.3 KB
15news.bbs.geek.nz6.7 KB0.0 KB0.0 KB6.7 KB100%2.2 KB
16localhost6.1 KB0.0 KB0.0 KB6.1 KB100%2.0 KB
TOTAL: 1666.5 MB42.8 KB75.2 MB141.7 MB 46%4.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 5 04:15:00 - 04:59:59 424 1.9% 0.161.8 MB 2.8% 0.70
May 5 05:00:00 - 05:59:59 522 2.4% 0.141.6 MB 2.4% 0.45
May 5 06:00:00 - 06:59:59 802 3.6% 0.223.8 MB 5.7% 1.08
May 5 07:00:00 - 07:59:59 968 4.4% 0.272.5 MB 3.8% 0.72
May 5 08:00:00 - 08:59:59 1153 5.2% 0.323.5 MB 5.3% 1.00
May 5 09:00:00 - 09:59:59 1098 5.0% 0.303.3 MB 5.0% 0.94
May 5 10:00:00 - 10:59:59 1138 5.2% 0.323.4 MB 5.2% 0.97
May 5 11:00:00 - 11:59:59 1051 4.8% 0.292.8 MB 4.3% 0.80
May 5 12:00:00 - 12:59:59 1100 5.0% 0.313.2 MB 4.9% 0.91
May 5 13:00:00 - 13:59:59 1169 5.3% 0.323.5 MB 5.3% 1.00
May 5 14:00:00 - 14:59:59 1209 5.5% 0.343.2 MB 4.9% 0.92
May 5 15:00:00 - 15:59:59 1254 5.7% 0.353.3 MB 5.0% 0.93
May 5 16:00:00 - 16:59:59 1140 5.2% 0.323.2 MB 4.8% 0.91
May 5 17:00:00 - 17:59:59 1217 5.5% 0.343.3 MB 4.9% 0.93
May 5 18:00:00 - 18:59:59 1343 6.1% 0.374.4 MB 6.7% 1.26
May 5 19:00:00 - 19:59:59 1064 4.8% 0.302.9 MB 4.4% 0.83
May 5 20:00:00 - 20:59:59 1012 4.6% 0.283.0 MB 4.5% 0.85
May 5 21:00:00 - 21:59:59 893 4.1% 0.252.4 MB 3.6% 0.68
May 5 22:00:00 - 22:59:59 736 3.3% 0.202.3 MB 3.5% 0.65
May 5 23:00:00 - 23:59:59 709 3.2% 0.202.3 MB 3.4% 0.65
May 6 00:00:00 - 00:59:59 529 2.4% 0.151.5 MB 2.2% 0.42
May 6 01:00:00 - 01:59:59 509 2.3% 0.141.5 MB 2.2% 0.42
May 6 02:00:00 - 02:59:59 429 2.0% 0.121.4 MB 2.1% 0.40
May 6 03:00:00 - 03:59:59 405 1.8% 0.111.3 MB 1.9% 0.36
May 6 04:00:00 - 04:15:00 122 0.6% 0.14757.4 KB 1.1% 0.84
TOTAL: 24:00:00 21996 100.0% 0.2566.2 MB 100.0% 0.78
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 5304 243 0 0 0 0 0 0 5061
2newsfeed.neva.ru 1478 250 0 0 0 0 0 0 1228
3feeder.eternal-september.org 930 55 0 0 0 0 0 0 875
4csiph-out.news.weretis.net 798 224 0 1 0 0 0 0 573
5news-out.mixmin.net 779 4 0 0 0 0 0 0 775
6newsfeed.fu-berlin.de 726 51 0 2 0 0 0 0 673
7news.freedyn.net 278 8 0 0 0 0 0 0 270
8news-out.aioe.org 84 21 0 0 0 0 0 0 63
9news.fcku.it 69 0 0 0 0 0 0 0 69
10xanadu-bbs.net 11 0 0 0 0 0 0 0 11
11newsfeeds.xmission.com 8 1 0 0 0 0 0 0 7
12news.swapon.de 8 0 0 0 0 0 0 0 8
13news.redatomik.org 5 0 0 0 0 0 0 0 5
14nntp.pasdenom.info 3 0 0 0 0 0 0 0 3
TOTAL: 14 10481 857 0 3 0 0 0 0 9621

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 118
lada.talk 86
it-alt.sport.calcio.juventus 62
spline.eisfair 46
lada.auto 44
mpc.lists.freebsd.ports.bugs 39
it.annunci.varie 27
mozilla.dev.security.policy 27
gmane.comp.gis.openstreetmap 26
it.annunci.commerciali 25
lada.commerce 25
alt.windows7.general 24
it.annunci.immobiliari 23
mpc.lists.nanog 18
alt.survival 12
cakewalk.audio 12
lada.photo 12
oesterreich.tratsch 9
spline.fli4l 9
mpc.lists.freebsd.x11 8
TOTAL: 123 857

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6941
EMP (phn path) 923
Too many newsgroups 59
EMP (phn nph) 55
User-issued cancel 46
EMP (phl) 42
Binary Payload 30
HTML Multipart 24
Scoring filter (8) 16
Scoring filter (10) 11
Scoring filter (16) 8
Scoring filter (9) 8
Scoring filter (12) 6
Scoring filter (14) 5
Scoring filter (13) 5
Scoring filter (11) 4
Binary: misplaced PAR2 3
Binary: misplaced par2 3
HTML post 3
Scoring filter (15) 3
TOTAL: 21 8196

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com6006767
TOTAL: 16006767

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 145
TOTAL: 1 145
Bad Message-ID's offered
csiph-out.feeder.erje.net 5
TOTAL: 1 5
Huge articles
csiph-out.feeder.erje.net 652
csiph-out.news.weretis.net 37
feeder.eternal-september.org 28
news-out.mixmin.net 13
news.freedyn.net 4
newsfeed.fu-berlin.de 4
news.fcku.it 4
newsfeeds.xmission.com 4
TOTAL: 8 746
Including strange strings
newsfeed.fu-berlin.de 382
csiph-out.news.weretis.net 55
csiph-out.feeder.erje.net 36
news-out.mixmin.net 26
newsfeed.neva.ru 12
news.swapon.de 7
news-out.aioe.org 4
feeder.eternal-september.org 3
news.redatomik.org 3
newsfeeds.xmission.com 2
TOTAL: 11 531
No colon-space in header
csiph-out.feeder.erje.net 1
TOTAL: 1 1
TOTAL: 5 1428

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 40481 1118 20190 64 0 0 2%23:59:54
2news.freedyn.net 22376 722 20881 2 0 0 3%23:59:54
3xanadu-bbs 22740 677 21090 1 0 52 2%23:59:54
4fu-berlin.de 20737 522 20215 0 0 0 2%23:59:54
5usenet.pasdenom.info 23843 122 20125 1706 0 6 0%23:59:54
6news.redatomik.org 21486 17 20914 1 0 0 0%23:59:54
7aioe 19539 11 18536 4 0 0 0%23:59:41
8xmission 22100 7 20558 0 0 0 0%23:59:53
9news.mixmin.net 20111 5 19533 0 0 0 0%23:59:54
10weretis.net 14437 5 14280 2 0 0 0%23:59:29
11news.swapon.de 2103 4 2071 0 0 0 0%23:54:32
12eternal-september 19106 4 18046 0 0 0 0%23:59:52
13neva.ru 20299 2 19930 5 0 0 0%23:59:54
14news.fcku.it 2318 1 2280 0 0 0 0%22:35:24
15erje 17871 1 17686 20 0 3 0%23:59:54
16bwh 0 0 0 0 0 21355 0%23:58:42
17au2pb 0 0 0 0 0 21373 0%23:58:42
TOTAL: 17 289547 3218 256335 1805 0 42789 1%406:25:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net6.9 MB7.6 KB7.0 MB0.1 KB/s9.8 KB23:59:54
2xanadu-bbs6.6 MB1.3 KB6.6 MB0.1 KB/s10.0 KB23:59:54
3fu-berlin.de6.3 MB0.0 KB6.3 MB0.1 KB/s12.3 KB23:59:54
4news.bbs.geek.nz3.1 MB335.5 KB3.4 MB0.0 KB/s3.0 KB23:59:54
5usenet.pasdenom.info478.1 KB8.2 MB8.6 MB0.1 KB/s4.8 KB23:59:54
6news.redatomik.org65.5 KB1.9 KB67.4 KB0.0 KB/s3.7 KB23:59:54
7aioe37.6 KB14.0 KB51.6 KB0.0 KB/s3.4 KB23:59:41
8xmission14.9 KB0.0 KB14.9 KB0.0 KB/s2.1 KB23:59:53
9news.mixmin.net14.2 KB0.0 KB14.2 KB0.0 KB/s2.8 KB23:59:54
10weretis.net11.2 KB36.0 KB47.2 KB0.0 KB/s6.7 KB23:59:29
11eternal-september9.2 KB0.0 KB9.2 KB0.0 KB/s2.3 KB23:59:52
12news.swapon.de7.6 KB0.0 KB7.6 KB0.0 KB/s1.9 KB23:54:32
13neva.ru3.7 KB11.2 KB14.9 KB0.0 KB/s2.1 KB23:59:54
14erje1.3 KB57.1 KB58.3 KB0.0 KB/s2.8 KB23:59:54
15news.fcku.it1.2 KB0.0 KB1.2 KB0.0 KB/s1.2 KB22:35:24
16bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
17au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
TOTAL: 1723.6 MB8.6 MB32.2 MB0.0 KB/s6.6 KB406:25:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1144.138.90.254 288 89292.5 KB 81 0 003:42:27
2204-73-239-109.cust.centrio.cz 20 3888.1 KB 12 2 002:41:59
368-185-181-64.dhcp.mdsn.wi.charter.com 15 22.6 KB 2 1 103:12:38
4dhcp-089-099-203-254.chello.nl 55 11.4 KB 1 0 013:51:33
TOTAL: 4 378 130384.7 KB 96 3 126:53:04

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 288 89292.5 KB 81 0 003:42:27
2*.cust.centrio.cz 20 3888.1 KB 12 2 002:41:59
3*.dhcp.mdsn.wi.charter.com 15 22.6 KB 2 1 103:12:38
4*.chello.nl 55 11.4 KB 1 0 013:51:33
5*.dsl.bell.ca3104 00.0 KB 0 0 003:24:25
TOTAL: 53482 130384.7 KB 96 3 126:53:04

NNRP auth users [Top 20]:

UserConn
moussa 288
ctomczyk 14
ohlandl 1
TOTAL: 3 303

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dhcp-089-099-203-254.chello.nl 4.389 0.802 0.00013:51:33
144.138.90.254 26.197 5.833 1.03503:42:27
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca 249.596 39.556 0.00003:24:25
68-185-181-64.dhcp.mdsn.wi.charter.com 1.313 0.234 0.00403:12:38
204-73-239-109.cust.centrio.cz 1.940 0.302 0.04602:41:59
TOTAL: 5 283.435 46.727 1.08526:53:04

Curious NNRP server explorers [Top 20]:

SystemConn
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca 3104
TOTAL: 1 3104

NNRP client timeouts [Top 20]:

SystemConnPeer
68-185-181-64.dhcp.mdsn.wi.charter.com 3 0
204-73-239-109.cust.centrio.cz 2 0
TOTAL: 2 5 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 129 99.2%
2pl 1 0.8%
TOTAL: 2 130100.0%

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

NewsgroupCount
1comp.lang.javascript 37
2comp.arch 31
3comp.arch.fpga 27
4comp.sys.ibm.pc.games.action 10
5comp.os.vms 9
6comp.sys.ibm.ps2.hardware 5
7comp.arch.embedded 3
8comp.os.msdos.djgpp 3
9comp.ai.neural-nets 1
10comp.unix.aix 1
11pl.comp.lang.javascript 1
12comp.os.os2.announce 1
13comp.os.msdos.apps 1
TOTAL: 13 130