Daily Usenet report for csiph.com

Jan 17 04:15:00 -- Jan 18 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 17 12:10:02 csiph pgpverify[24048]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 44901 55.9%8.7 MB 69.3%
innd 22750 28.3%2.5 MB 19.9%
nnrpd 9300 11.6%829.8 KB 6.5%
innfeed 3205 4.0%545.7 KB 4.3%
nocem 97 0.1%10.2 KB 0.1%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 80258 100.0%12.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 520572 56.9%
Negative hits 349464 38.2%
Do not exist 44084 4.8%
Cache misses 527 0.1%
TOTAL: 4 914647 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.444 0.0%205 0.000 2.166 15.375
article cleanup00:00:00.245 0.0%43969 0.000 0.006 0.027
article logging00:00:00.631 0.0%44548 0.000 0.014 0.108
article parse00:00:01.998 0.0%180934 0.001 0.011 0.103
article write00:01:30.433 0.1%25747 0.180 3.512 16.537
artlog/artcncl00:00:00.003 0.0%113 0.000 0.027 0.167
artlog/artparse00:00:00.003 0.0%265 0.000 0.011 0.333
data move00:00:00.278 0.0%921398 0.000 0.000 0.002
hisgrep/artcncl00:00:00.041 0.0%203 0.000 0.202 8.000
hishave/artcncl00:00:00.011 0.0%205 0.000 0.054 9.000
hishave/artparse00:00:00.000 0.0%265 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:16.965 0.0%916391 0.001 0.019 0.085
history sync00:00:02.599 0.0%365 0.000 7.121 52.000
history write00:01:25.283 0.1%43937 0.068 1.941 15.067
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
hiswrite/artparse00:00:00.587 0.0%265 0.000 2.215 35.000
idle23:53:27.509 99.5%933285 54.363 92.156 317.864
nntp read00:00:02.992 0.0%943185 0.001 0.003 0.006
overview write00:01:27.054 0.1%25747 0.319 3.381 31.247
perl filter00:01:46.133 0.1%43923 1.194 2.416 3.894
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.468 0.0%51584 0.000 0.009 0.036
TOTAL: 24:00:59.15024:00:03.677 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.063 0.0%25701 0.000 0.002 0.020
article prepare00:00:00.006 0.0%44364 0.000 0.000 0.006
article read00:00:01.233 0.0%5056 0.000 0.244 9.641
backlog stats00:00:00.291 0.0%628725 0.000 0.000 0.001
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.353 0.0%450163 0.002 0.005 0.010
data write00:00:03.137 0.0%429328 0.003 0.007 0.014
idle23:52:42.995100.0%628725 65.794 136.726 397.993
status file00:00:00.195 0.0%273 0.000 0.714 1.250
TOTAL: 23:52:59.05523:52:50.273100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle11:33:03.770 99.9%9284 605.000 4479.079600131.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.113 0.0%18956 0.000 0.006 0.071
readart00:00:00.516 0.0%78 0.000 6.615 13.000
TOTAL: 11:33:28.40011:33:04.399 99.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1usenet.blueworldhosting.com 288 30965 5869 22923 2173 18%23:44:44
2csiph-out.feeder.erje.net 1152 94157 4432 87921 1804 4%94:59:27
3feeder.eternal-september.org 6 38335 3248 33888 1199 8%24:01:08
4news-out.mixmin.net 3 30556 2741 25628 2187 8%24:08:44
5news-out.aioe.org 4 24927 2530 22339 58 10%24:09:28
6csiph-out.news.weretis.net 4 57404 1974 55180 250 3%51:44:18
7newsfeed.neva.ru 150 55613 1274 49362 4977 2%71:34:18
8newsfeed.fu-berlin.de 34 27410 1272 25853 285 4%28:04:38
9newsfeeds.xmission.com 1 25581 547 25023 11 2%25:24:50
10news.fcku.it 1 10251 501 9750 0 4%31:32:23
11news.freedyn.net 3 29703 488 28624 591 1%24:09:15
12xanadu-bbs.net 1 42701 369 37335 4997 0%24:12:18
13news.redatomik.org 1 23013 274 22732 7 1%23:17:53
14news.swapon.de 4 2350 226 2114 10 9%23:05:52
15au2pb.net 1 27210 66 27113 31 0%24:24:17
16nntp.pasdenom.info 2 24833 7 24823 3 0%23:59:31
17news.bbs.geek.nz 1 23970 2 23968 0 0%23:59:44
18localhost 35 0 0 0 0 0%00:00:00
TOTAL: 18 1691 568979 25820 524576 18583 4%566:32:48
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net17.1 MB7.8 KB4.8 MB21.9 MB 78%3.6 KB
2usenet.blueworldhosting.com16.5 MB1.8 KB22.8 MB39.3 MB 41%5.0 KB
3feeder.eternal-september.org8.1 MB5.5 KB11.5 MB19.5 MB 41%4.5 KB
4news-out.mixmin.net8.0 MB0.0 KB4.0 MB12.0 MB 66%2.5 KB
5csiph-out.news.weretis.net6.1 MB1.8 KB7.8 MB14.0 MB 43%6.4 KB
6news-out.aioe.org5.8 MB1.8 KB109.8 KB5.9 MB 98%2.3 KB
7newsfeed.neva.ru3.4 MB99.7 KB31.7 MB35.2 MB 9%5.8 KB
8newsfeed.fu-berlin.de2.9 MB0.0 KB621.7 KB3.6 MB 82%2.3 KB
9newsfeeds.xmission.com1.8 MB1.8 KB504.2 KB2.3 MB 78%4.2 KB
10news.freedyn.net1.4 MB1.8 KB4.5 MB5.9 MB 23%5.6 KB
11xanadu-bbs.net955.6 KB14.1 KB52.3 MB53.3 MB 1%10.2 KB
12news.fcku.it851.8 KB0.0 KB0.0 KB851.8 KB100%1.7 KB
13news.redatomik.org681.8 KB0.0 KB88.5 KB770.4 KB 88%2.7 KB
14news.swapon.de589.5 KB0.0 KB21.5 KB611.0 KB 96%2.6 KB
15au2pb.net263.1 KB0.0 KB189.4 KB452.5 KB 58%4.7 KB
16nntp.pasdenom.info14.4 KB1.8 KB55.0 KB71.2 KB 20%7.1 KB
17news.bbs.geek.nz4.6 KB0.0 KB0.0 KB4.6 KB100%2.3 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1874.4 MB138.0 KB140.9 MB215.5 MB 34%5.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 17 04:15:00 - 04:59:59 421 1.6% 0.161.2 MB 1.7% 0.47
Jan 17 05:00:00 - 05:59:59 463 1.8% 0.131.2 MB 1.7% 0.35
Jan 17 06:00:00 - 06:59:59 448 1.7% 0.121.5 MB 2.0% 0.41
Jan 17 07:00:00 - 07:59:59 542 2.1% 0.151.7 MB 2.2% 0.47
Jan 17 08:00:00 - 08:59:59 837 3.3% 0.232.4 MB 3.2% 0.68
Jan 17 09:00:00 - 09:59:59 939 3.6% 0.262.7 MB 3.7% 0.77
Jan 17 10:00:00 - 10:59:59 1338 5.2% 0.374.8 MB 6.4% 1.36
Jan 17 11:00:00 - 11:59:59 1130 4.4% 0.313.7 MB 4.9% 1.04
Jan 17 12:00:00 - 12:59:59 1147 4.5% 0.323.4 MB 4.5% 0.95
Jan 17 13:00:00 - 13:59:59 1230 4.8% 0.343.5 MB 4.7% 0.99
Jan 17 14:00:00 - 14:59:59 1378 5.4% 0.384.2 MB 5.6% 1.19
Jan 17 15:00:00 - 15:59:59 1451 5.6% 0.404.5 MB 6.1% 1.28
Jan 17 16:00:00 - 16:59:59 1512 5.9% 0.424.0 MB 5.4% 1.14
Jan 17 17:00:00 - 17:59:59 1457 5.7% 0.403.8 MB 5.2% 1.09
Jan 17 18:00:00 - 18:59:59 1731 6.7% 0.484.8 MB 6.5% 1.36
Jan 17 19:00:00 - 19:59:59 1594 6.2% 0.444.6 MB 6.2% 1.30
Jan 17 20:00:00 - 20:59:59 1480 5.8% 0.414.0 MB 5.3% 1.12
Jan 17 21:00:00 - 21:59:59 1538 6.0% 0.434.0 MB 5.4% 1.14
Jan 17 22:00:00 - 22:59:59 1323 5.1% 0.373.6 MB 4.8% 1.02
Jan 17 23:00:00 - 23:59:59 934 3.6% 0.262.6 MB 3.4% 0.73
Jan 18 00:00:00 - 00:59:59 815 3.2% 0.232.3 MB 3.0% 0.64
Jan 18 01:00:00 - 01:59:59 723 2.8% 0.202.1 MB 2.9% 0.61
Jan 18 02:00:00 - 02:59:59 594 2.3% 0.171.6 MB 2.2% 0.46
Jan 18 03:00:00 - 03:59:59 603 2.3% 0.171.9 MB 2.5% 0.53
Jan 18 04:00:00 - 04:15:00 109 0.4% 0.12383.0 KB 0.5% 0.43
TOTAL: 24:00:00 25737 100.0% 0.3074.2 MB 100.0% 0.88
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 4948 825 0 14 0 0 0 0 4109
2xanadu-bbs.net 4899 8 0 2 0 0 0 0 4889
3news-out.mixmin.net 2201 1 0 0 0 0 0 0 2200
4usenet.blueworldhosting.com 2167 762 0 1 0 0 0 0 1404
5csiph-out.feeder.erje.net 1811 1047 0 2 0 0 0 0 762
6feeder.eternal-september.org 1233 120 0 3 0 0 0 0 1110
7newsfeed.fu-berlin.de 781 70 0 1 0 0 0 0 710
8news.freedyn.net 592 5 0 1 0 0 0 0 586
9csiph-out.news.weretis.net 241 139 0 2 0 0 0 0 100
10news-out.aioe.org 83 54 0 1 0 0 0 0 28
11news.swapon.de 39 0 0 0 0 0 0 0 39
12au2pb.net 31 22 0 1 0 0 0 0 8
13newsfeeds.xmission.com 11 6 0 1 0 0 0 0 4
14news.redatomik.org 8 0 0 1 0 0 0 0 7
15nntp.pasdenom.info 3 1 0 1 0 0 0 0 1
16news.fcku.it 2 0 0 0 0 0 0 0 2
17news.bbs.geek.nz 1 0 0 1 0 0 0 0 0
TOTAL: 17 19051 3060 0 32 0 0 0 015959

Unwanted newsgroups [Top 20]:

NewsgroupCount
mailing.freebsd.svn-ports-all 263
mailing.freebsd.svn-ports-head 253
ut.test 210
fa.linux.kernel 194
lada.talk 108
it-alt.sport.calcio.juventus 99
mailing.freebsd.pkg-fallout 91
mailing.freebsd.ports-bugs 69
mozilla.general 67
fa.netbsd.bugs 65
lada.auto 64
mailing.freebsd.gnome 63
alt.survival 55
alt.biology 52
spline.eisfair 51
mailing.openbsd.ports-changes 46
alt.binaries.multimedia.tatu 45
mpc.lists.freebsd.gnome 43
fa.freebsd.cvs-all 39
mailing.freebsd.svn-src-all 39
TOTAL: 228 3060

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 10685
EMP (phn path) 2351
EMP (phl) 1191
EMP (phn nph) 207
PFM bot misc 110
Too many newsgroups 100
User-issued cancel 73
Binary: misplaced rar 65
Scoring filter (9) 63
Scoring filter (26) 32
Scoring filter (10) 28
Binary Payload 20
HTML Multipart 20
Scoring filter (20) 18
Scoring filter (11) 12
Binary: misplaced par2 10
Scoring filter (27) 8
Scoring filter (21) 8
Scoring filter (17) 7
Scoring filter (12) 7
TOTAL: 45 15098

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com8909191
TOTAL: 18909191

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 11
usenet.blueworldhosting.com 3
TOTAL: 2 14
Huge articles
usenet.blueworldhosting.com 78
xanadu-bbs.net 63
csiph-out.news.weretis.net 51
feeder.eternal-september.org 40
news.freedyn.net 24
news-out.mixmin.net 5
newsfeeds.xmission.com 3
newsfeed.fu-berlin.de 1
nntp.pasdenom.info 1
news.redatomik.org 1
TOTAL: 11 268
Including strange strings
newsfeed.fu-berlin.de 474
news.swapon.de 29
news-out.aioe.org 21
feeder.eternal-september.org 16
news-out.mixmin.net 16
csiph-out.news.weretis.net 9
csiph-out.feeder.erje.net 8
news.freedyn.net 2
news.fcku.it 2
usenet.blueworldhosting.com 1
TOTAL: 11 579
TOTAL: 3 861

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1erje 26757 1974 19190 100 0 0 7%23:59:56
2news.freedyn.net 29846 1835 23384 8 0 0 6%23:59:56
3xanadu-bbs 27668 1565 23807 0 0 5 5%23:59:56
4fu-berlin.de 24069 1169 22899 0 0 0 4%23:59:46
5news.bbs.geek.nz 46812 965 23457 13 0 2 2%23:59:54
6aioe 24280 541 20398 35 0 0 2%23:59:56
7usenet.pasdenom.info 33432 330 23497 1901 0 4 0%23:59:56
8au2pb 25250 200 24167 5 0 0 0%23:59:56
9news.redatomik.org 30192 197 23937 14 0 0 0%23:59:54
10xmission 27799 35 23906 5 0 0 0%23:59:56
11neva.ru 27999 16 22804 152 0 0 0%23:59:56
12news.mixmin.net 25153 14 21401 0 0 0 0%23:59:55
13weretis.net 21789 12 21166 2 0 0 0%23:59:51
14news.fcku.it 2639 9 2257 0 0 0 0%22:59:03
15eternal-september 25762 9 20428 0 0 0 0%23:59:53
16news.swapon.de 2303 3 2045 2 0 0 0%23:45:30
17bwh 19613 0 18457 1 0 0 0%23:59:45
TOTAL: 17 421363 8874 337200 2238 0 11 2%406:42:59
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net10.5 MB39.5 KB10.5 MB0.1 KB/s5.8 KB23:59:56
2xanadu-bbs9.7 MB0.0 KB9.7 MB0.1 KB/s6.3 KB23:59:56
3fu-berlin.de8.4 MB0.0 KB8.4 MB0.1 KB/s7.4 KB23:59:46
4erje5.6 MB583.0 KB6.1 MB0.1 KB/s3.0 KB23:59:56
5news.bbs.geek.nz2.6 MB70.1 KB2.7 MB0.0 KB/s2.8 KB23:59:54
6aioe1.6 MB129.8 KB1.7 MB0.0 KB/s3.0 KB23:59:56
7usenet.pasdenom.info1.2 MB9.7 MB10.9 MB0.1 KB/s5.0 KB23:59:56
8au2pb880.3 KB81.8 KB962.1 KB0.0 KB/s4.7 KB23:59:56
9news.redatomik.org668.8 KB79.7 KB748.5 KB0.0 KB/s3.5 KB23:59:54
10xmission121.9 KB8.5 KB130.3 KB0.0 KB/s3.3 KB23:59:56
11neva.ru67.9 KB548.0 KB615.9 KB0.0 KB/s3.7 KB23:59:56
12news.mixmin.net53.2 KB0.0 KB53.2 KB0.0 KB/s3.8 KB23:59:55
13weretis.net34.5 KB39.5 KB74.0 KB0.0 KB/s5.3 KB23:59:51
14eternal-september26.9 KB0.0 KB26.9 KB0.0 KB/s3.0 KB23:59:53
15news.fcku.it18.6 KB0.0 KB18.6 KB0.0 KB/s2.1 KB22:59:03
16news.swapon.de8.6 KB5.4 KB13.9 KB0.0 KB/s2.8 KB23:45:30
17bwh0.0 KB1.2 KB1.2 KB0.0 KB/s1.2 KB23:59:45
TOTAL: 1741.3 MB11.3 MB52.6 MB0.0 KB/s4.8 KB406:42:59
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 59 71228.9 KB 28 0 006:09:15
2tempe0.bbox.io 21 714.6 KB 2 0 003:52:50
TOTAL: 2 80 78243.6 KB 30 0 011:33:27

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 59 71228.9 KB 28 0 006:09:15
2*.bbox.io 21 714.6 KB 2 0 003:52:50
3*.chello.nl 2 00.0 KB 0 0 000:30:17
4*.tampfl.dsl-w.verizon.net 2 00.0 KB 0 0 001:01:04
TOTAL: 4 84 78243.6 KB 30 0 011:33:27

NNRP auth users [Top 20]:

UserConn
moussa 59
TOTAL: 1 59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
202.89.176.220 6.315 1.520 0.17206:09:15
tempe0.bbox.io 1.663 0.334 0.00003:52:50
pool-71-98-160-84.tampfl.dsl-w.verizon.net 0.148 0.036 0.00001:01:04
dhcp-089-099-203-254.chello.nl 0.138 0.052 0.00000:30:17
TOTAL: 4 8.264 1.942 0.17211:33:27

Curious NNRP server explorers [Top 20]:

SystemConn
pool-71-98-160-84.tampfl.dsl-w.verizon.net 2
dhcp-089-099-203-254.chello.nl 2
TOTAL: 2 4

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 78100.0%
TOTAL: 1 78100.0%

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

NewsgroupCount
1comp.os.vms 27
2comp.arch 18
3comp.sys.ibm.pc.games.action 11
4comp.sys.ibm.pc.hardware.storage 6
5comp.unix.programmer 4
6comp.unix.misc 3
7comp.os.msdos.djgpp 2
8comp.ai.neural-nets 2
9comp.os.os2.announce 2
10comp.sys.ibm.pc.games.war-historical 1
11comp.ai.philosophy 1
12comp.os.os2.apps 1
TOTAL: 12 78