Daily Usenet report for csiph.com

Dec 19 04:15:00 -- Dec 20 04:15:00

Unknown entries from news log file:

First 50 / 9541 lines (0.5%)

Dec 19 04:15:01 csiph nnrpd[84917]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84918]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84919]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84920]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84921]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84922]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84923]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:15:01 csiph nnrpd[84924]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85322]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85323]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85324]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85325]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85326]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85327]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85328]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:16:01 csiph nnrpd[85329]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85358]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85357]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85360]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85359]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85356]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85361]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85355]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:17:01 csiph nnrpd[85362]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85394]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85388]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85389]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85390]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85391]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85393]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85395]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:18:01 csiph nnrpd[85392]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85428]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85429]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85430]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85431]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85432]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85433]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85434]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:19:01 csiph nnrpd[85435]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85471]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85472]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85473]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85474]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85475]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85476]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85477]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:20:01 csiph nnrpd[85478]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:21:01 csiph nnrpd[85506]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 19 04:21:01 csiph nnrpd[85507]: feeder01.blueworldhosting.com noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 79316 57.2%7.8 MB 48.1%
inn 35437 25.6%5.6 MB 34.4%
innd 21887 15.8%2.5 MB 15.4%
innfeed 2030 1.5%338.4 KB 2.0%
controlchan 12 0.0%1.4 KB 0.0%
rnews 1 0.0%0.1 KB 0.0%
TOTAL: 6 138683 100.0%16.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 195610 42.6%
Negative hits 177469 38.6%
Cache misses 52394 11.4%
Do not exist 34045 7.4%
TOTAL: 4 459518 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.545 0.0%258 0.000 2.112 70.000
article cleanup00:00:00.242 0.0%33736 0.000 0.007 0.036
article logging00:00:00.444 0.0%34262 0.000 0.013 0.059
article parse00:00:01.986 0.0%140036 0.000 0.014 0.109
article write00:00:41.109 0.0%22754 0.167 1.807 13.220
artlog/artcncl00:00:00.001 0.0%159 0.000 0.006 0.062
artlog/artparse00:00:00.000 0.0%390 0.000 0.000 0.000
data move00:00:00.214 0.0%503743 0.000 0.000 0.003
hisgrep/artcncl00:00:00.087 0.0%257 0.000 0.339 7.500
hishave/artcncl00:00:00.000 0.0%258 0.000 0.000 0.000
hishave/artparse00:00:00.003 0.0%371 0.000 0.008 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.917 0.0%459934 0.001 0.009 0.086
history sync00:00:01.445 0.0%342 0.000 4.225 69.333
history write00:00:24.818 0.0%33752 0.092 0.735 6.384
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:00.757 0.0%371 0.000 2.040 8.318
idle22:46:19.025 99.7%517514 79.044 158.409 515.103
nntp read00:00:01.748 0.0%498997 0.001 0.004 0.008
overview write00:00:58.223 0.1%22754 0.565 2.559 21.554
perl filter00:00:50.670 0.1%33733 0.945 1.502 3.429
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.374 0.0%45512 0.000 0.008 0.030
TOTAL: 22:50:54.36422:49:25.608 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.066 0.0%22847 0.000 0.003 0.021
article prepare00:00:00.000 0.0%5676 0.000 0.000 0.000
article read00:00:01.129 0.0%951 0.000 1.187 109.286
backlog stats00:00:00.090 0.0%245089 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.032 0.0%192662 0.001 0.005 0.017
data write00:00:01.235 0.0%172911 0.002 0.007 0.017
idle22:44:18.043100.0%245089 157.929 333.993 900.693
status file00:00:00.090 0.0%181 0.000 0.497 6.000
TOTAL: 22:44:26.68522:44:21.685100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:06.102 0.0%85571 0.000 0.071 0.333
idle124:28:42.769 99.5%144834 0.000 3094.044600186.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.923 0.0%300635 0.000 0.006 0.333
readart00:00:02.930 0.0%64739 0.000 0.045 95.000
TOTAL: 125:06:27.656124:28:53.724 99.5%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 145
pause 2
paused 2
reload 2
reserve 2
shutdown 12
TOTAL: 11 176

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
ymer@mulakaj.com400004
TOTAL400004

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 23 31092 5181 24944 967 16%26:04:05
2news-out.mixmin.net 6 28443 4880 20628 2935 17%24:17:32
3xanadu-bbs.net 4 34389 3968 25806 4615 11%22:49:25
4au2pb.net 5 22769 2877 19501 391 12%21:28:03
5newsfeeds.xmission.com 5 21851 1965 19697 189 8%21:40:08
6newsfeed.neva.ru 151 42524 1806 38872 1846 4%69:39:27
7newsfeed.fu-berlin.de 36 27449 1430 25809 210 5%31:23:05
8usenet.blueworldhosting.com 2684 72163 824 71014 325 1%02:30:20
9nntp.pasdenom.info 6 22007 174 21799 34 0%23:08:31
10news.swapon.de 5 2056 136 1914 6 6%23:13:56
11localhost 28 3 3 0 0 100%00:00:01
TOTAL: 11 2953 304746 23244 269984 11518 7%266:14:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.mixmin.net14.6 MB16.0 KB5.2 MB19.7 MB 73%2.6 KB
2feeder.eternal-september.org13.8 MB10.6 KB9.0 MB22.9 MB 60%3.8 KB
3xanadu-bbs.net11.8 MB0.0 KB76.3 MB88.1 MB 13%10.5 KB
4au2pb.net6.7 MB16.0 KB2.1 MB8.8 MB 75%2.8 KB
5newsfeed.neva.ru6.1 MB0.0 KB6.4 MB12.5 MB 48%3.5 KB
6newsfeeds.xmission.com6.0 MB0.0 KB838.0 KB6.8 MB 88%3.2 KB
7newsfeed.fu-berlin.de3.1 MB0.0 KB672.5 KB3.8 MB 82%2.3 KB
8usenet.blueworldhosting.com2.5 MB0.0 KB2.3 MB4.8 MB 51%4.3 KB
9nntp.pasdenom.info420.9 KB0.0 KB435.4 KB856.3 KB 49%4.1 KB
10news.swapon.de352.9 KB0.0 KB28.1 KB381.0 KB 92%2.7 KB
11localhost4.2 KB0.0 KB0.0 KB4.2 KB100%1.4 KB
TOTAL: 1165.3 MB42.5 KB103.2 MB168.6 MB 38%5.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 19 04:15:00 - 04:59:59 321 1.4% 0.121.0 MB 1.6% 0.39
Dec 19 05:00:00 - 05:59:59 485 2.1% 0.131.7 MB 2.5% 0.47
Dec 19 06:00:00 - 06:59:59 467 2.0% 0.131.5 MB 2.3% 0.42
Dec 19 07:00:00 - 07:59:59 595 2.6% 0.171.6 MB 2.4% 0.45
Dec 19 08:00:00 - 08:59:59 831 3.6% 0.232.7 MB 4.2% 0.77
Dec 19 09:00:00 - 09:59:59 1093 4.7% 0.302.8 MB 4.3% 0.80
Dec 19 10:00:00 - 10:59:59 1244 5.3% 0.353.4 MB 5.3% 0.98
Dec 19 11:00:00 - 11:59:59 1102 4.7% 0.312.9 MB 4.5% 0.84
Dec 19 12:00:00 - 12:59:59 1051 4.5% 0.292.7 MB 4.2% 0.78
Dec 19 13:00:00 - 13:59:59 1218 5.2% 0.343.6 MB 5.5% 1.03
Dec 19 14:00:00 - 14:59:59 1331 5.7% 0.373.7 MB 5.6% 1.04
Dec 19 15:00:00 - 15:59:59 1143 4.9% 0.323.1 MB 4.7% 0.87
Dec 19 16:00:00 - 16:59:59 1237 5.3% 0.343.4 MB 5.2% 0.96
Dec 19 17:00:00 - 17:59:59 1451 6.2% 0.404.2 MB 6.5% 1.20
Dec 19 18:00:00 - 18:59:59 1295 5.6% 0.363.5 MB 5.4% 0.99
Dec 19 19:00:00 - 19:59:59 1237 5.3% 0.343.3 MB 5.1% 0.95
Dec 19 20:00:00 - 20:59:59 1157 5.0% 0.323.0 MB 4.6% 0.86
Dec 19 21:00:00 - 21:59:59 1175 5.1% 0.333.0 MB 4.6% 0.86
Dec 19 22:00:00 - 22:59:59 1051 4.5% 0.292.8 MB 4.3% 0.79
Dec 19 23:00:00 - 23:59:59 937 4.0% 0.262.6 MB 4.0% 0.74
Dec 20 00:00:00 - 00:59:59 956 4.1% 0.272.9 MB 4.4% 0.82
Dec 20 01:00:00 - 01:59:59 446 1.9% 0.121.3 MB 2.0% 0.36
Dec 20 02:00:00 - 02:59:59 815 3.5% 0.232.4 MB 3.6% 0.67
Dec 20 03:00:00 - 03:59:59 488 2.1% 0.141.7 MB 2.6% 0.49
Dec 20 04:00:00 - 04:15:00 132 0.6% 0.15400.7 KB 0.6% 0.45
TOTAL: 24:00:00 23258 100.0% 0.2765.2 MB 100.0% 0.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1xanadu-bbs.net 4616 499 0 0 0 0 0 0 4117
2news-out.mixmin.net 2950 11 0 1 0 0 0 0 2938
3newsfeed.neva.ru 1849 592 0 0 0 0 0 0 1257
4feeder.eternal-september.org 986 168 0 1 0 0 0 0 817
5newsfeed.fu-berlin.de 618 61 0 0 0 0 0 0 557
6au2pb.net 417 378 0 1 0 0 0 0 38
7usenet.blueworldhosting.com 335 117 0 0 25 0 0 0 193
8newsfeeds.xmission.com 196 183 0 0 0 0 0 0 13
9nntp.pasdenom.info 35 31 0 0 0 0 0 0 4
10news.swapon.de 18 0 0 0 0 0 0 0 18
TOTAL: 10 12020 2040 0 3 25 0 0 0 9952

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 225
ut.test 194
mailing.freebsd.pkg-fallout 156
alt.politics 139
lada.talk 84
mailing.freebsd.ports-bugs 74
alt.windows7.general 63
pld.cvs.commit 44
it-alt.sport.calcio.juventus 41
mailing.openbsd.source-changes 38
mailing.freebsd.svn-ports-all 37
mailing.freebsd.svn-ports-head 35
alt.politics.scorched-earth 31
alt.survival 30
mailing.openbsd.misc 30
mpc.lists.nanog 30
spline.eisfair 28
24hoursupport.helpdesk 25
fa.openbsd.source-changes 24
mailing.freebsd.svn-src-all 23
TOTAL: 201 2040

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

GroupsCount
nz.politics.announce 17
alt.culture.china 5
uk.radio.amateur.moderated 3
TOTAL: 3 25

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4905
EMP (phn path) 2727
EMP (phl) 975
EMP (phn nph) 194
PFM bot misc 120
HTML Multipart 50
Too many newsgroups 34
Binary Image: misplaced jpg 9
HTML Image Tags 6
HTML post 4
Too many newsgroups (low_xpost) 2
Binary: misplaced arj 1
TOTAL: 12 9027

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 287
feeder.eternal-september.org 45
au2pb.net 18
news-out.mixmin.net 8
usenet.blueworldhosting.com 6
newsfeeds.xmission.com 5
nntp.pasdenom.info 3
newsfeed.fu-berlin.de 3
TOTAL: 8 375
Including strange strings
newsfeed.fu-berlin.de 440
news-out.mixmin.net 28
feeder.eternal-september.org 18
news.swapon.de 12
au2pb.net 12
usenet.blueworldhosting.com 10
xanadu-bbs.net 5
newsfeeds.xmission.com 4
nntp.pasdenom.info 1
newsfeed.neva.ru 1
TOTAL: 10 531
No colon-space in header
usenet.blueworldhosting.com 19
TOTAL: 1 19
TOTAL: 3 925

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1usenet.pasdenom.info 26646 228 22316 540 0 21 0%23:15:20
2au2pb 20870 135 20020 139 0 0 0%23:15:21
3xanadu-bbs 20389 134 19066 82 0 2 0%23:15:21
4fu-berlin.de 21573 39 21505 29 0 10 0%23:15:19
5xmission 24316 24 21088 0 0 1 0%23:15:21
6news.mixmin.net 19231 21 18361 0 0 30 0%23:15:19
7neva.ru 22490 8 21164 59 0 69 0%23:15:19
8eternal-september 18691 1 17484 3 0 1 0%23:15:20
9bwh 1821 0 1787 0 0 0 0%03:23:57
10news.swapon.de 1941 0 1914 14 0 0 0%23:11:54
TOTAL: 10 177968 590 164705 866 0 134 0%212:38:31
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1usenet.pasdenom.info715.5 KB1.1 MB1.8 MB0.0 KB/s2.4 KB23:15:20
2au2pb618.2 KB355.4 KB973.7 KB0.0 KB/s3.6 KB23:15:21
3xanadu-bbs580.0 KB150.5 KB730.5 KB0.0 KB/s3.4 KB23:15:21
4fu-berlin.de212.5 KB39.5 KB252.0 KB0.0 KB/s3.7 KB23:15:19
5news.mixmin.net91.3 KB0.0 KB91.3 KB0.0 KB/s4.3 KB23:15:19
6xmission70.7 KB0.0 KB70.7 KB0.0 KB/s2.9 KB23:15:21
7neva.ru57.6 KB234.9 KB292.4 KB0.0 KB/s4.4 KB23:15:19
8eternal-september2.4 KB4.2 KB6.6 KB0.0 KB/s1.6 KB23:15:20
9bwh0.0 KB0.0 KB0.0 KB0.0 KB/s003:23:57
10news.swapon.de0.0 KB27.9 KB27.9 KB0.0 KB/s2.0 KB23:11:54
TOTAL: 102.3 MB1.9 MB4.2 MB0.0 KB/s2.9 KB212:38:31
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 279 76185.2 KB 68 2 004:17:15
2tempe0.bbox.io 50 813.9 KB 8 1 108:15:50
3dhcp-089-099-203-254.chello.nl 5 34.2 KB 3 0 002:09:48
471.94.21.184 4 28.4 KB 2 0 000:00:04
TOTAL: 4 338 89211.7 KB 81 3 1125:05:12

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 411 78193.6 KB 70 2 0110:21:15
2*.bbox.io 50 813.9 KB 8 1 108:15:50
3*.chello.nl 5 34.2 KB 3 0 002:09:48
4*.ip-188-165-239.eu 11 00.0 KB 0 0 000:03:42
5*.blueworldhosting.com9536 00.0 KB 0 0 000:43:56
6*.hsd1.nj.comcast.net 7 00.0 KB 0 0 003:30:38
TOTAL: 610020 89211.7 KB 81 3 1125:05:12

NNRP auth users [Top 20]:

UserConn
moussa 279
kev009 1
TOTAL: 2 280

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
63.142.120.8 9.211 9.454 0.000101:07:10
tempe0.bbox.io 4.543 0.805 0.00308:15:50
162.212.88.221 0.289 0.112 0.00004:56:42
202.89.176.220 27.685 5.954 0.64704:17:15
c-73-195-247-24.hsd1.nj.comcast.net 0.621 0.088 0.00003:30:38
dhcp-089-099-203-254.chello.nl 0.500 0.083 0.00002:09:48
feeder01.blueworldhosting.com 775.738 160.669 0.00000:43:56
ns342002.ip-188-165-239.eu 0.880 0.224 0.00000:03:42
71.94.21.184 1.667 1.679 0.00000:00:04
69.160.37.54 1.795 0.340 0.00000:00:01
TOTAL: 10 822.929 179.408 0.650125:05:12

Curious NNRP server explorers [Top 20]:

SystemConn
feeder01.blueworldhosting.com 9536
63.142.120.8 100
69.160.37.54 24
ns342002.ip-188-165-239.eu 11
c-73-195-247-24.hsd1.nj.comcast.net 7
162.212.88.221 4
TOTAL: 6 9682

NNRP client timeouts [Top 20]:

SystemConnPeer
tempe0.bbox.io 13 0
c-73-195-247-24.hsd1.nj.comcast.net 7 0
dhcp-089-099-203-254.chello.nl 4 0
71.94.21.184 1 1
TOTAL: 4 25 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 85 95.5%
2news 2 2.2%
3alt 2 2.2%
TOTAL: 3 89100.0%

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

NewsgroupCount
1comp.arch 23
2comp.sys.ibm.pc.games.action 15
3comp.os.vms 15
4comp.sys.ibm.ps2.hardware 9
5comp.arch.fpga 7
6comp.os.linux.networking 5
7comp.ai.neural-nets 3
8comp.ai.philosophy 2
9news.admin.peering 2
10comp.os.os2.misc 2
11comp.arch.embedded 2
12alt.test 2
13comp.os.os2.advocacy 1
14comp.ai.nat-lang 1
TOTAL: 14 89