Daily Usenet report

Sep 11 03:00:06 -- Sep 12 03:00:05

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 / 153 lines (3.3%)
Sep 11 05:00:22 perseus controlchan[28453]: Unknown "cmsg" control by patatata.bbs@bbs.wretch.cc
Sep 11 05:10:17 perseus controlchan[28453]: Unknown "cmsg" control by allanlai.bbs@bbs.wretch.cc
Sep 11 05:40:13 perseus controlchan[28453]: Unknown "cmsg" control by bluemeteor.bbs@bbs.wretch.cc
Sep 11 05:40:13 perseus controlchan[28453]: Unknown "cmsg" control by visualrock.bbs@bbs.wretch.cc
Sep 11 05:40:16 perseus controlchan[28453]: Unknown "cmsg" control by emptycup.bbs@bbs.wretch.cc

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	2157868	97.4%	191.6 MB	96.9%
innd	36078	1.6%	3.9 MB	2.0%
nnrpd	20014	0.9%	1.9 MB	1.0%
cnfsstat	2304	0.1%	398.0 KB	0.2%
controlchan	181	0.0%	19.3 KB	0.0%
expireover	2	0.0%	0.2 KB	0.0%

TOTAL: 6	2216447	100.0%	197.9 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	2143589	98.1%
Negative hits	36672	1.7%
Positive hits	3332	0.2%
Cache misses	1084	0.0%

TOTAL: 4	2184677	100.0%

INND timer:

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

article cancel	00:00:46.675	0.1%	13837	0.000	3.373	1539.000
article cleanup	00:00:23.377	0.0%	2130859	0.003	0.011	0.021
article logging	00:02:12.397	0.2%	2143943	0.003	0.062	5.345
article parse	00:18:09.324	1.3%	162946961	0.004	0.007	0.036
article write	09:42:11.457	40.4%	2101901	4.467	16.619	36.300
artlog/artcncl	00:00:00.164	0.0%	13373	0.000	0.012	17.000
artlog/artparse	00:00:00.000	0.0%	35	0.000	0.000	0.000
data move	00:03:37.947	0.3%	172629209	0.001	0.001	0.003
hisgrep/artcncl	00:00:45.202	0.1%	4285	0.000	10.549	1539.000
hishave/artcncl	00:00:01.013	0.0%	13837	0.000	0.073	6.066
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:13:24.838	0.9%	2172158	0.000	0.371	6.409
history sync	00:00:00.386	0.0%	290	0.000	1.331	75.000
history write	00:52:36.999	3.7%	2101901	0.021	1.502	15.315
hiswrite/artcncl	00:00:00.217	0.0%	9552	0.000	0.023	19.000
idle	07:53:22.666	32.9%	154180821	0.128	0.184	0.625
nntp read	00:20:07.105	1.4%	161593107	0.005	0.007	0.025
overview write	04:09:30.179	17.3%	2101901	1.821	7.122	105.147
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.004	0.0%	175	0.000	0.023	0.500

TOTAL: 23:59:39.398	23:37:09.950	98.4%	-	-	-	-

nnrpd timer:

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

hisgrep	00:20:53.380	1.3%	21557	0.000	58.143	287.043
idle	24:15:36.936	91.9%	145986	0.000	598.256	200320.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:30:20.507	1.9%	608975	0.000	2.989	16931.597
readart	00:20:03.196	1.3%	48760	0.000	24.676	542.000

TOTAL: 26:23:56.764	25:26:54.019	96.4%	-	-	-	-

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

news@news.cs.nctu.edu.tw	1	0	0	0	0	1
newsgroups@news.uwaterloo.ca	0	1	0	0	0	1
newsmaster@carnet.hr	14	14	0	0	0	28

TOTAL	15	15	0	0	0	30

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	325	2168815	2137872	0	30943	98%	480:55:50

TOTAL: 1		325	2168815	2137872	0	30943	98%	480:55:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	388.5 GB	5.9 MB	388.5 GB	99%	187.8 KB	480:55:50

TOTAL: 1		388.5 GB	5.9 MB	388.5 GB	99%	187.8 KB	480:55:50

Incoming articles:

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

Sep 11 03:00:06 - 03:59:59	91671	4.4%	25.51	17.9 GB	4.6%	5227.46
Sep 11 04:00:00 - 04:59:59	91213	4.3%	25.34	17.8 GB	4.6%	5176.05
Sep 11 05:00:00 - 05:59:59	87444	4.2%	24.29	17.2 GB	4.4%	5012.10
Sep 11 06:00:00 - 06:59:59	78812	3.7%	21.89	15.1 GB	3.9%	4411.19
Sep 11 07:00:00 - 07:59:59	85481	4.1%	23.74	16.7 GB	4.3%	4850.74
Sep 11 08:00:00 - 08:59:59	84139	4.0%	23.37	16.7 GB	4.3%	4861.27
Sep 11 09:00:00 - 09:59:59	84308	4.0%	23.42	17.1 GB	4.4%	4969.34
Sep 11 10:00:00 - 10:59:59	85163	4.1%	23.66	16.5 GB	4.3%	4806.10
Sep 11 11:00:00 - 11:59:59	91914	4.4%	25.53	17.4 GB	4.5%	5061.26
Sep 11 12:00:00 - 12:59:59	90121	4.3%	25.03	16.4 GB	4.2%	4778.46
Sep 11 13:00:00 - 13:59:59	85366	4.1%	23.71	14.8 GB	3.8%	4306.06
Sep 11 14:00:00 - 14:59:59	81029	3.9%	22.51	15.2 GB	3.9%	4436.06
Sep 11 15:00:00 - 15:59:59	72933	3.5%	20.26	12.8 GB	3.3%	3730.13
Sep 11 16:00:00 - 16:59:59	85377	4.1%	23.72	14.7 GB	3.8%	4289.12
Sep 11 17:00:00 - 17:59:59	90872	4.3%	25.24	16.8 GB	4.3%	4886.01
Sep 11 18:00:00 - 18:59:59	90407	4.3%	25.11	16.2 GB	4.2%	4726.18
Sep 11 19:00:00 - 19:59:59	92797	4.4%	25.78	15.4 GB	4.0%	4472.41
Sep 11 20:00:00 - 20:59:59	85753	4.1%	23.82	15.4 GB	4.0%	4474.56
Sep 11 21:00:00 - 21:59:59	89041	4.2%	24.73	16.6 GB	4.3%	4842.58
Sep 11 22:00:00 - 22:59:59	93986	4.5%	26.11	17.0 GB	4.4%	4957.26
Sep 11 23:00:00 - 23:59:59	94717	4.5%	26.31	16.9 GB	4.3%	4909.34
Sep 12 00:00:00 - 00:59:59	85289	4.1%	23.69	14.7 GB	3.8%	4294.75
Sep 12 01:00:00 - 01:59:59	92595	4.4%	25.72	15.8 GB	4.1%	4609.74
Sep 12 02:00:00 - 02:59:59	91910	4.4%	25.53	16.7 GB	4.3%	4877.21
Sep 12 03:00:00 - 03:00:05	58	0.0%	11.60	8.7 MB	0.0%	1776.78

TOTAL: 23:59:59	2102396	100.0%	24.33	387.8 GB	100.0%	4706.68

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	19.8 GB	14.3%	78	777.00	2.15
02	BIN	137.8 GB	43.9 GB	31.8%	78	780.53	2.14
03	SMALL	13.0 GB	11.5 GB	88.0%	4	5.05	31.34
04	BIN	80.1 GB	427.8 MB	0.5%	48	394.59	2.46
05	BIN	80.1 GB	76.9 GB	96.0%	67	395.27	2.46
06	SMALL	13.0 GB	11.4 GB	87.8%	4	5.03	31.47
07	BIN	80.1 GB	75.2 GB	93.9%	47	393.69	2.47
08	BIN	80.1 GB	72.7 GB	90.8%	67	394.64	2.46
09	SMALL	13.0 GB	11.2 GB	85.9%	4	5.02	31.50
10	BIN	80.1 GB	71.5 GB	89.3%	47	392.75	2.47
11	BIN	80.1 GB	79.0 GB	98.6%	67	395.79	2.46
12	SMALL	13.0 GB	11.4 GB	87.5%	4	5.06	31.27
13	BIN	80.1 GB	71.0 GB	88.6%	47	392.86	2.47
14	BIN	80.1 GB	69.4 GB	86.6%	67	394.96	2.46

TOTAL: 14	-	968.5 GB	625.2 GB	64.5%	629	338.90	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	42060	28824	0	138	0	0	0	0	13098
2	?	35	0	0	0	0	0	0	0	35

TOTAL: 2		42095	28824	0	138	0	0	0	0	13133

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.pictures.amateur.butts	823
alt.binaries.ua-r	446
alt.binaries.pictures.erotica.asian.female	275
alt.binaries.pictures.erotica.fetish.urine	274
alt.binaries.pictures.fetish.urine	185
alt.binaries.picutures.erotica	173
alt.biniaries.erotica	170
alt.binaries.erotica.erotica.bisexuals	169
alt.binaries.picutres.erotica.fetish.hair	164
alt.binaries.pictures.erotica.exhibitionismw	164
alt.binaries.pictures.erotica.highheels	163
alt.binaries.pictures.erotica.voyerism	162
alt.binaries.pictures.erotica.gay	160
alt.binaries.pictures.erotica.female.ejaculation	159
alt.binaries.pictures.erotica.fetish.balloons	158
alt.binaries.pictures.erotica.pin-up	157
alt.binaries.pictures.erotica.latinal	156
alt.binaries.pictures.erotica.voyeuris	156
alt.binaries.pictures.erotica.fat	155
alt.binaries.pictures.erotica.filipina	154

TOTAL: 3199	28824

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Bad command received
	192.168.33.37	30
	TOTAL: 1	30
Including strange strings
	192.168.33.37	13098
	TOTAL: 1	13098

TOTAL: 2		13128

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	4	21144	1.7 GB	12	0	0	11:43:41
2	rol238.rol.kis.ru	2	61	2.1 MB	1	0	0	00:16:33
3	news.pcs-net.net	27	36	6.6 MB	36	0	0	05:13:05
4	ts18-a179.moscow.dial.rol.ru	1	11	2.5 MB	1	0	0	00:21:58
5	ts3-a11.voronezh.dial.rol.ru	6	4	198.0 KB	2	0	0	00:22:13

TOTAL: 5		40	21256	1.7 GB	52	0	0	26:23:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	4	21144	1.7 GB	12	0	0	11:43:41
2	*.rol.kis.ru	2	61	2.1 MB	1	0	0	00:16:33
3	*.pcs-net.net	27	36	6.6 MB	36	0	0	05:13:05
4	*.moscow.dial.rol.ru	24	11	2.5 MB	1	0	0	00:42:32
5	*.voronezh.dial.rol.ru	6	4	198.0 KB	2	0	0	00:22:13
6	*.sovintel.net	11	0	0.0 KB	0	0	0	00:00:16
7	*.cis.ru	2	0	0.0 KB	0	0	0	00:21:21
8	*.spb.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:03
9	*.lek.ru	1	0	0.0 KB	0	0	0	00:00:05
10	*.nsk.dial.rol.ru	20	0	0.0 KB	0	0	0	00:00:11
11	unresolved	41	0	0.0 KB	0	0	0	06:42:11
12	*.tony.pp.ru	71	0	0.0 KB	0	0	0	00:36:18
13	*.rol.ru	5817	0	0.0 KB	0	0	0	00:19:26
14	*.nn.ru	49	0	0.0 KB	0	0	0	00:00:30

TOTAL: 14		6076	21256	1.7 GB	52	0	0	26:22:36

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	14.648	124.220	0.000	11:43:41
194.154.66.131	0.040	0.088	0.000	06:42:05
news.pcs-net.net	0.152	0.192	0.000	05:13:05
net1-x-ring.tony.pp.ru	41.720	395.138	0.000	00:36:18
ts3-a11.voronezh.dial.rol.ru	0.040	0.048	0.000	00:22:13
ts18-a179.moscow.dial.rol.ru	0.000	0.024	0.000	00:21:58
sword.cis.ru	0.120	0.108	0.000	00:21:21
rol238.rol.kis.ru	0.016	0.036	0.000	00:16:33
ts18-a9.moscow.dial.rol.ru	0.000	0.016	0.000	00:10:02
balancer-a.rol.ru	3.732	3.524	0.000	00:09:48
balancer-b.rol.ru	3.620	3.524	0.000	00:09:38
ts24-b178.moscow.dial.rol.ru	0.004	0.000	0.000	00:05:11
?	0.024	0.068	0.000	00:04:05
ts24-a155.moscow.dial.rol.ru	0.044	0.016	0.000	00:02:33
ts24-b81.moscow.dial.rol.ru	0.004	0.012	0.000	00:01:32
ts24-b237.moscow.dial.rol.ru	0.004	0.004	0.000	00:01:02
82.140.73.76	0.188	0.180	0.000	00:00:33
gray.nn.ru	0.076	0.100	0.000	00:00:30
fw-nat.sovintel.net	0.116	0.052	0.000	00:00:16
ts2-b166.nsk.dial.rol.ru	0.028	0.028	0.000	00:00:11

TOTAL: 31	64.676	527.482	0.000	26:23:14

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-a.rol.ru	2909
balancer-b.rol.ru	2908
net1-x-ring.tony.pp.ru	71
gray.nn.ru	49
194.154.66.131	39
ts2-b166.nsk.dial.rol.ru	20
fw-nat.sovintel.net	11
ts24-b81.moscow.dial.rol.ru	8
ts24-a156.moscow.dial.rol.ru	5
ts24-a83.moscow.dial.rol.ru	5
sword.cis.ru	2
ts24-b237.moscow.dial.rol.ru	2
194.186.71.137	2
news.lek.ru	1
ts18-a9.moscow.dial.rol.ru	1
ts5-a91.spb.dial.rol.ru	1
ts24-b178.moscow.dial.rol.ru	1
ts24-a155.moscow.dial.rol.ru	1

TOTAL: 18	6036

NNRP no permission clients:

System	Conn

82.140.73.76	136
cornholio.migtel.ru	17
ppp83-237-255-6.pppoe.mtu-net.ru	3
host-213-145-66-217.spbmts.ru	2
ppp0-007.ciscom.ru	2
195.28.36.242	1
matona.gns.ru	1

TOTAL: 7	162

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	71

TOTAL: 1	71

NNRP client timeouts [Top 20]:

System	Conn	Peer

news.pcs-net.net	21	0
194.154.66.131	4	0
sword.cis.ru	2	0
ts18-a9.moscow.dial.rol.ru	1	0
ts3-a11.voronezh.dial.rol.ru	1	0

TOTAL: 5	29	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	21200	100.0%

TOTAL: 1		21200	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	16472
2	alt.binaries.pictures.artpics	2853
3	alt.binaries.pictures.bras	1763
4	alt.binaries.erotic.amateur.pictures	61
5	alt.binaries.pictures.vladmodels	19
6	alt.binaries.pictures.sandra	11
7	alt.sex.pantyhose	11
8	alt.binaries.erotica.pictures.cheerleaders	6
9	alt.bainaries.pictures.sex.fetish.fish	2
10	alt.christnet.sex.fetish.fat.furry	2

TOTAL: 10		21200