Daily Usenet report

Oct 6 03:00:05 -- Oct 7 03:00:02

Log entries by program
History cache
INND timer
nnrpd timer
Control commands to INND
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 / 109 lines (4.6%)
Oct 6 05:20:20 perseus controlchan[10305]: Unknown "cmsg" control by juiihung.bbs@bbs.wretch.cc
Oct 6 05:20:27 perseus controlchan[10305]: Unknown "cmsg" control by juiihung.bbs@bbs.wretch.cc
Oct 6 09:10:30 perseus controlchan[10305]: Unknown "cmsg" control by Evilspirited.bbs@bbs.wretch.cc
Oct 6 09:10:30 perseus controlchan[10305]: Unknown "cmsg" control by exssperion.bbs@bbs.wretch.cc
Oct 6 09:34:16 perseus expireover[16977]: OVDB: expiregroup: recounting alt.binaries.sony.psp

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	2273021	98.1%	197.4 MB	97.5%
nnrpd	24653	1.1%	2.4 MB	1.2%
innd	15803	0.7%	2.2 MB	1.1%
cnfsstat	2304	0.1%	401.2 KB	0.2%
controlchan	108	0.0%	11.3 KB	0.0%
expireover	1	0.0%	0.1 KB	0.0%

TOTAL: 6	2315890	100.0%	202.4 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	2254125	96.8%
Negative hits	57857	2.5%
Positive hits	15881	0.7%
Cache misses	1399	0.1%

TOTAL: 4	2329262	100.0%

INND timer:

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

article cancel	00:01:23.292	0.1%	22642	0.000	3.679	1437.000
article cleanup	00:00:25.982	0.0%	2240975	0.002	0.012	0.025
article logging	00:01:56.596	0.1%	2251102	0.004	0.052	3.493
article parse	00:19:54.688	1.4%	165996277	0.005	0.007	0.038
article write	10:31:23.367	43.8%	2199417	3.744	17.224	28.248
artlog/artcncl	00:00:01.639	0.0%	22505	0.000	0.073	9.485
artlog/artparse	00:00:00.002	0.0%	108	0.000	0.019	1.000
data move	00:03:50.735	0.3%	175585190	0.001	0.001	0.004
hisgrep/artcncl	00:01:15.780	0.1%	17033	0.000	4.449	1437.000
hishave/artcncl	00:00:02.763	0.0%	22642	0.000	0.122	112.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:44.887	0.8%	2307452	0.001	0.305	7.835
history sync	00:00:00.314	0.0%	291	0.000	1.079	3.000
history write	00:52:34.650	3.7%	2199417	0.027	1.434	14.158
hiswrite/artcncl	00:00:02.954	0.0%	5609	0.000	0.527	560.000
idle	06:24:50.030	26.7%	155407299	0.127	0.149	0.659
nntp read	00:21:46.064	1.5%	164229155	0.005	0.008	0.035
overview write	04:47:57.489	20.0%	2199417	1.293	7.855	133.613
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%	110	0.000	0.009	0.015

TOTAL: 24:00:23.981	23:39:11.233	98.5%	-	-	-	-

nnrpd timer:

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

hisgrep	00:32:45.628	3.1%	72334	0.000	27.174	67.285
idle	13:00:25.240	74.9%	242365	0.000	193.201	200255.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	01:13:08.105	7.0%	774345	0.000	5.667	68096.391
readart	01:05:35.488	6.3%	147309	0.000	26.716	576.333

TOTAL: 17:22:07.362	15:51:54.461	91.3%	-	-	-	-

Control commands to INND:

Command	Number

flush	144
flushlogs	1
paused	2

TOTAL: 3	147

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	346	2252135	2221164	0	30971	98%	485:01:26

TOTAL: 1		346	2252135	2221164	0	30971	98%	485:01:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	427.0 GB	3.9 MB	427.0 GB	99%	198.8 KB	485:01:26

TOTAL: 1		427.0 GB	3.9 MB	427.0 GB	99%	198.8 KB	485:01:26

Incoming articles:

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

Oct 6 03:00:05 - 03:59:59	95354	4.3%	26.52	18.2 GB	4.3%	5295.53
Oct 6 04:00:00 - 04:59:59	86636	3.9%	24.07	17.3 GB	4.1%	5036.88
Oct 6 05:00:00 - 05:59:59	83112	3.8%	23.09	16.9 GB	4.0%	4911.97
Oct 6 06:00:00 - 06:59:59	80931	3.7%	22.48	16.1 GB	3.8%	4680.89
Oct 6 07:00:00 - 07:59:59	81546	3.7%	22.65	16.1 GB	3.8%	4675.45
Oct 6 08:00:00 - 08:59:59	88882	4.0%	24.69	17.8 GB	4.2%	5182.28
Oct 6 09:00:00 - 09:59:59	75877	3.5%	21.08	15.4 GB	3.6%	4488.35
Oct 6 10:00:00 - 10:59:59	85419	3.9%	23.73	18.0 GB	4.3%	5229.84
Oct 6 11:00:00 - 11:59:59	85449	3.9%	23.74	16.6 GB	3.9%	4827.36
Oct 6 12:00:00 - 12:59:59	97563	4.4%	27.10	18.4 GB	4.4%	5369.49
Oct 6 13:00:00 - 13:59:59	95674	4.4%	26.58	19.0 GB	4.5%	5520.54
Oct 6 14:00:00 - 14:59:59	96221	4.4%	26.73	19.5 GB	4.6%	5686.43
Oct 6 15:00:00 - 15:59:59	90793	4.1%	25.22	18.5 GB	4.4%	5393.18
Oct 6 16:00:00 - 16:59:59	88170	4.0%	24.49	17.6 GB	4.2%	5118.21
Oct 6 17:00:00 - 17:59:59	89515	4.1%	24.87	18.0 GB	4.3%	5236.86
Oct 6 18:00:00 - 18:59:59	93079	4.2%	25.86	18.2 GB	4.3%	5310.46
Oct 6 19:00:00 - 19:59:59	93486	4.3%	25.97	18.4 GB	4.4%	5363.23
Oct 6 20:00:00 - 20:59:59	100085	4.6%	27.80	18.3 GB	4.3%	5330.17
Oct 6 21:00:00 - 21:59:59	94577	4.3%	26.27	18.4 GB	4.3%	5349.86
Oct 6 22:00:00 - 22:59:59	98185	4.5%	27.27	18.4 GB	4.4%	5356.16
Oct 6 23:00:00 - 23:59:59	95948	4.4%	26.65	18.4 GB	4.4%	5365.77
Oct 7 00:00:00 - 00:59:59	90501	4.1%	25.14	15.2 GB	3.6%	4414.63
Oct 7 01:00:00 - 01:59:59	110454	5.0%	30.68	15.7 GB	3.7%	4571.15
Oct 7 02:00:00 - 02:59:59	101146	4.6%	28.10	18.2 GB	4.3%	5288.95
Oct 7 03:00:00 - 03:00:02	121	0.0%	60.50	20.4 MB	0.0%	10430.90

TOTAL: 23:59:57	2198724	100.0%	25.45	422.3 GB	100.0%	5125.26

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	88.1 GB	63.9%	90	820.72	2.04
02	BIN	137.8 GB	123.1 GB	89.3%	90	829.53	2.02
03	SMALL	13.0 GB	9.8 GB	75.3%	5	5.02	31.55
04	BIN	80.1 GB	62.1 GB	77.5%	58	429.66	2.26
05	BIN	80.1 GB	64.3 GB	80.3%	78	431.58	2.25
06	SMALL	13.0 GB	9.8 GB	75.1%	5	5.06	31.29
07	BIN	80.1 GB	54.3 GB	67.8%	58	427.97	2.27
08	BIN	80.1 GB	57.7 GB	72.0%	78	430.31	2.26
09	SMALL	13.0 GB	9.5 GB	72.7%	5	4.97	31.84
10	BIN	80.1 GB	48.5 GB	60.5%	58	427.08	2.28
11	BIN	80.1 GB	67.7 GB	84.5%	78	432.35	2.25
12	SMALL	13.0 GB	9.7 GB	74.7%	5	5.07	31.20
13	BIN	80.1 GB	47.2 GB	59.0%	58	426.99	2.28
14	BIN	80.1 GB	54.1 GB	67.6%	78	430.57	2.26

TOTAL: 14	-	968.5 GB	706.0 GB	72.9%	744	368.20	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	51684	41316	0	245	0	0	0	0	10123
2	?	108	0	0	0	0	0	0	0	108

TOTAL: 2		51792	41316	0	245	0	0	0	0	10231

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.sounds.mp3.test	1614
alt.binaries.	561
alt.binaries.pictures.eroctica.rape	559
alt.binaries.dvd-r	552
alt.binaries.pictures.erotica.feti	522
alt.binaries.pictures.eroctica.torture	521
alt.binaries.pictures.erotic.unusual.object.penetrat	505
alt.binaries.easeynews.pervert	487
alt.binaries.pictures.erotica.asian.female	437
korea.binaries.tv	411
alt.binaries.erotica.freckles	366
alt.binaries.nonnude	333
alt.binaries.jerry	328
cuhk.society.hualien	194
alt.binaries.pictures.katie	166
alt.binaries.pictures.amateur.butts	139
alt.binaries.pictures.erotica.highheels	139
stu.virus	138
alt.binaries.game	130
alt.binaries.pictures.erotica.teen.male.twinks	112

TOTAL: 14756	41316

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Bad command received
	192.168.33.37	18
	TOTAL: 1	18
Including strange strings
	192.168.33.37	10122
	TOTAL: 1	10122

TOTAL: 2		10140

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	11	72317	6.0 GB	36	0	0	13:24:03
2	ts30-b243.moscow.dial.rol.ru	9	28	1019.4 KB	8	0	0	00:11:40
3	ts30-b62.moscow.dial.rol.ru	15	23	76.4 KB	7	0	0	01:25:20
4	ts2-b81.syktyvkar.dial.rol.ru	5	8	540.9 KB	3	0	0	00:08:04
5	ts16-a87.moscow.dial.rol.ru	2	4	304.0 KB	3	0	0	00:19:53
6	194.67.5.188	3	3	56.8 KB	29	0	0	00:18:26
7	news.bytecity.ru	5	1	1.4 KB	211	0	0	00:00:06

TOTAL: 7		50	72384	6.0 GB	297	0	0	17:21:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	11	72317	6.0 GB	36	0	0	13:24:03
2	*.moscow.dial.rol.ru	27	55	1.4 MB	18	0	0	02:06:55
3	*.syktyvkar.dial.rol.ru	5	8	540.9 KB	3	0	0	00:08:04
4	unresolved	15	3	56.8 KB	29	0	0	00:31:06
5	*.bytecity.ru	5	1	1.4 KB	211	0	0	00:00:06
6	*.irkutsk.dial.rol.ru	2	0	0.0 KB	0	0	0	00:00:02
7	*.pcs-net.net	2	0	0.0 KB	0	0	0	00:02:00
8	*.volgograd.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:30
9	*.tony.pp.ru	71	0	0.0 KB	0	0	0	00:37:57
10	*.voronezh.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:48
11	*.rol.ru	5818	0	0.0 KB	0	0	0	00:22:07
12	*.sovintel.net	17	0	0.0 KB	0	0	0	00:00:31
13	*.lek.ru	2	0	0.0 KB	0	0	0	00:00:18
14	*.nsk.dial.rol.ru	19	0	0.0 KB	0	0	0	00:00:48
15	*.spb.dial.rol.ru	2	0	0.0 KB	0	0	0	00:00:01
16	*.nn.ru	53	0	0.0 KB	0	0	0	00:00:33

TOTAL: 16		6051	72384	6.0 GB	297	0	0	17:17:32

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	43.308	377.040	0.000	13:24:03
ts30-b62.moscow.dial.rol.ru	0.044	0.036	0.000	01:25:20
net1-x-ring.tony.pp.ru	43.056	404.279	0.000	00:37:57
ts16-a87.moscow.dial.rol.ru	0.004	0.024	0.000	00:19:53
194.67.5.188	0.272	0.368	0.000	00:18:26
ts30-b243.moscow.dial.rol.ru	0.028	0.048	0.000	00:11:40
balancer-b.rol.ru	3.612	3.716	0.000	00:11:04
balancer-a.rol.ru	3.684	3.864	0.000	00:11:02
194.67.5.183	0.000	0.000	0.000	00:10:25
ts18-a178.moscow.dial.rol.ru	0.032	0.008	0.000	00:10:00
ts2-b81.syktyvkar.dial.rol.ru	0.036	0.020	0.000	00:08:04
news.pcs-net.net	0.000	0.000	0.000	00:02:00
212.46.236.125	0.020	0.024	0.000	00:01:54
?	0.000	0.032	0.000	00:01:36
cornholio.migtel.ru	1.576	1.440	0.000	00:01:32
ts1-b137.samara.dial.rol.ru	0.012	0.012	0.000	00:01:02
ts2-b167.nsk.dial.rol.ru	0.020	0.036	0.000	00:00:48
ts2-a36.voronezh.dial.rol.ru	0.012	0.004	0.000	00:00:48
82.140.73.76	0.080	0.120	0.000	00:00:41
gray.nn.ru	0.108	0.072	0.000	00:00:33

TOTAL: 33	96.272	791.415	0.000	17:21:04

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2932
balancer-a.rol.ru	2886
net1-x-ring.tony.pp.ru	71
gray.nn.ru	53
ts2-b167.nsk.dial.rol.ru	19
fw-nat.sovintel.net	17
212.46.236.216	6
212.46.236.125	5
news.lek.ru	2
ts1-a111.irkutsk.dial.rol.ru	2
news.pcs-net.net	2
ts5-b246.spb.dial.rol.ru	2
ts2-a93.volgograd.dial.rol.ru	1
ts1-b137.samara.dial.rol.ru	1
ts2-a36.voronezh.dial.rol.ru	1
ts18-a178.moscow.dial.rol.ru	1
194.67.5.183	1

TOTAL: 17	6002

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	1072
82.140.73.76	63
ts1-b137.samara.dial.rol.ru	7
ts1-a221.bratsk.dial.rol.ru	4
195.28.36.242	1
83.167.116.18	1
83.220.64.45	1
12.147.176.2	1
83.220.64.114	1

TOTAL: 9	1151

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	27

TOTAL: 1	27

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts30-b62.moscow.dial.rol.ru	4	0
194.67.5.188	1	0
ts18-a178.moscow.dial.rol.ru	1	0
ts16-a87.moscow.dial.rol.ru	1	0
194.67.5.183	1	0

TOTAL: 5	8	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	71258	99.9%
2	pl	16	0.0%
3	hr	14	0.0%
4	japan2nd	13	0.0%
5	de	6	0.0%
6	lists	1	0.0%

TOTAL: 6		71308	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	58937
2	alt.binaries.pictures.bras	7754
3	alt.binaries.pictures.artpics	4550
4	japan2nd.binaries.pictures.erotica.lolita	13
5	pl.rec.anime	10
6	alt.binaries.nospam.female.bodyhair	8
7	de.etc.sprache.deutsch	6
8	pl.sci.historia	6
9	hr.sci.jezik	5
10	hr.rec.film.anime	5
11	alt.binaries.pictures.erotica	3
12	hr.rec.humor.best-of-usenet	3
13	alt.binaries.pictures.erotica.incest	3
14	alt.sex.binaries.erotica.teen	2
15	lists.freebsd.isp	1
16	alt.binaries.pictures.erotica.amateur.lolita	1
17	hr.rec.film.crtani.binaries	1

TOTAL: 17		71308