Daily Usenet report for csiph.com

Jun 12 04:15:00 -- Jun 13 04:15:00

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jun 12 10:40:02 csiph nnrpd[9867]: 46.165.242.75 noperm ihave without permission
Jun 13 02:15:11 csiph nnrpd[51136]: 46.165.242.75 noperm ihave without permission
Jun 13 02:20:12 csiph nnrpd[51419]: 46.165.242.75 noperm ihave without permission
Jun 13 02:25:14 csiph nnrpd[51565]: 46.165.242.75 noperm ihave without permission
Jun 13 02:30:33 csiph nnrpd[51848]: 46.165.242.75 noperm ihave without permission
Jun 13 02:35:41 csiph nnrpd[52021]: 46.165.242.75 noperm ihave without permission
Jun 13 02:40:50 csiph nnrpd[52275]: 46.165.242.75 noperm ihave without permission
Jun 13 02:45:56 csiph nnrpd[52444]: 46.165.242.75 noperm ihave without permission
Jun 13 02:51:04 csiph nnrpd[52730]: 46.165.242.75 noperm ihave without permission
Jun 13 02:56:20 csiph nnrpd[52878]: 46.165.242.75 noperm ihave without permission
Jun 13 03:01:19 csiph nnrpd[53717]: 46.165.242.75 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 44611 39.1%3.9 MB 26.4%
inn 42177 37.0%7.9 MB 53.0%
innd 24138 21.2%2.5 MB 17.0%
innfeed 3094 2.7%547.4 KB 3.6%
nocem 84 0.1%8.0 KB 0.1%
TOTAL: 5 114104 100.0%14.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 478087 57.9%
Negative hits 305639 37.0%
Do not exist 41249 5.0%
Cache misses 511 0.1%
TOTAL: 4 825486 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.801 0.0%261 0.000 3.069 33.000
article cleanup00:00:00.254 0.0%41101 0.000 0.006 0.021
article logging00:00:00.517 0.0%41659 0.000 0.012 0.079
article parse00:00:01.505 0.0%152955 0.000 0.010 0.025
article write00:01:54.393 0.1%22613 1.362 5.059 49.266
artlog/artcncl00:00:00.003 0.0%221 0.000 0.014 0.143
artlog/artparse00:00:00.001 0.0%311 0.000 0.003 1.000
data move00:00:00.287 0.0%792845 0.000 0.000 0.002
hisgrep/artcncl00:00:00.138 0.0%253 0.000 0.545 16.000
hishave/artcncl00:00:00.011 0.0%261 0.000 0.042 0.917
hishave/artparse00:00:00.001 0.0%310 0.000 0.003 0.167
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.038 0.0%827897 0.000 0.007 0.265
history sync00:00:01.874 0.0%347 0.000 5.401 146.667
history write00:00:30.444 0.0%41088 0.081 0.741 16.619
hiswrite/artcncl00:00:00.001 0.0%8 0.000 0.125 0.167
hiswrite/artparse00:00:00.125 0.0%310 0.000 0.403 9.000
idle23:54:13.775 99.5%823039 56.630 104.556 275.679
nntp read00:00:02.797 0.0%830782 0.002 0.003 0.007
overview write00:01:22.433 0.1%22613 0.164 3.645 86.490
perl filter00:01:41.573 0.1%41080 1.002 2.473 3.944
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.420 0.0%45301 0.000 0.009 0.030
TOTAL: 24:00:48.81023:59:57.391 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.079 0.0%22772 0.000 0.003 0.022
article prepare00:00:00.006 0.0%33418 0.000 0.000 0.008
article read00:00:02.084 0.0%4183 0.000 0.498 9.737
backlog stats00:00:00.262 0.0%571428 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.985 0.0%402896 0.000 0.005 0.009
data write00:00:02.838 0.0%383656 0.003 0.007 0.013
idle23:53:52.452100.0%571428 87.120 150.557 475.191
status file00:00:00.267 0.0%239 0.000 1.117 20.667
TOTAL: 23:54:09.05523:53:59.973100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%6 0.000 0.000 0.000
idle20:32:50.146 98.2%108067 84.133 684.484602787.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.224 0.0%217071 0.000 0.006 0.167
readart00:00:25.682 0.0%10016 0.000 2.564 94.000
TOTAL: 20:55:55.71120:33:17.052 98.2%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1usenet.blueworldhosting.com 288 26830 5823 19162 1845 21%23:44:58
2csiph-out.feeder.erje.net 1152 131834 5030 117776 9028 3%95:07:09
3feeder.eternal-september.org 4 34483 3341 30163 979 9%24:38:58
4news-out.mixmin.net 1 26688 2325 23334 1029 8%23:59:48
546.165.242.75 0 21506 1591 19901 14 7%23:59:57
6newsfeed.fu-berlin.de 23 27323 1100 25822 401 4%30:10:43
7newsfeed.neva.ru 148 56119 1028 50183 4908 1%71:46:06
8csiph-out.news.weretis.net 3 47402 649 46303 450 1%44:02:12
9newsfeeds.xmission.com 0 18553 394 18151 8 2%18:25:34
10news.freedyn.net 4 25959 363 25534 62 1%24:08:16
11news.fcku.it 1 8748 349 8264 135 3%23:48:12
12au2pb.net 1 23284 170 23090 24 0%24:12:48
13news.redatomik.org 1 21128 107 21017 4 0%22:26:07
14news.swapon.de 1 2063 74 1988 1 3%23:34:03
15nntp.pasdenom.info 3 22456 20 22431 5 0%24:08:25
16news.bbs.geek.nz 1 23884 18 23866 0 0%08:55:44
17localhost 43 7 7 0 0 100%00:00:00
18xanadu-bbs.net 20 501 4 497 0 0%21:06:56
19news-out.aioe.org 1 0 0 0 0 0%00:00:00
TOTAL: 19 1695 518768 22393 477482 18893 4%528:15:56
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net19.2 MB44.3 KB40.4 MB59.7 MB 32%4.3 KB
2usenet.blueworldhosting.com17.4 MB0.0 KB23.8 MB41.1 MB 42%5.5 KB
3feeder.eternal-september.org8.7 MB40.0 KB11.3 MB20.0 MB 43%4.7 KB
4news-out.mixmin.net7.5 MB0.0 KB2.7 MB10.2 MB 73%3.1 KB
546.165.242.753.8 MB0.0 KB49.9 KB3.8 MB 98%2.5 KB
6newsfeed.neva.ru2.8 MB27.8 KB41.5 MB44.3 MB 6%7.6 KB
7newsfeed.fu-berlin.de2.3 MB0.0 KB1.1 MB3.4 MB 68%2.3 KB
8csiph-out.news.weretis.net2.1 MB3.2 KB5.0 MB7.1 MB 29%6.6 KB
9news.freedyn.net1.1 MB0.0 KB1.0 MB2.2 MB 52%5.2 KB
10newsfeeds.xmission.com1000.6 KB0.0 KB198.3 KB1.2 MB 83%3.0 KB
11news.fcku.it739.3 KB0.0 KB7.1 MB7.8 MB 9%16.6 KB
12au2pb.net398.3 KB3.2 KB215.1 KB616.6 KB 64%3.2 KB
13news.redatomik.org266.0 KB0.0 KB270.8 KB536.8 KB 49%4.8 KB
14news.swapon.de162.4 KB0.0 KB1.7 KB164.2 KB 98%2.2 KB
15nntp.pasdenom.info33.6 KB0.0 KB13.6 KB47.2 KB 71%1.9 KB
16news.bbs.geek.nz24.3 KB0.0 KB0.0 KB24.3 KB100%1.3 KB
17localhost9.8 KB0.0 KB0.0 KB9.8 KB100%1.4 KB
18xanadu-bbs.net9.6 KB0.0 KB0.0 KB9.6 KB100%2.4 KB
19news-out.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1967.5 MB118.6 KB134.5 MB202.1 MB 33%5.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 12 04:15:00 - 04:59:59 326 1.4% 0.121.1 MB 1.6% 0.41
Jun 12 05:00:00 - 05:59:59 491 2.2% 0.142.0 MB 2.9% 0.57
Jun 12 06:00:00 - 06:59:59 538 2.4% 0.151.6 MB 2.4% 0.47
Jun 12 07:00:00 - 07:59:59 689 3.0% 0.191.9 MB 2.8% 0.54
Jun 12 08:00:00 - 08:59:59 896 4.0% 0.252.3 MB 3.3% 0.64
Jun 12 09:00:00 - 09:59:59 1022 4.5% 0.283.3 MB 4.9% 0.94
Jun 12 10:00:00 - 10:59:59 1133 5.0% 0.314.1 MB 6.0% 1.17
Jun 12 11:00:00 - 11:59:59 886 3.9% 0.252.3 MB 3.4% 0.66
Jun 12 12:00:00 - 12:59:59 1071 4.7% 0.302.9 MB 4.3% 0.83
Jun 12 13:00:00 - 13:59:59 1121 5.0% 0.313.5 MB 5.2% 1.00
Jun 12 14:00:00 - 14:59:59 1236 5.5% 0.344.5 MB 6.6% 1.28
Jun 12 15:00:00 - 15:59:59 1290 5.7% 0.363.6 MB 5.2% 1.01
Jun 12 16:00:00 - 16:59:59 1195 5.3% 0.333.1 MB 4.6% 0.90
Jun 12 17:00:00 - 17:59:59 1500 6.6% 0.424.5 MB 6.6% 1.28
Jun 12 18:00:00 - 18:59:59 1336 5.9% 0.373.8 MB 5.5% 1.07
Jun 12 19:00:00 - 19:59:59 1135 5.0% 0.323.2 MB 4.7% 0.91
Jun 12 20:00:00 - 20:59:59 1332 5.9% 0.374.3 MB 6.3% 1.22
Jun 12 21:00:00 - 21:59:59 1214 5.4% 0.343.5 MB 5.1% 0.98
Jun 12 22:00:00 - 22:59:59 1022 4.5% 0.282.7 MB 4.0% 0.78
Jun 12 23:00:00 - 23:59:59 795 3.5% 0.222.2 MB 3.3% 0.63
Jun 13 00:00:00 - 00:59:59 702 3.1% 0.202.2 MB 3.2% 0.62
Jun 13 01:00:00 - 01:59:59 522 2.3% 0.141.5 MB 2.2% 0.43
Jun 13 02:00:00 - 02:59:59 449 2.0% 0.121.6 MB 2.3% 0.45
Jun 13 03:00:00 - 03:59:59 579 2.6% 0.161.9 MB 2.8% 0.55
Jun 13 04:00:00 - 04:15:00 127 0.6% 0.14400.9 KB 0.6% 0.45
TOTAL: 24:00:00 22607 100.0% 0.2668.0 MB 100.0% 0.81
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 9069 703 0 5 0 6 0 0 8355
2newsfeed.neva.ru 4926 413 0 2 0 0 0 0 4511
3usenet.blueworldhosting.com 1847 462 0 0 1 0 0 0 1384
4feeder.eternal-september.org 1046 164 0 1 0 0 0 0 881
5news-out.mixmin.net 1012 3 0 0 0 0 0 0 1009
6newsfeed.fu-berlin.de 739 59 0 0 0 0 0 0 680
7csiph-out.news.weretis.net 426 124 0 0 0 0 0 0 302
8news.fcku.it 150 0 0 6 0 0 0 0 144
9news.freedyn.net 62 2 0 0 0 0 0 0 60
10au2pb.net 22 15 0 0 0 0 0 0 7
1146.165.242.75 20 11 0 0 0 0 0 0 9
12news.swapon.de 14 0 0 0 0 0 0 0 14
13newsfeeds.xmission.com 10 6 0 0 0 0 0 0 4
14nntp.pasdenom.info 5 3 0 0 0 1 0 0 1
15news.redatomik.org 1 0 0 0 0 0 0 0 1
TOTAL: 15 19349 1965 0 14 1 7 0 017362

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 253
mailing.freebsd.pkg-fallout 159
alt.survival 112
alt.politics.scorched-earth 99
mailing.freebsd.ports-bugs 90
mailing.freebsd.svn-ports-all 63
mailing.freebsd.svn-ports-head 62
spline.eisfair 61
mozilla.general 57
ut.test 52
lada.talk 42
mailing.openssl.dev 36
it-alt.sport.calcio.juventus 33
24hoursupport.helpdesk 33
mailing.openssl.cvs 31
mailing.openbsd.misc 20
gmane.linux.alsa.devel 19
fa.freebsd.cvs-all 18
cakewalk.audio 18
mailing.freebsd.svn-src-all 18
TOTAL: 196 1965

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

GroupsCount
nz.politics.announce 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11195
EMP (phn path) 2535
PFM bot misc 1344
EMP (phl) 858
EMP (phn nph) 172
Binary: misplaced PAR2 84
User-issued cancel 65
Too many newsgroups 61
Binary Payload 21
HTML Multipart 17
Scoring filter (11) 15
Scoring filter (12) 10
Scoring filter (24) 9
Scoring filter (18) 8
Scoring filter (10) 7
Scoring filter (17) 7
Scoring filter (13) 7
Binary: misplaced par2 6
Scoring filter (16) 6
Scoring filter (14) 6
TOTAL: 45 16494

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com7408383
TOTAL: 17408383

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
usenet.blueworldhosting.com 130
news.fcku.it 93
feeder.eternal-september.org 47
csiph-out.news.weretis.net 18
news.freedyn.net 11
news-out.mixmin.net 7
newsfeeds.xmission.com 3
au2pb.net 1
TOTAL: 8 310
Including strange strings
newsfeed.fu-berlin.de 389
feeder.eternal-september.org 61
csiph-out.feeder.erje.net 41
news-out.mixmin.net 29
news.swapon.de 13
newsfeed.neva.ru 12
46.165.242.75 7
news.fcku.it 3
csiph-out.news.weretis.net 2
TOTAL: 9 557
No colon-space in header
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 3 868

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 40824 2136 19439 47 0 0 5%23:51:35
2news.freedyn.net 27225 1276 20931 7 1 0 4%23:56:09
3xanadu-bbs 25190 1031 21554 2 0 190 4%23:59:55
4fu-berlin.de 21262 900 20361 0 0 0 4%23:55:15
5aioe 23283 283 18909 7 0 0 1%23:50:07
6usenet.pasdenom.info 30280 253 21024 1311 0 15 0%23:59:55
7au2pb 23219 119 21424 3 0 0 0%23:59:55
8news.redatomik.org 24904 108 21265 8 0 0 0%23:58:56
9weretis.net 21016 87 19768 20 0 0 0%23:57:41
10xmission 25178 78 21050 2 0 0 0%23:59:48
11news.mixmin.net 23437 40 19141 1 0 0 0%23:59:55
12eternal-september 23599 20 18031 0 0 0 0%23:59:50
13bwh 17333 9 15523 0 0 0 0%23:52:58
14news.swapon.de 2314 5 1992 3 0 0 0%23:50:19
15erje 20967 3 16807 568 0 0 0%23:50:07
16neva.ru 25351 2 20401 31 0 0 0%23:59:55
17news.fcku.it 1839 2 1518 0 0 0 0%22:20:36
TOTAL: 17 377221 6352 299138 2010 1 205 1%405:22:56
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net9.6 MB55.9 KB9.6 MB0.1 KB/s7.7 KB23:56:09
2xanadu-bbs8.8 MB4.5 KB8.8 MB0.1 KB/s8.8 KB23:59:55
3fu-berlin.de8.6 MB0.0 KB8.6 MB0.1 KB/s9.8 KB23:55:15
4news.bbs.geek.nz5.3 MB137.8 KB5.4 MB0.1 KB/s2.6 KB23:51:35
5aioe703.2 KB11.7 KB714.9 KB0.0 KB/s2.5 KB23:50:07
6usenet.pasdenom.info697.6 KB9.5 MB10.2 MB0.1 KB/s6.7 KB23:59:55
7news.redatomik.org238.5 KB10.2 KB248.7 KB0.0 KB/s2.1 KB23:58:56
8au2pb208.3 KB18.1 KB226.4 KB0.0 KB/s1.9 KB23:59:55
9weretis.net155.8 KB156.1 KB312.0 KB0.0 KB/s2.9 KB23:57:41
10xmission146.2 KB10.4 KB156.6 KB0.0 KB/s2.0 KB23:59:48
11news.mixmin.net92.3 KB1.8 KB94.1 KB0.0 KB/s2.3 KB23:59:55
12eternal-september38.8 KB0.0 KB38.8 KB0.0 KB/s1.9 KB23:59:50
13bwh12.5 KB0.0 KB12.5 KB0.0 KB/s1.4 KB23:52:58
14news.swapon.de7.9 KB6.7 KB14.6 KB0.0 KB/s1.8 KB23:50:19
15erje4.6 KB1.5 MB1.5 MB0.0 KB/s2.7 KB23:50:07
16news.fcku.it4.5 KB0.0 KB4.5 KB0.0 KB/s2.3 KB22:20:36
17neva.ru3.4 KB103.8 KB107.2 KB0.0 KB/s3.2 KB23:59:55
TOTAL: 1734.5 MB11.6 MB46.1 MB0.0 KB/s5.6 KB405:22:56
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 288 54154.5 KB 49 0 004:48:24
2mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 130 3291.7 KB 18 0 002:02:16
368-185-181-64.dhcp.mdsn.wi.charter.com 20 79.8 KB 7 7 604:44:03
TOTAL: 3 438 93256.1 KB 74 7 620:55:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 311 54154.5 KB 49 0 008:29:46
2*.dsl.bell.ca 130 3291.7 KB 18 0 002:02:16
3*.dhcp.mdsn.wi.charter.com 20 79.8 KB 7 7 604:44:03
4*.chello.nl 14 00.0 KB 0 0 005:38:55
5*.dynamic.upc.nl 2 00.0 KB 0 0 000:00:25
6*.rev.numericable.fr 2 00.0 KB 0 0 000:00:23
TOTAL: 6 479 93256.1 KB 74 7 620:55:50

NNRP auth users [Top 20]:

UserConn
moussa 288
ohlandl 6
TOTAL: 2 294

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dhcp-089-099-203-254.chello.nl 1.142 0.260 0.00005:38:55
202.89.176.220 29.479 7.945 0.77304:48:24
68-185-181-64.dhcp.mdsn.wi.charter.com 2.079 0.422 0.01704:44:03
71.98.160.36 0.579 0.166 0.00003:31:02
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 17.205 5.170 0.00002:02:16
46.165.242.75 0.993 0.220 0.00000:10:19
92-108-162-83.dynamic.upc.nl 0.133 0.022 0.00000:00:25
81-67-242-59.rev.numericable.fr 0.134 0.031 0.00000:00:23
TOTAL: 8 51.744 14.236 0.79020:55:50

Curious NNRP server explorers [Top 20]:

SystemConn
dhcp-089-099-203-254.chello.nl 14
46.165.242.75 12
71.98.160.36 7
216.239.174.71 4
92-108-162-83.dynamic.upc.nl 2
81-67-242-59.rev.numericable.fr 2
TOTAL: 6 41

NNRP client timeouts [Top 20]:

SystemConnPeer
dhcp-089-099-203-254.chello.nl 10 0
71.98.160.36 7 0
68-185-181-64.dhcp.mdsn.wi.charter.com 3 0
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 2 2
TOTAL: 4 22 2

Newsgroup request counts (by category):

CategoryCountPct
1comp 61 65.6%
2rec 16 17.2%
3sci 9 9.7%
4uk 4 4.3%
5alt 3 3.2%
TOTAL: 5 93100.0%

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

NewsgroupCount
1comp.os.vms 20
2comp.sys.ibm.ps2.hardware 14
3rec.arts.tv 11
4comp.arch 9
5sci.physics 8
6comp.os.msdos.djgpp 4
7comp.ai.philosophy 4
8comp.arch.embedded 3
9rec.outdoors.rv-travel 3
10comp.os.os2.apps 2
11comp.sys.ibm.pc.games.rpg 2
12rec.boats 2
13alt.os.linux.ubuntu 2
14uk.legal 1
15uk.rec.sheds 1
16comp.os.cpm 1
17uk.media.radio.archers 1
18alt.folklore.computers 1
19uk.politics.misc 1
20comp.sys.ibm.pc.games.strategic 1
21sci.military.naval 1
22comp.ai.neural-nets 1
TOTAL: 22 93