Daily Usenet report

Jan 29 03:00:01 -- Jan 30 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
Miscellaneous innd statistics
Rnews connections rejected
NNRP readership statistics
NNRP readership statistics (by domain)
NNRP total resource statistics
Curious NNRP server explorers
NNRP no permission clients
Newsgroup request counts (by category)
Newsgroup request counts (by newsgroup)

First 48 / 48 lines (100.0%)
Jan 29 09:00:23 andromeda nnrpd[30451]: SM could not find token type or method was not initialized (0)
Jan 29 11:48:17 andromeda controlchan[18019]: skipping newgroup alt.fr.misc.transport.divers y alteferians@alteferians.org (would be created)
Jan 29 12:01:09 andromeda controlchan[18019]: Unknown "cmsg" control by doono329.bbs@bbs.cs.nctu.edu.tw
Jan 29 12:01:15 andromeda controlchan[18019]: Unknown "cmsg" control by A2301133.bbs@bbs.cs.nctu.edu.tw
Jan 29 12:02:05 andromeda controlchan[18019]: Unknown "cmsg" control by vaakaa.bbs@bbs.cs.nctu.edu.tw
Jan 29 12:02:22 andromeda controlchan[18019]: Unknown "cmsg" control by 7h0t8wuj8iayq53@jetable.org
Jan 29 12:02:25 andromeda controlchan[18019]: Unknown "cmsg" control by nbasa1q3ozgaxwk@jetable.net
Jan 29 12:02:28 andromeda controlchan[18019]: Unknown "cmsg" control by dcnm1q2f5qqz@gmail.com
Jan 29 12:02:31 andromeda controlchan[18019]: Unknown "cmsg" control by qseooet7ehf80xz@jetable.net
Jan 29 12:02:35 andromeda controlchan[18019]: Unknown "cmsg" control by amal9zkpr3bauwa@jetable.com
Jan 29 12:02:39 andromeda controlchan[18019]: Unknown "cmsg" control by PpsmPADUGvn3@meltmail.com
Jan 29 12:02:40 andromeda controlchan[18019]: Unknown "cmsg" control by g4s8i6c9vcv6ypz@jetable.net
Jan 29 12:02:40 andromeda controlchan[18019]: Unknown "cmsg" control by rb8tqes4x91kork@jetable.com
Jan 29 12:02:41 andromeda controlchan[18019]: Unknown "cmsg" control by 7165oOY7igv1@meltmail.com
Jan 29 12:03:31 andromeda controlchan[18019]: Unknown "cmsg" control by JayBruce.bbs@bbs.cs.nctu.edu.tw
Jan 29 12:03:46 andromeda controlchan[18019]: skipping newgroup alt.alteferians.abuse y alteferians@alteferians.org (would be created)
Jan 29 12:05:10 andromeda controlchan[18019]: Unknown "cmsg" control by JayBruce.bbs@bbs.cs.nctu.edu.tw
Jan 29 12:05:17 andromeda controlchan[18019]: Unknown "cmsg" control by JayBruce.bbs@bbs.cs.nctu.edu.tw
Jan 29 13:32:19 andromeda controlchan[18019]: skipping newgroup alt.alteferians.news y alteferians@alteferians.org (would be created)
Jan 29 15:56:26 andromeda controlchan[18019]: Unknown "cmsg" control by tw7p1nyisfguxpd@jetable.net
Jan 29 16:02:35 andromeda controlchan[18019]: Unknown "cmsg" control by 7h0t8wuj8iayq53@jetable.org
Jan 29 16:12:24 andromeda controlchan[18019]: Unknown "cmsg" control by nbasa1q3ozgaxwk@jetable.net
Jan 29 21:38:08 andromeda nnrpd[14857]: SM could not find token type or method was not initialized (0)
Jan 29 21:42:25 andromeda innd: buffindexed: overview data is too large 25187
Jan 29 21:43:01 andromeda innd: buffindexed: overview data is too large 25187
Jan 29 21:43:25 andromeda innd: buffindexed: overview data is too large 25186
Jan 29 21:44:03 andromeda innd: buffindexed: overview data is too large 25186
Jan 29 21:44:33 andromeda innd: buffindexed: overview data is too large 25186
Jan 29 21:45:14 andromeda innd: buffindexed: overview data is too large 25187
Jan 29 21:45:42 andromeda innd: buffindexed: overview data is too large 25187
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25388
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25388
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25387
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25387
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25387
Jan 29 21:46:19 andromeda innd: buffindexed: overview data is too large 25387
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25456
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25456
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25455
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25455
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25455
Jan 29 23:55:50 andromeda innd: buffindexed: overview data is too large 25455
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25390
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25390
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25389
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25389
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25389
Jan 29 23:59:50 andromeda innd: buffindexed: overview data is too large 25389

Log entries by program:

Program name	Lines	%Lines	Size	%Size

inn	4152865	98.9%	361.5 MB	98.3%
innd	25507	0.6%	3.8 MB	1.0%
nnrpd	18376	0.4%	1.9 MB	0.5%
cnfsstat	2304	0.1%	403.3 KB	0.1%
rnews	105	0.0%	9.5 KB	0.0%
controlchan	24	0.0%	3.7 KB	0.0%

TOTAL: 6	4199181	100.0%	367.7 MB	100.0%

History cache:

Reason	Count	%Count

Do not exist	4143544	99.8%
Negative hits	8445	0.2%
Positive hits	1099	0.0%
Cache misses	471	0.0%

TOTAL: 4	4153559	100.0%

INND timer:

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

article cancel	00:00:01.907	0.0%	2716	0.000	0.702	24.000
article cleanup	00:00:33.852	0.0%	4142398	0.002	0.008	0.023
article logging	00:00:36.439	0.0%	4149212	0.000	0.009	0.029
article parse	00:01:24.466	0.1%	11691502	0.003	0.007	0.018
article write	00:17:14.595	1.2%	4044692	0.120	0.256	2.040
artlog/artcncl	00:00:00.027	0.0%	2679	0.000	0.010	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:00:24.609	0.0%	12199954	0.001	0.002	0.004
hisgrep/artcncl	00:00:01.409	0.0%	1554	0.000	0.907	24.000
hishave/artcncl	00:00:00.164	0.0%	2716	0.000	0.060	1.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:29.229	0.1%	4150843	0.000	0.021	1.382
history sync	00:00:00.170	0.0%	862	0.000	0.197	2.000
history write	00:04:23.334	0.3%	4142382	0.011	0.064	3.213
hiswrite/artcncl	00:00:00.283	0.0%	1162	0.000	0.244	7.000
idle	22:01:33.142	91.8%	10040989	0.018	7.897	60005.000
nntp read	00:01:06.844	0.1%	11088347	0.002	0.006	0.029
overview write	01:25:18.573	5.9%	4044692	0.599	1.266	17.162
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%	21	0.000	0.000	0.000

TOTAL: 23:59:25.548	23:54:09.043	99.6%	-	-	-	-

nnrpd timer:

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

hisgrep	00:02:23.307	0.2%	146353	0.000	0.979	0.000
idle	17:36:51.978	98.5%	299422	14.286	211.781	85709.286
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:56.255	0.2%	903827	0.000	0.129	0.000
readart	00:06:47.911	0.6%	292847	0.000	1.393	0.000

TOTAL: 17:52:31.600	17:47:59.451	99.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

alteferians@alteferians.org	3	0	0	0	0	3

TOTAL	3	0	0	0	0	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed

1	192.168.33.37	437	2931344	2868881	0	62463	97%	181:05:56

TOTAL: 1		437	2931344	2868881	0	62463	97%	181:05:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art	Elapsed

1	192.168.33.37	13.4 GB	242.6 KB	13.4 GB	99%	4.8 KB	181:05:56

TOTAL: 1		13.4 GB	242.6 KB	13.4 GB	99%	4.8 KB	181:05:56

Incoming articles:

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

Jan 29 11:00:01 - 11:59:59	146114	3.6%	40.60	494.3 MB	3.0%	140.64
Jan 29 12:00:00 - 12:59:59	653583	16.2%	181.55	2.1 GB	13.0%	610.37
Jan 29 13:00:00 - 13:59:59	346158	8.6%	96.16	1.4 GB	9.0%	419.05
Jan 29 14:00:00 - 14:59:59	352710	8.7%	97.97	1.2 GB	7.4%	347.13
Jan 29 15:00:00 - 15:59:59	255114	6.3%	70.86	819.2 MB	5.0%	233.00
Jan 29 16:00:00 - 16:59:59	166991	4.1%	46.39	662.6 MB	4.0%	188.48
Jan 29 17:00:00 - 17:59:59	97756	2.4%	27.15	652.6 MB	4.0%	185.62
Jan 29 18:00:00 - 18:59:59	200279	5.0%	55.63	1.1 GB	6.8%	318.49
Jan 29 19:00:00 - 19:59:59	206393	5.1%	57.33	1.5 GB	9.0%	423.26
Jan 29 20:00:00 - 20:59:59	287547	7.1%	79.87	1.4 GB	8.4%	393.58
Jan 29 21:00:00 - 21:59:59	264383	6.5%	73.44	963.1 MB	5.9%	273.95
Jan 29 22:00:00 - 22:59:59	270761	6.7%	75.21	811.7 MB	4.9%	230.90
Jan 29 23:00:00 - 23:59:59	297051	7.3%	82.51	738.6 MB	4.5%	210.09
Jan 30 00:00:00 - 00:59:59	224931	5.6%	62.48	642.8 MB	3.9%	182.84
Jan 30 01:00:00 - 01:59:59	214869	5.3%	59.69	712.1 MB	4.3%	202.54
Jan 30 02:00:00 - 02:59:59	60978	1.5%	16.94	1.1 GB	6.8%	317.31
Jan 30 03:00:00 - 03:00:01	23	0.0%	23.00	1.5 MB	0.0%	1560.21

TOTAL: 16:00:00	4045641	100.0%	70.24	16.1 GB	100.0%	292.35

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days

01	BIN	137.8 GB	3.5 GB	2.5%	197	33.40	50.07
02	BIN	137.8 GB	41.8 GB	30.4%	197	33.31	50.21
03	SMALL	13.0 GB	12.7 GB	97.7%	20	31.84	4.97
04	BIN	80.1 GB	39.9 GB	49.8%	153	16.76	57.99
05	BIN	80.1 GB	24.5 GB	30.6%	170	16.61	58.52
06	SMALL	13.0 GB	12.2 GB	93.2%	20	31.78	4.98
07	BIN	80.1 GB	28.4 GB	35.5%	153	16.68	58.26
08	BIN	80.1 GB	7.3 GB	9.1%	170	16.61	58.51
09	SMALL	13.0 GB	12.2 GB	93.9%	20	31.89	4.96
10	BIN	80.1 GB	22.1 GB	27.6%	153	16.64	58.40
11	BIN	80.1 GB	31.6 GB	39.5%	170	16.76	57.98
12	BIN	13.0 GB	4.7 GB	36.1%	580	16.73	9.46
13	BIN	80.1 GB	64.9 GB	81.1%	94	16.63	58.47
14	SMALL	80.1 GB	40.8 GB	51.0%	2	31.83	30.54

TOTAL: 14	-	968.5 GB	346.8 GB	35.8%	2099	23.39	-

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other

1	192.168.33.37	104544	97709	0	16	0	0	0	0	6819
2	?	1	0	0	0	0	0	0	0	1

TOTAL: 2		104545	97709	0	16	0	0	0	0	6820

Unwanted newsgroups [Top 20]:

Newsgroup	Count

alt.binaries.pwp	32551
alt.binaries.dvdrs.pw	8823
alt.binaries.private.not.yours	8722
alt.binaries.comics.reposts	6814
bellsouth.net.test	3498
alt.binaries.blu-ray	2900
alt.binaries.insiderz	2695
alt.binaries.pictures.katie	2484
alt.binaries.mp3.audiobooks.francais	1476
microsoft.public.test	1408
alt.binaries.amazing	949
alt.binaries.dvd.criterion	729
alt.binaries.just4fun.nl	609
alt.binaries.matrix	530
alt.binaries.applications	516
alt.binaries.bonelles	484
alt.binaries.ls	455
alt.binaries.wmvhd	454
alt.binaries.pictures.amateur.butts	407
alt.binaries.nospam.female.short-hair	388

TOTAL: 1755	97709

Miscellaneous innd statistics [Top 10]:

Event	Server	Number

Including strange strings
	192.168.33.37	6817
	TOTAL: 1	6817

TOTAL: 1		6817

Rnews connections rejected:

Reason	Conn

400 No space left on device writing logging article file -- throttling	105

TOTAL: 1	105

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	keyhole.sovam.com	3	146353	3.0 GB	18	0	0	12:18:58
2	195.218.244.87	2	2	19.1 KB	1	0	0	00:01:49
3	rol24.rol.kis.ru	3	1	1.2 KB	1	0	0	00:02:09

TOTAL: 3		8	146356	3.0 GB	20	0	0	17:52:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed

1	*.sovam.com	3	146353	3.0 GB	18	0	0	12:18:58
2	unresolved	2	2	19.1 KB	1	0	0	00:01:49
3	*.rol.kis.ru	3	1	1.2 KB	1	0	0	00:02:09
4	*.sovintel.net	13	0	0.0 KB	0	0	0	00:00:08
5	*.tony.pp.ru	68	0	0.0 KB	0	0	0	00:02:29
6	*.net.rol.ru	32	0	0.0 KB	0	0	0	05:09:58
7	*.rol.ru	5760	0	0.0 KB	0	0	0	00:14:20

TOTAL: 7		5881	146356	3.0 GB	20	0	0	17:49:55

NNRP total resource statistics [Top 20]:

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

keyhole.sovam.com	26.231	49.195	0.000	12:18:58
modul-gw000.net.rol.ru	0.044	0.072	0.000	05:09:58
balancer-b.rol.ru	3.208	3.172	0.000	00:07:17
balancer-a.rol.ru	3.400	3.552	0.000	00:07:03
ts2-b230.nsk.dial.rol.ru	0.036	0.060	0.000	00:02:35
net1-x-ring.tony.pp.ru	0.120	0.084	0.000	00:02:29
rol24.rol.kis.ru	0.008	0.008	0.000	00:02:09
195.218.244.87	0.028	0.068	0.000	00:01:49
fw-nat.sovintel.net	0.072	0.040	0.000	00:00:08
195.49.165.34	0.000	0.004	0.000	00:00:00
95.79.7.23	0.004	0.000	0.000	00:00:00

TOTAL: 11	33.151	56.255	0.000	17:52:30

Curious NNRP server explorers [Top 20]:

System	Conn

balancer-b.rol.ru	2880
balancer-a.rol.ru	2880
net1-x-ring.tony.pp.ru	68
modul-gw000.net.rol.ru	32
ts2-b230.nsk.dial.rol.ru	19
fw-nat.sovintel.net	13

TOTAL: 6	5892

NNRP no permission clients:

System	Conn

ts2-b230.nsk.dial.rol.ru	3
95.79.7.23	1
195.49.165.34	1

TOTAL: 3	5

Newsgroup request counts (by category):

Category		Count	Pct

1	alt	119372	81.6%
2	hr	26983	18.4%
3	lada	1	0.0%

TOTAL: 3		146356	100.0%

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

Newsgroup		Count

1	alt.binaries.multimedia.erotic.playboy	104421
2	hr.alt.seks.binaries.playboy	26983
3	alt.binaries.full.post.verified.playboy	6385
4	alt.binaries.pictures.bras	5860
5	alt.mag.playboy	2629
6	alt.binaries.photos.nude-art.moderated	75
7	alt.binaries.erotica.pornstars	2
8	lada.music	1

TOTAL: 8		146356