Daily Usenet report for csiph.com

Nov 13 04:15:00 -- Nov 14 04:15:00

Unknown entries from news log file:

First 50 / 2825 lines (1.8%)

Nov 13 04:15:29 csiph nnrpd[95482]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:15:59 csiph nnrpd[95483]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:16:32 csiph nnrpd[95509]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:17:02 csiph nnrpd[95510]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:17:33 csiph nnrpd[95536]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:18:04 csiph nnrpd[95537]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:18:34 csiph nnrpd[95563]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:19:04 csiph nnrpd[95569]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:19:35 csiph nnrpd[95593]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:20:05 csiph nnrpd[95632]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:20:36 csiph nnrpd[95640]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:21:07 csiph nnrpd[95666]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:21:37 csiph nnrpd[95668]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:22:07 csiph nnrpd[95715]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:22:38 csiph nnrpd[95716]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:23:08 csiph nnrpd[95742]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:23:40 csiph nnrpd[95743]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:24:10 csiph nnrpd[95781]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:24:40 csiph nnrpd[95782]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:25:12 csiph nnrpd[95818]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:25:42 csiph nnrpd[95819]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:26:12 csiph nnrpd[95849]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:26:43 csiph nnrpd[95850]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:27:13 csiph nnrpd[95869]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:27:44 csiph nnrpd[95877]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:28:14 csiph nnrpd[95878]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:28:45 csiph nnrpd[95904]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:29:16 csiph nnrpd[95905]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:29:47 csiph nnrpd[95934]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:30:18 csiph nnrpd[95945]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:30:49 csiph nnrpd[95971]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:31:20 csiph nnrpd[96113]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:31:51 csiph nnrpd[96139]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:32:22 csiph nnrpd[96140]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:32:53 csiph nnrpd[96166]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:33:24 csiph nnrpd[96188]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:33:55 csiph nnrpd[96214]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:34:25 csiph nnrpd[96215]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:34:56 csiph nnrpd[96244]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:35:27 csiph nnrpd[96255]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:35:58 csiph nnrpd[96267]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:36:28 csiph nnrpd[96282]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:36:59 csiph nnrpd[96283]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:37:29 csiph nnrpd[96309]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:38:00 csiph nnrpd[96310]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:38:31 csiph nnrpd[96336]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:39:02 csiph nnrpd[96337]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:39:32 csiph nnrpd[96363]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:40:04 csiph nnrpd[96374]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:40:35 csiph nnrpd[96409]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
inn 51852 42.8%7.5 MB 53.9%
nnrpd 46496 38.4%4.1 MB 29.8%
innd 20851 17.2%2.0 MB 14.4%
innfeed 1851 1.5%271.2 KB 1.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 121051 100.0%13.9 MB100.0%

History cache:

ReasonCount%Count
Negative hits 268003 45.9%
Positive hits 265169 45.4%
Do not exist 51148 8.8%
Cache misses 111 0.0%
TOTAL: 4 584431 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.541 0.0%135 0.000 4.007 30.143
article cleanup00:00:00.359 0.0%50494 0.000 0.007 0.022
article logging00:00:00.706 0.0%51040 0.000 0.014 0.040
article parse00:00:03.290 0.0%254943 0.003 0.013 0.073
article write00:01:12.224 0.1%28064 0.211 2.574 8.076
artlog/artcncl00:00:00.001 0.0%124 0.000 0.008 0.143
artlog/artparse00:00:00.007 0.0%733 0.000 0.010 0.077
data move00:00:00.311 0.0%725925 0.000 0.000 0.002
hisgrep/artcncl00:00:00.106 0.0%134 0.000 0.791 9.000
hishave/artcncl00:00:00.000 0.0%135 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%733 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:12.295 0.0%584352 0.001 0.021 0.151
history sync00:00:02.925 0.0%354 0.000 8.263 105.500
history write00:01:21.463 0.1%50487 0.079 1.614 6.947
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:01.048 0.0%733 0.000 1.430 23.400
idle23:53:55.377 99.5%716003 35.575 120.161 353.758
nntp read00:00:02.465 0.0%713826 0.002 0.003 0.008
overview write00:01:22.894 0.1%28064 0.433 2.954 14.133
perl filter00:01:38.832 0.1%50487 0.976 1.958 3.922
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.531 0.0%56128 0.000 0.009 0.027
TOTAL: 24:00:50.80723:59:55.375 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.071 0.0%28037 0.000 0.003 0.014
article prepare00:00:00.002 0.0%5412 0.000 0.000 0.062
article read00:00:00.218 0.0%1099 0.000 0.198 67.000
backlog stats00:00:00.098 0.0%304211 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.357 0.0%238196 0.000 0.006 0.012
data write00:00:01.547 0.0%214686 0.001 0.007 0.018
idle23:54:51.033100.0%304211 157.396 282.998 895.261
status file00:00:00.080 0.0%190 0.000 0.421 1.000
TOTAL: 23:54:59.62723:54:54.406100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:05.578 0.0%36655 0.000 0.152 0.385
idle85:20:50.958 99.9%76674 0.000 4007.238600092.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.969 0.0%154277 0.000 0.006 0.333
readart00:00:00.012 0.0%75 0.000 0.160 2.000
TOTAL: 85:27:03.80385:20:57.517 99.9%----

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 46921 7402 36730 2789 15%24:55:53
2news-out.mixmin.net 2 35782 5514 25762 4506 15%23:59:49
3au2pb.net 1 31440 4577 25812 1051 14%24:23:48
4xanadu-bbs.net 0 50755 3983 39542 7230 7%23:47:46
5newsfeeds.xmission.com 1 29356 2412 26728 216 8%23:50:43
6newsfeed.neva.ru 139 56090 2383 46833 6874 4%71:32:49
7newsfeed.fu-berlin.de 60 34011 1456 32140 415 4%35:18:41
8nntp.pasdenom.info 3 30046 266 29624 156 0%24:09:32
9news.swapon.de 2 2613 241 2367 5 9%25:43:21
10localhost 26 0 0 0 0 0%00:00:00
TOTAL: 10 239 317014 28234 265538 23242 8%277:42:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder.eternal-september.org19.7 MB1.8 KB18.3 MB37.9 MB 51%3.8 KB
2news-out.mixmin.net17.5 MB0.0 KB8.4 MB25.9 MB 67%2.7 KB
3au2pb.net12.3 MB39.4 KB5.2 MB17.5 MB 70%3.2 KB
4xanadu-bbs.net10.3 MB39.2 KB170.1 MB180.5 MB 5%16.5 KB
5newsfeeds.xmission.com7.1 MB0.0 KB4.8 MB11.9 MB 59%4.6 KB
6newsfeed.neva.ru7.1 MB13.2 KB58.4 MB65.5 MB 10%7.2 KB
7newsfeed.fu-berlin.de3.6 MB0.0 KB1.0 MB4.6 MB 77%2.5 KB
8nntp.pasdenom.info625.8 KB31.6 KB889.2 KB1.5 MB 40%3.7 KB
9news.swapon.de617.3 KB0.0 KB513.4 KB1.1 MB 54%4.6 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1078.7 MB125.2 KB267.6 MB346.5 MB 22%6.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 13 04:15:00 - 04:59:59 374 1.3% 0.141.1 MB 1.4% 0.42
Nov 13 05:00:00 - 05:59:59 571 2.0% 0.162.0 MB 2.6% 0.57
Nov 13 06:00:00 - 06:59:59 601 2.1% 0.171.8 MB 2.3% 0.51
Nov 13 07:00:00 - 07:59:59 953 3.4% 0.262.6 MB 3.4% 0.75
Nov 13 08:00:00 - 08:59:59 1077 3.8% 0.302.9 MB 3.7% 0.81
Nov 13 09:00:00 - 09:59:59 1300 4.6% 0.363.5 MB 4.5% 1.00
Nov 13 10:00:00 - 10:59:59 1471 5.2% 0.413.8 MB 4.9% 1.09
Nov 13 11:00:00 - 11:59:59 1197 4.3% 0.333.1 MB 4.0% 0.88
Nov 13 12:00:00 - 12:59:59 1356 4.8% 0.383.5 MB 4.5% 1.00
Nov 13 13:00:00 - 13:59:59 1510 5.4% 0.423.8 MB 4.9% 1.09
Nov 13 14:00:00 - 14:59:59 1543 5.5% 0.434.5 MB 5.7% 1.27
Nov 13 15:00:00 - 15:59:59 1660 5.9% 0.464.8 MB 6.1% 1.35
Nov 13 16:00:00 - 16:59:59 1624 5.8% 0.454.4 MB 5.7% 1.27
Nov 13 17:00:00 - 17:59:59 1609 5.7% 0.454.4 MB 5.7% 1.26
Nov 13 18:00:00 - 18:59:59 1563 5.6% 0.434.2 MB 5.4% 1.19
Nov 13 19:00:00 - 19:59:59 1518 5.4% 0.424.2 MB 5.4% 1.19
Nov 13 20:00:00 - 20:59:59 1317 4.7% 0.373.5 MB 4.5% 1.00
Nov 13 21:00:00 - 21:59:59 1292 4.6% 0.363.3 MB 4.2% 0.93
Nov 13 22:00:00 - 22:59:59 1556 5.5% 0.434.4 MB 5.6% 1.25
Nov 13 23:00:00 - 23:59:59 1129 4.0% 0.313.2 MB 4.1% 0.90
Nov 14 00:00:00 - 00:59:59 892 3.2% 0.252.6 MB 3.4% 0.75
Nov 14 01:00:00 - 01:59:59 727 2.6% 0.202.6 MB 3.3% 0.74
Nov 14 02:00:00 - 02:59:59 576 2.1% 0.161.6 MB 2.1% 0.46
Nov 14 03:00:00 - 03:59:59 518 1.8% 0.141.7 MB 2.2% 0.48
Nov 14 04:00:00 - 04:15:00 131 0.5% 0.15366.3 KB 0.5% 0.41
TOTAL: 24:00:00 28065 100.0% 0.3277.9 MB 100.0% 0.92
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1xanadu-bbs.net 7241 606 0 3 0 0 0 0 6632
2newsfeed.neva.ru 6866 1575 0 1 1 0 0 0 5289
3news-out.mixmin.net 4518 19 0 0 0 0 0 0 4499
4feeder.eternal-september.org 2813 339 0 1 0 0 0 0 2473
5au2pb.net 1035 982 0 1 0 0 0 0 52
6newsfeed.fu-berlin.de 782 45 0 0 0 0 0 0 737
7newsfeeds.xmission.com 198 171 0 0 0 0 0 0 27
8nntp.pasdenom.info 153 135 0 1 0 0 0 0 17
9news.swapon.de 57 0 0 0 0 0 0 0 57
TOTAL: 9 23663 3872 0 7 1 0 0 019783

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 811
lada.talk 189
lada.auto 142
ut.test 140
alt.politics.scorched-earth 124
mailing.freebsd.svn-src-all 113
fa.freebsd.cvs-all 111
mailing.freebsd.ports-bugs 98
mailing.freebsd.svn-ports-all 90
mailing.freebsd.svn-ports-head 89
mailing.gcc.gcc-cvs 83
mozilla.dev.tree-alerts 73
alt.politics 62
mailing.openbsd.source-changes 60
pld.cvs.commit 59
mailing.freebsd.svn-src-head 58
mailing.freebsd.pkg-fallout 56
mailing.freebsd.svn-src-stable 55
mailing.freebsd.svn-src-stable-10 52
alt.windows7.general 42
TOTAL: 256 3872

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 13376
EMP (phn path) 3413
EMP (phl) 1072
EMP (phn nph) 249
PFM bot misc 197
HTML Multipart 91
Too many newsgroups 66
HTML post 23
HTML Image Tags 9
User-issued spam cancel 5
Too many newsgroups (meow) 4
Binary Payload 1
Binary Image: misplaced jpg 1
Binary: misplaced rar 1
TOTAL: 14 18508

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 608
feeder.eternal-september.org 63
au2pb.net 31
newsfeeds.xmission.com 16
news-out.mixmin.net 5
news.swapon.de 3
nntp.pasdenom.info 2
TOTAL: 7 728
Including strange strings
newsfeed.fu-berlin.de 390
news.swapon.de 52
feeder.eternal-september.org 38
news-out.mixmin.net 38
newsfeeds.xmission.com 11
au2pb.net 9
xanadu-bbs.net 5
newsfeed.neva.ru 4
TOTAL: 8 547
TOTAL: 2 1275

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1au2pb 24561 155 23323 79 0 0 0%23:59:56
2usenet.pasdenom.info 32427 129 26924 746 0 1 0%23:59:56
3xanadu-bbs 26563 75 23952 34 0 20 0%23:59:56
4fu-berlin.de 26214 43 26159 11 0 0 0%23:59:44
5news.mixmin.net 23458 17 22472 0 0 0 0%23:59:44
6xmission 32184 12 25575 3 0 0 0%23:59:56
7neva.ru 26214 7 25474 26 0 0 0%23:59:56
8eternal-september 21030 6 20025 2 0 0 0%23:59:56
9news.swapon.de 2429 1 2399 7 0 0 0%23:58:04
TOTAL: 9 215080 445 196303 908 0 21 0%215:57:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1au2pb701.1 KB255.1 KB956.2 KB0.0 KB/s4.1 KB23:59:56
2usenet.pasdenom.info587.4 KB1.5 MB2.0 MB0.0 KB/s2.4 KB23:59:56
3xanadu-bbs487.3 KB69.5 KB556.8 KB0.0 KB/s5.1 KB23:59:56
4fu-berlin.de260.7 KB14.7 KB275.4 KB0.0 KB/s5.1 KB23:59:44
5news.mixmin.net91.3 KB0.0 KB91.3 KB0.0 KB/s5.4 KB23:59:44
6neva.ru52.0 KB177.5 KB229.5 KB0.0 KB/s7.0 KB23:59:56
7xmission28.3 KB6.4 KB34.7 KB0.0 KB/s2.3 KB23:59:56
8eternal-september22.1 KB2.6 KB24.7 KB0.0 KB/s3.1 KB23:59:56
9news.swapon.de4.2 KB15.5 KB19.7 KB0.0 KB/s2.5 KB23:58:04
TOTAL: 92.2 MB2.0 MB4.2 MB0.0 KB/s3.2 KB215:57:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 288 68165.3 KB 65 0 004:33:03
2tempe0.bbox.io 38 512.0 KB 1 0 007:36:07
3challenger.nielsenmedia.com 12 11.2 KB 1 0 001:16:08
4pool-71-98-160-84.tampfl.dsl-w.verizon.net 9 11.2 KB 1 0 001:25:21
TOTAL: 4 347 75179.7 KB 68 0 085:26:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 325 68165.3 KB 65 0 068:16:04
2*.bbox.io 38 512.0 KB 1 0 007:36:07
3*.nielsenmedia.com 12 11.2 KB 1 0 001:16:08
4*.tampfl.dsl-w.verizon.net 9 11.2 KB 1 0 001:25:21
5*.ip-188-165-239.eu 53 00.0 KB 0 0 000:39:38
6*.mixmin.net2825 00.0 KB 0 0 000:27:03
7*.chello.nl 23 00.0 KB 0 0 003:37:09
8*.156.22.98.dynamic.ip.windstream.net 3 00.0 KB 0 0 002:09:01
TOTAL: 83288 75179.7 KB 68 0 085:26:35

NNRP auth users [Top 20]:

UserConn
moussa 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
162.212.88.214 1.102 1.074 0.00063:42:58
tempe0.bbox.io 3.162 0.648 0.00007:36:07
202.89.176.220 28.438 6.135 0.73504:33:03
dhcp-089-099-203-254.chello.nl 1.969 0.351 0.00003:37:09
h143.156.22.98.dynamic.ip.windstream.net 0.298 0.083 0.00002:09:01
pool-71-98-160-84.tampfl.dsl-w.verizon.net 0.654 0.139 0.00001:25:21
challenger.nielsenmedia.com 0.877 0.152 0.00001:16:08
ns342002.ip-188-165-239.eu 4.582 1.473 0.00000:39:38
snorky.mixmin.net 235.797 45.691 0.00000:27:03
69.160.37.54 1.907 0.371 0.00000:00:02
TOTAL: 10 278.786 56.117 0.73585:26:35

Curious NNRP server explorers [Top 20]:

SystemConn
snorky.mixmin.net 2825
ns342002.ip-188-165-239.eu 53
69.160.37.54 25
dhcp-089-099-203-254.chello.nl 23
162.212.88.214 12
h143.156.22.98.dynamic.ip.windstream.net 3
TOTAL: 6 2941

NNRP client timeouts [Top 20]:

SystemConnPeer
tempe0.bbox.io 12 0
162.212.88.214 4 4
h143.156.22.98.dynamic.ip.windstream.net 3 0
pool-71-98-160-84.tampfl.dsl-w.verizon.net 2 0
challenger.nielsenmedia.com 2 0
TOTAL: 5 23 4

Newsgroup request counts (by category):

CategoryCountPct
1comp 75100.0%
TOTAL: 1 75100.0%

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

NewsgroupCount
1comp.sys.ibm.pc.games.war-historical 24
2comp.arch 23
3comp.sys.ibm.pc.games.action 11
4comp.arch.embedded 4
5comp.os.vms 3
6comp.os.os2.apps 3
7comp.ai.philosophy 2
8comp.lang.awk 2
9comp.unix.aix 1
10comp.sys.ibm.pc.hardware.storage 1
11comp.sys.hp.hardware 1
TOTAL: 11 75