Daily Usenet report for csiph.com

Nov 8 04:15:00 -- Nov 9 04:15:00

Unknown entries from news log file:

First 50 / 2740 lines (1.8%)

Nov 8 05:00:20 csiph nnrpd[62475]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:00:50 csiph nnrpd[62476]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:01:21 csiph nnrpd[62508]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:01:52 csiph nnrpd[62509]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:02:22 csiph nnrpd[62535]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:02:53 csiph nnrpd[62536]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:03:23 csiph nnrpd[62562]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:03:53 csiph nnrpd[62563]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:04:25 csiph nnrpd[62589]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:04:56 csiph nnrpd[62590]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:05:27 csiph nnrpd[62626]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:05:58 csiph nnrpd[62627]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:06:29 csiph nnrpd[62652]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:07:00 csiph nnrpd[62654]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:07:31 csiph nnrpd[62662]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:08:02 csiph nnrpd[62681]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:08:33 csiph nnrpd[62682]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:09:04 csiph nnrpd[62843]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:09:34 csiph nnrpd[62844]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:10:04 csiph nnrpd[62883]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:10:36 csiph nnrpd[62884]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:11:06 csiph nnrpd[62931]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:11:37 csiph nnrpd[62932]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:12:07 csiph nnrpd[62958]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:12:38 csiph nnrpd[62959]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:13:08 csiph nnrpd[62988]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:13:40 csiph nnrpd[62989]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:14:10 csiph nnrpd[63015]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:14:41 csiph nnrpd[63016]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:15:11 csiph nnrpd[63033]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:15:43 csiph nnrpd[63053]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:16:14 csiph nnrpd[63054]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:16:44 csiph nnrpd[63080]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:17:15 csiph nnrpd[63081]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:17:45 csiph nnrpd[63107]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:18:16 csiph nnrpd[63108]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:18:48 csiph nnrpd[63136]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:19:18 csiph nnrpd[63275]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:19:48 csiph nnrpd[63304]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:20:19 csiph nnrpd[63315]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:20:50 csiph nnrpd[63341]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:21:21 csiph nnrpd[63342]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:21:52 csiph nnrpd[63368]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:22:22 csiph nnrpd[63390]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:22:53 csiph nnrpd[63405]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:23:24 csiph nnrpd[63419]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:23:55 csiph nnrpd[63420]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:24:25 csiph nnrpd[63446]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:24:56 csiph nnrpd[63447]: snorky.mixmin.net noperm ihave without permission
Nov 8 05:25:27 csiph nnrpd[63483]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
inn 58627 43.1%8.1 MB 52.8%
nnrpd 41545 30.6%3.8 MB 24.5%
innd 33885 24.9%3.2 MB 21.0%
innfeed 1860 1.4%271.1 KB 1.7%
TOTAL: 4 135917 100.0%15.4 MB100.0%

History cache:

ReasonCount%Count
Negative hits 245873 45.2%
Positive hits 229906 42.3%
Do not exist 60705 11.2%
Cache misses 7076 1.3%
TOTAL: 4 543560 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.536 0.0%187 0.000 2.866 17.000
article cleanup00:00:00.397 0.0%48266 0.000 0.008 0.024
article logging00:00:00.601 0.0%48727 0.000 0.012 0.074
article parse00:00:23.832 0.0%1937492 0.004 0.012 0.104
article write00:00:55.834 0.1%22974 0.193 2.430 8.308
artlog/artcncl00:00:00.000 0.0%131 0.000 0.000 0.000
artlog/artparse00:00:00.124 0.0%9867 0.000 0.013 0.125
data move00:00:01.811 0.0%2341592 0.000 0.001 0.002
hisgrep/artcncl00:00:00.050 0.0%183 0.000 0.273 12.000
hishave/artcncl00:00:00.000 0.0%187 0.000 0.000 0.000
hishave/artparse00:00:00.044 0.0%9867 0.000 0.004 0.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:02.606 0.0%535638 0.001 0.005 0.032
history sync00:00:01.553 0.0%327 0.000 4.749 63.000
history write00:00:48.420 0.1%48265 0.096 1.003 8.538
hiswrite/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
hiswrite/artparse00:00:08.072 0.0%9864 0.000 0.818 18.882
idle23:54:05.023 99.5%2304813 6.580 37.333 437.718
nntp read00:00:08.905 0.0%2307381 0.001 0.004 0.006
overview write00:01:17.117 0.1%22974 0.391 3.357 16.246
perl filter00:01:45.030 0.1%48265 1.004 2.176 4.295
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.442 0.0%45948 0.000 0.010 0.037
TOTAL: 24:00:35.88023:59:40.397 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.070 0.0%23238 0.000 0.003 0.020
article prepare00:00:00.000 0.0%7960 0.000 0.000 0.000
article read00:00:00.275 0.0%1679 0.000 0.164 22.500
backlog stats00:00:00.080 0.0%266215 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.117 0.0%199895 0.001 0.006 0.013
data write00:00:01.417 0.0%180786 0.001 0.008 0.014
idle23:54:18.996100.0%266215 183.909 323.269 1041.099
status file00:00:00.098 0.0%196 0.000 0.500 1.000
TOTAL: 23:54:27.77823:54:22.053100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:01.790 0.0%25527 0.000 0.070 0.480
idle76:21:52.955 99.6%60235 0.000 4564.007600184.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.775 0.0%121578 0.000 0.006 0.333
readart00:00:00.174 0.0%115 0.000 1.513 46.000
TOTAL: 76:40:29.36476:21:55.694 99.6%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 5 44962 5727 34891 4344 12%24:46:02
2au2pb.net 1 25011 4999 19138 874 19%23:55:25
3news-out.mixmin.net 2 91007 4624 81255 5128 5%24:06:41
4newsfeeds.xmission.com 1 22693 2463 20130 100 10%22:36:46
5newsfeed.neva.ru 147 49947 1940 36901 11106 3%72:20:01
6xanadu-bbs.net 9 57879 1446 43324 13109 2%62:18:02
7newsfeed.fu-berlin.de 45 28750 1291 26983 476 4%32:00:15
8nntp.pasdenom.info 12 22910 193 22553 164 0%23:59:30
9news.swapon.de 7 1903 176 1721 6 9%23:52:52
10188.40.76.149 0 1187 13 1157 17 1%01:59:43
11localhost 26 0 0 0 0 0%00:00:01
TOTAL: 11 255 346249 22872 288053 35324 6%311:55:18
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.mixmin.net15.5 MB0.0 KB11.0 MB26.5 MB 58%2.8 KB
2feeder.eternal-september.org15.0 MB0.0 KB36.8 MB51.8 MB 29%5.3 KB
3au2pb.net12.6 MB0.0 KB5.9 MB18.5 MB 68%3.2 KB
4newsfeeds.xmission.com6.4 MB0.0 KB2.8 MB9.2 MB 69%3.7 KB
5newsfeed.neva.ru6.4 MB19.0 KB109.6 MB116.0 MB 5%9.1 KB
6xanadu-bbs.net4.1 MB0.0 KB3.2 GB3.2 GB 0%230.6 KB
7newsfeed.fu-berlin.de2.8 MB0.0 KB1.1 MB3.9 MB 72%2.3 KB
8nntp.pasdenom.info494.2 KB0.0 KB1.6 MB2.1 MB 23%6.0 KB
9news.swapon.de435.8 KB0.0 KB595.8 KB1.0 MB 42%5.7 KB
10188.40.76.14965.6 KB0.0 KB40.6 KB106.2 KB 61%3.5 KB
11localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1163.9 MB19.0 KB3.4 GB3.4 GB 1%61.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 8 04:15:00 - 04:59:59 308 1.3% 0.11952.1 KB 1.4% 0.35
Nov 8 05:00:00 - 05:59:59 432 1.9% 0.121.4 MB 2.3% 0.41
Nov 8 06:00:00 - 06:59:59 518 2.3% 0.141.9 MB 2.9% 0.53
Nov 8 07:00:00 - 07:59:59 703 3.1% 0.202.2 MB 3.5% 0.64
Nov 8 08:00:00 - 08:59:59 827 3.6% 0.232.2 MB 3.4% 0.62
Nov 8 09:00:00 - 09:59:59 914 4.0% 0.252.4 MB 3.7% 0.68
Nov 8 10:00:00 - 10:59:59 1108 4.8% 0.313.0 MB 4.7% 0.85
Nov 8 11:00:00 - 11:59:59 994 4.3% 0.282.7 MB 4.2% 0.77
Nov 8 12:00:00 - 12:59:59 970 4.2% 0.272.6 MB 4.0% 0.73
Nov 8 13:00:00 - 13:59:59 1008 4.4% 0.282.5 MB 4.0% 0.72
Nov 8 14:00:00 - 14:59:59 1161 5.1% 0.323.0 MB 4.6% 0.85
Nov 8 15:00:00 - 15:59:59 1096 4.8% 0.303.1 MB 4.8% 0.88
Nov 8 16:00:00 - 16:59:59 1316 5.7% 0.373.7 MB 5.7% 1.05
Nov 8 17:00:00 - 17:59:59 1459 6.3% 0.413.8 MB 5.9% 1.08
Nov 8 18:00:00 - 18:59:59 1428 6.2% 0.404.2 MB 6.5% 1.18
Nov 8 19:00:00 - 19:59:59 1372 6.0% 0.383.6 MB 5.6% 1.02
Nov 8 20:00:00 - 20:59:59 1299 5.7% 0.363.4 MB 5.3% 0.97
Nov 8 21:00:00 - 21:59:59 1195 5.2% 0.333.3 MB 5.1% 0.92
Nov 8 22:00:00 - 22:59:59 1221 5.3% 0.343.3 MB 5.2% 0.95
Nov 8 23:00:00 - 23:59:59 1081 4.7% 0.302.9 MB 4.5% 0.82
Nov 9 00:00:00 - 00:59:59 806 3.5% 0.222.3 MB 3.6% 0.66
Nov 9 01:00:00 - 01:59:59 623 2.7% 0.171.9 MB 2.9% 0.53
Nov 9 02:00:00 - 02:59:59 533 2.3% 0.151.8 MB 2.7% 0.50
Nov 9 03:00:00 - 03:59:59 498 2.2% 0.142.0 MB 3.1% 0.56
Nov 9 04:00:00 - 04:15:00 109 0.5% 0.12313.4 KB 0.5% 0.35
TOTAL: 24:00:00 22979 100.0% 0.2764.2 MB 100.0% 0.76
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1xanadu-bbs.net 13037 223 0 0 0 0 0 012814
2newsfeed.neva.ru 10951 1130 0 1 0 0 0 0 9820
3news-out.mixmin.net 5152 30 0 0 0 0 0 0 5122
4feeder.eternal-september.org 4348 330 0 0 0 0 0 0 4018
5au2pb.net 898 832 0 0 0 0 0 0 66
6newsfeed.fu-berlin.de 821 68 0 0 0 0 0 0 753
7nntp.pasdenom.info 164 148 0 0 0 0 0 0 16
8newsfeeds.xmission.com 108 73 0 0 0 0 0 0 35
9news.swapon.de 32 0 0 0 0 0 0 0 32
10188.40.76.149 6 0 0 0 0 0 0 0 6
TOTAL: 10 35517 2834 0 1 0 0 0 032682

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 350
lada.talk 285
alt.politics.scorched-earth 118
mailing.freebsd.pkg-fallout 106
it-alt.sport.calcio.juventus 101
pld.cvs.commit 99
ut.test 78
alt.survival 78
lada.auto 64
mailing.freebsd.ports-bugs 62
fa.openbsd.tech 60
mailing.openbsd.tech 60
mailing.freebsd.svn-ports-all 58
mailing.freebsd.svn-ports-head 58
alt.politics 55
mailing.freebsd.svn-src-all 50
fa.freebsd.cvs-all 49
nemo.dev.serveur 48
mailing.freebsd.svn-src-head 47
mailing.openbsd.source-changes 42
TOTAL: 211 2834

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 16801
EMP (phn path) 3871
EMP (phl) 1021
Binary: misplaced rar 191
PFM bot misc 179
EMP (phn nph) 151
Too many newsgroups 28
HTML Multipart 22
Binary Image: misplaced jpg 22
HTML post 19
Binary: misplaced rev 12
Too many newsgroups (low_xpost) 11
User-issued spam cancel 11
HTML Image Tags 7
Binary: misplaced mp4 1
Binary: misplaced sfv 1
Binary: misplaced nfo 1
Binary: misplaced srr 1
Binary: misplaced bin 1
Binary: misplaced par2 1
TOTAL: 20 22352

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 9666
feeder.eternal-september.org 102
au2pb.net 37
newsfeeds.xmission.com 25
news-out.mixmin.net 19
nntp.pasdenom.info 12
news.swapon.de 3
newsfeed.fu-berlin.de 3
TOTAL: 8 9867
Including strange strings
newsfeed.fu-berlin.de 349
feeder.eternal-september.org 47
news.swapon.de 26
news-out.mixmin.net 17
au2pb.net 14
newsfeeds.xmission.com 6
newsfeed.neva.ru 3
xanadu-bbs.net 1
TOTAL: 8 463
TOTAL: 2 10330

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 27513 139 21039 341 0 11 0%23:59:55
2au2pb 18645 134 17732 75 0 0 0%23:59:55
3usenet.pasdenom.info 27058 124 21648 1011 0 293 0%23:59:55
4fu-berlin.de 21368 43 21309 16 0 0 0%23:59:48
5news.mixmin.net 18863 26 18199 0 0 0 0%23:59:53
6xmission 25521 24 20143 2 0 0 0%23:59:47
7eternal-september 17566 12 16748 3 0 0 0%23:59:55
8neva.ru 21948 5 20911 37 0 0 0%23:59:49
9news.swapon.de 1871 0 1864 0 0 0 0%23:54:18
TOTAL: 9 180353 507 159593 1485 0 304 0%215:53:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs740.5 KB717.0 KB1.4 MB0.0 KB/s3.0 KB23:59:55
2fu-berlin.de569.8 KB22.2 KB592.0 KB0.0 KB/s10.0 KB23:59:48
3au2pb508.5 KB584.5 KB1.1 MB0.0 KB/s5.2 KB23:59:55
4usenet.pasdenom.info436.2 KB2.3 MB2.8 MB0.0 KB/s2.5 KB23:59:55
5news.mixmin.net87.7 KB0.0 KB87.7 KB0.0 KB/s3.4 KB23:59:53
6eternal-september63.2 KB4.2 KB67.5 KB0.0 KB/s4.5 KB23:59:55
7xmission57.3 KB3.5 KB60.8 KB0.0 KB/s2.3 KB23:59:47
8neva.ru37.9 KB125.5 KB163.5 KB0.0 KB/s3.9 KB23:59:49
9news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:18
TOTAL: 92.4 MB3.8 MB6.2 MB0.0 KB/s3.2 KB215:53:15
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 239 82235.5 KB 65 0 003:56:00
2tempe0.bbox.io 22 2350.7 KB 5 0 003:02:15
392-108-162-83.dynamic.upc.nl 3 34.4 KB 2 0 000:05:34
4pool-71-98-160-84.tampfl.dsl-w.verizon.net 21 35.7 KB 3 0 003:37:21
5challenger.nielsenmedia.com 7 35.7 KB 3 0 001:30:56
6dhcp-089-099-203-254.chello.nl 44 11.0 KB 1 0 007:56:50
TOTAL: 6 336 115302.9 KB 79 0 076:40:00

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 465 82235.5 KB 65 0 058:32:19
2*.bbox.io 22 2350.7 KB 5 0 003:02:15
3*.tampfl.dsl-w.verizon.net 21 35.7 KB 3 0 003:37:21
4*.dynamic.upc.nl 3 34.4 KB 2 0 000:05:34
5*.nielsenmedia.com 7 35.7 KB 3 0 001:30:56
6*.chello.nl 44 11.0 KB 1 0 007:56:50
7*.mixmin.net2740 00.0 KB 0 0 000:26:20
8*.147.22.98.dynamic.ip.windstream.net 2 00.0 KB 0 0 001:28:19
TOTAL: 83304 115302.9 KB 79 0 076:40:00

NNRP auth users [Top 20]:

UserConn
moussa 239
TOTAL: 1 239

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
63.142.120.8 14.377 3.807 0.00047:54:06
dhcp-089-099-203-254.chello.nl 3.765 0.636 0.00007:56:50
162.212.88.214 0.372 0.155 0.00006:31:56
202.89.176.220 24.205 4.875 0.60303:56:00
pool-71-98-160-84.tampfl.dsl-w.verizon.net 1.750 0.287 0.00003:37:21
tempe0.bbox.io 1.954 0.259 0.00003:02:15
challenger.nielsenmedia.com 0.694 0.092 0.00001:30:56
h168.147.22.98.dynamic.ip.windstream.net 0.175 0.051 0.00001:28:19
snorky.mixmin.net 229.721 42.811 0.00000:26:20
88.235.169.164 1.315 0.263 0.00000:10:13
92-108-162-83.dynamic.upc.nl 0.380 0.054 0.00000:05:34
69.160.37.54 1.915 0.373 0.00000:00:02
TOTAL: 12 280.623 53.663 0.60376:40:00

Curious NNRP server explorers [Top 20]:

SystemConn
snorky.mixmin.net 2740
63.142.120.8 181
69.160.37.54 25
88.235.169.164 16
162.212.88.214 4
h168.147.22.98.dynamic.ip.windstream.net 2
TOTAL: 6 2968

NNRP client timeouts [Top 20]:

SystemConnPeer
pool-71-98-160-84.tampfl.dsl-w.verizon.net 7 0
tempe0.bbox.io 5 0
challenger.nielsenmedia.com 3 0
h168.147.22.98.dynamic.ip.windstream.net 2 0
TOTAL: 4 17 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 115100.0%
TOTAL: 1 115100.0%

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

NewsgroupCount
1comp.arch 69
2comp.arch.embedded 10
3comp.sys.ibm.ps2.hardware 8
4comp.lang.awk 6
5comp.os.vms 6
6comp.sys.ibm.pc.games.war-historical 6
7comp.os.linux.networking 2
8comp.sys.ibm.pc.games.action 2
9comp.ai.neural-nets 2
10comp.arch.fpga 1
11comp.sys.ibm.pc.hardware.storage 1
12comp.arch.storage 1
13comp.os.os2.apps 1
TOTAL: 13 115
Valid XHTML 1.1 Strict CSS is valid!
innreport 3.1.0 (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: admin@kev009.com