Daily Usenet report for csiph.com

Oct 9 04:15:00 -- Oct 10 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 9 18:16:50 csiph pgpverify[18850]: pgpverify: verification failed
Oct 9 19:38:31 csiph pgpverify[21941]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 38822 60.5%7.3 MB 71.4%
innd 21633 33.7%2.4 MB 23.2%
innfeed 3213 5.0%509.9 KB 4.9%
nnrpd 428 0.7%47.6 KB 0.5%
nocem 50 0.1%5.6 KB 0.1%
controlchan 7 0.0%0.9 KB 0.0%
pgpverify 2 0.0%0.2 KB 0.0%
TOTAL: 7 64155 100.0%10.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 406090 56.6%
Negative hits 273084 38.1%
Do not exist 37924 5.3%
Cache misses 536 0.1%
TOTAL: 4 717634 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.186 0.0%165 0.000 7.188 67.000
article cleanup00:00:00.238 0.0%37703 0.000 0.006 0.023
article logging00:00:00.720 0.0%38269 0.000 0.019 0.811
article parse00:00:01.483 0.0%134181 0.004 0.011 0.060
article write00:05:50.679 0.4%21427 0.463 16.366 70.949
artlog/artcncl00:00:00.001 0.0%160 0.000 0.006 0.077
artlog/artparse00:00:00.004 0.0%363 0.000 0.011 1.000
data move00:00:00.256 0.0%685421 0.000 0.000 0.001
hisgrep/artcncl00:00:00.245 0.0%163 0.000 1.503 11.182
hishave/artcncl00:00:00.014 0.0%165 0.000 0.085 1.273
hishave/artparse00:00:00.000 0.0%363 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.498 0.0%719679 0.000 0.010 0.170
history sync00:00:01.621 0.0%355 0.000 4.566 95.333
history write00:00:29.956 0.0%37675 0.060 0.795 10.110
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
hiswrite/artparse00:00:00.239 0.0%363 0.000 0.658 10.500
idle23:50:25.835 99.3%692374 67.127 123.959 335.348
nntp read00:00:02.505 0.0%708326 0.002 0.004 0.006
overview write00:01:29.475 0.1%21427 0.186 4.176 102.145
perl filter00:01:36.161 0.1%37588 1.564 2.558 4.548
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.388 0.0%42896 0.000 0.009 0.028
TOTAL: 24:00:53.84224:00:08.504 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.054 0.0%21518 0.000 0.003 0.026
article prepare00:00:00.002 0.0%30296 0.000 0.000 0.013
article read00:00:04.612 0.0%4271 0.000 1.080 15.273
backlog stats00:00:00.221 0.0%409072 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.628 0.0%309876 0.000 0.005 0.012
data write00:00:02.188 0.0%291793 0.004 0.007 0.013
idle23:53:16.901100.0%409072 128.836 210.224 572.044
status file00:00:00.517 0.0%295 0.000 1.753 9.400
TOTAL: 23:53:33.59323:53:26.123100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%13 0.000 0.000 0.000
idle15:59:16.115 99.9%277 360.000207783.809900054.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.008 0.0%706 0.000 0.011 0.500
readart00:00:00.483 0.0%56 0.000 8.625 71.000
TOTAL: 16:00:39.55215:59:16.606 99.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
grisbi-control@grisbi.org000200
TOTAL000200

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 3 46226 5200 39842 1184 11%48:08:07
2csiph-out.feeder.erje.net 1152 117558 5117 103864 8577 4%94:57:48
3feeder.eternal-september.org 4 33294 3011 29573 710 9%23:59:21
4news-out.aioe.org 3 20454 1710 18717 27 8%24:09:40
5news-out.mixmin.net 2 24694 1609 22391 694 6%24:08:57
6newsfeed.neva.ru 141 49574 1278 43604 4692 2%72:20:00
7newsfeed.fu-berlin.de 25 23545 1166 21910 469 4%29:10:41
8newsfeeds.xmission.com 1 20903 1021 19841 41 4%24:26:56
9news.redatomik.org 2 15608 509 15089 10 3%19:09:21
10news.fcku.it 2 8733 419 8211 103 4%23:59:22
11xanadu-bbs.net 22 580 105 468 7 18%22:36:06
12news.swapon.de 6 1872 36 1836 0 1%24:14:22
13news.freedyn.net 3 24075 36 24001 38 0%24:09:05
14nntp.pasdenom.info 4 34257 19 34208 30 0%24:07:03
15news.bbs.geek.nz 1 16710 6 16704 0 0%23:59:52
16localhost 36 1 1 0 0 100%00:00:00
TOTAL: 16 1407 438084 21243 400259 16582 4%503:36:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net16.9 MB117.7 KB42.8 MB59.9 MB 28%4.5 KB
2csiph-out.news.weretis.net14.8 MB2.6 KB15.9 MB30.7 MB 48%4.9 KB
3feeder.eternal-september.org7.6 MB3.9 KB13.2 MB20.8 MB 36%5.7 KB
4news-out.mixmin.net5.2 MB0.0 KB1.7 MB6.9 MB 75%3.1 KB
5news-out.aioe.org4.2 MB0.0 KB42.6 KB4.3 MB 99%2.5 KB
6newsfeed.neva.ru3.6 MB44.1 KB41.8 MB45.5 MB 7%7.8 KB
7newsfeeds.xmission.com2.9 MB0.0 KB2.5 MB5.4 MB 53%5.2 KB
8newsfeed.fu-berlin.de2.6 MB0.0 KB7.3 MB9.9 MB 25%6.2 KB
9news.redatomik.org1.2 MB0.0 KB146.6 KB1.3 MB 88%2.6 KB
10news.fcku.it857.7 KB2.9 KB6.7 MB7.5 MB 11%14.8 KB
11xanadu-bbs.net302.7 KB0.0 KB59.4 KB362.1 KB 83%3.2 KB
12news.freedyn.net141.9 KB0.0 KB2.7 MB2.9 MB 4%39.5 KB
13news.swapon.de84.5 KB0.0 KB0.0 KB84.5 KB100%2.3 KB
14nntp.pasdenom.info33.2 KB5.7 KB458.4 KB497.2 KB 6%10.1 KB
15news.bbs.geek.nz8.5 KB0.0 KB0.0 KB8.5 KB100%1.4 KB
16localhost1.6 KB0.0 KB0.0 KB1.6 KB100%1.6 KB
TOTAL: 1660.4 MB176.9 KB135.3 MB195.9 MB 30%5.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 9 04:15:00 - 04:59:59 302 1.4% 0.111010.6 KB 1.6% 0.37
Oct 9 05:00:00 - 05:59:59 393 1.8% 0.111.1 MB 1.9% 0.32
Oct 9 06:00:00 - 06:59:59 585 2.7% 0.161.8 MB 3.0% 0.52
Oct 9 07:00:00 - 07:59:59 746 3.5% 0.212.4 MB 3.9% 0.67
Oct 9 08:00:00 - 08:59:59 826 3.9% 0.232.3 MB 3.8% 0.66
Oct 9 09:00:00 - 09:59:59 919 4.3% 0.262.6 MB 4.3% 0.75
Oct 9 10:00:00 - 10:59:59 934 4.4% 0.262.5 MB 4.2% 0.72
Oct 9 11:00:00 - 11:59:59 949 4.4% 0.262.4 MB 3.9% 0.68
Oct 9 12:00:00 - 12:59:59 1096 5.1% 0.303.0 MB 4.8% 0.84
Oct 9 13:00:00 - 13:59:59 1061 5.0% 0.292.7 MB 4.5% 0.78
Oct 9 14:00:00 - 14:59:59 1115 5.2% 0.313.4 MB 5.5% 0.96
Oct 9 15:00:00 - 15:59:59 1307 6.1% 0.363.5 MB 5.7% 1.00
Oct 9 16:00:00 - 16:59:59 1316 6.1% 0.373.6 MB 6.0% 1.03
Oct 9 17:00:00 - 17:59:59 1284 6.0% 0.364.2 MB 6.8% 1.18
Oct 9 18:00:00 - 18:59:59 1272 5.9% 0.353.5 MB 5.8% 1.01
Oct 9 19:00:00 - 19:59:59 1271 5.9% 0.353.4 MB 5.6% 0.96
Oct 9 20:00:00 - 20:59:59 1137 5.3% 0.322.9 MB 4.7% 0.82
Oct 9 21:00:00 - 21:59:59 1028 4.8% 0.292.7 MB 4.5% 0.77
Oct 9 22:00:00 - 22:59:59 884 4.1% 0.252.8 MB 4.5% 0.79
Oct 9 23:00:00 - 23:59:59 652 3.0% 0.181.9 MB 3.1% 0.53
Oct 10 00:00:00 - 00:59:59 632 3.0% 0.182.0 MB 3.3% 0.57
Oct 10 01:00:00 - 01:59:59 548 2.6% 0.151.7 MB 2.8% 0.48
Oct 10 02:00:00 - 02:59:59 450 2.1% 0.121.3 MB 2.2% 0.37
Oct 10 03:00:00 - 03:59:59 542 2.5% 0.151.6 MB 2.7% 0.46
Oct 10 04:00:00 - 04:15:00 170 0.8% 0.19588.7 KB 0.9% 0.65
TOTAL: 24:00:00 21419 100.0% 0.2560.9 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 8612 324 0 17 0 0 0 0 8271
2newsfeed.neva.ru 4732 458 0 6 0 0 0 0 4268
3csiph-out.news.weretis.net 1253 220 0 2 0 0 0 0 1031
4newsfeed.fu-berlin.de 870 39 0 0 0 0 0 0 831
5feeder.eternal-september.org 771 57 0 0 0 0 0 0 714
6news-out.mixmin.net 725 4 0 0 0 0 0 0 721
7news.fcku.it 99 0 0 2 0 0 0 0 97
8newsfeeds.xmission.com 41 1 0 0 0 0 0 0 40
9news.freedyn.net 39 0 0 0 0 0 0 0 39
10news-out.aioe.org 33 25 0 0 0 0 0 0 8
11nntp.pasdenom.info 29 2 0 1 0 0 0 0 26
12news.redatomik.org 22 0 0 0 0 0 0 0 22
13xanadu-bbs.net 7 0 0 0 0 0 0 0 7
14news.swapon.de 3 0 0 0 0 0 0 0 3
TOTAL: 14 17236 1130 0 28 0 0 0 016078

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 224
ut.test 111
lada.talk 111
alt.survival 71
fa.netbsd.bugs 43
alt.windows7.general 38
it-alt.sport.calcio.juventus 32
fa.openbsd.source-changes 30
mozilla.support.seamonkey 29
cakewalk.audio 27
fa.freebsd.cvs-all 19
mpc.lists.nanog 19
spline.eisfair 19
lada.auto 17
alt.philosophy 14
fa.openbsd.tech 14
mozilla.support.firefox 12
mpc.lists.freebsd.gecko 11
alt.pl.nieruchomosci.gielda 11
mozilla.governance 11
TOTAL: 127 1130

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11518
EMP (phn path) 2111
EMP (phl) 863
Binary: misplaced par2 208
EMP (phn nph) 193
Too many newsgroups 64
Binary Payload 25
User-issued cancel 15
Scoring filter (18) 11
Scoring filter (9) 9
HTML Multipart 8
Scoring filter (12) 5
Binary Image: misplaced jpg 4
HTML post 3
Scoring filter (13) 3
Binary: misplaced rar 3
Too many newsgroups (low_xpost) 2
Scoring filter (11) 2
Scoring filter (14) 2
Poison newsgroup 2
TOTAL: 27 15060

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com4204444
TOTAL: 14204444

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 83
TOTAL: 1 83
Huge articles
news.fcku.it 84
csiph-out.news.weretis.net 77
feeder.eternal-september.org 68
newsfeed.fu-berlin.de 52
newsfeeds.xmission.com 37
news.freedyn.net 26
news-out.mixmin.net 10
nntp.pasdenom.info 6
news.redatomik.org 2
xanadu-bbs.net 1
TOTAL: 10 363
Including strange strings
newsfeed.fu-berlin.de 403
feeder.eternal-september.org 48
csiph-out.news.weretis.net 39
csiph-out.feeder.erje.net 25
news-out.mixmin.net 20
news.redatomik.org 11
newsfeed.neva.ru 9
news-out.aioe.org 7
news.swapon.de 3
newsfeeds.xmission.com 2
TOTAL: 11 568
TOTAL: 3 1014

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 38170 2439 18175 51 0 0 6%23:57:52
2news.freedyn.net 22181 1340 19933 8 0 0 6%23:50:00
3xanadu-bbs 23618 852 20465 7 0 123 3%23:59:42
4fu-berlin.de 19888 695 19192 0 0 0 3%23:50:53
5erje 17054 367 15709 77 0 0 2%23:50:00
6usenet.pasdenom.info 23859 332 19792 1274 0 21 1%23:59:56
7news.redatomik.org 21219 67 19900 3 0 0 0%23:54:55
8aioe 19123 12 18131 2 0 0 0%23:50:01
9weretis.net 14964 9 14214 0 0 0 0%23:50:00
10xmission 22895 6 19570 2 0 0 0%23:59:54
11eternal-september 19103 6 17350 0 0 0 0%23:59:38
12news.fcku.it 1967 4 1883 0 0 0 0%22:38:05
13news.mixmin.net 19632 3 18813 0 0 0 0%23:58:23
14neva.ru 20344 2 18949 15 0 0 0%23:59:02
15au2pb 0 0 0 0 0 20604 0%23:50:06
16bwh 0 0 0 0 0 20584 0%23:50:05
17news.swapon.de 1964 0 1916 3 0 0 0%23:50:19
TOTAL: 17 285981 6134 243992 1442 0 41332 2%405:08:51
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net7.2 MB29.0 KB7.3 MB0.1 KB/s5.5 KB23:50:00
2news.bbs.geek.nz6.1 MB178.4 KB6.3 MB0.1 KB/s2.6 KB23:57:52
3xanadu-bbs6.1 MB12.0 KB6.1 MB0.1 KB/s7.3 KB23:59:42
4fu-berlin.de5.7 MB0.0 KB5.7 MB0.1 KB/s8.4 KB23:50:53
5erje1.1 MB289.6 KB1.4 MB0.0 KB/s3.1 KB23:50:00
6usenet.pasdenom.info843.0 KB6.7 MB7.5 MB0.1 KB/s4.8 KB23:59:56
7news.redatomik.org157.0 KB10.8 KB167.8 KB0.0 KB/s2.4 KB23:54:55
8aioe32.6 KB8.0 KB40.6 KB0.0 KB/s2.9 KB23:50:01
9weretis.net21.7 KB0.0 KB21.7 KB0.0 KB/s2.4 KB23:50:00
10eternal-september15.9 KB0.0 KB15.9 KB0.0 KB/s2.6 KB23:59:38
11news.mixmin.net12.3 KB0.0 KB12.3 KB0.0 KB/s4.1 KB23:58:23
12xmission11.9 KB2.5 KB14.3 KB0.0 KB/s1.8 KB23:59:54
13news.fcku.it9.7 KB0.0 KB9.7 KB0.0 KB/s2.4 KB22:38:05
14neva.ru4.1 KB30.9 KB35.1 KB0.0 KB/s2.1 KB23:59:02
15au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:06
16bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:05
17news.swapon.de0.0 KB5.8 KB5.8 KB0.0 KB/s1.9 KB23:50:19
TOTAL: 1727.3 MB7.2 MB34.5 MB0.0 KB/s4.7 KB405:08:51
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1tempe0.bbox.io 30 44166.3 KB 2 0 004:34:55
2challenger.nielsenmedia.com 6 611.5 KB 2 0 001:01:33
3c-73-195-247-24.hsd1.nj.comcast.net 3 59.8 KB 2 0 001:14:00
4cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 2 12.6 KB 1 1 100:34:55
TOTAL: 4 41 56190.2 KB 7 1 116:00:38

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbox.io 30 44166.3 KB 2 0 004:34:55
2*.nielsenmedia.com 6 611.5 KB 2 0 001:01:33
3*.hsd1.nj.comcast.net 3 59.8 KB 2 0 001:14:00
4*.19-1.cable.virginm.net 2 12.6 KB 1 1 100:34:55
5*.dhcp.mdsn.wi.charter.com 6 00.0 KB 0 0 001:30:52
6*.chello.nl 8 00.0 KB 0 0 001:14:09
7unresolved 14 00.0 KB 0 0 005:50:12
TOTAL: 7 69 56190.2 KB 7 1 116:00:38

NNRP auth users [Top 20]:

UserConn
jwood 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.239.174.218 0.973 0.348 0.00005:50:12
tempe0.bbox.io 2.554 0.774 0.00004:34:55
68-185-181-64.dhcp.mdsn.wi.charter.com 0.474 0.156 0.00001:30:52
dhcp-089-099-203-254.chello.nl 0.631 0.171 0.00001:14:09
c-73-195-247-24.hsd1.nj.comcast.net 0.473 0.070 0.00001:14:00
challenger.nielsenmedia.com 0.523 0.129 0.00001:01:33
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 0.235 0.071 0.00500:34:55
TOTAL: 7 5.863 1.719 0.00516:00:38

Curious NNRP server explorers [Top 20]:

SystemConn
216.239.174.218 14
dhcp-089-099-203-254.chello.nl 8
68-185-181-64.dhcp.mdsn.wi.charter.com 6
TOTAL: 3 28

NNRP client timeouts [Top 20]:

SystemConnPeer
216.239.174.218 10 0
tempe0.bbox.io 7 0
challenger.nielsenmedia.com 2 0
68-185-181-64.dhcp.mdsn.wi.charter.com 2 0
c-73-195-247-24.hsd1.nj.comcast.net 2 0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 1 0
TOTAL: 6 24 0

Newsgroup request counts (by category):

CategoryCountPct
1muc 39 69.6%
2comp 12 21.4%
3news 5 8.9%
TOTAL: 3 56100.0%

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

NewsgroupCount
1muc.lists.freebsd.current 39
2comp.lang.awk 11
3news.admin.peering 5
4comp.lang.pl1 1
TOTAL: 4 56