Daily Usenet report

Sep 9 03:00:03 -- Sep 10 03:00:02

Log entries by program
History cache
INND timer
nnrpd timer
Control commands to INND
Control Channel
Incoming Feeds (INN)
Incoming Volume (INN)
Incoming articles
CNFS buffer status
Sites sending bad articles
Unwanted newsgroups
Miscellaneous innd statistics
NNRP readership statistics
NNRP readership statistics (by domain)
NNRP total resource statistics
Curious NNRP server explorers
NNRP no permission clients
NNRP gethostbyaddr failures
NNRP client timeouts
Newsgroup request counts (by category)
Newsgroup request counts (by newsgroup)

First 5 / 47 lines (10.6%)
Sep 9 06:20:10 perseus controlchan[28453]: Unknown "cmsg" control by calpis0.bbs@bbs.wretch.cc
Sep 9 06:30:13 perseus controlchan[28453]: Unknown "cmsg" control by AIRAQ.bbs@bbs.wretch.cc
Sep 9 07:50:10 perseus controlchan[28453]: Unknown "cmsg" control by ether.bbs@bbs.wretch.cc
Sep 9 07:50:10 perseus controlchan[28453]: Unknown "cmsg" control by richard168.bbs@bbs.wretch.cc
Sep 9 07:50:10 perseus controlchan[28453]: Unknown "cmsg" control by AIRAQ.bbs@bbs.wretch.cc

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	2059867	97.8%	182.1 MB	97.2%
innd	23353	1.1%	2.8 MB	1.5%
nnrpd	20357	1.0%	2.0 MB	1.1%
cnfsstat	2304	0.1%	399.0 KB	0.2%
controlchan	50	0.0%	5.7 KB	0.0%

TOTAL: 5	2105931	100.0%	187.3 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	2044721	98.0%
Negative hits	36948	1.8%
Positive hits	2097	0.1%
Cache misses	1819	0.1%

TOTAL: 4	2085585	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)

article cancel	00:02:04.123	0.1%	16790	0.000	7.393	1590.000
article cleanup	00:00:22.551	0.0%	2029132	0.003	0.011	0.022
article logging	00:01:55.407	0.1%	2043223	0.004	0.056	4.973
article parse	00:17:44.059	1.2%	163375018	0.004	0.007	0.034
article write	09:07:19.075	38.0%	2009373	2.520	16.343	47.048
artlog/artcncl	00:00:02.315	0.0%	16268	0.000	0.142	28.000
artlog/artparse	00:00:00.000	0.0%	24	0.000	0.000	0.000
data move	00:03:35.317	0.2%	172982753	0.001	0.001	0.003
hisgrep/artcncl	00:01:58.871	0.1%	3806	0.000	31.233	1590.000
hishave/artcncl	00:00:01.758	0.0%	16790	0.000	0.105	377.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:16:26.203	1.1%	2069704	0.000	0.476	8.139
history sync	00:00:00.308	0.0%	290	0.000	1.062	3.000
history write	00:52:59.334	3.7%	2009373	0.020	1.582	11.030
hiswrite/artcncl	00:00:01.078	0.0%	12984	0.000	0.083	13.000
idle	08:30:54.689	35.5%	155048001	0.128	0.198	1.586
nntp read	00:19:48.843	1.4%	162133210	0.005	0.007	0.030
overview write	04:04:24.467	17.0%	2009373	1.348	7.298	136.646
perl filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.001	0.0%	52	0.000	0.019	0.200

TOTAL: 23:59:43.696	23:39:38.399	98.6%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)

hisgrep	00:14:01.596	0.8%	30063	0.000	27.994	71.797
idle	25:22:46.062	92.2%	67839	0.000	1346.807	200464.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:49:38.554	3.0%	454252	0.000	6.557	3158.407
readart	00:30:43.046	1.9%	61433	0.000	30.001	220.778

TOTAL: 27:32:17.123	26:57:09.258	97.9%	-	-	-	-

Control commands to INND:

Command	Number

flush	144
flushlogs	1
paused	2

TOTAL: 3	147

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK

abekar@bonbon.net	1	0	0	0	0	0
alexander.bartolich@gmx.at	0	1	0	0	0	1
defence@alt-comp-config.at	1	0	0	0	0	1

TOTAL	2	1	0	0	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	344	2020009	2000313	1	19695	99%	486:55:54

TOTAL: 1		344	2020009	2000313	1	19695	99%	486:55:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	380.6 GB	3.3 MB	380.6 GB	99%	197.6 KB	486:55:54

TOTAL: 1		380.6 GB	3.3 MB	380.6 GB	99%	197.6 KB	486:55:54

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec

Sep 9 03:00:03 - 03:59:59	87245	4.3%	24.25	17.2 GB	4.5%	5020.35
Sep 9 04:00:00 - 04:59:59	85734	4.3%	23.82	16.6 GB	4.4%	4849.19
Sep 9 05:00:00 - 05:59:59	86359	4.3%	23.99	17.5 GB	4.6%	5095.29
Sep 9 06:00:00 - 06:59:59	78889	3.9%	21.91	15.8 GB	4.2%	4615.10
Sep 9 07:00:00 - 07:59:59	85470	4.3%	23.74	17.1 GB	4.5%	4979.75
Sep 9 08:00:00 - 08:59:59	83829	4.2%	23.29	16.8 GB	4.4%	4907.00
Sep 9 09:00:00 - 09:59:59	80857	4.0%	22.46	16.6 GB	4.4%	4839.60
Sep 9 10:00:00 - 10:59:59	82719	4.1%	22.98	16.5 GB	4.3%	4814.78
Sep 9 11:00:00 - 11:59:59	90310	4.5%	25.09	18.3 GB	4.8%	5338.94
Sep 9 12:00:00 - 12:59:59	93410	4.6%	25.95	19.0 GB	5.0%	5523.60
Sep 9 13:00:00 - 13:59:59	91807	4.6%	25.50	17.6 GB	4.6%	5134.53
Sep 9 14:00:00 - 14:59:59	85658	4.3%	23.79	16.8 GB	4.4%	4899.29
Sep 9 15:00:00 - 15:59:59	81915	4.1%	22.75	16.0 GB	4.2%	4648.04
Sep 9 16:00:00 - 16:59:59	79110	3.9%	21.98	15.1 GB	4.0%	4386.94
Sep 9 17:00:00 - 17:59:59	78175	3.9%	21.72	14.7 GB	3.9%	4291.25
Sep 9 18:00:00 - 18:59:59	77486	3.9%	21.52	14.1 GB	3.7%	4110.79
Sep 9 19:00:00 - 19:59:59	83680	4.2%	23.24	13.3 GB	3.5%	3881.03
Sep 9 20:00:00 - 20:59:59	76570	3.8%	21.27	12.9 GB	3.4%	3762.64
Sep 9 21:00:00 - 21:59:59	76187	3.8%	21.16	12.6 GB	3.3%	3681.92
Sep 9 22:00:00 - 22:59:59	77254	3.8%	21.46	12.7 GB	3.4%	3709.26
Sep 9 23:00:00 - 23:59:59	85694	4.3%	23.80	15.7 GB	4.1%	4582.27
Sep 10 00:00:00 - 00:59:59	82899	4.1%	23.03	14.7 GB	3.9%	4269.43
Sep 10 01:00:00 - 01:59:59	87753	4.4%	24.38	15.4 GB	4.0%	4471.19
Sep 10 02:00:00 - 02:59:59	90627	4.5%	25.17	16.8 GB	4.4%	4894.75
Sep 10 03:00:00 - 03:00:02	65	0.0%	32.50	11.9 MB	0.0%	6099.18

TOTAL: 23:59:59	2009702	100.0%	23.26	380.1 GB	100.0%	4612.81

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	30.7 GB	22.3%	77	753.01	2.22
02	BIN	137.8 GB	54.4 GB	39.5%	77	763.06	2.19
03	SMALL	13.0 GB	10.6 GB	81.6%	4	5.41	29.23
04	BIN	80.1 GB	17.1 GB	21.3%	47	386.76	2.51
05	BIN	80.1 GB	13.3 GB	16.6%	67	386.64	2.51
06	SMALL	13.0 GB	10.6 GB	81.3%	4	5.46	28.97
07	BIN	80.1 GB	11.9 GB	14.8%	47	385.50	2.52
08	BIN	80.1 GB	9.2 GB	11.5%	67	386.62	2.51
09	SMALL	13.0 GB	10.4 GB	79.5%	4	5.42	29.18
10	BIN	80.1 GB	8.2 GB	10.2%	47	385.57	2.52
11	BIN	80.1 GB	15.3 GB	19.1%	67	387.78	2.51
12	SMALL	13.0 GB	10.6 GB	81.1%	4	5.42	29.17
13	BIN	80.1 GB	7.7 GB	9.6%	47	385.25	2.52
14	BIN	80.1 GB	5.8 GB	7.3%	67	386.83	2.51

TOTAL: 14	-	968.5 GB	215.7 GB	22.3%	626	332.11	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	33871	19654	0	110	0	0	0	0	14107
2	?	24	0	0	0	0	0	0	0	24

TOTAL: 2		33895	19654	0	110	0	0	0	0	14131

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.uar	1801
alt.binaries.pictures.amateur.butts	1047
pedo.japanese.fetish.lolita	307
alt.binaries.multimedia.erotica.transexuals	252
alt.binaries.pictures.erotica.asian.female	215
alt.binaries.pictures.senior-citizens	173
alt.binaries.jerry	170
alt.binaries.erotica.amateur.gaymen	135
alt.binaries.pictures.bdstudio	114
alt.binaries.pictures.black.erotica	112
alt.binaries.pictures.erotica.teenboys	109
ntu-kpi.test	108
alt.prowling-for-gay-sex	98
alt.sex.femdom.alt.sex.bondage	95
alt.binaries.pictures.black.erotica.females	95
alt.sex.femdom.alt	94
alt.binaries.sandra.model	94
alt.binaries.multimedia.transsexuals	93
corel.PaintShopProPhotoX2	90
alt.sex.femdom.alt.personals.bondage	86

TOTAL: 1670	19654

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	14107
	TOTAL: 1	14107

TOTAL: 1		14107

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	4	30055	3.6 GB	12	0	0	11:07:39
2	ts12-b58.spb.dial.rol.ru	3	99	160.0 KB	15	0	0	00:05:59
3	ts1-a48.irkutsk.dial.rol.ru	3	72	149.2 KB	2	0	0	00:28:00
4	ts1-b119.krasnoyarsk.dial.rol.ru	6	12	27.9 KB	1	0	0	00:10:17
5	ts18-a103.moscow.dial.rol.ru	2	5	269.3 KB	1	0	0	00:06:04
6	ts16-b147.moscow.dial.rol.ru	1	4	296.4 KB	10	0	0	00:12:37

TOTAL: 6		19	30247	3.6 GB	41	0	0	27:30:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	4	30055	3.6 GB	12	0	0	11:07:39
2	*.spb.dial.rol.ru	8	99	160.0 KB	15	0	0	00:06:05
3	*.irkutsk.dial.rol.ru	3	72	149.2 KB	2	0	0	00:28:00
4	*.krasnoyarsk.dial.rol.ru	6	12	27.9 KB	1	0	0	00:10:17
5	*.moscow.dial.rol.ru	46	9	565.8 KB	11	0	0	02:04:15
6	*.barnaul.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:21
7	*.sovintel.net	13	0	0.0 KB	0	0	0	00:00:16
8	unresolved	78	0	0.0 KB	0	0	0	12:52:15
9	*.tony.pp.ru	69	0	0.0 KB	0	0	0	00:02:44
10	*.lek.ru	1	0	0.0 KB	0	0	0	00:00:12
11	*.pcs-net.net	1	0	0.0 KB	0	0	0	00:00:01
12	*.rol.ru	5817	0	0.0 KB	0	0	0	00:18:59
13	*.nn.ru	29	0	0.0 KB	0	0	0	00:00:20
14	*.nnovgorod.dial.rol.ru	1	0	0.0 KB	0	0	0	00:11:06
15	*.nsk.dial.rol.ru	20	0	0.0 KB	0	0	0	00:00:13

TOTAL: 15		6097	30247	3.6 GB	41	0	0	27:25:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed

194.154.66.131	0.132	0.140	0.000	12:52:15
keyhole.sovam.com	14.840	135.468	0.000	11:07:39
ts1-a48.irkutsk.dial.rol.ru	0.016	0.012	0.000	00:28:00
ts23-a103.moscow.dial.rol.ru	0.024	0.032	0.000	00:27:55
ts23-b100.moscow.dial.rol.ru	0.000	0.004	0.000	00:16:40
ts23-b230.moscow.dial.rol.ru	0.004	0.008	0.000	00:14:37
ts24-a100.moscow.dial.rol.ru	0.060	0.044	0.000	00:14:26
ts16-b147.moscow.dial.rol.ru	0.004	0.008	0.000	00:12:37
ts4-a190.nnovgorod.dial.rol.ru	0.004	0.000	0.000	00:11:06
ts1-b119.krasnoyarsk.dial.rol.ru	0.044	0.044	0.000	00:10:17
ts16-a60.moscow.dial.rol.ru	0.004	0.000	0.000	00:10:05
ts23-a114.moscow.dial.rol.ru	0.000	0.000	0.000	00:10:00
balancer-b.rol.ru	3.476	3.444	0.000	00:09:55
balancer-a.rol.ru	3.468	3.596	0.000	00:09:03
ts18-a234.moscow.dial.rol.ru	0.004	0.008	0.000	00:06:54
ts18-a103.moscow.dial.rol.ru	0.004	0.000	0.000	00:06:04
ts12-b58.spb.dial.rol.ru	0.008	0.028	0.000	00:05:59
ts24-b163.moscow.dial.rol.ru	0.004	0.004	0.000	00:03:36
?	0.024	0.064	0.000	00:02:54
net1-x-ring.tony.pp.ru	0.128	0.112	0.000	00:02:44

TOTAL: 48	22.904	143.716	0.000	27:30:45

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2911
balancer-a.rol.ru	2906
194.154.66.131	78
net1-x-ring.tony.pp.ru	69
gray.nn.ru	29
ts2-a102.nsk.dial.rol.ru	20
fw-nat.sovintel.net	13
ts24-b227.moscow.dial.rol.ru	6
ts5-a154.spb.dial.rol.ru	5
ts18-a28.moscow.dial.rol.ru	5
ts23-a172.moscow.dial.rol.ru	5
ts23-a12.moscow.dial.rol.ru	4
ts9-b52.moscow.dial.rol.ru	4
ts24-a210.moscow.dial.rol.ru	3
ts23-b230.moscow.dial.rol.ru	2
ts16-a60.moscow.dial.rol.ru	2
ts27-b176.moscow.dial.rol.ru	2
ts23-a114.moscow.dial.rol.ru	1
news.pcs-net.net	1
ts23-a103.moscow.dial.rol.ru	1

TOTAL: 32	6079

NNRP no permission clients:

System	Conn

82.140.73.76	143
87.245.178.164	37
mcc-dyn-30-53.kosnet.ru	7
84.47.156.86	1
83.220.64.94	1
195.28.36.242	1
83.220.64.64	1
83.220.64.75	1
ts24-b163.moscow.dial.rol.ru	1
89.184.226.158	1

TOTAL: 10	194

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	48

TOTAL: 1	48

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts1-a48.irkutsk.dial.rol.ru	2	0
ts23-a114.moscow.dial.rol.ru	1	0
ts1-b119.krasnoyarsk.dial.rol.ru	1	0
194.154.66.131	1	0
ts23-b230.moscow.dial.rol.ru	1	0
ts23-b100.moscow.dial.rol.ru	1	0
ts4-a190.nnovgorod.dial.rol.ru	1	0
ts16-a60.moscow.dial.rol.ru	1	0
ts24-a100.moscow.dial.rol.ru	1	0

TOTAL: 9	10	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	30105	99.4%
2	hr	95	0.3%
3	microsoft	72	0.2%
4	fido7	13	0.0%

TOTAL: 4		30285	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	25158
2	alt.binaries.pictures.artpics	2935
3	alt.binaries.pictures.bras	2000
4	hr.rec.humor	95
5	microsoft.public.ru.russian.windows.vista	72
6	fido7.ru.symbian	12
7	alt.binaries.erotica.pregnant-movies	5
8	alt.binaries.pictures.erotica.incest	3
9	alt.games.final-fantasy.people.humor.heh	2
10	alt.humor.mylarcity	1
11	fido7.ru.computer.humor	1
12	alt.binaries.erotica.pregnant	1

TOTAL: 12		30285