Daily Usenet report

Nov 18 03:00:02 -- Nov 19 10:41:06

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 / 6 lines (83.3%)
Nov 18 08:48:18 andromeda expireover[25307]: OVDB: expiregroup: recounting alt.binaries.sounds.mp3.classic-rock
Nov 18 11:37:10 andromeda controlchan[31688]: skipping newgroup alt.fr.la.condition.militaire y fff@alteferians.org (would be created)
Nov 18 20:43:59 andromeda ovdb_monitor[10610]: OVDB: realloc: Cannot allocate memory: 1614544896
Nov 18 20:44:00 andromeda ovdb_monitor[10610]: OVDB: txn_checkpoint: failed to flush the buffer cache Cannot allocate memory
Nov 18 20:44:34 andromeda ovdb_monitor[10610]: OVDB: realloc: Cannot allocate memory: 1614544896

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	1662757	94.5%	164.7 MB	94.6%
nnrpd	81455	4.6%	7.3 MB	4.2%
innd	11574	0.7%	1.6 MB	0.9%
cnfsstat	3008	0.2%	530.8 KB	0.3%
ovdb_monitor	4	0.0%	0.4 KB	0.0%
controlchan	3	0.0%	0.3 KB	0.0%
expireover	1	0.0%	0.1 KB	0.0%

TOTAL: 7	1758802	100.0%	174.1 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	1655366	96.4%
Negative hits	40032	2.3%
Positive hits	21152	1.2%
Cache misses	601	0.0%

TOTAL: 4	1717151	100.0%

INND timer:

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

article cancel	00:01:37.249	0.1%	29941	0.000	3.248	175.778
article cleanup	00:00:25.821	0.0%	1627754	0.006	0.016	0.027
article logging	00:01:46.267	0.2%	1633649	0.004	0.065	2.781
article parse	00:16:00.140	1.5%	102565122	0.006	0.009	0.115
article write	05:43:15.932	31.3%	1595869	1.616	12.906	697.971
artlog/artcncl	00:00:03.183	0.0%	28380	0.000	0.112	88.556
artlog/artparse	00:00:00.002	0.0%	55	0.000	0.036	1.000
data move	00:03:57.811	0.4%	109200522	0.002	0.002	0.009
hisgrep/artcncl	00:00:45.054	0.1%	17804	0.000	2.531	443.750
hishave/artcncl	00:00:16.639	0.0%	29941	0.000	0.556	133.308
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:40:27.930	3.7%	1687854	0.002	1.438	13.568
history sync	00:00:00.437	0.0%	240	0.000	1.821	23.000
history write	01:49:19.388	10.0%	1623862	0.041	4.039	25.072
hiswrite/artcncl	00:00:31.869	0.0%	12137	0.000	2.626	402.333
idle	04:18:30.159	23.5%	94075848	0.103	0.165	60005.000
nntp read	00:17:34.635	1.6%	101303676	0.007	0.010	0.095
overview write	04:42:52.916	25.8%	1595869	3.114	10.636	54.982
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:21.958	0.0%	1595870	0.000	0.014	0.025

TOTAL: 18:18:12.742	17:57:47.390	98.1%	-	-	-	-

nnrpd timer:

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

hisgrep	00:09:37.932	1.6%	7473	0.000	77.336	457.333
idle	07:16:31.375	72.4%	185435	0.000	141.243	200202.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	01:31:10.541	15.1%	421336	0.000	12.984	17862.040
readart	00:08:30.856	1.4%	15566	0.000	32.819	130.419

TOTAL: 10:02:35.801	09:05:50.704	90.6%	-	-	-	-

Control commands to INND:

Command	Number

flush	110
flushlogs	1
paused	2

TOTAL: 3	113

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK

fff@alteferians.org	1	0	0	0	0	1

TOTAL	1	0	0	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	260	1633177	1600713	65	32399	98%	354:23:44

TOTAL: 1		260	1633177	1600713	65	32399	98%	354:23:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	298.9 GB	17.4 MB	299.0 GB	99%	192.0 KB	354:23:44

TOTAL: 1		298.9 GB	17.4 MB	299.0 GB	99%	192.0 KB	354:23:44

Incoming articles:

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

Nov 18 03:00:02 - 03:59:59	88189	5.5%	24.51	16.0 GB	5.3%	4656.54
Nov 18 04:00:00 - 04:59:59	74581	4.7%	20.72	13.2 GB	4.4%	3848.34
Nov 18 05:00:00 - 05:59:59	77921	4.9%	21.64	14.7 GB	4.9%	4287.47
Nov 18 06:00:00 - 06:59:59	81770	5.1%	22.71	16.1 GB	5.3%	4679.21
Nov 18 07:00:00 - 07:59:59	86450	5.4%	24.01	17.5 GB	5.8%	5096.12
Nov 18 08:00:00 - 08:59:59	83614	5.2%	23.23	16.8 GB	5.6%	4898.77
Nov 18 09:00:00 - 09:59:59	83925	5.3%	23.31	16.6 GB	5.5%	4842.33
Nov 18 10:00:00 - 10:59:59	86543	5.4%	24.04	18.0 GB	6.0%	5231.13
Nov 18 11:00:00 - 11:59:59	91893	5.8%	25.53	18.6 GB	6.2%	5425.18
Nov 18 12:00:00 - 12:59:59	91386	5.7%	25.39	18.6 GB	6.2%	5428.81
Nov 18 13:00:00 - 13:59:59	93563	5.9%	25.99	17.7 GB	5.9%	5160.13
Nov 18 14:00:00 - 14:59:59	91061	5.7%	25.29	17.7 GB	5.9%	5142.70
Nov 18 15:00:00 - 15:59:59	94081	5.9%	26.13	18.4 GB	6.1%	5350.16
Nov 18 16:00:00 - 16:59:59	97689	6.1%	27.14	17.2 GB	5.7%	5022.24
Nov 18 17:00:00 - 17:59:59	101813	6.4%	28.28	17.6 GB	5.8%	5112.79
Nov 18 18:00:00 - 18:59:59	93550	5.9%	25.99	16.4 GB	5.4%	4768.82
Nov 18 19:00:00 - 19:59:59	86500	5.4%	24.03	15.3 GB	5.1%	4447.82
Nov 18 20:00:00 - 20:59:59	66493	4.2%	18.47	11.8 GB	3.9%	3442.41
Nov 18 21:00:00 - 21:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 18 22:00:00 - 22:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 18 23:00:00 - 23:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 00:00:00 - 00:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 03:00:00 - 03:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 04:00:00 - 04:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 05:00:00 - 05:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 06:00:00 - 06:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 07:00:00 - 07:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 08:00:00 - 08:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 09:00:00 - 09:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 19 10:00:00 - 10:41:06	25500	1.6%	10.34	3.0 GB	1.0%	1263.31

TOTAL: 31:41:04	1596522	100.0%	14.00	301.1 GB	100.0%	2768.04

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	108.0 GB	78.3%	93	798.14	2.10
02	BIN	137.8 GB	124.3 GB	90.2%	93	803.79	2.08
03	SMALL	13.0 GB	1.2 GB	9.5%	6	6.03	26.25
04	BIN	80.1 GB	54.8 GB	68.4%	64	405.22	2.40
05	BIN	80.1 GB	26.3 GB	32.8%	81	406.29	2.39
06	SMALL	13.0 GB	1.0 GB	8.0%	6	6.01	26.31
07	BIN	80.1 GB	50.1 GB	62.5%	64	403.98	2.41
08	BIN	80.1 GB	22.6 GB	28.3%	81	405.31	2.40
09	SMALL	13.0 GB	1.1 GB	8.7%	6	6.04	26.19
10	BIN	80.1 GB	47.1 GB	58.8%	64	402.86	2.41
11	BIN	80.1 GB	32.5 GB	40.5%	81	406.10	2.39
12	BIN	13.0 GB	8.3 GB	63.7%	35	403.11	0.39
13	BIN	80.1 GB	65.5 GB	81.8%	5	404.77	2.40
14	SMALL	80.1 GB	7.4 GB	9.2%	0	6.03	161.20

TOTAL: 14	-	968.5 GB	550.2 GB	56.8%	679	348.30	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	37787	27994	0	3885	0	0	0	0	5908
2	?	55	0	0	0	0	0	0	0	55

TOTAL: 2		37842	27994	0	3885	0	0	0	0	5963

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.b4e.erotica	1928
microsoft.public.test	905
alt.binaries.jerry	499
alt.binaries.pictures.erotica.asian.female	462
alt.binaries.pictures.erotica.transsexual	427
alt.binaries.pictures.erotica.downblouse	382
alt.binaries.pictures.erotica.breats	299
alt.binaries.picture.erotica.amateur.facials	266
alt.binaries.pictures.erotica.goo-shot	246
alt.binaries.pictures.erotica.female.masturbation	244
alt.binaries.pictuers.erotica.female	241
alt.binaries.ftn.music	235
alt.binaries.pictures.amateur.butts	224
alt.binaires.pictures.erotica.butts	218
alt.binaries.pictures.erotica.breasts.sm	205
alt.binaries.erotica.kinky.teens	200
alt.binaries.easeynews.pervert	196
alt.binaries.pictures.erotica.nipples	194
alt.biniaries.erotica	189
alt.binaries.erotica.best-teens	188

TOTAL: 1447	27994

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Bad command received
	192.168.33.37	9
	TOTAL: 1	9
Including strange strings
	192.168.33.37	5899
	TOTAL: 1	5899

TOTAL: 2		5908

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	1	7391	1008.7 MB	3	0	0	04:33:28
2	ts30-b188.moscow.dial.rol.ru	16	85	172.1 KB	3	0	0	00:04:31
3	ts1-b235.saratov.dial.rol.ru	4	77	8.1 MB	0	0	0	01:41:55
4	194.154.66.225	7	61	9.7 MB	68	0	0	00:29:42
5	ts30-a205.moscow.dial.rol.ru	1	14	761.1 KB	3	0	0	00:35:13
6	ts16-a95.moscow.dial.rol.ru	4	12	474.4 KB	8	0	0	00:43:00
7	adsl-195.218.206.168.ranetka.ru	2	9	519.3 KB	6	0	0	00:04:25
8	ts2-a135.syktyvkar.dial.rol.ru	3	3	156.5 KB	1	0	0	00:02:59

TOTAL: 8		38	7652	1.0 GB	92	0	0	10:02:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	1	7391	1008.7 MB	3	0	0	04:33:28
2	*.moscow.dial.rol.ru	28	111	1.4 MB	14	0	0	01:37:39
3	*.saratov.dial.rol.ru	4	77	8.1 MB	0	0	0	01:41:55
4	unresolved	9	61	9.7 MB	68	0	0	00:34:29
5	*.218.206.168.ranetka.ru	2	9	519.3 KB	6	0	0	00:04:25
6	*.syktyvkar.dial.rol.ru	3	3	156.5 KB	1	0	0	00:02:59
7	*.ulyanovsk.dial.rol.ru	1	0	0.0 KB	0	0	0	00:02:52
8	*.krasnodar.dial.rol.ru	1	0	0.0 KB	0	0	0	00:08:35
9	*.krasnoyarsk.dial.rol.ru	4	0	0.0 KB	0	0	0	00:00:03
10	*.sovintel.net	14	0	0.0 KB	0	0	0	00:00:49
11	*.tony.pp.ru	57	0	0.0 KB	0	0	0	00:17:54
12	*.rol.ru	4365	0	0.0 KB	0	0	0	00:15:29
13	*.nn.ru	8	0	0.0 KB	0	0	0	00:00:01
14	*.nsk.dial.rol.ru	14	0	0.0 KB	0	0	0	00:00:46

TOTAL: 14		4511	7652	1.0 GB	92	0	0	09:35:07

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	4.308	41.311	0.000	04:33:28
ts1-b235.saratov.dial.rol.ru	0.020	0.088	0.000	01:41:55
ts16-a95.moscow.dial.rol.ru	0.028	0.016	0.000	00:43:00
ts30-a205.moscow.dial.rol.ru	0.004	0.024	0.000	00:35:13
194.154.66.225	0.032	0.124	0.000	00:29:42
cornholio.migtel.ru	23.972	25.556	0.000	00:26:54
net1-x-ring.tony.pp.ru	2.504	7.324	0.000	00:17:54
ts30-a136.moscow.dial.rol.ru	0.004	0.004	0.000	00:14:48
?	0.016	0.036	0.000	00:13:36
ts1-a162.krasnodar.dial.rol.ru	0.000	0.000	0.000	00:08:35
balancer-b.rol.ru	2.648	2.976	0.000	00:08:31
balancer-a.rol.ru	2.708	2.912	0.000	00:06:58
213.206.41.219	0.112	0.236	0.000	00:04:47
ts30-b188.moscow.dial.rol.ru	0.040	0.060	0.000	00:04:31
adsl-195.218.206.168.ranetka.ru	0.004	0.016	0.000	00:04:25
ts2-a135.syktyvkar.dial.rol.ru	0.028	0.108	0.000	00:02:59
ts1-a22.ulyanovsk.dial.rol.ru	0.004	0.004	0.000	00:02:52
fw-nat.sovintel.net	0.096	0.044	0.000	00:00:49
ts2-a224.nsk.dial.rol.ru	0.024	0.032	0.000	00:00:46
ts23-b115.moscow.dial.rol.ru	0.008	0.004	0.000	00:00:05

TOTAL: 24	36.592	80.911	0.000	10:02:01

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2199
balancer-a.rol.ru	2166
net1-x-ring.tony.pp.ru	57
fw-nat.sovintel.net	14
ts2-a224.nsk.dial.rol.ru	14
gray.nn.ru	8
ts23-b115.moscow.dial.rol.ru	5
ts1-a191.krasnoyarsk.dial.rol.ru	4
ts30-a136.moscow.dial.rol.ru	2
ts1-a162.krasnodar.dial.rol.ru	1
ts1-a22.ulyanovsk.dial.rol.ru	1
194.186.32.82	1
213.206.41.219	1

TOTAL: 13	4473

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	16768
wn2nat45.beelinegprs.ru	1

TOTAL: 2	16769

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	43

TOTAL: 1	43

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts16-a95.moscow.dial.rol.ru	2	0
ts30-a136.moscow.dial.rol.ru	1	0
ts30-a205.moscow.dial.rol.ru	1	0

TOTAL: 3	4	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	7523	99.5%
2	a	41	0.5%

TOTAL: 2		7564	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	5866
2	alt.binaries.pictures.bras	1124
3	alt.binaries.pictures.artpics	384
4	alt.basement.graveyard	44
5	alt.binaries.models.petite	38
6	a.bsu.talk	30
7	alt.binaries.pictures.vladmodels	26
8	alt.binaries.pictures.erotica.porn	13
9	a.bsu.religion	11
10	alt.binaries.erotica.pregnant	8
11	alt.binaries.pictures.erotica.age.13-17	6
12	alt.binaries.nospam.female.bodyhair	3
13	alt.binaries.pictures.erotic.pre-teen	3
14	alt.binaries.nospam.incest	3
15	alt.binaries.pictures.sandra	2
16	alt.binaries.pictures.lolita	2
17	alt.binaries.pictures.kid	1

TOTAL: 17		7564