Daily Usenet report

Oct 9 03:00:02 -- Oct 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 / 13 lines (38.5%)
Oct 9 04:01:01 perseus controlchan[31034]: Unknown "cmsg" control by sux0116.bbs@bbs.cs.nctu.edu.tw
Oct 9 07:30:50 perseus controlchan[31034]: Unknown "cmsg" control by happypanda.bbs@bbs.cs.nctu.edu.tw
Oct 9 09:01:08 perseus controlchan[31034]: Unknown "cmsg" control by homehouse.bbs@bbs.cs.nctu.edu.tw
Oct 9 09:05:49 perseus controlchan[31034]: Unknown "cmsg" control by tetsu.bbs@bbs.cs.nctu.edu.tw
Oct 9 09:35:57 perseus controlchan[31034]: Unknown "cmsg" control by pgli.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	763244	96.3%	68.3 MB	95.2%
nnrpd	18122	2.3%	1.8 MB	2.5%
innd	9061	1.1%	1.3 MB	1.7%
cnfsstat	2304	0.3%	398.7 KB	0.5%
controlchan	15	0.0%	1.7 KB	0.0%

TOTAL: 5	792746	100.0%	71.7 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	742030	95.0%
Negative hits	36814	4.7%
Positive hits	1912	0.2%
Cache misses	488	0.1%

TOTAL: 4	781244	100.0%

INND timer:

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

article cancel	00:00:13.450	0.0%	3747	0.000	3.590	1037.000
article cleanup	00:00:06.697	0.0%	741362	0.000	0.009	0.029
article logging	00:01:47.605	0.1%	759791	0.000	0.142	15.113
article parse	00:02:43.901	0.2%	37624397	0.002	0.004	0.010
article write	01:17:53.494	5.4%	690310	0.215	6.770	33.381
artlog/artcncl	00:00:01.750	0.0%	3702	0.000	0.473	410.250
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:00:42.956	0.0%	40451279	0.000	0.001	0.002
hisgrep/artcncl	00:00:08.525	0.0%	2359	0.000	3.614	1018.500
hishave/artcncl	00:00:02.179	0.0%	3747	0.000	0.582	1037.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:10:14.117	0.7%	778240	0.000	0.789	18.792
history sync	00:00:00.252	0.0%	309	0.000	0.816	9.000
history write	00:35:40.950	2.5%	690310	0.010	3.101	31.328
hiswrite/artcncl	00:00:00.966	0.0%	1388	0.000	0.696	245.000
idle	20:00:53.149	83.4%	37243058	0.724	1.935	23.782
nntp read	00:03:12.415	0.2%	37615671	0.002	0.005	0.009
overview write	01:41:30.112	7.0%	690310	0.729	8.822	69.405
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.000	0.0%	14	0.000	0.000	0.000

TOTAL: 24:00:21.614	23:55:12.518	99.6%	-	-	-	-

nnrpd timer:

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

hisgrep	00:02:25.192	0.4%	12258	0.000	11.845	18.630
idle	10:13:20.004	94.8%	30966	0.000	1188.400	179572.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:14:33.784	2.2%	92375	0.000	9.459	3856.206
readart	00:05:13.760	0.8%	24687	0.000	12.710	31.696

TOTAL: 10:47:18.666	10:35:32.740	98.2%	-	-	-	-

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

news@news2.open-news-network.org	0	0	1	0	0	1
no_spam@all.please	1	0	0	0	0	1

TOTAL	1	0	1	0	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	635	748816	698876	1	49939	93%	475:04:05

TOTAL: 1		635	748816	698876	1	49939	93%	475:04:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	55.2 GB	469.5 KB	55.2 GB	99%	77.3 KB	475:04:05

TOTAL: 1		55.2 GB	469.5 KB	55.2 GB	99%	77.3 KB	475:04:05

Incoming articles:

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

Oct 9 03:00:02 - 03:59:59	27944	4.0%	7.77	1.6 GB	2.9%	466.15
Oct 9 04:00:00 - 04:59:59	29807	4.3%	8.28	2.2 GB	4.1%	653.52
Oct 9 05:00:00 - 05:59:59	22740	3.3%	6.32	3.0 GB	5.5%	866.77
Oct 9 06:00:00 - 06:59:59	20881	3.0%	5.80	2.9 GB	5.3%	844.88
Oct 9 07:00:00 - 07:59:59	22414	3.2%	6.23	2.4 GB	4.3%	691.51
Oct 9 08:00:00 - 08:59:59	27427	4.0%	7.62	1.4 GB	2.6%	409.50
Oct 9 09:00:00 - 09:59:59	24879	3.6%	6.91	2.8 GB	5.1%	817.00
Oct 9 10:00:00 - 10:59:59	27845	4.0%	7.73	2.8 GB	5.2%	829.16
Oct 9 11:00:00 - 11:59:59	27465	4.0%	7.63	2.0 GB	3.6%	575.01
Oct 9 12:00:00 - 12:59:59	26444	3.8%	7.35	2.2 GB	4.1%	646.06
Oct 9 13:00:00 - 13:59:59	31353	4.5%	8.71	2.9 GB	5.3%	835.28
Oct 9 14:00:00 - 14:59:59	32375	4.7%	8.99	2.6 GB	4.8%	762.77
Oct 9 15:00:00 - 15:59:59	25665	3.7%	7.13	2.9 GB	5.4%	856.35
Oct 9 16:00:00 - 16:59:59	30100	4.4%	8.36	2.9 GB	5.3%	844.28
Oct 9 17:00:00 - 17:59:59	29677	4.3%	8.24	2.5 GB	4.6%	736.60
Oct 9 18:00:00 - 18:59:59	32291	4.7%	8.97	2.2 GB	3.9%	626.27
Oct 9 19:00:00 - 19:59:59	34437	5.0%	9.57	2.2 GB	4.1%	654.54
Oct 9 20:00:00 - 20:59:59	36215	5.2%	10.06	2.2 GB	4.1%	651.27
Oct 9 21:00:00 - 21:59:59	31842	4.6%	8.85	2.3 GB	4.3%	682.88
Oct 9 22:00:00 - 22:59:59	27294	4.0%	7.58	2.6 GB	4.8%	757.31
Oct 9 23:00:00 - 23:59:59	30095	4.4%	8.36	1.7 GB	3.1%	488.47
Oct 10 00:00:00 - 00:59:59	26203	3.8%	7.28	1.0 GB	1.9%	296.99
Oct 10 01:00:00 - 01:59:59	36598	5.3%	10.17	1.4 GB	2.5%	399.53
Oct 10 02:00:00 - 02:59:59	28088	4.1%	7.80	1.7 GB	3.2%	504.99
Oct 10 03:00:00 - 03:00:02	13	0.0%	6.50	705.3 KB	0.0%	352.67

TOTAL: 24:00:00	690092	100.0%	7.99	54.6 GB	100.0%	662.38

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	90.1 GB	65.4%	216	107.82	15.51
02	BIN	137.8 GB	43.9 GB	31.8%	217	107.90	15.50
03	SMALL	13.0 GB	10.4 GB	80.0%	18	6.09	25.97
04	BIN	80.1 GB	20.6 GB	25.7%	167	54.20	17.93
05	BIN	80.1 GB	45.8 GB	57.1%	187	53.71	18.10
06	SMALL	13.0 GB	10.4 GB	79.8%	18	6.17	25.64
07	BIN	80.1 GB	79.3 GB	99.1%	166	53.85	18.05
08	BIN	80.1 GB	27.5 GB	34.3%	187	53.94	18.02
09	SMALL	13.0 GB	9.4 GB	71.9%	18	6.15	25.74
10	BIN	80.1 GB	64.0 GB	79.9%	166	53.95	18.02
11	BIN	80.1 GB	67.1 GB	83.7%	187	53.59	18.14
12	SMALL	13.0 GB	10.2 GB	78.2%	18	6.13	25.82
13	BIN	80.1 GB	61.2 GB	76.4%	166	53.84	18.05
14	BIN	80.1 GB	28.8 GB	35.9%	187	53.53	18.16

TOTAL: 14	-	968.5 GB	568.6 GB	58.7%	1918	47.92	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	69446	50975	0	43	0	0	0	0	18428
2	?	4	0	0	0	0	0	0	0	4

TOTAL: 2		69450	50975	0	43	0	0	0	0	18432

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.pwp	2594
alt.binaries.dvdrs.pw	2020
alt.binaries.pictures.amateur.butts	1498
microsoft.public.test	1148
free.binaries.pictures.erotica.amateur.male	867
alt.binaries.pictures.blonde	547
alt.binaries.boys	542
alt.binaires.erotica.teen	540
alt.binaries.movie	523
alt.binaries.game	470
alt.binaires.erotica	459
alt.binaires.pictures.erotica.teens	449
alt.binaries.erotic.erotika.young	448
alt.binaries.nospam.female.short-hair	355
alt.binaries.pictures.black.erotica	309
alt.pictures.erotica	300
alt.biniaries.erotica	299
alt.binaries.erotica.early-teens	298
alt.binaries.pictures.black.erotica.females	286
alt.binaries.erotica.best-teens	282

TOTAL: 2126	50975

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	18428
	TOTAL: 1	18428

TOTAL: 1		18428

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	3	12258	744.0 MB	9	0	0	09:02:21
2	ts18-a117.moscow.dial.rol.ru	1	23	1.2 MB	3	0	0	00:27:20

TOTAL: 2		4	12281	745.2 MB	12	0	2	10:47:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	3	12258	744.0 MB	9	0	0	09:02:21
2	*.moscow.dial.rol.ru	3	23	1.2 MB	3	0	0	00:43:54
3	*.net.rol.ru	2	0	0.0 KB	0	0	0	00:19:59
4	*.sarov.dial.rol.ru	4	0	0.0 KB	0	0	0	00:22:01
5	*.tony.pp.ru	74	0	0.0 KB	0	0	0	00:02:35
6	*.rol.ru	5765	0	0.0 KB	0	0	0	00:14:43
7	*.tr155m.lavtech.ru	1	0	0.0 KB	0	0	1	00:00:06

TOTAL: 7		5852	12281	745.2 MB	12	0	1	10:47:18

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	8.092	78.114	0.000	09:02:21
ts18-a117.moscow.dial.rol.ru	0.008	0.020	0.000	00:27:20
modul-gw000.net.rol.ru	0.004	0.000	0.000	00:19:59
ts18-a233.moscow.dial.rol.ru	0.008	0.004	0.000	00:16:34
ts2-a179.sarov.dial.rol.ru	0.000	0.000	0.000	00:10:19
ts2-a50.sarov.dial.rol.ru	0.004	0.000	0.000	00:10:15
balancer-b.rol.ru	3.700	3.216	0.000	00:07:38
balancer-a.rol.ru	3.644	3.088	0.000	00:07:05
net1-x-ring.tony.pp.ru	0.112	0.112	0.000	00:02:35
?	0.012	0.008	0.000	00:01:36
ts2-a149.sarov.dial.rol.ru	0.008	0.008	0.000	00:01:27
web02.tr155m.lavtech.ru	0.004	0.000	0.000	00:00:06
cornholio.migtel.ru	0.008	0.008	0.000	00:00:00

TOTAL: 13	15.604	84.578	0.000	10:47:18

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2883
balancer-a.rol.ru	2882
net1-x-ring.tony.pp.ru	74
ts18-a233.moscow.dial.rol.ru	2
modul-gw000.net.rol.ru	2
ts2-a149.sarov.dial.rol.ru	2
ts2-a50.sarov.dial.rol.ru	1
web02.tr155m.lavtech.ru	1
ts2-a179.sarov.dial.rol.ru	1

TOTAL: 9	5848

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	6

TOTAL: 1	6

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	14

TOTAL: 1	14

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-a50.sarov.dial.rol.ru	1	0
ts18-a117.moscow.dial.rol.ru	1	0
ts2-a179.sarov.dial.rol.ru	1	0

TOTAL: 3	3	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	12265	99.9%
2	aus	12	0.1%

TOTAL: 2		12277	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	5765
2	alt.binaries.pictures.bras	5226
3	alt.binaries.pictures.artpics	1263
4	aus.sex.incest	12
5	alt.binaries.pictures.lolita	9
6	alt.sex.erotica.teen	2

TOTAL: 6		12277