Daily Usenet report

Jan 19 03:00:06 -- Jan 20 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 / 12 lines (41.7%)
Jan 19 03:40:44 perseus controlchan[24499]: Unknown "cmsg" control by max252378.bbs@bbs.wretch.cc
Jan 19 04:50:43 perseus controlchan[24499]: Unknown "cmsg" control by helenchen3.bbs@bbs.wretch.cc
Jan 19 04:50:43 perseus controlchan[24499]: Unknown "cmsg" control by helenchen3.bbs@bbs.wretch.cc
Jan 19 05:23:09 perseus controlchan[24499]: Unknown "cmsg" control by snowbow.bbs@bbs.cs.nthu.edu.tw
Jan 19 06:00:48 perseus controlchan[24499]: Unknown "cmsg" control by Leamars.bbs@bbs.wretch.cc

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	2108281	98.2%	188.8 MB	97.6%
nnrpd	19066	0.9%	1.9 MB	1.0%
innd	17205	0.8%	2.3 MB	1.2%
cnfsstat	2304	0.1%	399.8 KB	0.2%
controlchan	14	0.0%	1.8 KB	0.0%

TOTAL: 5	2146870	100.0%	193.5 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	2096161	97.0%
Negative hits	61353	2.8%
Positive hits	2904	0.1%
Cache misses	1059	0.0%

TOTAL: 4	2161477	100.0%

INND timer:

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

article cancel	00:00:50.744	0.1%	11578	0.000	4.383	937.000
article cleanup	00:00:23.192	0.0%	2088739	0.003	0.011	0.022
article logging	00:02:29.360	0.2%	2096379	0.004	0.071	5.561
article parse	00:17:41.099	1.2%	159883918	0.004	0.007	0.021
article write	10:18:54.950	43.0%	2031628	3.242	18.278	36.276
artlog/artcncl	00:00:03.284	0.0%	11047	0.000	0.297	36.631
artlog/artparse	00:00:00.001	0.0%	49	0.000	0.020	1.000
data move	00:03:33.558	0.2%	169419468	0.001	0.001	0.002
hisgrep/artcncl	00:00:44.238	0.1%	3728	0.000	11.866	937.000
hishave/artcncl	00:00:01.024	0.0%	11578	0.000	0.088	35.826
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:07:38.619	0.5%	2150116	0.000	0.213	6.343
history sync	00:00:00.251	0.0%	290	0.000	0.866	2.000
history write	00:44:33.206	3.1%	2031628	0.029	1.316	10.502
hiswrite/artcncl	00:00:02.116	0.0%	7850	0.000	0.270	11.000
idle	07:46:02.964	32.4%	151348888	0.129	0.185	0.858
nntp read	00:19:35.370	1.4%	158611256	0.005	0.007	0.018
overview write	03:56:08.427	16.4%	2031628	0.812	6.974	160.304
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%	12	0.000	0.000	0.000

TOTAL: 23:59:23.209	23:38:42.403	98.6%	-	-	-	-

nnrpd timer:

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

hisgrep	00:08:35.178	0.9%	46814	0.000	11.005	53.134
idle	12:01:17.897	76.9%	101095	0.000	428.091	202867.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	02:06:20.891	13.5%	302806	0.000	25.035	20090.885
readart	00:42:50.637	4.6%	97743	0.000	26.300	1597.500

TOTAL: 15:37:56.335	14:59:04.603	95.9%	-	-	-	-

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

fff@alteferians.org	2	0	0	0	0	2

TOTAL	2	0	0	0	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	349	2009817	1965326	7	44484	97%	465:09:49

TOTAL: 1		349	2009817	1965326	7	44484	97%	465:09:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	377.7 GB	1019.1 KB	377.7 GB	99%	197.0 KB	465:09:49

TOTAL: 1		377.7 GB	1019.1 KB	377.7 GB	99%	197.0 KB	465:09:49

Incoming articles:

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

Jan 19 03:00:06 - 03:59:59	78168	3.8%	21.75	14.3 GB	3.8%	4178.38
Jan 19 04:00:00 - 04:59:59	76812	3.8%	21.34	14.5 GB	3.8%	4236.83
Jan 19 05:00:00 - 05:59:59	73274	3.6%	20.35	14.0 GB	3.7%	4073.36
Jan 19 06:00:00 - 06:59:59	75157	3.7%	20.88	15.7 GB	4.1%	4558.86
Jan 19 07:00:00 - 07:59:59	81339	4.0%	22.59	15.6 GB	4.1%	4538.11
Jan 19 08:00:00 - 08:59:59	82045	4.0%	22.79	17.3 GB	4.6%	5049.32
Jan 19 09:00:00 - 09:59:59	86523	4.3%	24.03	18.2 GB	4.8%	5299.73
Jan 19 10:00:00 - 10:59:59	92244	4.5%	25.62	19.2 GB	5.1%	5593.67
Jan 19 11:00:00 - 11:59:59	92400	4.5%	25.67	18.4 GB	4.8%	5355.70
Jan 19 12:00:00 - 12:59:59	85167	4.2%	23.66	16.8 GB	4.4%	4900.67
Jan 19 13:00:00 - 13:59:59	87734	4.3%	24.37	17.0 GB	4.5%	4940.52
Jan 19 14:00:00 - 14:59:59	89049	4.4%	24.74	16.6 GB	4.4%	4844.82
Jan 19 15:00:00 - 15:59:59	87055	4.3%	24.18	16.1 GB	4.2%	4685.93
Jan 19 16:00:00 - 16:59:59	86659	4.3%	24.07	15.8 GB	4.2%	4609.98
Jan 19 17:00:00 - 17:59:59	87451	4.3%	24.29	15.8 GB	4.2%	4598.51
Jan 19 18:00:00 - 18:59:59	85615	4.2%	23.78	15.2 GB	4.0%	4417.63
Jan 19 19:00:00 - 19:59:59	85600	4.2%	23.78	14.0 GB	3.7%	4077.90
Jan 19 20:00:00 - 20:59:59	90854	4.5%	25.24	13.7 GB	3.6%	3988.10
Jan 19 21:00:00 - 21:59:59	80018	3.9%	22.23	15.1 GB	4.0%	4389.89
Jan 19 22:00:00 - 22:59:59	88595	4.4%	24.61	15.3 GB	4.0%	4447.83
Jan 19 23:00:00 - 23:59:59	86495	4.3%	24.03	15.0 GB	4.0%	4362.29
Jan 20 00:00:00 - 00:59:59	83309	4.1%	23.14	14.4 GB	3.8%	4184.75
Jan 20 01:00:00 - 01:59:59	89771	4.4%	24.94	15.6 GB	4.1%	4548.95
Jan 20 02:00:00 - 02:59:59	81036	4.0%	22.51	15.6 GB	4.1%	4556.83
Jan 20 03:00:00 - 03:00:02	63	0.0%	31.50	12.7 MB	0.0%	6524.43

TOTAL: 23:59:56	2032433	100.0%	23.52	379.1 GB	100.0%	4601.68

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	102.3 GB	74.2%	137	751.35	2.23
02	BIN	137.8 GB	32.5 GB	23.6%	138	755.66	2.21
03	SMALL	13.0 GB	1.6 GB	12.0%	10	6.23	25.39
04	BIN	80.1 GB	28.0 GB	34.9%	99	384.91	2.53
05	BIN	80.1 GB	45.3 GB	56.6%	119	386.39	2.52
06	SMALL	13.0 GB	1.6 GB	12.0%	10	6.24	25.37
07	BIN	80.1 GB	13.0 GB	16.3%	99	384.58	2.53
08	BIN	80.1 GB	31.4 GB	39.2%	119	386.17	2.52
09	SMALL	13.0 GB	854.0 MB	6.4%	10	6.18	25.61
10	BIN	80.1 GB	1.3 GB	1.7%	99	384.30	2.53
11	BIN	80.1 GB	55.1 GB	68.8%	119	386.97	2.51
12	SMALL	13.0 GB	1.4 GB	10.7%	10	6.23	25.41
13	BIN	80.1 GB	78.6 GB	98.1%	98	383.77	2.53
14	BIN	80.1 GB	28.6 GB	35.7%	119	385.90	2.52

TOTAL: 14	-	968.5 GB	421.6 GB	43.5%	1186	331.32	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	64752	54955	0	229	0	0	0	0	9568
2	?	49	0	0	0	0	0	0	0	49

TOTAL: 2		64801	54955	0	229	0	0	0	0	9617

Unwanted newsgroups [Top 20]:

Newsgroup	Count

microsoft.public.test	1397
alt.binaries.pictures.erotica.breast.natural	414
alt.binaries.youth	390
alt.binaries.picture.erotica.amature	371
alt.binaires.pictures.erotica.amatuer.female	331
alt.binaries.erotica.pictures.tasteless	329
alt.binaries.movies.classic	306
alt.binaries.picture.erotic.alt.sex	306
alt.binaries.erotica.kinky.teens	295
alt.binaries.pictures.erotica.downblouse	295
alt.sex.teen	292
alt.binaries.pictures.erotica.amateur.digicam	291
alt.binaries.pictures.erotica.pictures	288
alt.binaries.erotics	282
alt.binaries.pictures.erotica.asian.female	279
alt.binaries.pictures.erotica.highheels	275
alt.binaires.erotica	269
alt.binaries.pictures.erotica.erotic	268
alt.binariers.pictures.erotica.oral	262
alt.binaries.pictures.erotica.exibitionism	262

TOTAL: 3110	54955

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	7641
	TOTAL: 1	7641

TOTAL: 1		7641

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	7	46814	4.1 GB	21	0	0	09:17:59
2	ts16-a202.moscow.dial.rol.ru	29	89	10.5 MB	33	0	0	03:13:07
3	ts18-a240.moscow.dial.rol.ru	21	62	5.4 MB	23	0	0	01:45:50
4	ts30-a25.moscow.dial.rol.ru	10	49	299.6 KB	6	0	0	00:04:21
5	ts30-a157.moscow.dial.rol.ru	13	45	59.0 KB	3	0	0	00:16:05
6	joysp.dial.sovam.com	3	2	95.2 KB	2	0	0	00:32:51

TOTAL: 6		83	47061	4.2 GB	88	0	2	15:37:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	7	46814	4.1 GB	21	0	0	09:17:59
2	*.moscow.dial.rol.ru	73	245	16.2 MB	65	0	0	05:19:24
3	*.dial.sovam.com	3	2	95.2 KB	2	0	0	00:32:51
4	*.sovintel.net	15	0	0.0 KB	0	0	0	00:00:41
5	*.lavtech.ru	1	0	0.0 KB	0	0	1	00:00:00
6	*.nsk.dial.rol.ru	14	0	0.0 KB	0	0	0	00:00:10
7	*.tony.pp.ru	71	0	0.0 KB	0	0	0	00:03:12
8	*.rol.ru	5769	0	0.0 KB	0	0	0	00:21:22
9	*.nn.ru	14	0	0.0 KB	0	0	0	00:00:10

TOTAL: 9		5967	47061	4.2 GB	88	0	1	15:37:04

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	23.784	216.010	0.000	09:17:59
ts16-a202.moscow.dial.rol.ru	0.056	0.176	0.000	03:13:07
ts18-a240.moscow.dial.rol.ru	0.080	0.108	0.000	01:45:50
joysp.dial.sovam.com	0.004	0.028	0.000	00:32:51
ts30-a157.moscow.dial.rol.ru	0.032	0.032	0.000	00:16:05
balancer-b.rol.ru	3.704	3.828	0.000	00:11:19
balancer-a.rol.ru	3.472	3.612	0.000	00:10:03
ts30-a25.moscow.dial.rol.ru	0.032	0.044	0.000	00:04:21
net1-x-ring.tony.pp.ru	0.116	0.148	0.000	00:03:12
?	0.004	0.012	0.000	00:01:09
fw-nat.sovintel.net	0.112	0.068	0.000	00:00:41
cornholio.migtel.ru	0.092	0.124	0.000	00:00:13
ts2-a8.nsk.dial.rol.ru	0.016	0.032	0.000	00:00:10
gray.nn.ru	0.004	0.016	0.000	00:00:09
ppp91-122-81-183.pppoe.avangard-dsl.ru	0.004	0.008	0.000	00:00:01
195.218.211.23	0.004	0.000	0.000	00:00:00
ns1.lavtech.ru	0.004	0.008	0.000	00:00:00
delta.nn.ru	0.004	0.008	0.000	00:00:00
174.128.c10008-a77.dsl-dynamic.vsi.ru	0.000	0.004	0.000	00:00:00
89.207.69.186	0.000	0.004	0.000	00:00:00

TOTAL: 21	31.532	224.270	0.000	15:37:19

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2887
balancer-a.rol.ru	2882
net1-x-ring.tony.pp.ru	71
fw-nat.sovintel.net	15
ts2-a8.nsk.dial.rol.ru	14
gray.nn.ru	13
delta.nn.ru	1
ns1.lavtech.ru	1

TOTAL: 8	5884

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	65
ppp91-122-81-183.pppoe.avangard-dsl.ru	2
174.128.c10008-a77.dsl-dynamic.vsi.ru	1
nas5.ozerki.net	1
89.207.69.186	1
195.218.211.23	1

TOTAL: 6	71

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	13

TOTAL: 1	13

NNRP client timeouts [Top 20]:

System	Conn	Peer

joysp.dial.sovam.com	1	0
ts30-a157.moscow.dial.rol.ru	1	0
ts18-a240.moscow.dial.rol.ru	1	0
ts16-a202.moscow.dial.rol.ru	1	0

TOTAL: 4	4	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	46967	99.8%
2	clari	51	0.1%
3	hr	43	0.1%

TOTAL: 3		47061	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	29724
2	alt.binaries.pictures.bras	11387
3	alt.binaries.pictures.artpics	5703
4	alt.binaries.erotica.breasts.large	73
5	alt.binaries.pictures.breasts	51
6	hr.rec.humor	43
7	clari.web.biz.stocks	30
8	alt.binaries.pictures.erotica.nude	17
9	clari.web.world.europe.balkans	8
10	alt.binaries.erotica.amateur.pictures	6
11	clari.web.world.europe.central	3
12	clari.web.world.europe.germany	3
13	clari.web.world.europe.british_isles.biz	3
14	alt.binaries.erotica.pictures.bondage	3
15	alt.binaries.erotica.teen.female.masturbation	2
16	clari.web.world.europe.germany.biz	2
17	clari.web.world.asia.china.biz	1
18	alt.binaries.erotica.post.yourself.nude	1
19	clari.web.world.europe.eastern	1

TOTAL: 19		47061