Daily Usenet report

May 2 03:01:45 -- May 3 13:34:43

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 gethostbyaddr failures
NNRP client timeouts
Newsgroup request counts (by category)
Newsgroup request counts (by newsgroup)

First 5 / 234912 lines (0.0%)
May 2 03:01:50 andromeda expireover[4092]: buffindexed lock II.
May 2 03:01:50 andromeda expireover[4092]: buffindxed group lock -> unlock attemp.
May 2 03:01:50 andromeda expireover[4092]: buffindexed lock II.
May 2 03:01:50 andromeda expireover[4092]: buffindxed group lock -> unlock attemp.
May 2 03:01:50 andromeda expireover[4092]: buffindexed lock II.

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	917508	78.2%	88.7 MB	81.0%
expireover	174984	14.9%	13.0 MB	11.9%
makehistory	59903	5.1%	5.5 MB	5.0%
nnrpd	12410	1.1%	1.2 MB	1.1%
innd	7517	0.6%	996.0 KB	0.9%
cnfsstat	1120	0.1%	200.8 KB	0.2%
ovdb_server	20	0.0%	1.3 KB	0.0%
controlchan	7	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%

TOTAL: 9	1173470	100.0%	109.6 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	1025217	82.5%
Negative hits	212724	17.1%
Positive hits	4561	0.4%
Cache misses	870	0.1%

TOTAL: 4	1243372	100.0%

INND timer:

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

article cancel	00:00:13.466	0.0%	1641	0.000	8.206	409.000
article cleanup	00:00:12.958	0.0%	910140	0.000	0.014	0.037
article logging	00:01:21.668	0.2%	914516	0.000	0.089	7.200
article parse	00:07:55.527	1.1%	49110109	0.005	0.010	0.149
article write	03:05:11.836	26.9%	690693	1.393	16.088	171.466
artlog/artcncl	00:00:00.048	0.0%	1640	0.000	0.029	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:01:50.217	0.3%	52385785	0.000	0.002	0.032
hisgrep/artcncl	00:00:08.254	0.0%	1379	0.000	5.985	409.000
hishave/artcncl	00:00:04.001	0.0%	1641	0.000	2.438	385.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:15:16.236	2.2%	1243945	0.000	0.737	14.015
history sync	00:00:00.157	0.0%	166	0.000	0.946	25.000
history write	00:30:28.202	4.4%	690693	0.000	2.647	23.585
hiswrite/artcncl	00:00:00.950	0.0%	262	0.000	3.626	41.312
idle	03:18:46.860	28.8%	45292571	0.000	0.263	60005.000
nntp read	00:08:32.879	1.2%	48536766	0.003	0.011	0.134
overview write	03:49:20.452	33.3%	690693	0.243	19.923	15132.857
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%	5	0.000	0.000	0.000

TOTAL: 11:29:25.980	11:19:23.711	98.5%	-	-	-	-

nnrpd timer:

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

hisgrep	00:09:46.523	1.0%	103928	0.000	5.644	62.818
idle	11:47:43.139	72.1%	216804	0.000	195.860	279577.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	02:55:07.460	17.8%	777746	0.000	13.510	21237.333
readart	00:50:18.949	5.1%	210512	0.000	14.341	988.000

TOTAL: 16:22:13.028	15:42:56.071	96.0%	-	-	-	-

Control commands to INND:

Command	Number

flush	66
flushlogs	1
paused	2
shutdown	2
throttle	1

TOTAL: 5	72

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK

hun-mnt@news.sztaki.hu	0	0	1	0	0	1
news@sherb.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	302	914607	689410	3887	221310	75%	125:20:04

TOTAL: 1		302	914607	689410	3887	221310	75%	125:20:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	120.4 GB	17.8 MB	120.4 GB	99%	138.6 KB	125:20:04

TOTAL: 1		120.4 GB	17.8 MB	120.4 GB	99%	138.6 KB	125:20:04

Incoming articles:

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

May 2 03:01:45 - 03:59:59	50968	7.4%	14.58	9.3 GB	7.6%	2776.17
May 2 04:00:00 - 04:59:59	58904	8.5%	16.36	10.5 GB	8.6%	3044.26
May 2 05:00:00 - 05:59:59	58695	8.5%	16.30	10.2 GB	8.3%	2961.95
May 2 06:00:00 - 06:59:59	58644	8.5%	16.29	9.9 GB	8.1%	2887.92
May 2 07:00:00 - 07:59:59	54970	7.9%	15.27	10.1 GB	8.2%	2929.78
May 2 08:00:00 - 08:59:59	54085	7.8%	15.02	10.1 GB	8.3%	2947.36
May 2 09:00:00 - 09:59:59	74670	10.8%	20.74	13.9 GB	11.4%	4053.80
May 2 10:00:00 - 10:59:59	62228	9.0%	17.29	11.1 GB	9.1%	3230.15
May 2 11:00:00 - 11:59:59	66422	9.6%	18.45	11.7 GB	9.6%	3407.17
May 2 12:00:00 - 12:59:59	29491	4.3%	8.19	5.0 GB	4.1%	1456.00
May 2 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 18:00:00 - 18:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 19:00:00 - 19:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 20:00:00 - 20:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 21:00:00 - 21:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 22:00:00 - 22:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 2 23:00:00 - 23:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 00:00:00 - 00:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 03:00:00 - 03:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 04:00:00 - 04:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 05:00:00 - 05:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 06:00:00 - 06:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 07:00:00 - 07:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 08:00:00 - 08:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 09:00:00 - 09:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 10:00:00 - 10:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 3 11:00:00 - 11:59:59	4663	0.7%	1.30	451.9 MB	0.4%	128.54
May 3 12:00:00 - 12:59:59	73208	10.6%	20.34	12.0 GB	9.8%	3490.75
May 3 13:00:00 - 13:34:43	44918	6.5%	21.56	8.0 GB	6.5%	4005.72

TOTAL: 34:32:58	691866	100.0%	5.56	122.1 GB	100.0%	1028.98

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	73.9 GB	53.6%	18	788.54	2.12
02	BIN	137.8 GB	73.5 GB	53.3%	18	777.44	2.15
03	SMALL	13.0 GB	2.1 GB	15.8%	1	5.40	29.29
04	LARGE	80.1 GB	20.0 MB	0.0%	0	0.00	0.00
05	BIN	80.1 GB	38.6 GB	48.2%	16	393.66	2.47
06	SMALL	13.0 GB	2.0 GB	15.5%	1	5.48	28.89
07	LARGE	80.1 GB	20.0 MB	0.0%	0	0.00	0.00
08	BIN	80.1 GB	38.7 GB	48.4%	16	393.99	2.47
09	SMALL	13.0 GB	2.1 GB	15.8%	1	5.42	29.20
10	LARGE	80.1 GB	20.0 MB	0.0%	0	0.00	0.00
11	BIN	80.1 GB	38.9 GB	48.5%	16	394.60	2.46
12	SMALL	13.0 GB	2.0 GB	15.6%	1	5.47	28.92
13	LARGE	80.1 GB	20.0 MB	0.0%	0	0.00	0.00
14	BIN	80.1 GB	39.0 GB	48.7%	16	394.02	2.47

TOTAL: 14	-	968.5 GB	310.8 GB	32.1%	104	317.09	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	223999	219329	0	138	0	0	0	0	4532
2	?	3	0	0	0	0	0	0	0	3

TOTAL: 2		224002	219329	0	138	0	0	0	0	4535

Unwanted newsgroups [Top 20]:

Newsgroup	Count

rss.netflix	8054
alt.binaries.mom	6617
alt.binaries.multimedia.erotica.male.repost	6149
alt.binaries.multimedia.erotica.black	5677
alt.binaries.bloaf	4541
alt.binaries.mst3k.riffs.ect.nopasswords	4447
alt.binaries.tv.deutsch	4256
alt.binaries.hdtv.french	4229
alt.binaries.mp3.audiobooks	4203
alt.binaries.usenet2day	4093
alt.binaries.hdtv.x264	3741
alt.binaries.highspeed	3720
rss.fark	3518
alt.binaries.multimedia.documentaries	3494
alt.binaries.movies.french.xxx	3334
alt.binaries.ufg	3319
alt.binaries.southern-charms.pictures	3105
alt.binaries.classic.tv.shows	3068
alt.binaries.seduction	3064
alt.binaries.colossus	3061

TOTAL: 2674	219329

INND misc events:

Events	Count

CCreader	2

TOTAL: 1	2

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Bad command received
	192.168.33.37	270
	TOTAL: 1	270
Including strange strings
	192.168.33.37	4368
	TOTAL: 1	4368

TOTAL: 2		4638

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	15	103771	1.9 GB	120	0	0	08:55:37
2	news.bytecity.ru	1	152	524.9 KB	82	0	0	00:00:11
3	194.186.96.249	2	141	384.1 KB	2	0	0	00:02:19
4	81.211.124.120.adsl-spb.net.rol.ru	61	138	421.1 KB	1664	0	0	00:04:02
5	ts2-a176.angarsk.dial.rol.ru	12	104	17.9 MB	1	0	0	04:24:40
6	ts2-a45.novokuznetzk.dial.rol.ru	5	78	167.9 KB	3	0	0	00:04:08
7	195.46.178.115	2	58	120.9 KB	1	0	0	00:01:05
8	195.239.203.176	3	52	8.0 MB	2	0	0	01:06:05
9	ts2-a35.omsk.dial.rol.ru	1	51	5.8 MB	1	0	0	00:34:36
10	195.239.70.117	10	39	130.7 KB	2	0	0	00:04:06
11	ts1-b115.tyumen.dial.rol.ru	1	34	209.3 KB	1	0	0	00:00:53
12	ts27-b65.moscow.dial.rol.ru	10	24	47.0 KB	2	0	0	00:00:57
13	81.211.124.120	9	24	55.7 KB	310	0	0	00:03:22
14	212.46.255.87	6	12	25.0 KB	2	0	0	00:02:12
15	ts18-a18.moscow.dial.rol.ru	1	11	1.3 MB	1	0	0	00:05:09
16	195.218.197.22	3	10	603.5 KB	1	0	0	00:02:24
17	dyn-20-170.dynadsl.kis.ru	14	10	29.7 KB	2	1	0	00:00:52
18	194.186.126.208	2	9	15.3 KB	1	0	0	00:00:27
19	szaa4.dial.sovam.com	2	6	684.1 KB	32	0	0	00:05:39
20	ts12-b39.spb.dial.rol.ru	2	5	16.0 KB	1	0	0	00:00:23
21	212.44.130.15	4	4	11.5 KB	21	0	0	00:01:02
22	feed-bkup.private	1	3	5.9 KB	1	0	0	00:01:52
23	fw-nat.sovintel.net	6	2	4.9 KB	7	0	0	00:00:12
24	ts2-a208.ufa.dial.rol.ru	1	1	1.2 KB	1	0	0	00:00:09

TOTAL: 24		174	104739	1.9 GB	2261	1	0	16:21:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	15	103771	1.9 GB	120	0	0	08:55:37
2	unresolved	55	349	9.3 MB	342	0	0	01:43:13
3	*.bytecity.ru	1	152	524.9 KB	82	0	0	00:00:11
4	*.211.124.120.adsl-spb.net.rol.ru	61	138	421.1 KB	1664	0	0	00:04:02
5	*.angarsk.dial.rol.ru	12	104	17.9 MB	1	0	0	04:24:40
6	*.novokuznetzk.dial.rol.ru	5	78	167.9 KB	3	0	0	00:04:08
7	*.omsk.dial.rol.ru	1	51	5.8 MB	1	0	0	00:34:36
8	*.moscow.dial.rol.ru	17	35	1.4 MB	3	0	0	00:06:35
9	*.tyumen.dial.rol.ru	1	34	209.3 KB	1	0	0	00:00:53
10	*.dynadsl.kis.ru	22	10	29.7 KB	2	1	0	00:00:55
11	*.dial.sovam.com	2	6	684.1 KB	32	0	0	00:05:39
12	*.spb.dial.rol.ru	2	5	16.0 KB	1	0	0	00:00:23
13	*.private	1	3	5.9 KB	1	0	0	00:01:52
14	*.sovintel.net	6	2	4.9 KB	7	0	0	00:00:12
15	*.ufa.dial.rol.ru	1	1	1.2 KB	1	0	0	00:00:09
16	*.lek.ru	3	0	0.0 KB	0	0	0	00:10:06
17	*.gp-group.ru	1	0	0.0 KB	0	0	0	00:00:00
18	*.tony.pp.ru	33	0	0.0 KB	0	0	0	00:00:23
19	*.rol.ru	2796	0	0.0 KB	0	0	0	00:07:40

TOTAL: 19		3035	104739	1.9 GB	2261	1	0	16:21:35

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	14.340	36.640	0.000	08:55:37
ts2-a176.angarsk.dial.rol.ru	0.344	0.832	0.000	04:24:40
195.239.203.176	0.012	0.056	0.000	01:06:05
ts2-a35.omsk.dial.rol.ru	0.012	0.052	0.000	00:34:36
195.239.203.179	0.004	0.000	0.000	00:10:15
news.lek.ru	0.120	0.252	0.000	00:10:06
212.46.254.1	0.000	0.008	0.000	00:06:06
szaa4.dial.sovam.com	0.004	0.016	0.000	00:05:39
ts18-a18.moscow.dial.rol.ru	0.004	0.012	0.000	00:05:09
ts2-a45.novokuznetzk.dial.rol.ru	0.016	0.020	0.000	00:04:08
195.239.70.117	0.016	0.044	0.000	00:04:06
81.211.124.120.adsl-spb.net.rol.ru	0.644	1.020	0.000	00:04:02
balancer-b.rol.ru	1.936	1.864	0.000	00:03:54
balancer-a.rol.ru	1.788	1.820	0.000	00:03:45
81.211.124.120	0.140	0.168	0.000	00:03:22
195.46.161.55	0.000	0.004	0.000	00:02:26
195.218.197.22	0.008	0.020	0.000	00:02:24
194.186.96.249	0.012	0.056	0.000	00:02:19
212.46.255.87	0.008	0.036	0.000	00:02:12
feed-bkup.private	0.004	0.012	0.000	00:01:52

TOTAL: 39	19.752	43.672	0.000	16:21:59

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	1401
balancer-a.rol.ru	1395
net1-x-ring.tony.pp.ru	33
dyn-20-46.dynadsl.kis.ru	8
195.46.161.55	7
212.46.254.1	4
ts12-b111.moscow.dial.rol.ru	3
news.lek.ru	3
ts9-a117.moscow.dial.rol.ru	3
194.186.135.35	1
out6.gp-group.ru	1
195.239.203.179	1
212.46.203.39	1

TOTAL: 13	2861

NNRP no permission clients:

System	Conn

88.87.86.57	15
pppoe-153.23.110.89-adsl.spbnit.ru	2

TOTAL: 2	17

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	3

TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-a176.angarsk.dial.rol.ru	5	0
ts2-a35.omsk.dial.rol.ru	1	0
195.239.203.179	1	0

TOTAL: 3	7	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	106911	99.3%
2	fido7	447	0.4%
3	comp	176	0.2%
4	linux	63	0.1%
5	fa	38	0.0%
6	de	4	0.0%
7	viwa	1	0.0%
8	muc	1	0.0%

TOTAL: 8		107641	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.artpics	23952
2	alt.binaries.pictures.aviation	21672
3	alt.binaries.pictures.bikini.central	20674
4	alt.binaries.pictures.bras	18855
5	alt.binaries.pictures.animals	15626
6	alt.binaries.pictures.anime	3260
7	alt.binaries.pictures.automobile	1460
8	alt.binaries.pictures.art.pin-up	1176
9	fido7.su.history	104
10	alt.binaries.pictures.youth-and-beauty	82
11	linux.kernel	63
12	comp.lang.c++	61
13	fido7.ru.linux.chainik	58
14	fido7.su.hardw.chainik	55
15	alt.binaries.pictures.erotica.bondage	51
16	alt.binaries.nospam.breasts.natural	51
17	fa.linux.kernel	38
18	fido7.mo.economics	37
19	fido7.su.pilot	35
20	fido7.ru.windows.xp	34
21	fido7.ru.baby	26
22	fido7.ru.unix.bsd	24
23	alt.binaries.amp	22
24	fido7.su.hardw.pc.video	21
25	comp.programming.threads	19
26	comp.databases.oracle.server	17
27	comp.lang.python	15
28	alt.binaries.pictures.nudism.youth.and.families	11
29	comp.lang.c	11
30	comp.lang.lisp	10
31	alt.binaries.multimedia.erotica.voyeurism	10
32	fido7.ru.dogs	10
33	comp.unix.programmer	9
34	fido7.crack.talks	9
35	comp.os.linux.misc	8
36	comp.lang.perl.misc	8
37	fido7.pvt.cat.club	7
38	fido7.ru.linux	6
39	alt.binaries.aoi	5
40	comp.lang.clipper	5
41	de.etc.militaer	4
42	alt.certification.cisco	4
43	comp.lang.tcl	3
44	comp.lang.postscript	3
45	fido7.mistress.rus	3
46	fido7.ru.cars.japan	3
47	fido7.ru.unix.prog	3
48	fido7.ru.cisco	3
49	fido7.ru.java	2
50	fido7.su.hardw.cdrom	2
51	comp.protocols.tcp-ip	2
52	fido7.ru.psychology	2
53	comp.sys.dec	2
54	fido7.ru.os.cmp	2
55	muc.lists.freebsd.stable	1
56	comp.databases.ibm-db2	1
57	comp.dcom.sys.cisco	1
58	fido7.ru.sex	1
59	viwa.crack.and.hack	1
60	comp.os.linux.security	1

TOTAL: 60		107641