Daily Usenet report

Oct 8 03:00:01 -- 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
INND misc events
Miscellaneous innd statistics
NNRP readership statistics
NNRP readership statistics (by domain)
NNRP total resource statistics
Curious NNRP server explorers
NNRP no permission clients
NNRP client timeouts
Newsgroup request counts (by category)
Newsgroup request counts (by newsgroup)

First 5 / 32 lines (15.6%)
Oct 8 04:53:05 andromeda controlchan[31634]: Unknown "cmsg" control by uuuuu.bbs@bbs.cs.nctu.edu.tw
Oct 8 05:33:03 andromeda controlchan[31634]: Unknown "cmsg" control by whitehairzoi.bbs@bbs.cs.nctu.edu.tw
Oct 8 05:53:52 andromeda controlchan[31634]: Unknown "cmsg" control by eemichael.bbs@bbs.cs.nctu.edu.tw
Oct 8 06:31:05 andromeda controlchan[31634]: Unknown "cmsg" control by pgli.bbs@bbs.cs.nctu.edu.tw
Oct 8 07:34:47 andromeda controlchan[31634]: Unknown "cmsg" control by furato.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	852739	94.6%	77.4 MB	93.6%
nnrpd	36358	4.0%	3.5 MB	4.2%
innd	9724	1.1%	1.4 MB	1.7%
cnfsstat	2800	0.3%	487.7 KB	0.6%
controlchan	25	0.0%	3.0 KB	0.0%

TOTAL: 5	901646	100.0%	82.8 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	827248	93.1%
Negative hits	58216	6.6%
Positive hits	2457	0.3%
Cache misses	443	0.0%

TOTAL: 4	888364	100.0%

INND timer:

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

article cancel	00:03:18.296	0.2%	5691	0.000	34.844	3420.000
article cleanup	00:00:10.298	0.0%	824826	0.000	0.012	0.045
article logging	00:01:27.791	0.1%	846918	0.000	0.104	17.540
article parse	00:03:42.364	0.2%	30661019	0.000	0.007	0.167
article write	00:52:30.821	3.4%	759914	0.486	4.146	84.567
artlog/artcncl	00:00:04.507	0.0%	5625	0.000	0.801	203.500
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:01:18.293	0.1%	33371373	0.000	0.002	26.270
hisgrep/artcncl	00:00:25.360	0.0%	2552	0.000	9.937	601.333
hishave/artcncl	00:01:21.490	0.1%	5691	0.000	14.319	822.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:01:28.567	7.8%	883320	0.597	8.251	321.050
history sync	00:00:02.424	0.0%	322	0.000	7.528	589.000
history write	03:04:26.087	11.9%	824550	1.676	13.421	793.925
hiswrite/artcncl	00:01:23.302	0.1%	3139	0.000	26.538	3420.000
idle	16:26:22.544	63.4%	29780696	0.000	1.987	60003.000
nntp read	00:04:17.181	0.3%	30513246	0.000	0.008	25.032
overview write	03:06:44.420	12.0%	759914	2.616	14.744	4220.767
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.043	0.0%	22	0.000	1.955	42.000

TOTAL: 25:55:43.396	25:49:03.788	99.6%	-	-	-	-

nnrpd timer:

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

hisgrep	00:19:35.404	1.0%	70890	0.000	16.581	127.882
idle	30:22:54.459	95.0%	335047	0.000	326.445	201140.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:22:21.162	1.2%	825547	0.000	1.625	4513.812
readart	00:20:06.788	1.0%	142243	0.000	8.484	66.500

TOTAL: 31:59:36.361	31:24:57.813	98.2%	-	-	-	-

Control commands to INND:

Command	Number

flush	155
flushlogs	1
paused	2
shutdown	2
throttle	1

TOTAL: 5	161

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	615	844202	779352	57	64793	92%	487:00:20
2	localhost	0	0	0	0	0	0%	00:01:39

TOTAL: 2		615	844202	779352	57	64793	92%	487:01:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	62.4 GB	4.0 MB	62.4 GB	99%	77.5 KB	487:00:20
2	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0	00:01:39

TOTAL: 2		62.4 GB	4.0 MB	62.4 GB	99%	77.5 KB	487:01:59

Incoming articles:

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

Oct 8 03:00:01 - 03:59:59	25655	3.4%	7.13	2.3 GB	3.7%	678.75
Oct 8 04:00:00 - 04:59:59	22905	3.0%	6.36	2.2 GB	3.5%	640.47
Oct 8 05:00:00 - 05:59:59	22379	2.9%	6.22	2.3 GB	3.6%	658.31
Oct 8 06:00:00 - 06:59:59	24370	3.2%	6.77	2.3 GB	3.7%	680.27
Oct 8 07:00:00 - 07:59:59	21773	2.9%	6.05	2.4 GB	3.8%	699.19
Oct 8 08:00:00 - 08:59:59	20040	2.6%	5.57	2.4 GB	3.8%	700.91
Oct 8 09:00:00 - 09:59:59	25980	3.4%	7.22	2.9 GB	4.6%	841.41
Oct 8 10:00:00 - 10:59:59	20228	2.7%	5.62	1.8 GB	2.8%	510.20
Oct 8 11:00:00 - 11:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 12:00:00 - 12:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 18:00:00 - 18:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 19:00:00 - 19:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 20:00:00 - 20:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 21:00:00 - 21:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 22:00:00 - 22:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 8 23:00:00 - 23:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 00:00:00 - 00:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 03:00:00 - 03:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 04:00:00 - 04:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 05:00:00 - 05:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 06:00:00 - 06:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 07:00:00 - 07:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 08:00:00 - 08:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 9 09:00:00 - 09:59:59	59385	7.8%	16.50	5.3 GB	8.4%	1544.96
Oct 9 10:00:00 - 10:59:59	31224	4.1%	8.67	3.5 GB	5.6%	1016.07
Oct 9 11:00:00 - 11:59:59	27146	3.6%	7.54	1.9 GB	3.0%	547.87
Oct 9 12:00:00 - 12:59:59	26670	3.5%	7.41	2.3 GB	3.7%	668.07
Oct 9 13:00:00 - 13:59:59	31604	4.2%	8.78	2.9 GB	4.6%	836.94
Oct 9 14:00:00 - 14:59:59	32173	4.2%	8.94	2.6 GB	4.2%	763.97
Oct 9 15:00:00 - 15:59:59	25558	3.4%	7.10	2.9 GB	4.7%	855.90
Oct 9 16:00:00 - 16:59:59	30235	4.0%	8.40	2.9 GB	4.6%	848.54
Oct 9 17:00:00 - 17:59:59	30046	4.0%	8.35	2.5 GB	3.9%	715.15
Oct 9 18:00:00 - 18:59:59	31269	4.1%	8.69	2.1 GB	3.4%	618.08
Oct 9 19:00:00 - 19:59:59	35542	4.7%	9.87	2.3 GB	3.6%	666.52
Oct 9 20:00:00 - 20:59:59	35762	4.7%	9.93	2.3 GB	3.6%	662.17
Oct 9 21:00:00 - 21:59:59	31836	4.2%	8.84	2.4 GB	3.7%	684.85
Oct 9 22:00:00 - 22:59:59	27060	3.6%	7.52	2.6 GB	4.1%	755.50
Oct 9 23:00:00 - 23:59:59	30446	4.0%	8.46	1.6 GB	2.6%	473.54
Oct 10 00:00:00 - 00:59:59	25879	3.4%	7.19	1022.0 MB	1.6%	290.71
Oct 10 01:00:00 - 01:59:59	36830	4.8%	10.23	1.4 GB	2.2%	407.08
Oct 10 02:00:00 - 02:59:59	28040	3.7%	7.79	1.8 GB	2.8%	519.04
Oct 10 03:00:00 - 03:00:02	17	0.0%	8.50	995.7 KB	0.0%	497.86

TOTAL: 48:00:01	760052	100.0%	4.40	62.8 GB	100.0%	380.93

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	966.3 MB	0.7%	192	116.95	14.30
02	BIN	137.8 GB	40.9 GB	29.7%	192	116.07	14.41
03	SMALL	13.0 GB	10.0 GB	76.4%	16	6.10	25.93
04	BIN	80.1 GB	14.8 GB	18.4%	149	58.24	16.69
05	BIN	80.1 GB	364.3 MB	0.4%	166	58.32	16.67
06	SMALL	13.0 GB	9.3 GB	71.5%	16	6.06	26.09
07	BIN	80.1 GB	3.0 GB	3.8%	149	58.19	16.70
08	BIN	80.1 GB	62.5 GB	78.0%	165	57.99	16.76
09	SMALL	13.0 GB	9.4 GB	72.2%	16	6.06	26.09
10	BIN	80.1 GB	76.9 GB	96.0%	148	58.07	16.74
11	BIN	80.1 GB	7.1 GB	8.9%	166	58.57	16.60
12	BIN	13.0 GB	11.1 GB	85.4%	553	58.32	2.71
13	BIN	80.1 GB	39.6 GB	49.4%	90	58.22	16.70
14	SMALL	80.1 GB	66.0 GB	82.4%	1	6.12	158.77

TOTAL: 14	-	968.5 GB	351.9 GB	36.3%	2019	51.66	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	87057	64689	0	276	0	0	0	0	22092
2	?	4	0	0	0	0	0	0	0	4

TOTAL: 2		87061	64689	0	276	0	0	0	0	22096

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.boys	3247
alt.binaries.dvdrs.pw	3062
alt.binaries.pictures.amateur.butts	2259
alt.binaries.pwp	1994
microsoft.public.test	1291
alt.binaries.pictures.blonde	1026
alt.binaries.eroticaa	1021
free.binaries.pictures.erotica.amateur.male	867
alt.binaries.game	818
alt.binaries.movie	526
alt.binaries.erotica.best-teens	515
alt.binaires.pictures.erotica.amatuer.female	512
alt.binaires.pictures.erotica.butts	502
alt.binaries.pictures.comics.repost	487
alt.binaries.pictures.erotica.early-teen	479
alt.binaries.erotic.erotika.young	464
alt.binaires.pictures.erotica.teens	349
alt.binaries.erotica.amateur.females	330
pictures.erotica.lesbians	320
alt.binaires.erotica.teen	316

TOTAL: 2172	64689

INND misc events:

Events	Count

CCreader	5

TOTAL: 1	5

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	22092
	TOTAL: 1	22092

TOTAL: 1		22092

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	8	70890	2.5 GB	27	0	0	27:42:33
2	195.218.244.87	3	34	1.9 MB	2	0	0	00:13:05
3	ts18-a106.moscow.dial.rol.ru	1	13	739.2 KB	9	0	0	00:23:39
4	ts18-a1.moscow.dial.rol.ru	2	3	172.1 KB	1	0	0	00:19:12
5	ts18-a233.moscow.dial.rol.ru	1	1	0.0 KB	1	0	0	00:01:20
6	ts18-a117.moscow.dial.rol.ru	1	1	88.9 KB	2	0	0	00:13:23

TOTAL: 6		16	70942	2.5 GB	42	0	0	31:59:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	8	70890	2.5 GB	27	0	0	27:42:33
2	unresolved	3	34	1.9 MB	2	0	0	00:13:05
3	*.moscow.dial.rol.ru	5	18	1000.1 KB	13	0	0	00:57:34
4	*.sovintel.net	14	0	0.0 KB	0	0	0	00:00:20
5	*.net.rol.ru	8	0	0.0 KB	0	0	0	00:58:25
6	*.sarov.dial.rol.ru	8	0	0.0 KB	0	0	0	00:51:53
7	*.tony.pp.ru	140	0	0.0 KB	0	0	0	00:27:41
8	*.rol.ru	11536	0	0.0 KB	0	0	0	00:33:09

TOTAL: 8		11722	70942	2.5 GB	42	0	0	31:59:27

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	30.456	50.308	0.000	27:42:33
modul-gw000.net.rol.ru	0.008	0.016	0.000	00:58:25
net1-x-ring.tony.pp.ru	117.988	115.052	0.000	00:27:41
ts18-a106.moscow.dial.rol.ru	0.076	0.112	0.000	00:23:39
ts2-a118.sarov.dial.rol.ru	0.004	0.004	0.000	00:20:06
ts18-a1.moscow.dial.rol.ru	0.004	0.004	0.000	00:19:12
balancer-a.rol.ru	7.152	10.444	0.000	00:16:39
balancer-b.rol.ru	7.268	10.448	0.000	00:16:30
?	0.012	0.172	0.000	00:14:42
ts18-a117.moscow.dial.rol.ru	0.004	0.000	0.000	00:13:23
195.218.244.87	0.024	0.084	0.000	00:13:05
ts2-a50.sarov.dial.rol.ru	0.004	0.004	0.000	00:10:29
ts2-a38.sarov.dial.rol.ru	0.004	0.008	0.000	00:10:09
ts2-a179.sarov.dial.rol.ru	0.000	0.004	0.000	00:10:02
ts18-a233.moscow.dial.rol.ru	0.004	0.008	0.000	00:01:20
ts2-a149.sarov.dial.rol.ru	0.004	0.000	0.000	00:01:06
fw-nat.sovintel.net	0.096	0.064	0.000	00:00:20
cornholio.migtel.ru	0.008	0.004	0.000	00:00:00
c5851.72.sinor.ru	0.000	0.004	0.000	00:00:00

TOTAL: 19	163.116	186.740	0.000	31:59:27

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	5769
balancer-a.rol.ru	5767
net1-x-ring.tony.pp.ru	140
fw-nat.sovintel.net	14
modul-gw000.net.rol.ru	8
ts2-a50.sarov.dial.rol.ru	2
ts2-a118.sarov.dial.rol.ru	2
ts2-a38.sarov.dial.rol.ru	2
ts2-a179.sarov.dial.rol.ru	1
ts2-a149.sarov.dial.rol.ru	1

TOTAL: 10	11706

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	7
c5851.72.sinor.ru	1

TOTAL: 2	8

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-a118.sarov.dial.rol.ru	2	0
ts2-a50.sarov.dial.rol.ru	1	0
ts18-a1.moscow.dial.rol.ru	1	0
ts18-a117.moscow.dial.rol.ru	1	0
195.218.244.87	1	0
ts2-a179.sarov.dial.rol.ru	1	0
ts2-a38.sarov.dial.rol.ru	1	0

TOTAL: 7	8	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	67447	100.0%

TOTAL: 1		67447	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	39673
2	alt.binaries.pictures.bras	20943
3	alt.binaries.pictures.artpics	6779
4	alt.binaries.erotica.pornstars	34
5	alt.binaries.pictures.erotica.amateur.lolita	7
6	alt.binaries.erotica.pregnant	4
7	alt.binaries.pictures.eroctica.teen.fuck	3
8	alt.binaries.series.mature.women	2
9	alt.binaries.pictures.lolita	1
10	alt.sex.erotica.teen	1

TOTAL: 10		67447