Daily Usenet report

Jul 1 04:50:13 -- Jul 2 04:41:36

Log entries by program
History cache
INND timer
Control commands to INND
Incoming Feeds (INN)
Incoming articles
CNFS buffer status
Sites sending bad articles
Unwanted newsgroups
Supposedly-moderated groups with unmoderated postings
INND perl filter
INND too many connects per minute
INND misc events
Miscellaneous innd statistics
Outgoing Feeds (innfeed) by Articles
Outgoing Feeds (innfeed) by Volume
UUCP batches created
Rnews articles offered from
Rnews connections rejected
NNRP readership statistics
NNRP readership statistics (by domain)
NNRP gethostbyaddr failures

First 19 / 19 lines (100.0%)
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 0, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 1, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 2, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 3, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 4, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 5, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 6, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 7, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 8, initializing
Jul 1 15:44:49 elvis innd: buffindexed: No magic cookie found for buffindexed 9, initializing
Jul 1 15:45:16 elvis innd: filter: Restored EMP database.
Jul 1 15:46:29 elvis overchan[11689]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Jul 1 16:07:10 elvis innd: CNFS-sm: cnfs_retrieve: token @03064255463033310000003599DB0000000B@: bogus cycbuff name: BUF031:0x6b33b600:11
Jul 1 16:07:53 elvis controlchan[10056]: No headers in article <1246401906.16703@news.belwue.de>
Jul 1 16:08:13 elvis controlchan[10056]: No headers in article <checkgroups-1246429528@news.grisbi.org>
Jul 1 16:08:16 elvis controlchan[10056]: No headers in article <cmsg-20090701070405$7083@vulture.killfile.org>
Jul 1 18:49:15 elvis controlchan[10056]: No headers in article <rmgroup-microsoft.public.it.windows7-1246466836@news.trigofacile.com>
Jul 1 18:49:45 elvis controlchan[10056]: No headers in article <checkgroups-1246466963@news.trigofacile.com>
Jul 2 01:30:46 elvis controlchan[10056]: No headers in article <checkgroups_2009-07@dana.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	148711	49.7%	11.8 MB	42.6%
inn	115272	38.5%	13.1 MB	46.9%
innfeed	27021	9.0%	1.3 MB	4.6%
cnfsstat	7564	2.5%	1.5 MB	5.5%
nnrpd	780	0.3%	97.6 KB	0.3%
controlchan	15	0.0%	1.4 KB	0.0%
rnews	9	0.0%	0.8 KB	0.0%
batcher	6	0.0%	0.7 KB	0.0%
overchan	2	0.0%	0.4 KB	0.0%
TOTAL: 9	299380	100.0%	27.8 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	124158	43.0%
Do not exist	115443	40.0%
Positive hits	28988	10.0%
Cache misses	20331	7.0%
TOTAL: 4	288920	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.319	0.0%	1103	0.000	5.729	83.000
article control	00:00:04.273	0.0%	1053	0.000	4.058	68.000
article link	00:00:00.000	0.0%	0	0.000	0.000	0.000
article write	00:04:40.075	0.5%	79272	0.000	3.533	377.667
history grep	00:00:00.756	0.0%	605	0.000	1.250	24.000
history lookup	00:08:12.449	0.8%	288911	0.000	1.705	73.714
history sync	00:00:00.968	0.0%	12219	0.000	0.079	12.000
history write	00:10:02.865	1.0%	114656	1.000	5.258	25.667
idle	15:11:21.965	91.4%	3221663	0.000	16.973	57086.000
overview write	00:34:27.252	3.5%	79272	0.000	26.078	2829.000
perl filter	00:12:57.832	1.3%	114622	1.000	6.786	91.938
site send	00:00:25.522	0.0%	168085	0.000	0.152	459.083
TOTAL: 16:36:35.272	16:22:20.276	98.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	1345
flushlogs	1
paused	1
TOTAL: 3	1347

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	nntp.noris.net	14	52651	29385	8860	14406	55%	07:51:29
2	newsfeed0.freenet.de	311	32417	22254	8551	1612	68%	10:50:41
3	news.nl.linux.org	8	4441	2813	1590	38	63%	10:52:50
4	newsfeed1.freenet.de	300	8650	1241	7286	123	14%	10:40:46
5	localhost	2	7	7	0	0	100%	00:00:00
6	finalfrontier.franken.de	156	0	0	0	0	0%	00:00:09
TOTAL: 6		796	98166	55700	26287	16179	56%	40:15:55

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 1 15:50:13 - 15:59:59	1533	1.9%	2.61	4.0 MB	0.4%	6.94
Jul 1 16:00:00 - 16:59:59	20136	25.4%	5.59	137.4 MB	12.6%	39.08
Jul 1 17:00:00 - 17:59:59	14669	18.5%	4.07	231.6 MB	21.2%	65.87
Jul 1 18:00:00 - 18:59:59	6884	8.7%	1.91	263.1 MB	24.1%	74.83
Jul 1 19:00:00 - 19:59:59	8835	11.1%	2.45	171.3 MB	15.7%	48.72
Jul 1 20:00:00 - 20:59:59	4798	6.1%	1.33	18.7 MB	1.7%	5.32
Jul 1 21:00:00 - 21:59:59	4230	5.3%	1.18	19.6 MB	1.8%	5.58
Jul 1 22:00:00 - 22:59:59	3411	4.3%	0.95	26.9 MB	2.5%	7.64
Jul 1 23:00:00 - 23:59:59	3097	3.9%	0.86	11.1 MB	1.0%	3.14
Jul 2 00:00:00 - 00:59:59	2917	3.7%	0.81	16.1 MB	1.5%	4.57
Jul 2 01:00:00 - 01:59:59	2263	2.9%	0.63	9.9 MB	0.9%	2.82
Jul 2 02:00:00 - 02:59:59	2318	2.9%	0.64	14.5 MB	1.3%	4.13
Jul 2 03:00:00 - 03:59:59	2184	2.8%	0.61	6.9 MB	0.6%	1.97
Jul 2 04:00:00 - 04:41:36	2000	2.5%	0.80	161.8 MB	14.8%	66.38
TOTAL: 12:51:23	79275	100.0%	1.71	1.1 GB	100.0%	24.18

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
BUF001	MDEU	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF002	MDEU	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF003	MDEU	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF004	MDEU	1.9 GB	719.6 MB	36.8%	0	0.32	71.65
BUF005	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF006	MSUP	1.9 GB	1.8 GB	94.9%	3	0.97	23.88
BUF007	MSUP	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF008	MSUP	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF009	MSUP	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF010	MSUP	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF011	MBIN	1.9 GB	491.5 KB	0.0%	21	0.00	0.00
BUF012	MBIN	1.9 GB	491.5 KB	0.0%	21	0.00	0.00
BUF013	MBIN	1.9 GB	737.6 MB	37.8%	21	36.89	0.63
BUF014	MBIN	1.9 GB	491.5 KB	0.0%	21	0.00	0.00
BUF015	MBIN	1.9 GB	491.5 KB	0.0%	21	0.00	0.00
BUF016	MBIN	1.9 GB	491.5 KB	0.0%	21	0.00	0.00
BUF017	MBIN	1.9 GB	732.9 MB	37.5%	20	0.00	0.00
BUF018	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF019	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF020	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF021	MBIN	1.9 GB	491.5 KB	0.0%	18	0.00	0.00
BUF022	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF023	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF024	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF025	MBIN	1.9 GB	491.5 KB	0.0%	20	0.00	0.00
BUF026	MALT	1.9 GB	229.8 MB	11.8%	11	5.00	4.63
BUF027	MALT	1.9 GB	491.5 KB	0.0%	11	0.00	0.00
BUF028	MALT	1.9 GB	491.5 KB	0.0%	11	0.00	0.00
BUF029	MALT	1.9 GB	491.5 KB	0.0%	11	0.00	0.00
BUF030	MALT	1.9 GB	491.5 KB	0.0%	11	0.00	0.00
BUF031	MALT	1.9 GB	1.7 GB	88.1%	10	0.00	0.00
BUF032	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF033	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF034	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF035	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF036	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF037	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF038	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF039	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF040	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF041	MALL	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF042	MALL	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF043	MALL	1.9 GB	491.5 KB	0.0%	2	0.00	0.00
BUF044	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF045	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF046	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF047	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF048	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF049	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF050	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF051	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF052	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF053	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF054	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF055	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF056	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF057	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF058	MALL	1.9 GB	491.5 KB	0.0%	1	0.00	0.00
BUF059	MALL	1.9 GB	918.5 MB	47.0%	0	1.82	12.69
BUF060	MALL	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF061	MALL	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF062	MALL	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF063	MALL	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF064	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF065	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF066	MALT	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF067	MALT	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF068	MALT	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF069	MBIN	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF070	MBIN	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF071	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF072	MALT	1.9 GB	491.5 KB	0.0%	9	0.00	0.00
BUF073	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF074	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF075	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF076	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF077	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF078	MBIN	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF079	MALT	1.9 GB	491.5 KB	0.0%	10	0.00	0.00
BUF080	MALT	1.9 GB	491.5 KB	0.0%	9	0.00	0.00
BUF086	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF088	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUF089	MDEU	1.9 GB	491.5 KB	0.0%	0	0.00	0.00
BUFCTL	MCTL	500.0 MB	202.7 MB	40.5%	11	0.00	0.00
BUFNOC	MNOC	500.0 MB	43.3 MB	8.7%	2	0.15	40.82
TOTAL: 85	-	159.3 GB	7.0 GB	4.4%	605	5.07	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	nntp.noris.net	33835	8414	15	1	25405
2	newsfeed0.freenet.de	1867	77	25	1	1764
3	newsfeed1.freenet.de	131	2	9	0	120
4	news-out.odn.de	117	0	1	0	116
5	news.nl.linux.org	47	0	21	0	26
TOTAL: 5		35997	8493	71	2	27431

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.sex.pictures.nospam	7376
alt.sex.telephone	172
alt.sex.erotica.market.place	76
alt.binaries.nospam.breasts.natural	73
alt.sex.telephone.ads	57
microsoft.public.internetexplorer.beta	53
alt.sex.erotica.marketplace	50
talk.us.rhode-island	44
alt.sex.femdom	40
alt.penthouse.sex.telephone	33
misc.phone.mobile.iphone	23
alt.sex.marketplace	23
alt.sex.phone.ads	21
novell.support.groupwise.8x.clients	16
novell.support.identity-manager.engine-drivers	16
alt.sex.masterbation.pictures.female.teen	15
gnu.hurd.bug	14
novell.support.edirectory.netware	14
comp.lang.xharbour	13
novell.support.zenworks.configuration-management.10.policies	11
TOTAL: 149	8493

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
alt.christnet.religion	1
sci.physics.strings	1
TOTAL: 2	2

INND perl filter [Top 20]:

Reason	Count
EMP (md5)	8249
EMP (phl)	6253
Poison newsgroup	5440
Binary in non-binary group	5277
Scoring filter (8)	494
Cancel for rejected article	400
Adult group ECP	243
Scoring filter (9)	153
Scoring filter (11)	113
Scoring filter (10)	85
Scoring filter (13)	48
Scoring filter (12)	42
Scoring filter (15)	14
HTML post	13
Scoring filter (14)	9
Attached HTML file	7
Scoring filter (16)	6
Scoring filter (17)	5
Too many test groups in crosspost	1
Scoring filter (19)	1
TOTAL: 22	26855

INND too many connects per minute:

System	Conn
news-out.odn.de	103
newsfeed0.freenet.de	59
newsfeed1.freenet.de	56
91.62.219.113	2
88.73.100.101	2
TOTAL: 5	222

INND misc events:

Events	Count
gethostbyname error	7
TOTAL: 1	7

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	nntp.noris.net	16
	TOTAL: 1	16
Including strange strings
	nntp.noris.net	111
	newsfeed0.freenet.de	38
	news-out.odn.de	2
	newsfeed1.freenet.de	1
	TOTAL: 4	152
TOTAL: 2		168

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	newsserver.news.garr.it	5310124	207859	4599120	37068	3335	324332	3%	2773:40:36
2	news-in.odn.de	10106	3365	6741	0	0	4	33%	2773:40:35
3	nntp.noris.net	27609	453	26840	21	0	1442	1%	2773:40:35
4	newsfeed.freenet.de	7293	108	6850	302	0	2103	1%	2773:40:35
5	news.hometree.net	13	13	0	0	0	11	100%	2781:23:12
6	mail2news	0	0	0	0	0	625765	0%	2783:01:35
7	fu-berlin.de	16	0	16	0	0	0	0%	2773:40:35
8	news.nl.linux.org	0	0	0	0	0	0	0%	2773:40:35
9	hammer.uoregon.edu	0	0	0	0	0	3163	0%	2782:51:39
TOTAL: 9		5355161	211798	4639567	37391	3335	956820	3%	24989:19:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	newsserver.news.garr.it	1.3 GB	1.1 GB	2.4 GB	0.3 KB/s	10.3 KB	2773:40:36
2	news-in.odn.de	10.9 MB	0.0 KB	10.9 MB	0.0 KB/s	3.3 KB	2773:40:35
3	nntp.noris.net	1.0 MB	71.5 KB	1.1 MB	0.0 KB/s	2.3 KB	2773:40:35
4	newsfeed.freenet.de	234.6 KB	687.2 KB	921.8 KB	0.0 KB/s	2.2 KB	2773:40:35
5	news.hometree.net	37.5 KB	0.0 KB	37.5 KB	0.0 KB/s	2.9 KB	2781:23:12
6	mail2news	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	2783:01:35
7	fu-berlin.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	2773:40:35
8	news.nl.linux.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	2773:40:35
9	hammer.uoregon.edu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	2782:51:39
TOTAL: 9		1.3 GB	1.1 GB	2.4 GB	0.0 KB/s	10.2 KB	24989:19:57

UUCP batches created:

Server	Offered	Articles	Size	Elapsed
josef_1	3	554	1.4 MB	00:00:03
TOTAL: 1	3	554	1.4 MB	00:00:03

Rnews articles offered from:

System	Offered
polaris	7
TOTAL: 1	7

Rnews connections rejected:

Reason	Conn
400 Input/output error writing SMstore file -- throttling	2
TOTAL: 1	2

NNRP readership statistics:

System		Conn	Elapsed
1	dsl01.83.171.191.94.ip-pool.nefkom.net	2	00:00:21
2	badcb44.bad.pppool.de	3	00:00:00
3	77-20-163-116-dynip.superkabel.de	1	00:00:00
4	213.185.132.10	4	00:00:00
5	mallard.franken.de	1	00:00:00
6	dsl01.83.171.161.222.ip-pool.nefkom.net	4	00:00:00
7	dsl01.83.171.149.185.ip-pool.nefkom.net	3	00:00:00
TOTAL: 7		18	00:00:22

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.83.171.161.222.ip-pool.nefkom.net	4	00:00:00
2	unresolved	4	00:00:00
3	*.83.171.149.185.ip-pool.nefkom.net	3	00:00:00
4	*.bad.pppool.de	3	00:00:00
5	*.83.171.191.94.ip-pool.nefkom.net	2	00:00:21
6	*.franken.de	1	00:00:00
7	*.superkabel.de	1	00:00:00
TOTAL: 7		18	00:00:22

NNRP gethostbyaddr failures [Top 20]:

System	Conn
213.185.132.10	4
TOTAL: 1	4