Daily Usenet report

Sep 27 03:00:05 -- Sep 28 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 gethostbyaddr failures
NNRP client timeouts
Newsgroup request counts (by category)
Newsgroup request counts (by newsgroup)

First 5 / 84 lines (6.0%)
Sep 27 05:10:18 andromeda controlchan[17396]: Unknown "cmsg" control by Lendlice.bbs@bbs.wretch.cc
Sep 27 05:20:10 andromeda controlchan[17396]: Unknown "cmsg" control by elina.bbs@bbs.wretch.cc
Sep 27 05:20:11 andromeda controlchan[17396]: Unknown "cmsg" control by elina.bbs@bbs.wretch.cc
Sep 27 06:50:30 andromeda controlchan[17396]: Unknown "cmsg" control by sweetpibo.bbs@bbs.wretch.cc
Sep 27 07:30:26 andromeda controlchan[17396]: Unknown "cmsg" control by jamchin.bbs@bbs.wretch.cc

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	2608244	97.7%	234.0 MB	97.3%
innd	37389	1.4%	4.1 MB	1.7%
nnrpd	21393	0.8%	2.1 MB	0.9%
cnfsstat	2304	0.1%	401.7 KB	0.2%
controlchan	85	0.0%	8.9 KB	0.0%

TOTAL: 5	2669415	100.0%	240.6 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	2445725	92.6%
Cache misses	121488	4.6%
Negative hits	44627	1.7%
Positive hits	30148	1.1%

TOTAL: 4	2641988	100.0%

INND timer:

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

article cancel	00:04:04.195	0.3%	159416	0.000	1.532	2084.000
article cleanup	00:00:36.680	0.0%	2435062	0.006	0.015	0.026
article logging	00:02:06.249	0.1%	2449387	0.004	0.052	3.479
article parse	00:21:34.922	1.5%	151756134	0.006	0.009	0.023
article write	10:38:45.346	44.3%	2404551	4.757	15.939	24.350
artlog/artcncl	00:00:12.522	0.0%	159269	0.000	0.079	268.400
artlog/artparse	00:00:00.003	0.0%	85	0.000	0.035	1.000
data move	00:05:34.525	0.4%	160715688	0.002	0.002	0.003
hisgrep/artcncl	00:02:55.231	0.2%	152542	0.000	1.149	2084.000
hishave/artcncl	00:00:54.386	0.1%	159416	0.000	0.341	17.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:09:25.824	0.7%	2486930	0.001	0.228	6.583
history sync	00:00:00.272	0.0%	290	0.000	0.938	2.000
history write	00:44:55.153	3.1%	2404551	0.037	1.121	9.799
hiswrite/artcncl	00:00:00.189	0.0%	6874	0.000	0.027	7.500
idle	05:54:51.171	24.6%	141157697	0.119	0.151	0.437
nntp read	00:24:04.377	1.7%	150008344	0.007	0.010	0.023
overview write	04:59:35.542	20.8%	2404551	1.445	7.476	166.961
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.001	0.0%	84	0.000	0.012	1.000

TOTAL: 24:00:23.229	23:29:36.588	97.9%	-	-	-	-

nnrpd timer:

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

hisgrep	00:30:49.302	2.3%	94839	0.000	19.499	81.828
idle	19:39:10.362	86.2%	215465	0.000	328.361	200910.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:21:46.557	1.6%	681689	0.000	1.917	3949.091
readart	00:48:52.226	3.6%	195730	0.000	14.981	216.500

TOTAL: 22:48:36.782	21:20:38.447	93.6%	-	-	-	-

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

gvoltagg@verizon.net	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	326	2409157	2380209	3	28945	98%	474:44:57

TOTAL: 1		326	2409157	2380209	3	28945	98%	474:44:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	396.4 GB	2.9 MB	396.4 GB	99%	172.5 KB	474:44:57

TOTAL: 1		396.4 GB	2.9 MB	396.4 GB	99%	172.5 KB	474:44:57

Incoming articles:

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

Sep 27 03:00:05 - 03:59:59	93145	3.9%	25.91	17.0 GB	4.3%	4955.41
Sep 27 04:00:00 - 04:59:59	87968	3.7%	24.44	16.9 GB	4.2%	4911.21
Sep 27 05:00:00 - 05:59:59	96113	4.0%	26.70	15.4 GB	3.9%	4493.46
Sep 27 06:00:00 - 06:59:59	110504	4.6%	30.70	15.2 GB	3.8%	4435.88
Sep 27 07:00:00 - 07:59:59	96609	4.0%	26.84	12.1 GB	3.0%	3533.95
Sep 27 08:00:00 - 08:59:59	108327	4.5%	30.09	15.1 GB	3.8%	4402.93
Sep 27 09:00:00 - 09:59:59	91779	3.8%	25.49	14.7 GB	3.7%	4290.18
Sep 27 10:00:00 - 10:59:59	84160	3.5%	23.38	17.0 GB	4.3%	4940.15
Sep 27 11:00:00 - 11:59:59	96385	4.0%	26.77	15.2 GB	3.8%	4438.04
Sep 27 12:00:00 - 12:59:59	127635	5.3%	35.45	13.0 GB	3.3%	3790.87
Sep 27 13:00:00 - 13:59:59	118740	4.9%	32.98	16.3 GB	4.1%	4742.70
Sep 27 14:00:00 - 14:59:59	122191	5.1%	33.94	16.3 GB	4.1%	4760.17
Sep 27 15:00:00 - 15:59:59	109675	4.6%	30.47	17.3 GB	4.3%	5038.94
Sep 27 16:00:00 - 16:59:59	98793	4.1%	27.44	18.7 GB	4.7%	5443.26
Sep 27 17:00:00 - 17:59:59	97759	4.1%	27.16	17.8 GB	4.5%	5190.81
Sep 27 18:00:00 - 18:59:59	97261	4.0%	27.02	18.3 GB	4.6%	5329.44
Sep 27 19:00:00 - 19:59:59	95402	4.0%	26.50	17.8 GB	4.5%	5172.31
Sep 27 20:00:00 - 20:59:59	97186	4.0%	27.00	18.0 GB	4.5%	5252.97
Sep 27 21:00:00 - 21:59:59	99983	4.2%	27.77	17.9 GB	4.5%	5209.23
Sep 27 22:00:00 - 22:59:59	99139	4.1%	27.54	18.4 GB	4.6%	5354.55
Sep 27 23:00:00 - 23:59:59	94650	3.9%	26.29	18.2 GB	4.6%	5312.47
Sep 28 00:00:00 - 00:59:59	87865	3.7%	24.41	16.1 GB	4.0%	4685.73
Sep 28 01:00:00 - 01:59:59	93956	3.9%	26.10	16.6 GB	4.2%	4826.52
Sep 28 02:00:00 - 02:59:59	98780	4.1%	27.44	18.7 GB	4.7%	5454.74
Sep 28 03:00:00 - 03:00:02	66	0.0%	33.00	10.8 MB	0.0%	5544.10

TOTAL: 23:59:57	2404071	100.0%	27.83	398.1 GB	100.0%	4831.92

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	102.8 GB	74.6%	72	815.56	2.05
02	BIN	137.8 GB	115.8 GB	84.0%	72	805.23	2.08
03	SMALL	13.0 GB	4.4 GB	33.7%	4	6.86	23.06
04	BIN	80.1 GB	47.2 GB	58.9%	46	404.23	2.40
05	BIN	80.1 GB	16.7 GB	20.9%	63	406.13	2.39
06	SMALL	13.0 GB	4.3 GB	32.9%	4	6.85	23.10
07	BIN	80.1 GB	43.3 GB	54.1%	46	402.77	2.41
08	BIN	80.1 GB	14.4 GB	18.0%	63	404.72	2.40
09	SMALL	13.0 GB	4.4 GB	33.4%	4	6.88	23.01
10	BIN	80.1 GB	40.8 GB	51.0%	46	402.42	2.42
11	BIN	80.1 GB	21.8 GB	27.2%	63	406.76	2.39
12	SMALL	13.0 GB	4.3 GB	33.3%	4	6.82	23.19
13	BIN	80.1 GB	40.4 GB	50.4%	46	401.89	2.42
14	BIN	80.1 GB	14.4 GB	17.9%	63	405.70	2.40

TOTAL: 14	-	968.5 GB	475.0 GB	49.0%	596	349.69	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	44808	30377	0	97	0	0	0	0	14334
2	?	85	0	0	0	0	0	0	0	85

TOTAL: 2		44893	30377	0	97	0	0	0	0	14419

Unwanted newsgroups [Top 20]:

Newsgroup	Count

.usa	1290
alt.binaries.hotrod	694
alt.binaries.	578
alt.binaries.pictures.amateur.butts	398
alt.binaries.amateur.gangbang	367
alt.binaries.pictures.erotica.asian.female	365
alt.binaries.dvd.gaycinema	322
alt.binaries.pictuers.erotica.female	204
alt.binaries.fetish	198
alt.binaries.pictures.erotica.latinal	197
pedo.japanese.fetish.lolita	196
alt.binaries.multimedia.bisexual	195
alt.binaries.jerry	190
alt.binaries.pictures.erotica.highheels	188
alt.binaries.totally.fetish.photo	183
alt.binaries.pictures.erotica.goo-shot	183
alt.binaries.sound.mp3.rap-hiphop	171
alt.binaries.pictures.erotica.nipples	171
ccu.bbs.talk.market	169
allt.sex.fetish	167

TOTAL: 1922	30377

INND misc events:

Events	Count

CCreader	1

TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Bad command received
	192.168.33.37	25
	TOTAL: 1	25
Including strange strings
	192.168.33.37	14317
	TOTAL: 1	14317

TOTAL: 2		14342

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	24	94823	2.7 GB	72	0	0	13:20:43
2	ts1-a254.saratov.dial.rol.ru	1	22	47.2 KB	1	0	0	00:28:25
3	ts1-a113.saratov.dial.rol.ru	1	14	29.6 KB	1	0	0	00:29:21
4	212.46.236.20	1	10	1.7 MB	11	0	0	00:27:24
5	ts2-a210.ufa.dial.rol.ru	1	6	877.0 KB	26	0	0	00:12:01
6	ts2-b122.syktyvkar.dial.rol.ru	11	5	490.0 KB	3	0	0	00:32:06

TOTAL: 6		39	94880	2.7 GB	114	0	2	22:47:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	24	94823	2.7 GB	72	0	0	13:20:43
2	*.saratov.dial.rol.ru	2	36	76.9 KB	2	0	0	00:57:47
3	unresolved	47	10	1.7 MB	11	0	0	07:08:42
4	*.ufa.dial.rol.ru	1	6	877.0 KB	26	0	0	00:12:01
5	*.syktyvkar.dial.rol.ru	11	5	490.0 KB	3	0	0	00:32:06
6	*.moscow.dial.rol.ru	8	0	0.0 KB	0	0	0	00:04:06
7	*.sovintel.net	15	0	0.0 KB	0	0	0	00:00:25
8	*.spb.dial.rol.ru	3	0	0.0 KB	0	0	0	00:00:05
9	*.tony.pp.ru	64	0	0.0 KB	0	0	0	00:04:13
10	*.irkutsk.dial.rol.ru	1	0	0.0 KB	0	0	0	00:01:03
11	*.voronezh.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:00
12	*.pcs-net.net	1	0	0.0 KB	0	0	0	00:00:01
13	*.rol.ru	5824	0	0.0 KB	0	0	0	00:20:06
14	*.nn.ru	130	0	0.0 KB	0	0	0	00:00:25
15	*.lavtech.ru	1	0	0.0 KB	0	0	1	00:00:00
16	*.nsk.dial.rol.ru	78	0	0.0 KB	0	0	0	00:01:44

TOTAL: 16		6211	94880	2.7 GB	114	0	1	22:45:32

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	90.456	895.596	0.000	13:20:43
194.154.66.131	0.064	0.088	0.000	06:39:54
ts2-b122.syktyvkar.dial.rol.ru	0.008	0.032	0.000	00:32:06
ts1-a113.saratov.dial.rol.ru	0.072	0.120	0.000	00:29:21
ts1-a254.saratov.dial.rol.ru	0.040	0.060	0.000	00:28:25
212.46.236.20	0.048	0.088	0.000	00:27:24
ts2-a210.ufa.dial.rol.ru	0.072	0.116	0.000	00:12:01
balancer-b.rol.ru	3.624	3.904	0.000	00:10:12
balancer-a.rol.ru	3.828	3.788	0.000	00:09:53
net1-x-ring.tony.pp.ru	0.336	0.788	0.000	00:04:13
ts32-a94.moscow.dial.rol.ru	0.012	0.004	0.000	00:03:37
?	0.028	0.044	0.000	00:01:57
82.140.73.76	0.216	0.292	0.000	00:01:18
ts1-a196.irkutsk.dial.rol.ru	0.028	0.004	0.000	00:01:03
212.46.236.27	0.016	0.000	0.000	00:01:00
ts2-a99.nsk.dial.rol.ru	0.024	0.012	0.000	00:00:39
ts2-a239.nsk.dial.rol.ru	0.024	0.028	0.000	00:00:32
gray.nn.ru	0.204	0.256	0.000	00:00:25
fw-nat.sovintel.net	0.100	0.040	0.000	00:00:25
ts23-b18.moscow.dial.rol.ru	0.016	0.008	0.000	00:00:21

TOTAL: 37	99.668	905.912	0.000	22:47:14

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-a.rol.ru	2916
balancer-b.rol.ru	2908
gray.nn.ru	130
net1-x-ring.tony.pp.ru	64
194.154.66.131	39
ts2-b169.nsk.dial.rol.ru	20
ts2-a239.nsk.dial.rol.ru	20
ts2-b163.nsk.dial.rol.ru	19
ts2-a99.nsk.dial.rol.ru	19
fw-nat.sovintel.net	15
ts23-b2.moscow.dial.rol.ru	4
212.46.236.27	4
ts23-b18.moscow.dial.rol.ru	3
ts5-b231.spb.dial.rol.ru	3
212.46.202.56	2
news.pcs-net.net	1
ts3-b142.voronezh.dial.rol.ru	1
194.186.71.137	1
ts32-a94.moscow.dial.rol.ru	1
ts1-a196.irkutsk.dial.rol.ru	1

TOTAL: 21	6172

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	228
82.140.73.76	149
ppp0-028.ciscom.ru	8
86.110.172.53	5
ppp85-141-234-101.pppoe.mtu-net.ru	4
195.206.42.79	2
dap2.imm.uran.ru	1
84.47.164.2	1
83.167.116.18	1

TOTAL: 9	399

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	36

TOTAL: 1	36

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-b122.syktyvkar.dial.rol.ru	3	0
194.154.66.131	2	0
ts1-a113.saratov.dial.rol.ru	1	0

TOTAL: 3	6	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	95366	100.0%
2	fido7	36	0.0%

TOTAL: 2		95402	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	69108
2	alt.binaries.pictures.bras	13542
3	alt.binaries.pictures.artpics	12695
4	fido7.ru.linux	36
5	alt.binaries.models.petite	10
6	alt.binaries.pictures.erotica.ll-series	3
7	alt.binaries.nospam.female.bodyhair	3
8	alt.binaries.pictures.erotica.lolita	2
9	alt.binaries.nospam.female.bodyhair.pubes	2
10	alt.binaries.pictures.erotica.young	1

TOTAL: 10		95402