Daily Usenet report

Sep 26 03:00:02 -- Sep 27 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 / 61 lines (8.2%)
Sep 26 04:03:06 perseus controlchan[16499]: Unknown "cmsg" control by zoevera.bbs@bbs.cs.nctu.edu.tw
Sep 26 05:31:46 perseus controlchan[16499]: Unknown "cmsg" control by doono329.bbs@bbs.cs.nctu.edu.tw
Sep 26 06:00:59 perseus controlchan[16499]: Unknown "cmsg" control by JayBruce.bbs@bbs.cs.nctu.edu.tw
Sep 26 06:20:43 perseus controlchan[16499]: Unknown "cmsg" control by l210992.bbs@bbs.cs.nctu.edu.tw
Sep 26 07:00:58 perseus controlchan[16499]: Unknown "cmsg" control by halloha.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	575277	89.2%	54.0 MB	89.4%
innd	48744	7.6%	4.3 MB	7.2%
nnrpd	18229	2.8%	1.6 MB	2.7%
cnfsstat	2304	0.4%	395.4 KB	0.6%
controlchan	203	0.0%	28.3 KB	0.0%

TOTAL: 5	644757	100.0%	60.4 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	553864	92.6%
Negative hits	39911	6.7%
Positive hits	3439	0.6%
Cache misses	955	0.2%

TOTAL: 4	598169	100.0%

INND timer:

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

article cancel	00:00:23.918	0.0%	7646	0.000	3.128	667.000
article cleanup	00:00:05.430	0.0%	550918	0.000	0.010	0.029
article logging	00:01:49.720	0.1%	567615	0.000	0.193	15.694
article parse	00:02:58.879	0.2%	43366619	0.001	0.004	0.008
article write	00:42:46.428	3.0%	472188	0.166	5.435	27.009
artlog/artcncl	00:00:02.881	0.0%	7588	0.000	0.380	29.177
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:00:45.424	0.1%	45461377	0.000	0.001	0.003
hisgrep/artcncl	00:00:16.303	0.0%	4352	0.000	3.746	294.500
hishave/artcncl	00:00:01.789	0.0%	7646	0.000	0.234	570.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:10:12.813	0.7%	590866	0.000	1.037	20.748
history sync	00:00:00.247	0.0%	342	0.000	0.722	2.000
history write	00:24:48.976	1.7%	472188	0.005	3.153	31.003
hiswrite/artcncl	00:00:02.905	0.0%	3294	0.000	0.882	663.000
idle	21:10:54.493	88.3%	43179113	0.751	1.766	53.720
nntp read	00:03:33.831	0.2%	43389078	0.002	0.005	0.014
overview write	01:15:52.498	5.3%	472188	0.741	9.641	83.840
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%	199	0.000	0.000	0.000

TOTAL: 23:59:50.119	23:54:36.535	99.6%	-	-	-	-

nnrpd timer:

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

hisgrep	00:00:35.843	0.1%	16972	0.000	2.112	10.458
idle	12:00:44.889	92.5%	40493	0.000	1067.960	200410.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:38:47.237	5.0%	228217	0.000	10.197	163.169
readart	00:05:14.916	0.7%	34227	0.000	9.201	90.500

TOTAL: 12:59:25.444	12:45:22.885	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

newsmaster@carnet.hr	0	142	0	0	0	142

TOTAL	0	142	0	0	0	142

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	1268	548195	469519	4	78672	85%	439:08:43

TOTAL: 1		1268	548195	469519	4	78672	85%	439:08:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	51.6 GB	111.8 KB	51.6 GB	99%	98.6 KB	439:08:43

TOTAL: 1		51.6 GB	111.8 KB	51.6 GB	99%	98.6 KB	439:08:43

Incoming articles:

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

Sep 26 03:00:02 - 03:59:59	21250	4.5%	5.91	2.7 GB	5.2%	773.13
Sep 26 04:00:00 - 04:59:59	21664	4.6%	6.02	2.6 GB	5.1%	771.06
Sep 26 05:00:00 - 05:59:59	19421	4.1%	5.39	2.8 GB	5.4%	814.44
Sep 26 06:00:00 - 06:59:59	20380	4.3%	5.66	2.9 GB	5.7%	850.66
Sep 26 07:00:00 - 07:59:59	19853	4.2%	5.51	3.1 GB	5.9%	889.23
Sep 26 08:00:00 - 08:59:59	19212	4.1%	5.34	3.4 GB	6.6%	987.37
Sep 26 09:00:00 - 09:59:59	20928	4.4%	5.81	3.3 GB	6.5%	973.00
Sep 26 10:00:00 - 10:59:59	21126	4.5%	5.87	2.8 GB	5.4%	804.23
Sep 26 11:00:00 - 11:59:59	19098	4.0%	5.30	1.7 GB	3.2%	484.45
Sep 26 12:00:00 - 12:59:59	19842	4.2%	5.51	1.6 GB	3.0%	455.48
Sep 26 13:00:00 - 13:59:59	17626	3.7%	4.90	2.0 GB	3.8%	571.74
Sep 26 14:00:00 - 14:59:59	17377	3.7%	4.83	1.9 GB	3.8%	563.38
Sep 26 15:00:00 - 15:59:59	16091	3.4%	4.47	1.5 GB	2.9%	438.67
Sep 26 16:00:00 - 16:59:59	19351	4.1%	5.38	1.4 GB	2.7%	406.18
Sep 26 17:00:00 - 17:59:59	17802	3.8%	4.95	1.8 GB	3.5%	522.34
Sep 26 18:00:00 - 18:59:59	20324	4.3%	5.65	1.4 GB	2.8%	412.23
Sep 26 19:00:00 - 19:59:59	20680	4.4%	5.74	1.5 GB	2.9%	435.16
Sep 26 20:00:00 - 20:59:59	19756	4.2%	5.49	2.1 GB	4.0%	601.44
Sep 26 21:00:00 - 21:59:59	18388	3.9%	5.11	1.9 GB	3.6%	542.29
Sep 26 22:00:00 - 22:59:59	19427	4.1%	5.40	1.6 GB	3.2%	477.70
Sep 26 23:00:00 - 23:59:59	19526	4.1%	5.42	2.1 GB	4.1%	609.77
Sep 27 00:00:00 - 00:59:59	16176	3.4%	4.49	1.2 GB	2.4%	361.66
Sep 27 01:00:00 - 01:59:59	26685	5.7%	7.41	2.0 GB	4.0%	596.18
Sep 27 02:00:00 - 02:59:59	20251	4.3%	5.63	2.2 GB	4.2%	633.52
Sep 27 03:00:00 - 03:00:02	13	0.0%	6.50	2.1 MB	0.0%	1083.04

TOTAL: 24:00:00	472247	100.0%	5.47	51.4 GB	100.0%	623.98

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	111.9 GB	81.2%	215	102.71	16.29
02	BIN	137.8 GB	65.9 GB	47.9%	216	102.45	16.33
03	SMALL	13.0 GB	6.2 GB	47.4%	18	3.59	44.12
04	BIN	80.1 GB	42.6 GB	53.3%	166	51.08	19.03
05	BIN	80.1 GB	68.1 GB	85.0%	186	51.26	18.96
06	SMALL	13.0 GB	6.1 GB	47.0%	18	3.56	44.49
07	BIN	80.1 GB	21.4 GB	26.7%	166	51.20	18.98
08	BIN	80.1 GB	49.7 GB	62.0%	186	51.23	18.97
09	SMALL	13.0 GB	5.1 GB	39.2%	18	3.56	44.47
10	BIN	80.1 GB	6.0 GB	7.5%	166	51.35	18.93
11	BIN	80.1 GB	9.3 GB	11.6%	187	51.04	19.05
12	SMALL	13.0 GB	5.9 GB	45.5%	18	3.60	43.92
13	BIN	80.1 GB	3.2 GB	4.0%	166	51.49	18.88
14	BIN	80.1 GB	51.1 GB	63.7%	186	51.37	18.92

TOTAL: 14	-	968.5 GB	452.5 GB	46.7%	1912	44.96	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	95439	53369	0	38	0	0	0	0	42032
2	?	3	0	0	0	0	0	0	0	3

TOTAL: 2		95442	53369	0	38	0	0	0	0	42035

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.pwp	8255
alt.binaries.dvdrs.pw	4922
bih.rec.photo.binaries	4658
alt.binaries.wmvhd	2428
microsoft.public.test	1148
alt.binaries.game	949
easynews.alt.binaries.games.movies.apps	891
alt.binaries.movie	574
alt.binaries.private.not.yours	556
alt.binaries.dvd.classic.movies	426
alt.binaries.nospam.female.short-hair	417
alt.binaries.dgma	401
alt.binaris	331
alt.binary.warez.ibm-pc	329
de.alt.binaries	298
alt.binaries.boneless.nl	273
mozilla.feedback.firefox	238
alt.binaries.warez-software	228
alt.binaries.warez-pc	225
alt.binaries.warez-pc.0-day	187

TOTAL: 3343	53369

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	16693
	TOTAL: 1	16693

TOTAL: 1		16693

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	6	16972	1.0 GB	15	0	0	11:33:09
2	195.218.244.87	1	16	1.7 MB	1	0	0	00:01:32

TOTAL: 2		7	16988	1.0 GB	16	0	0	12:59:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	6	16972	1.0 GB	15	0	0	11:33:09
2	unresolved	9	16	1.7 MB	1	0	0	00:21:37
3	*.sovintel.net	16	0	0.0 KB	0	0	0	00:00:24
4	*.dynadsl.kis.ru	3	0	0.0 KB	0	0	0	00:29:43
5	*.moscow.dial.rol.ru	6	0	0.0 KB	0	0	0	00:13:12
6	*.sarov.dial.rol.ru	4	0	0.0 KB	0	0	0	00:03:22
7	*.tony.pp.ru	75	0	0.0 KB	0	0	0	00:02:47
8	*.rol.ru	5765	0	0.0 KB	0	0	0	00:14:35

TOTAL: 8		5884	16988	1.0 GB	16	0	0	12:59:25

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	12.460	131.174	0.000	11:33:09
dyn-19-111.dynadsl.kis.ru	0.092	0.108	0.000	00:29:43
ts18-a56.moscow.dial.rol.ru	0.012	0.008	0.000	00:10:20
212.46.236.42	0.024	0.008	0.000	00:10:03
194.186.71.137	0.024	0.008	0.000	00:10:01
balancer-b.rol.ru	3.524	3.400	0.000	00:07:39
balancer-a.rol.ru	3.640	3.400	0.000	00:06:56
ts18-a96.moscow.dial.rol.ru	0.004	0.000	0.000	00:02:51
net1-x-ring.tony.pp.ru	0.128	0.100	0.000	00:02:47
ts2-a98.sarov.dial.rol.ru	0.004	0.008	0.000	00:02:05
195.218.244.87	0.000	0.012	0.000	00:01:32
ts2-a67.sarov.dial.rol.ru	0.008	0.000	0.000	00:01:17
?	0.004	0.004	0.000	00:00:31
fw-nat.sovintel.net	0.120	0.080	0.000	00:00:24
cornholio.migtel.ru	0.000	0.012	0.000	00:00:00
wn2nat61.beelinegprs.ru	0.000	0.000	0.000	00:00:00

TOTAL: 16	20.044	138.322	0.000	12:59:25

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-a.rol.ru	2883
balancer-b.rol.ru	2882
net1-x-ring.tony.pp.ru	75
fw-nat.sovintel.net	16
212.46.236.42	6
dyn-19-111.dynadsl.kis.ru	3
ts18-a96.moscow.dial.rol.ru	3
ts18-a56.moscow.dial.rol.ru	3
194.186.71.137	2
ts2-a67.sarov.dial.rol.ru	2
ts2-a98.sarov.dial.rol.ru	2

TOTAL: 11	5877

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	7
wn2nat61.beelinegprs.ru	1

TOTAL: 2	8

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	7

TOTAL: 1	7

NNRP client timeouts [Top 20]:

System	Conn	Peer

194.186.71.137	1	0
212.46.236.42	1	0
ts18-a56.moscow.dial.rol.ru	1	0

TOTAL: 3	3	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	17386	100.0%

TOTAL: 1		17386	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	8395
2	alt.binaries.pictures.bras	6629
3	alt.binaries.pictures.artpics	2346
4	alt.binaries.erotica.pornstars	16

TOTAL: 4		17386