Daily Usenet report

Aug 28 03:00:03 -- Aug 29 03:00:01

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 / 30 lines (16.7%)
Aug 28 04:50:44 perseus controlchan[16196]: Unknown "cmsg" control by kirmizi.bbs@bbs.cs.nctu.edu.tw
Aug 28 05:40:32 perseus controlchan[16196]: Unknown "cmsg" control by okd.bbs@bbs.cs.nctu.edu.tw
Aug 28 05:45:25 perseus controlchan[16196]: Unknown "cmsg" control by sai.bbs@bbs.cs.nctu.edu.tw
Aug 28 07:40:29 perseus controlchan[16196]: Unknown "cmsg" control by ktj0328.bbs@bbs.cs.nctu.edu.tw
Aug 28 08:35:26 perseus controlchan[16196]: Unknown "cmsg" control by pwg.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	641022	92.4%	58.9 MB	92.0%
innd	31098	4.5%	3.0 MB	4.7%
nnrpd	18977	2.7%	1.7 MB	2.7%
cnfsstat	2304	0.3%	396.0 KB	0.6%
controlchan	32	0.0%	3.1 KB	0.0%

TOTAL: 5	693433	100.0%	64.1 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	591864	93.6%
Negative hits	36622	5.8%
Positive hits	2193	0.3%
Cache misses	1342	0.2%

TOTAL: 4	632021	100.0%

INND timer:

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

article cancel	00:00:16.154	0.0%	5866	0.000	2.754	847.000
article cleanup	00:00:05.506	0.0%	589864	0.000	0.009	0.031
article logging	00:01:31.015	0.1%	635024	0.000	0.143	14.586
article parse	00:02:27.415	0.2%	35379347	0.002	0.004	0.008
article write	00:51:45.757	3.6%	524362	0.197	5.923	85.970
artlog/artcncl	00:00:00.052	0.0%	5816	0.000	0.009	1.000
artlog/artparse	00:00:00.001	0.0%	9	0.000	0.111	1.000
data move	00:00:38.796	0.0%	37576618	0.000	0.001	0.002
hisgrep/artcncl	00:00:14.574	0.0%	3361	0.000	4.336	847.000
hishave/artcncl	00:00:00.283	0.0%	5866	0.000	0.048	18.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:09:04.211	0.6%	626568	0.000	0.869	18.590
history sync	00:00:00.218	0.0%	312	0.000	0.699	2.000
history write	00:29:41.154	2.1%	524362	0.011	3.397	38.406
hiswrite/artcncl	00:00:01.204	0.0%	2505	0.000	0.481	432.000
idle	20:53:07.323	87.0%	35169101	0.802	2.138	23.717
nntp read	00:02:55.578	0.2%	35395288	0.002	0.005	0.010
overview write	01:23:32.222	5.8%	524362	0.902	9.559	54.624
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%	31	0.000	0.000	0.000

TOTAL: 23:59:43.089	23:55:21.463	99.7%	-	-	-	-

nnrpd timer:

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

hisgrep	00:02:23.793	0.3%	15253	0.000	9.427	21.554
idle	12:13:46.737	96.5%	37091	0.000	1186.992	200362.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:03:21.325	0.4%	110980	0.000	1.814	1390.684
readart	00:05:33.314	0.7%	30874	0.000	10.796	57.043

TOTAL: 12:40:23.769	12:25:05.169	98.0%	-	-	-	-

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

control-hierarchy@my.news.server.com	0	0	1	0	0	1
fff@alteferians.org	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	942	619722	552812	2	66908	89%	443:15:04

TOTAL: 1		942	619722	552812	2	66908	89%	443:15:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	46.2 GB	812.3 KB	46.2 GB	99%	78.1 KB	443:15:04

TOTAL: 1		46.2 GB	812.3 KB	46.2 GB	99%	78.1 KB	443:15:04

Incoming articles:

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

Aug 28 03:00:03 - 03:59:59	23695	4.5%	6.59	1.9 GB	4.2%	567.31
Aug 28 04:00:00 - 04:59:59	21153	4.0%	5.88	2.0 GB	4.3%	580.91
Aug 28 05:00:00 - 05:59:59	20378	3.9%	5.66	2.2 GB	4.7%	629.11
Aug 28 06:00:00 - 06:59:59	18994	3.6%	5.28	1.9 GB	4.2%	558.71
Aug 28 07:00:00 - 07:59:59	16762	3.2%	4.66	1.7 GB	3.7%	494.41
Aug 28 08:00:00 - 08:59:59	17199	3.3%	4.78	2.1 GB	4.6%	619.84
Aug 28 09:00:00 - 09:59:59	18487	3.5%	5.14	2.0 GB	4.4%	595.62
Aug 28 10:00:00 - 10:59:59	18948	3.6%	5.26	1.7 GB	3.7%	492.10
Aug 28 11:00:00 - 11:59:59	18428	3.5%	5.12	1.5 GB	3.2%	426.92
Aug 28 12:00:00 - 12:59:59	21832	4.2%	6.06	1.9 GB	4.1%	547.70
Aug 28 13:00:00 - 13:59:59	22291	4.2%	6.19	2.3 GB	4.9%	656.39
Aug 28 14:00:00 - 14:59:59	19492	3.7%	5.41	2.0 GB	4.4%	593.07
Aug 28 15:00:00 - 15:59:59	22033	4.2%	6.12	1.9 GB	4.0%	541.83
Aug 28 16:00:00 - 16:59:59	23807	4.5%	6.61	1.8 GB	4.0%	532.64
Aug 28 17:00:00 - 17:59:59	22926	4.4%	6.37	2.0 GB	4.3%	572.97
Aug 28 18:00:00 - 18:59:59	25258	4.8%	7.02	2.2 GB	4.7%	630.00
Aug 28 19:00:00 - 19:59:59	24739	4.7%	6.87	2.1 GB	4.7%	623.57
Aug 28 20:00:00 - 20:59:59	25685	4.9%	7.13	1.9 GB	4.2%	561.74
Aug 28 21:00:00 - 21:59:59	23097	4.4%	6.42	1.9 GB	4.1%	554.14
Aug 28 22:00:00 - 22:59:59	24680	4.7%	6.86	2.5 GB	5.4%	726.57
Aug 28 23:00:00 - 23:59:59	21444	4.1%	5.96	1.6 GB	3.5%	473.83
Aug 29 00:00:00 - 00:59:59	21130	4.0%	5.87	1.4 GB	3.0%	398.41
Aug 29 01:00:00 - 01:59:59	28689	5.5%	7.97	1.7 GB	3.6%	487.22
Aug 29 02:00:00 - 02:59:59	23364	4.5%	6.49	1.8 GB	4.0%	529.13
Aug 29 03:00:00 - 03:00:01	8	0.0%	8.00	263.3 KB	0.0%	263.32

TOTAL: 23:59:58	524519	100.0%	6.07	46.0 GB	100.0%	558.09

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	21.8 GB	15.8%	214	90.97	18.39
02	BIN	137.8 GB	114.8 GB	83.3%	214	90.27	18.53
03	SMALL	13.0 GB	7.4 GB	56.4%	17	4.89	32.35
04	BIN	80.1 GB	8.9 GB	11.2%	165	45.52	21.35
05	BIN	80.1 GB	35.0 GB	43.6%	185	45.52	21.35
06	SMALL	13.0 GB	7.3 GB	56.1%	17	4.90	32.28
07	BIN	80.1 GB	67.6 GB	84.4%	164	45.25	21.48
08	BIN	80.1 GB	16.1 GB	20.1%	185	45.34	21.44
09	SMALL	13.0 GB	6.3 GB	48.1%	17	4.90	32.31
10	BIN	80.1 GB	52.0 GB	65.0%	164	45.87	21.19
11	BIN	80.1 GB	56.0 GB	70.0%	185	45.53	21.35
12	SMALL	13.0 GB	7.1 GB	54.4%	17	4.91	32.25
13	BIN	80.1 GB	49.3 GB	61.5%	164	45.48	21.37
14	BIN	80.1 GB	17.4 GB	21.7%	185	45.57	21.33

TOTAL: 14	-	968.5 GB	467.0 GB	48.2%	1893	40.35	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	110680	53097	0	173	0	0	0	0	57410
2	?	7	0	0	0	0	0	0	0	7

TOTAL: 2		110687	53097	0	173	0	0	0	0	57417

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.norge	5884
bih.misc.grafika.binaries	2300
alt.binaries.dvdrs.pw	1926
microsoft.public.test	1141
alt.binaries.amazing	1060
alt.binaries.pictures.amateur.butts	1032
alt.binaries.movie.german	943
alt.binaries.sandra.model	887
alt.binaries.pictures.blonde	542
alt.binaries.pictures.comics.repost	487
alt.binaries.pictures.comics.complete	480
alt.binaries.private.not.yours	454
alt.binaries.game	422
alt.binaries.alt.binaries.pictures.erotica	373
microsoft.public.internetexplorer.beta	366
alt.binaires.pictures.erotica.male	348
alt.binaries.pictures.katie	331
alt.binaries.pictures.erotica.group.sex	302
alt.binaries.nospam.female.short-hair	219
alt.binaries.erotica.toys	217

TOTAL: 1960	53097

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	45181
	TOTAL: 1	45181

TOTAL: 1		45181

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	5	15253	775.1 MB	15	0	0	11:07:02
2	195.218.244.87	4	70	4.4 MB	3	0	0	00:10:42
3	194.186.147.169	1	3	212.0 KB	1	0	0	00:00:41
4	rol82.rol.kis.ru	2	2	901.5 KB	2	0	0	00:00:42

TOTAL: 4		12	15328	780.7 MB	21	0	0	12:40:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	5	15253	775.1 MB	15	0	0	11:07:02
2	unresolved	8	73	4.7 MB	4	0	0	00:11:24
3	*.rol.kis.ru	2	2	901.5 KB	2	0	0	00:00:42
4	*.sovintel.net	14	0	0.0 KB	0	0	0	00:00:12
5	*.sarov.dial.rol.ru	8	0	0.0 KB	0	0	0	01:01:19
6	*.tony.pp.ru	64	0	0.0 KB	0	0	0	00:02:09
7	*.rol.ru	5763	0	0.0 KB	0	0	0	00:16:04

TOTAL: 7		5864	15328	780.7 MB	21	0	0	12:40:14

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	12.792	132.758	0.000	11:07:02
ts2-a152.sarov.dial.rol.ru	0.008	0.016	0.000	00:50:03
195.218.244.87	0.024	0.052	0.000	00:10:42
ts2-a29.sarov.dial.rol.ru	0.004	0.000	0.000	00:10:01
balancer-b.rol.ru	3.400	3.256	0.000	00:08:11
balancer-a.rol.ru	3.416	3.296	0.000	00:07:52
net1-x-ring.tony.pp.ru	0.096	0.132	0.000	00:02:09
?	0.004	0.020	0.000	00:01:19
ts2-a126.sarov.dial.rol.ru	0.008	0.016	0.000	00:01:15
rol82.rol.kis.ru	0.004	0.012	0.000	00:00:42
194.186.147.169	0.000	0.000	0.000	00:00:41
fw-nat.sovintel.net	0.060	0.072	0.000	00:00:12
cornholio.migtel.ru	0.284	0.296	0.000	00:00:09
195.98.59.67	0.008	0.000	0.000	00:00:00
87.245.178.164	0.004	0.000	0.000	00:00:00

TOTAL: 15	20.112	139.926	0.000	12:40:24

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2882
balancer-a.rol.ru	2881
net1-x-ring.tony.pp.ru	64
fw-nat.sovintel.net	14
ts2-a152.sarov.dial.rol.ru	5
195.98.59.67	3
ts2-a126.sarov.dial.rol.ru	2
ts2-a29.sarov.dial.rol.ru	1

TOTAL: 8	5852

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	196
87.245.178.164	3

TOTAL: 2	199

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	11

TOTAL: 1	11

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-a152.sarov.dial.rol.ru	5	0
ts2-a29.sarov.dial.rol.ru	1	0

TOTAL: 2	6	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	15294	100.0%
2	lada	2	0.0%

TOTAL: 2		15296	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	7642
2	alt.binaries.pictures.bras	6663
3	alt.binaries.pictures.artpics	916
4	alt.binaries.erotica.pornstars	70
5	alt.binaries.nospam.female.bodyhair.pubes	3
6	lada.music	2

TOTAL: 6		15296