Daily Usenet report

Aug 5 03:00:03 -- Aug 6 03:00:01

Log entries by program
History cache
INND timer
nnrpd timer
Control commands to INND
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 / 33 lines (15.2%)
Aug 5 03:10:28 perseus controlchan[29223]: Unknown "cmsg" control by bobo32.bbs@bbs.cs.nctu.edu.tw
Aug 5 03:50:33 perseus controlchan[29223]: Unknown "cmsg" control by lisa10.bbs@bbs.cs.nctu.edu.tw
Aug 5 07:05:13 perseus controlchan[29223]: Broken headers in article <cancel.21a8.ZnPlk.285690$Gv.79212@en-nntp-07.dc1.easynews.com>
Aug 5 07:05:13 perseus controlchan[29223]: Broken headers in article <cancel.3189.3oPlk.285691$Gv.132792@en-nntp-07.dc1.easynews.com>
Aug 5 12:00:33 perseus controlchan[29223]: Unknown "cmsg" control by JayBruce.bbs@bbs.cs.nctu.edu.tw

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	714839	88.9%	68.4 MB	89.7%
innd	68268	8.5%	5.8 MB	7.6%
nnrpd	18630	2.3%	1.6 MB	2.2%
cnfsstat	2304	0.3%	394.1 KB	0.5%
controlchan	33	0.0%	3.2 KB	0.0%

TOTAL: 5	804074	100.0%	76.3 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	637833	91.5%
Negative hits	41461	6.0%
Positive hits	16787	2.4%
Cache misses	681	0.1%

TOTAL: 4	696762	100.0%

INND timer:

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

article cancel	00:00:39.405	0.0%	21489	0.000	1.834	394.500
article cleanup	00:00:05.972	0.0%	634446	0.000	0.009	0.032
article logging	00:01:33.478	0.1%	693804	0.000	0.135	14.431
article parse	00:02:36.129	0.2%	37094657	0.002	0.004	0.009
article write	00:57:49.413	4.0%	553199	0.300	6.272	32.463
artlog/artcncl	00:00:00.948	0.0%	21292	0.000	0.045	32.375
artlog/artparse	00:00:00.001	0.0%	3	0.000	0.333	1.000
data move	00:00:40.339	0.0%	39445747	0.000	0.001	0.002
hisgrep/artcncl	00:00:12.455	0.0%	16926	0.000	0.736	166.538
hishave/artcncl	00:00:06.147	0.0%	21489	0.000	0.286	66.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:18.986	0.8%	676023	0.000	1.004	16.932
history sync	00:00:00.237	0.0%	317	0.000	0.748	14.000
history write	00:31:19.231	2.2%	553199	0.010	3.397	36.264
hiswrite/artcncl	00:00:19.697	0.0%	4563	0.000	4.317	3259.000
idle	20:28:00.396	85.2%	36815116	0.824	2.001	14.228
nntp read	00:03:06.032	0.2%	37103548	0.002	0.005	0.011
overview write	01:38:30.036	6.8%	553199	0.952	10.683	74.547
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%	35	0.000	0.000	0.000

TOTAL: 24:00:33.168	23:56:18.902	99.7%	-	-	-	-

nnrpd timer:

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

hisgrep	00:00:27.468	0.1%	11766	0.000	2.335	6.677
idle	09:33:15.937	95.3%	30300	0.000	1135.179	200162.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:11:35.044	1.9%	90478	0.000	7.682	2120.462
readart	00:04:22.820	0.7%	23947	0.000	10.975	15.900

TOTAL: 10:01:26.953	09:49:41.269	98.0%	-	-	-	-

Control commands to INND:

Command	Number

flush	144
flushlogs	1
paused	2

TOTAL: 3	147

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	695	659587	575256	42	84289	87%	451:33:48

TOTAL: 1		695	659587	575256	42	84289	87%	451:33:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	45.7 GB	932.8 KB	45.7 GB	99%	72.7 KB	451:33:48

TOTAL: 1		45.7 GB	932.8 KB	45.7 GB	99%	72.7 KB	451:33:48

Incoming articles:

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

Aug 5 03:00:03 - 03:59:59	22479	4.1%	6.25	1.8 GB	4.0%	531.48
Aug 5 04:00:00 - 04:59:59	22747	4.1%	6.32	2.1 GB	4.5%	599.70
Aug 5 05:00:00 - 05:59:59	22613	4.1%	6.28	2.1 GB	4.5%	599.72
Aug 5 06:00:00 - 06:59:59	21269	3.8%	5.91	2.0 GB	4.4%	591.53
Aug 5 07:00:00 - 07:59:59	21198	3.8%	5.89	1.8 GB	4.0%	537.88
Aug 5 08:00:00 - 08:59:59	19231	3.5%	5.34	2.0 GB	4.3%	577.71
Aug 5 09:00:00 - 09:59:59	20142	3.6%	5.59	2.3 GB	5.0%	668.81
Aug 5 10:00:00 - 10:59:59	22850	4.1%	6.35	2.2 GB	4.9%	651.21
Aug 5 11:00:00 - 11:59:59	21151	3.8%	5.88	1.4 GB	3.1%	411.49
Aug 5 12:00:00 - 12:59:59	23767	4.3%	6.60	2.1 GB	4.6%	614.87
Aug 5 13:00:00 - 13:59:59	23846	4.3%	6.62	2.2 GB	4.8%	638.33
Aug 5 14:00:00 - 14:59:59	22757	4.1%	6.32	2.2 GB	4.7%	627.95
Aug 5 15:00:00 - 15:59:59	22233	4.0%	6.18	2.0 GB	4.4%	584.19
Aug 5 16:00:00 - 16:59:59	24866	4.5%	6.91	2.2 GB	4.7%	628.46
Aug 5 17:00:00 - 17:59:59	24262	4.4%	6.74	2.0 GB	4.4%	584.28
Aug 5 18:00:00 - 18:59:59	27328	4.9%	7.59	2.0 GB	4.3%	581.89
Aug 5 19:00:00 - 19:59:59	28104	5.1%	7.81	1.6 GB	3.6%	480.39
Aug 5 20:00:00 - 20:59:59	25161	4.5%	6.99	1.7 GB	3.7%	498.46
Aug 5 21:00:00 - 21:59:59	23746	4.3%	6.60	1.9 GB	4.1%	544.88
Aug 5 22:00:00 - 22:59:59	23483	4.2%	6.52	2.1 GB	4.5%	606.64
Aug 5 23:00:00 - 23:59:59	19681	3.6%	5.47	1.3 GB	2.7%	367.78
Aug 6 00:00:00 - 00:59:59	18809	3.4%	5.22	1.0 GB	2.2%	297.88
Aug 6 01:00:00 - 01:59:59	26912	4.9%	7.48	1.8 GB	4.0%	537.82
Aug 6 02:00:00 - 02:59:59	24378	4.4%	6.77	2.1 GB	4.7%	623.79
Aug 6 03:00:00 - 03:00:01	7	0.0%	7.00	417.8 KB	0.0%	417.78

TOTAL: 23:59:58	553020	100.0%	6.40	46.0 GB	100.0%	557.80

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	121.7 GB	88.3%	212	90.26	18.53
02	BIN	137.8 GB	77.9 GB	56.6%	213	90.73	18.44
03	SMALL	13.0 GB	10.6 GB	81.6%	16	4.37	36.17
04	BIN	80.1 GB	1.3 GB	1.6%	164	45.88	21.19
05	BIN	80.1 GB	27.7 GB	34.6%	184	45.45	21.39
06	SMALL	13.0 GB	10.6 GB	81.4%	16	4.35	36.41
07	BIN	80.1 GB	59.9 GB	74.8%	163	45.88	21.19
08	BIN	80.1 GB	8.7 GB	10.8%	184	45.73	21.26
09	SMALL	13.0 GB	9.6 GB	73.4%	16	4.41	35.91
10	BIN	80.1 GB	44.3 GB	55.3%	163	45.55	21.34
11	BIN	80.1 GB	48.7 GB	60.8%	184	45.45	21.39
12	SMALL	13.0 GB	10.4 GB	79.7%	16	4.44	35.65
13	BIN	80.1 GB	41.3 GB	51.6%	163	45.57	21.33
14	BIN	80.1 GB	9.8 GB	12.3%	184	45.46	21.38

TOTAL: 14	-	968.5 GB	482.6 GB	49.8%	1878	40.28	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	140552	49070	0	540	0	0	0	0	90942
2	?	2	0	0	0	0	0	0	0	2

TOTAL: 2		140554	49070	0	540	0	0	0	0	90944

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.dvdrs.pw	5349
alt.binaries.pictures.amateur.butts	2164
alt.binaries.amazing	1635
microsoft.public.test	1144
alt.binaries.pictures.blonde	1104
alt.binaries.movies.dutch.repost	746
alt.binaries.pictures.bdstudio	482
alt.binaries.nospam.female.short-hair	405
alt.binaries.pictures.erotica.dicks	356
alt.binaries.picture.ls-series	351
alt.binaries.pictures.black.erotica.females	225
alt.binaries.pictures.black.erotica	222
alt.binaries.pictures.erotica.fetish.urine	207
alt.binaries.pictures.erotica.gay	202
alt.binaries.game	202
alt.binaries.alt.binaries.pictures.erotica	201
alt.binaries.pictures.erotica.fettish	188
alt.binaries.pictures.erotica.garters	184
alt.binaries.pictures.erotica.goo-shot	182
alt.binaries.playboy	181

TOTAL: 6938	49070

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	59316
	TOTAL: 1	59316

TOTAL: 1		59316

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	2	11766	585.8 MB	9	0	0	08:53:41
2	212.46.235.22	1	10	1.9 MB	2	0	0	00:19:01

TOTAL: 2		3	11776	587.7 MB	11	0	0	10:01:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	2	11766	585.8 MB	9	0	0	08:53:41
2	unresolved	14	10	1.9 MB	2	0	0	00:26:13
3	*.sovintel.net	15	0	0.0 KB	0	0	0	00:00:12
4	*.sarov.dial.rol.ru	5	0	0.0 KB	0	0	0	00:22:16
5	*.tony.pp.ru	71	0	0.0 KB	0	0	0	00:02:30
6	*.rol.ru	5762	0	0.0 KB	0	0	0	00:15:39
7	*.nn.ru	3	0	0.0 KB	0	0	0	00:00:01

TOTAL: 7		5872	11776	587.7 MB	11	0	0	10:01:25

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	7.444	81.446	0.000	08:53:41
212.46.235.22	0.036	0.080	0.000	00:19:01
ts2-a54.sarov.dial.rol.ru	0.000	0.008	0.000	00:10:00
ts2-a121.sarov.dial.rol.ru	0.000	0.000	0.000	00:10:00
balancer-b.rol.ru	3.520	3.300	0.000	00:07:52
balancer-a.rol.ru	3.280	3.244	0.000	00:07:46
195.218.244.87	0.008	0.016	0.000	00:03:33
212.46.235.23	0.024	0.040	0.000	00:03:27
net1-x-ring.tony.pp.ru	0.120	0.144	0.000	00:02:30
ts2-a171.sarov.dial.rol.ru	0.008	0.000	0.000	00:01:12
ts2-a59.sarov.dial.rol.ru	0.008	0.012	0.000	00:01:03
?	0.004	0.016	0.000	00:00:50
fw-nat.sovintel.net	0.104	0.044	0.000	00:00:12
195.46.163.98	0.008	0.008	0.000	00:00:06
195.98.59.67	0.024	0.020	0.000	00:00:04
cornholio.migtel.ru	0.120	0.160	0.000	00:00:02
gray.nn.ru	0.000	0.008	0.000	00:00:01

TOTAL: 17	14.708	88.546	0.000	10:01:28

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-a.rol.ru	2882
balancer-b.rol.ru	2880
net1-x-ring.tony.pp.ru	71
fw-nat.sovintel.net	15
195.98.59.67	9
gray.nn.ru	3
ts2-a171.sarov.dial.rol.ru	2
195.218.244.87	2
ts2-a121.sarov.dial.rol.ru	1
ts2-a59.sarov.dial.rol.ru	1
195.46.163.98	1
212.46.235.23	1
ts2-a54.sarov.dial.rol.ru	1

TOTAL: 13	5869

NNRP no permission clients:

System	Conn

cornholio.migtel.ru	100

TOTAL: 1	100

NNRP gethostbyaddr failures [Top 20]:

System	Conn

? (can't getpeername)	9

TOTAL: 1	9

NNRP client timeouts [Top 20]:

System	Conn	Peer

ts2-a121.sarov.dial.rol.ru	1	0
ts2-a54.sarov.dial.rol.ru	1	0

TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	9623	100.0%

TOTAL: 1		9623	100.0%

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

Newsgroup		Count

1	alt.binaries.pictures.erotica.vintage	5343
2	alt.binaries.pictures.bras	3537
3	alt.binaries.pictures.artpics	733
4	alt.binaries.camille	10

TOTAL: 4		9623