Daily Usenet report

Nov 30 03:00:01 -- Dec 1 03:00:01

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

First 20 / 20 lines (100.0%)
Nov 30 03:23:22 andromeda controlchan[26740]: Unknown "cmsg" control by airtouch.bbs@bbs.cs.nctu.edu.tw
Nov 30 04:38:05 andromeda controlchan[26740]: Unknown "cmsg" control by doono329.bbs@bbs.cs.nctu.edu.tw
Nov 30 04:50:32 andromeda controlchan[26740]: Unknown "cmsg" control by sd7694.bbs@bbs.cs.nctu.edu.tw
Nov 30 08:53:07 andromeda controlchan[26740]: Unknown "cmsg" control by jacily.bbs@bbs.cs.nctu.edu.tw
Nov 30 09:03:06 andromeda controlchan[26740]: Unknown "cmsg" control by jacily.bbs@bbs.cs.nctu.edu.tw
Nov 30 11:08:11 andromeda controlchan[26740]: Unknown "cmsg" control by A2301133.bbs@bbs.cs.nctu.edu.tw
Nov 30 11:18:07 andromeda controlchan[26740]: Unknown "cmsg" control by yvonnesu.bbs@bbs.cs.nctu.edu.tw
Nov 30 11:38:09 andromeda controlchan[26740]: Unknown "cmsg" control by v62585.bbs@bbs.cs.nctu.edu.tw
Nov 30 12:28:05 andromeda controlchan[26740]: Unknown "cmsg" control by ajemtw.bbs@bbs.cs.nctu.edu.tw
Nov 30 12:43:05 andromeda controlchan[26740]: Unknown "cmsg" control by jacksontown.bbs@bbs.cs.nctu.edu.tw
Nov 30 13:08:13 andromeda controlchan[26740]: Unknown "cmsg" control by doono329.bbs@bbs.cs.nctu.edu.tw
Nov 30 13:43:09 andromeda controlchan[26740]: Unknown "cmsg" control by sodso.bbs@bbs.cs.nctu.edu.tw
Nov 30 14:03:18 andromeda controlchan[26740]: Unknown "cmsg" control by celest.bbs@bbs.cs.nctu.edu.tw
Nov 30 14:08:14 andromeda controlchan[26740]: Unknown "cmsg" control by celest.bbs@bbs.cs.nctu.edu.tw
Nov 30 14:23:18 andromeda controlchan[26740]: Unknown "cmsg" control by littlemice.bbs@bbs.cs.nctu.edu.tw
Nov 30 17:18:11 andromeda controlchan[26740]: Unknown "cmsg" control by urchinsoda.bbs@bbs.cs.nctu.edu.tw
Nov 30 18:08:15 andromeda controlchan[26740]: Unknown "cmsg" control by douocoko.bbs@bbs.cs.nctu.edu.tw
Nov 30 18:23:33 andromeda controlchan[26740]: Unknown "cmsg" control by waiwai0624.bbs@bbs.cs.nctu.edu.tw
Nov 30 20:48:09 andromeda controlchan[26740]: Unknown "cmsg" control by A2301133.bbs@bbs.cs.nctu.edu.tw
Dec 1 00:13:06 andromeda controlchan[26740]: Unknown "cmsg" control by lemmi.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	591189	94.8%	53.0 MB	93.5%
nnrpd	18368	2.9%	1.8 MB	3.2%
innd	11987	1.9%	1.5 MB	2.7%
cnfsstat	2304	0.4%	404.4 KB	0.7%
controlchan	22	0.0%	2.3 KB	0.0%

TOTAL: 5	623870	100.0%	56.7 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	571236	95.2%
Negative hits	26630	4.4%
Positive hits	1847	0.3%
Cache misses	451	0.1%

TOTAL: 4	600164	100.0%

INND timer:

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

article cancel	00:00:05.978	0.0%	5590	0.000	1.069	414.000
article cleanup	00:00:07.411	0.0%	568574	0.000	0.013	0.067
article logging	00:01:28.740	0.1%	585643	0.000	0.152	10.657
article parse	00:03:15.180	0.2%	37409499	0.002	0.005	0.019
article write	00:30:33.990	2.1%	520890	0.266	3.521	15598.444
artlog/artcncl	00:00:00.701	0.0%	5455	0.000	0.129	322.500
artlog/artparse	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:01:11.388	0.1%	39745516	0.000	0.002	0.004
hisgrep/artcncl	00:00:02.670	0.0%	2186	0.000	1.221	161.000
hishave/artcncl	00:00:00.461	0.0%	5590	0.000	0.082	46.800
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:13.739	0.4%	595250	0.000	0.527	14.187
history sync	00:00:00.358	0.0%	334	0.000	1.072	3.000
history write	00:11:06.741	0.8%	568480	0.008	1.173	15.928
hiswrite/artcncl	00:00:01.376	0.0%	3404	0.000	0.404	414.000
idle	21:47:06.524	90.8%	37310340	0.356	2.102	1298.500
nntp read	00:03:50.334	0.3%	37420381	0.000	0.006	0.029
overview write	01:08:03.372	4.7%	520890	1.652	7.839	6601.933
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%	22	0.000	0.000	0.000

TOTAL: 23:59:51.055	23:52:08.963	99.5%	-	-	-	-

nnrpd timer:

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

idle	02:07:36.704	95.3%	6686	6.000	1145.185	200862.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:05:31.819	4.1%	73854	0.000	4.493	0.000
readart	00:00:00.026	0.0%	2	0.000	13.000	0.000

TOTAL: 02:13:54.553	02:13:08.549	99.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

control-microsoft@trigofacile.com	0	0	1	0	0	1
news@news.belwue.de	0	0	1	0	0	1

TOTAL	0	0	2	0	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	1751	580485	532673	19	47793	91%	308:30:20

TOTAL: 1		1751	580485	532673	19	47793	91%	308:30:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	50.8 GB	971.0 KB	50.8 GB	99%	91.8 KB	308:30:20

TOTAL: 1		50.8 GB	971.0 KB	50.8 GB	99%	91.8 KB	308:30:20

Incoming articles:

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

Nov 30 03:00:01 - 03:59:59	22665	4.4%	6.30	2.0 GB	4.0%	594.29
Nov 30 04:00:00 - 04:59:59	23114	4.4%	6.42	2.1 GB	4.1%	605.96
Nov 30 05:00:00 - 05:59:59	25443	4.9%	7.07	2.7 GB	5.2%	778.31
Nov 30 06:00:00 - 06:59:59	19171	3.7%	5.33	2.3 GB	4.6%	681.43
Nov 30 07:00:00 - 07:59:59	19562	3.8%	5.43	2.2 GB	4.4%	654.77
Nov 30 08:00:00 - 08:59:59	10619	2.0%	2.95	660.9 MB	1.3%	187.99
Nov 30 09:00:00 - 09:59:59	18254	3.5%	5.07	2.3 GB	4.5%	674.22
Nov 30 10:00:00 - 10:59:59	16502	3.2%	4.58	2.1 GB	4.1%	615.54
Nov 30 11:00:00 - 11:59:59	17254	3.3%	4.79	2.2 GB	4.2%	627.28
Nov 30 12:00:00 - 12:59:59	21030	4.0%	5.84	2.9 GB	5.7%	856.70
Nov 30 13:00:00 - 13:59:59	21954	4.2%	6.10	2.7 GB	5.4%	800.99
Nov 30 14:00:00 - 14:59:59	21393	4.1%	5.94	2.9 GB	5.7%	854.83
Nov 30 15:00:00 - 15:59:59	21620	4.2%	6.01	2.9 GB	5.6%	832.00
Nov 30 16:00:00 - 16:59:59	25179	4.8%	6.99	2.7 GB	5.4%	800.16
Nov 30 17:00:00 - 17:59:59	24491	4.7%	6.80	2.7 GB	5.3%	789.90
Nov 30 18:00:00 - 18:59:59	24772	4.8%	6.88	2.1 GB	4.1%	606.13
Nov 30 19:00:00 - 19:59:59	26060	5.0%	7.24	1.9 GB	3.7%	548.00
Nov 30 20:00:00 - 20:59:59	30719	5.9%	8.53	2.2 GB	4.2%	632.50
Nov 30 21:00:00 - 21:59:59	27159	5.2%	7.54	1.9 GB	3.7%	551.97
Nov 30 22:00:00 - 22:59:59	23241	4.5%	6.46	1.8 GB	3.5%	519.95
Nov 30 23:00:00 - 23:59:59	22089	4.2%	6.14	2.0 GB	3.8%	575.59
Dec 1 00:00:00 - 00:59:59	17131	3.3%	4.76	696.6 MB	1.3%	198.13
Dec 1 01:00:00 - 01:59:59	16803	3.2%	4.67	871.7 MB	1.7%	247.96
Dec 1 02:00:00 - 02:59:59	24698	4.7%	6.86	2.5 GB	4.8%	720.46
Dec 1 03:00:00 - 03:00:01	7	0.0%	7.00	500.0 KB	0.0%	499.98

TOTAL: 24:00:00	520930	100.0%	6.03	51.3 GB	100.0%	623.13

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	61.2 GB	44.4%	194	102.08	16.38
02	BIN	137.8 GB	100.5 GB	72.9%	194	102.52	16.31
03	SMALL	13.0 GB	5.6 GB	42.7%	18	3.90	40.56
04	BIN	80.1 GB	22.4 GB	27.9%	151	51.37	18.92
05	BIN	80.1 GB	7.6 GB	9.5%	168	51.36	18.93
06	SMALL	13.0 GB	5.0 GB	38.1%	18	3.92	40.35
07	BIN	80.1 GB	10.7 GB	13.4%	151	51.24	18.97
08	BIN	80.1 GB	70.0 GB	87.3%	167	51.14	19.01
09	SMALL	13.0 GB	5.0 GB	38.7%	18	3.89	40.62
10	BIN	80.1 GB	4.6 GB	5.8%	151	51.32	18.94
11	BIN	80.1 GB	14.6 GB	18.2%	168	51.33	18.94
12	BIN	13.0 GB	9.6 GB	73.5%	566	51.57	3.07
13	BIN	80.1 GB	47.3 GB	59.1%	92	51.32	18.94
14	SMALL	80.1 GB	7.6 GB	9.5%	2	3.94	246.59

TOTAL: 14	-	968.5 GB	371.7 GB	38.4%	2058	45.09	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	64756	47603	0	94	0	0	0	0	17059
2	?	4	0	0	0	0	0	0	0	4

TOTAL: 2		64760	47603	0	94	0	0	0	0	17063

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.pwp	4086
alt.binaries.dvdrs.pw	1842
alt.binaries.erotica.anime	1589
bih.rec.photo.binaries	1219
microsoft.public.test	1156
alt.binaries.pictures.amateur.butts	1068
alt.binaries.amazing	952
alt.binaries.pictures.totty.repost	673
alt.binaries.game	539
alt.fan.jimmy	538
alt.fan.teenboy	357
alt.binaries.pictures.erotica.transvestite	272
alt.binaries.fetish	242
alt.binaries.nospam.female.short-hair	230
alt.binaries.pictures.eroctica.butts	208
alt.binaries.pictures.black.erotica	181
alt.binaries.pictures.black.erotica.females	181
alt.binaries.erotica.fetishs	174
alt.binaries.pictues.erotica.fetish	173
alt.binaries.chakotay	170

TOTAL: 3018	47603

INND misc events:

Events	Count

CCreader	1

TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	17045
	TOTAL: 1	17045

TOTAL: 1		17045

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	ts3-b201.voronezh.dial.rol.ru	5	2	13.4 KB	2	0	0	00:00:07

TOTAL: 1		5	2	13.4 KB	2	0	0	02:13:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.voronezh.dial.rol.ru	5	2	13.4 KB	2	0	0	00:00:07
2	*.sovintel.net	13	0	0.0 KB	0	0	0	00:00:03
3	*.irkutsk.dial.rol.ru	20	0	0.0 KB	0	0	0	00:51:52
4	*.moscow.dial.rol.ru	1	0	0.0 KB	0	0	0	00:00:07
5	*.sarov.dial.rol.ru	3	0	0.0 KB	0	0	0	00:20:19
6	unresolved	19	0	0.0 KB	0	0	0	00:44:45
7	*.tony.pp.ru	73	0	0.0 KB	0	0	0	00:02:31
8	*.rol.ru	5760	0	0.0 KB	0	0	0	00:14:05

TOTAL: 8		5894	2	13.4 KB	2	0	0	02:13:52

NNRP total resource statistics [Top 20]:

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

194.186.188.82	0.240	0.424	0.000	00:40:46
ts1-a219.irkutsk.dial.rol.ru	0.028	0.016	0.000	00:21:17
ts1-a71.irkutsk.dial.rol.ru	0.024	0.024	0.000	00:14:19
ts1-a184.irkutsk.dial.rol.ru	0.008	0.012	0.000	00:10:24
ts2-a210.sarov.dial.rol.ru	0.000	0.000	0.000	00:10:02
ts2-a43.sarov.dial.rol.ru	0.004	0.000	0.000	00:10:02
balancer-b.rol.ru	3.424	3.324	0.000	00:07:17
balancer-a.rol.ru	3.340	3.352	0.000	00:06:47
ts1-a68.irkutsk.dial.rol.ru	0.068	0.016	0.000	00:05:50
net1-x-ring.tony.pp.ru	0.108	0.108	0.000	00:02:31
195.68.139.196	0.028	0.016	0.000	00:01:42
195.68.139.201	0.012	0.000	0.000	00:01:22
195.218.244.87	0.004	0.004	0.000	00:00:52
ts2-a193.sarov.dial.rol.ru	0.000	0.012	0.000	00:00:13
ts3-b201.voronezh.dial.rol.ru	0.020	0.012	0.000	00:00:07
ts11-a120.moscow.dial.rol.ru	0.000	0.004	0.000	00:00:07
fw-nat.sovintel.net	0.076	0.028	0.000	00:00:03
81.211.38.37	0.000	0.004	0.000	00:00:02
ts2-a146.khabarovsk.dial.rol.ru	0.000	0.000	0.000	00:00:01
186.12.32.95.c10008-a53.dsl-dynamic.vsi.ru	0.016	0.028	0.000	00:00:00

TOTAL: 21	7.400	7.384	0.000	02:13:55

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2880
balancer-a.rol.ru	2880
net1-x-ring.tony.pp.ru	73
fw-nat.sovintel.net	13
194.186.188.82	11
ts1-a68.irkutsk.dial.rol.ru	9
ts1-a71.irkutsk.dial.rol.ru	5
ts1-a219.irkutsk.dial.rol.ru	4
195.68.139.196	4
195.68.139.201	2
ts1-a184.irkutsk.dial.rol.ru	2
ts11-a120.moscow.dial.rol.ru	1
195.218.244.87	1
81.211.38.37	1
ts2-a43.sarov.dial.rol.ru	1
ts2-a193.sarov.dial.rol.ru	1
ts2-a210.sarov.dial.rol.ru	1

TOTAL: 17	5889

NNRP no permission clients:

System	Conn

186.12.32.95.c10008-a53.dsl-dynamic.vsi.ru	14
ts2-a146.khabarovsk.dial.rol.ru	2
cornholio.migtel.ru	1

TOTAL: 3	17

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts1-a219.irkutsk.dial.rol.ru	2	0
ts1-a184.irkutsk.dial.rol.ru	1	0
ts1-a71.irkutsk.dial.rol.ru	1	0
ts2-a43.sarov.dial.rol.ru	1	0
ts2-a210.sarov.dial.rol.ru	1	0

TOTAL: 5	6	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	1	50.0%
2	comp	1	50.0%

TOTAL: 2		2	100.0%

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

Newsgroup		Count

1	alt.drugs.dxm.enema.enema.enema	1
2	comp.os.research	1

TOTAL: 2		2