Daily Usenet report for csiph.com

Jul 2 04:15:00 -- Jul 3 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 2 22:45:02 csiph pgpverify[50749]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 44442 40.0%3.9 MB 27.0%
inn 40389 36.4%7.6 MB 52.4%
innd 22975 20.7%2.5 MB 16.9%
innfeed 3164 2.9%551.9 KB 3.7%
nocem 40 0.0%3.8 KB 0.0%
controlchan 3 0.0%0.3 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 111014 100.0%14.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 452385 57.0%
Negative hits 290990 36.7%
Do not exist 39247 4.9%
Cache misses 10896 1.4%
TOTAL: 4 793518 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.000 0.0%476 0.000 4.202 25.000
article cleanup00:00:00.273 0.0%38567 0.000 0.007 0.022
article logging00:00:00.561 0.0%39106 0.000 0.014 0.230
article parse00:00:01.355 0.0%133666 0.002 0.010 0.029
article write00:00:46.815 0.1%21661 0.245 2.161 22.814
artlog/artcncl00:00:00.008 0.0%465 0.000 0.017 0.056
artlog/artparse00:00:00.010 0.0%827 0.000 0.012 0.500
data move00:00:00.254 0.0%757962 0.000 0.000 0.001
hisgrep/artcncl00:00:00.032 0.0%475 0.000 0.067 18.000
hishave/artcncl00:00:00.003 0.0%476 0.000 0.006 0.250
hishave/artparse00:00:00.000 0.0%193 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.334 0.0%795388 0.001 0.008 0.127
history sync00:00:01.046 0.0%359 0.000 2.914 14.000
history write00:00:25.158 0.0%39184 0.086 0.642 4.683
hiswrite/artcncl00:00:00.008 0.0%1 8.000 8.000 8.000
hiswrite/artparse00:00:00.135 0.0%193 0.000 0.699 8.000
idle23:55:19.676 99.6%772307 71.736 111.510 277.665
nntp read00:00:02.649 0.0%778148 0.001 0.003 0.006
overview write00:01:22.536 0.1%21661 0.338 3.810 70.744
perl filter00:01:31.033 0.1%38507 1.115 2.364 3.881
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.394 0.0%43358 0.000 0.009 0.033
TOTAL: 24:00:52.94923:59:40.280 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.061 0.0%21997 0.000 0.003 0.014
article prepare00:00:00.002 0.0%28380 0.000 0.000 0.014
article read00:00:01.169 0.0%3045 0.000 0.384 5.222
backlog stats00:00:00.237 0.0%512017 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.910 0.0%369430 0.001 0.005 0.009
data write00:00:02.560 0.0%352648 0.003 0.007 0.014
idle23:53:57.817100.0%512017 100.811 168.037 559.233
status file00:00:00.216 0.0%276 0.000 0.783 3.000
TOTAL: 23:54:19.07923:54:03.972100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:01.670 0.0%17534 0.000 0.095 0.125
idle91:01:45.308 99.0%74230 59.000 4414.729600052.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:02:11.294 0.0%149823 0.000 0.876 330.858
readart00:00:26.663 0.0%27076 0.000 0.985 74.000
TOTAL: 91:57:09.98591:04:24.935 99.0%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1usenet.blueworldhosting.com 288 24978 5109 18169 1700 20%23:44:49
2csiph-out.feeder.erje.net 1152 123886 4182 111417 8287 3%94:57:40
3csiph-out.news.weretis.net 3 50060 3521 43421 3118 7%46:19:18
4feeder.eternal-september.org 4 32116 2063 29288 765 6%24:37:44
5news-out.aioe.org 3 21222 1794 19392 36 8%24:09:51
6news-out.mixmin.net 3 24399 1152 22753 494 4%24:08:54
7newsfeed.fu-berlin.de 26 22246 1108 20818 320 4%27:00:56
8newsfeed.neva.ru 147 62576 874 59431 2271 1%71:59:27
9news.fcku.it 1 12736 695 11909 132 5%34:01:46
10newsfeeds.xmission.com 1 21012 499 20495 18 2%24:22:29
11news.freedyn.net 3 24795 415 23913 467 1%24:09:12
12news.redatomik.org 2 41670 221 41443 6 0%44:52:14
13au2pb.net 1 26380 100 26260 20 0%24:12:49
14news.swapon.de 5 1743 47 1695 1 2%24:41:29
15nntp.pasdenom.info 3 20716 28 20677 11 0%23:57:44
16localhost 53 21 21 0 0 100%00:00:01
17xanadu-bbs.net 13 836 19 808 9 2%30:10:39
18news.bbs.geek.nz 2 23397 15 23382 0 0%23:59:50
TOTAL: 18 1710 534789 21863 495271 17655 4%591:26:52
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net18.1 MB44.9 KB23.3 MB41.4 MB 43%3.4 KB
2usenet.blueworldhosting.com14.7 MB0.0 KB19.6 MB34.3 MB 42%5.2 KB
3csiph-out.news.weretis.net9.4 MB0.0 KB35.6 MB45.0 MB 20%6.9 KB
4feeder.eternal-september.org5.6 MB5.7 KB7.2 MB12.8 MB 43%4.6 KB
5news-out.mixmin.net5.0 MB0.0 KB1.5 MB6.4 MB 77%4.0 KB
6news-out.aioe.org4.4 MB0.0 KB83.0 KB4.5 MB 98%2.5 KB
7newsfeed.neva.ru3.0 MB20.6 KB14.3 MB17.4 MB 17%5.7 KB
8newsfeed.fu-berlin.de2.5 MB0.0 KB787.6 KB3.3 MB 76%2.4 KB
9newsfeeds.xmission.com1.5 MB0.0 KB965.7 KB2.4 MB 61%4.8 KB
10news.freedyn.net1.3 MB0.0 KB4.2 MB5.5 MB 24%6.4 KB
11news.fcku.it1.3 MB72.0 KB4.8 MB6.2 MB 21%7.7 KB
12news.redatomik.org558.7 KB0.0 KB691.7 KB1.2 MB 44%5.5 KB
13au2pb.net269.7 KB5.7 KB53.2 KB328.7 KB 82%2.7 KB
14news.swapon.de106.0 KB0.0 KB427.8 KB533.8 KB 19%11.1 KB
15nntp.pasdenom.info57.1 KB0.0 KB17.7 KB74.8 KB 76%1.9 KB
16xanadu-bbs.net39.2 KB0.0 KB18.4 KB57.6 KB 68%2.1 KB
17localhost36.7 KB0.0 KB0.0 KB36.7 KB100%1.7 KB
18news.bbs.geek.nz18.5 KB0.0 KB0.0 KB18.5 KB100%1.2 KB
TOTAL: 1868.0 MB149.0 KB113.4 MB181.5 MB 37%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 2 04:15:00 - 04:59:59 601 2.8% 0.222.5 MB 3.7% 0.94
Jul 2 05:00:00 - 05:59:59 558 2.6% 0.152.1 MB 3.1% 0.60
Jul 2 06:00:00 - 06:59:59 640 3.0% 0.182.5 MB 3.8% 0.72
Jul 2 07:00:00 - 07:59:59 795 3.7% 0.222.8 MB 4.2% 0.80
Jul 2 08:00:00 - 08:59:59 981 4.5% 0.273.9 MB 5.7% 1.10
Jul 2 09:00:00 - 09:59:59 951 4.4% 0.262.6 MB 3.9% 0.74
Jul 2 10:00:00 - 10:59:59 1040 4.8% 0.293.0 MB 4.4% 0.85
Jul 2 11:00:00 - 11:59:59 1123 5.2% 0.313.8 MB 5.6% 1.07
Jul 2 12:00:00 - 12:59:59 1089 5.0% 0.303.7 MB 5.5% 1.04
Jul 2 13:00:00 - 13:59:59 1030 4.8% 0.293.8 MB 5.6% 1.07
Jul 2 14:00:00 - 14:59:59 1104 5.1% 0.313.3 MB 5.0% 0.95
Jul 2 15:00:00 - 15:59:59 1247 5.8% 0.353.5 MB 5.1% 0.98
Jul 2 16:00:00 - 16:59:59 1221 5.6% 0.343.4 MB 5.0% 0.96
Jul 2 17:00:00 - 17:59:59 1147 5.3% 0.323.1 MB 4.7% 0.89
Jul 2 18:00:00 - 18:59:59 1130 5.2% 0.313.8 MB 5.6% 1.07
Jul 2 19:00:00 - 19:59:59 940 4.3% 0.262.5 MB 3.7% 0.71
Jul 2 20:00:00 - 20:59:59 1049 4.8% 0.292.9 MB 4.3% 0.83
Jul 2 21:00:00 - 21:59:59 1100 5.1% 0.312.7 MB 4.1% 0.78
Jul 2 22:00:00 - 22:59:59 934 4.3% 0.262.9 MB 4.4% 0.83
Jul 2 23:00:00 - 23:59:59 757 3.5% 0.212.3 MB 3.4% 0.65
Jul 3 00:00:00 - 00:59:59 536 2.5% 0.151.6 MB 2.4% 0.45
Jul 3 01:00:00 - 01:59:59 674 3.1% 0.191.8 MB 2.7% 0.52
Jul 3 02:00:00 - 02:59:59 413 1.9% 0.111.2 MB 1.8% 0.34
Jul 3 03:00:00 - 03:59:59 486 2.2% 0.141.3 MB 1.9% 0.36
Jul 3 04:00:00 - 04:15:00 114 0.5% 0.13310.3 KB 0.5% 0.34
TOTAL: 24:00:00 21660 100.0% 0.2567.2 MB 100.0% 0.80
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 8329 263 0 10 0 0 0 0 8056
2csiph-out.news.weretis.net 3223 174 0 0 0 0 0 0 3049
3newsfeed.neva.ru 2269 333 0 4 0 0 0 0 1932
4usenet.blueworldhosting.com 1709 536 0 0 0 0 0 0 1173
5feeder.eternal-september.org 808 35 0 3 0 0 0 0 770
6newsfeed.fu-berlin.de 730 30 0 0 0 0 0 0 700
7news-out.mixmin.net 512 3 0 0 0 0 0 0 509
8news.freedyn.net 466 0 0 0 0 0 0 0 466
9news.fcku.it 103 0 0 0 0 0 0 0 103
10news-out.aioe.org 50 34 0 0 0 0 0 0 16
11au2pb.net 24 19 0 0 0 0 0 0 5
12newsfeeds.xmission.com 14 8 0 0 0 0 0 0 6
13nntp.pasdenom.info 11 5 0 0 0 0 0 0 6
14news.swapon.de 8 0 0 0 0 0 0 0 8
15xanadu-bbs.net 8 0 0 0 0 0 0 0 8
16news.redatomik.org 1 0 0 0 0 0 0 0 1
TOTAL: 16 18265 1440 0 17 0 0 0 016808

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 176
mailing.freebsd.pkg-fallout 159
ut.test 68
it-alt.sport.calcio.juventus 61
lada.talk 52
mailing.freebsd.svn-ports-all 51
mailing.freebsd.svn-ports-head 50
mailing.freebsd.ports-bugs 48
alt.windows7.general 34
gmane.comp.gis.openstreetmap.tagging 32
lada.auto 23
lada.photo 22
mozilla.support.thunderbird 19
alt.politics.scorched-earth 19
spline.eisfair 17
fa.netbsd.bugs 16
mailing.openssl.cvs 15
mailing.gcc.gcc-cvs 13
mozilla.general 13
mailing.unix.dovecot 12
TOTAL: 191 1440

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 10829
EMP (phn path) 1862
EMP (phl) 693
PFM bot misc 559
Binary: misplaced txt 536
User-issued cancel 301
EMP (phn nph) 192
Too many newsgroups 68
Binary Payload 54
Binary: misplaced TXT 52
Binary: misplaced doc 33
Scoring filter (18) 25
Scoring filter (21) 18
Scoring filter (8) 17
Scoring filter (14) 14
Scoring filter (23) 14
HTML Multipart 13
Scoring filter (19) 13
Scoring filter (10) 13
Scoring filter (9) 10
TOTAL: 50 15400

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com3503636
TOTAL: 13503636

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 42
usenet.blueworldhosting.com 1
TOTAL: 2 43
Huge articles
usenet.blueworldhosting.com 87
news.freedyn.net 40
csiph-out.news.weretis.net 29
news-out.mixmin.net 13
feeder.eternal-september.org 10
newsfeeds.xmission.com 5
newsfeed.fu-berlin.de 3
news.fcku.it 3
au2pb.net 1
news.redatomik.org 1
TOTAL: 11 193
Including strange strings
newsfeed.fu-berlin.de 397
csiph-out.feeder.erje.net 45
csiph-out.news.weretis.net 35
feeder.eternal-september.org 17
news-out.aioe.org 13
news-out.mixmin.net 10
newsfeed.neva.ru 8
news.swapon.de 7
usenet.blueworldhosting.com 4
news.fcku.it 1
TOTAL: 10 537
No colon-space in header
csiph-out.feeder.erje.net 627
usenet.blueworldhosting.com 8
TOTAL: 2 635
TOTAL: 4 1408

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.freedyn.net 24643 1354 19892 3 0 1 5%23:59:48
2news.bbs.geek.nz 39632 1266 19403 14 0 0 3%23:59:07
3xanadu-bbs 24046 1064 20552 0 0 0 4%23:59:33
4fu-berlin.de 20309 914 19394 0 0 0 4%23:59:42
5usenet.pasdenom.info 28569 219 20126 1287 0 324 0%23:59:43
6aioe 20264 199 17750 13 0 0 0%23:59:37
7news.redatomik.org 22260 120 20264 24 0 42 0%23:59:48
8weretis.net 17853 109 16496 18 0 0 0%23:59:43
9au2pb 22163 105 20480 1 0 14 0%23:59:48
10xmission 24545 40 20112 5 0 0 0%23:59:48
11news.mixmin.net 21445 33 19111 0 0 0 0%23:59:48
12bwh 16993 27 15326 0 0 0 0%23:59:11
13neva.ru 23972 24 19387 108 0 0 0%23:59:48
14eternal-september 20480 24 17296 0 0 0 0%23:59:48
15news.swapon.de 1961 15 1810 10 0 0 0%23:51:36
16erje 19645 7 17315 95 0 0 0%23:59:40
17news.fcku.it 1642 0 1454 0 0 0 0%21:59:10
TOTAL: 17 350422 5520 286168 1578 0 381 1%405:45:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net10.5 MB14.7 KB10.5 MB0.1 KB/s7.9 KB23:59:48
2xanadu-bbs9.6 MB0.0 KB9.6 MB0.1 KB/s9.3 KB23:59:33
3fu-berlin.de9.2 MB0.0 KB9.2 MB0.1 KB/s10.3 KB23:59:42
4news.bbs.geek.nz3.1 MB33.5 KB3.1 MB0.0 KB/s2.5 KB23:59:07
5usenet.pasdenom.info832.4 KB10.0 MB10.8 MB0.1 KB/s7.4 KB23:59:43
6aioe471.7 KB42.1 KB513.7 KB0.0 KB/s2.4 KB23:59:37
7news.redatomik.org398.7 KB100.6 KB499.4 KB0.0 KB/s3.5 KB23:59:48
8weretis.net272.1 KB88.2 KB360.3 KB0.0 KB/s2.8 KB23:59:43
9au2pb246.0 KB3.8 KB249.7 KB0.0 KB/s2.4 KB23:59:48
10xmission83.2 KB10.3 KB93.5 KB0.0 KB/s2.1 KB23:59:48
11news.mixmin.net70.4 KB0.0 KB70.4 KB0.0 KB/s2.1 KB23:59:48
12bwh51.4 KB0.0 KB51.4 KB0.0 KB/s1.9 KB23:59:11
13neva.ru48.1 KB453.9 KB502.0 KB0.0 KB/s3.8 KB23:59:48
14eternal-september41.2 KB0.0 KB41.2 KB0.0 KB/s1.7 KB23:59:48
15news.swapon.de17.7 KB26.9 KB44.6 KB0.0 KB/s1.8 KB23:51:36
16erje8.3 KB410.0 KB418.2 KB0.0 KB/s4.1 KB23:59:40
17news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:59:10
TOTAL: 1734.8 MB11.2 MB46.0 MB0.0 KB/s6.6 KB405:45:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 287 3460.1 KB 28 0 005:27:10
2tempe0.bbox.io 11 2847.8 KB 2 0 004:27:13
368-185-181-64.dhcp.mdsn.wi.charter.com 44 2745.8 KB 19 21 1911:06:04
4dhcp-089-099-203-254.chello.nl 31 2342.4 KB 7 0 107:23:57
5mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 125 1748.8 KB 16 0 000:42:21
TOTAL: 5 498 129244.9 KB 72 21 2091:57:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 299 3460.1 KB 28 0 067:02:06
2*.bbox.io 11 2847.8 KB 2 0 004:27:13
3*.dhcp.mdsn.wi.charter.com 44 2745.8 KB 19 21 1911:06:04
4*.chello.nl 31 2342.4 KB 7 0 107:23:57
5*.dsl.bell.ca 125 1748.8 KB 16 0 000:42:21
6*.nielsenmedia.com 9 00.0 KB 0 0 001:15:22
TOTAL: 6 519 129244.9 KB 72 21 2091:57:05

NNRP auth users [Top 20]:

UserConn
moussa 287
ohlandl 19
TOTAL: 2 306

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.246.166.30 1.479 1.127 0.00060:37:49
68-185-181-64.dhcp.mdsn.wi.charter.com 5.090 0.996 0.05211:06:04
dhcp-089-099-203-254.chello.nl 3.545 0.623 0.00007:23:57
202.89.176.220 28.305 7.444 0.79605:27:10
tempe0.bbox.io 1.290 0.228 0.00004:27:13
challenger.nielsenmedia.com 0.721 0.172 0.00001:15:22
71.98.160.36 0.196 0.055 0.00000:54:26
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 15.343 3.960 0.00000:42:21
196.52.16.50 0.676 1.130 0.00000:02:39
TOTAL: 9 56.645 15.735 0.84891:57:05

Curious NNRP server explorers [Top 20]:

SystemConn
challenger.nielsenmedia.com 9
216.246.166.30 8
71.98.160.36 3
196.52.16.50 1
TOTAL: 4 21

NNRP client timeouts [Top 20]:

SystemConnPeer
68-185-181-64.dhcp.mdsn.wi.charter.com 10 0
tempe0.bbox.io 7 0
challenger.nielsenmedia.com 2 3
71.98.160.36 1 0
TOTAL: 4 20 3

Newsgroup request counts (by category):

CategoryCountPct
1comp 112 86.8%
2alt 6 4.7%
3rec 5 3.9%
4uk 4 3.1%
5aus 1 0.8%
6sci 1 0.8%
TOTAL: 6 129100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 105
2comp.os.cpm 4
3alt.atheism 2
4comp.sys.ibm.pc.hardware.storage 2
5rec.food.cooking 2
6rec.outdoors.rv-travel 1
7alt.support.diabetes 1
8alt.abortion 1
9uk.legal 1
10rec.arts.tv 1
11alt.obituaries 1
12comp.ai.philosophy 1
13uk.rec.sheds 1
14sci.physics 1
15aus.cars 1
16rec.boats 1
17uk.comp.homebuilt 1
18alt.happy.valley 1
19uk.media.radio.archers 1
TOTAL: 19 129