Daily Usenet report for csiph.com: Feb 22 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 22 17:20:02 csiph controlchan[36517]: Unknown "forwarded" control by robomod@news.nic.it

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	41963	60.8%	8.0 MB	72.4%
innd	22754	33.0%	2.4 MB	21.9%
innfeed	3189	4.6%	527.4 KB	4.7%
nnrpd	961	1.4%	106.3 KB	0.9%
nocem	107	0.2%	11.2 KB	0.1%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	68976	100.0%	11.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	432719	56.7%
Negative hits	288871	37.8%
Do not exist	41126	5.4%
Cache misses	639	0.1%
TOTAL: 4	763355	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.760	0.0%	300	0.000	2.533	38.000
article cleanup	00:00:00.298	0.0%	41089	0.000	0.007	0.030
article logging	00:00:03.662	0.0%	41546	0.000	0.088	1.016
article parse	00:00:03.443	0.0%	139208	0.000	0.025	0.812
article write	00:02:43.530	0.2%	23624	0.154	6.922	39.595
artlog/artcncl	00:00:00.029	0.0%	248	0.000	0.117	7.000
artlog/artparse	00:00:00.347	0.0%	192	0.000	1.807	90.000
data move	00:00:00.286	0.0%	706632	0.000	0.000	0.006
hisgrep/artcncl	00:00:00.175	0.0%	298	0.000	0.587	11.000
hishave/artcncl	00:00:00.077	0.0%	300	0.000	0.257	3.556
hishave/artparse	00:00:00.000	0.0%	189	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:50.641	0.1%	764799	0.002	0.145	0.863
history sync	00:00:04.661	0.0%	357	0.000	13.056	107.500
history write	00:03:12.075	0.2%	41024	0.100	4.682	39.183
hiswrite/artcncl	00:00:00.030	0.0%	2	0.000	15.000	30.000
hiswrite/artparse	00:00:01.573	0.0%	189	0.000	8.323	53.714
idle	23:45:45.497	99.0%	690154	66.399	123.951	339.548
nntp read	00:00:03.350	0.0%	719285	0.002	0.005	0.007
overview write	00:03:59.829	0.3%	23624	0.675	10.152	188.732
perl filter	00:01:44.966	0.1%	40951	1.730	2.563	3.680
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.416	0.0%	47348	0.000	0.009	0.027
TOTAL: 24:00:51.566	23:59:35.645	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	23586	0.000	0.003	0.030
article prepare	00:00:00.007	0.0%	36440	0.000	0.000	0.007
article read	00:00:01.114	0.0%	6967	0.047	0.160	1.517
backlog stats	00:00:00.270	0.0%	442887	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.356	0.0%	338774	0.003	0.007	0.013
data write	00:00:01.926	0.0%	321399	0.002	0.006	0.015
idle	23:53:47.937	100.0%	442887	90.600	194.244	575.699
status file	00:00:00.367	0.0%	291	0.000	1.261	33.750
TOTAL: 23:54:03.415	23:53:54.048	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	34:35:05.130	100.0%	641	0.000	194235.772	600115.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.545	0.0%	1487	0.000	0.367	10.680
readart	00:00:00.736	0.0%	57	0.000	12.912	61.000
TOTAL: 34:35:56.624	34:35:06.411	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	144
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	164

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1153	133700	7647	115374	10679	5%	95:48:53
2	csiph-out.news.weretis.net	3	51490	4526	46052	912	8%	49:13:59
3	feeder.eternal-september.org	4	37750	3464	30570	3716	9%	24:48:55
4	news-out.aioe.org	3	23110	2786	20254	70	12%	24:08:59
5	newsfeed.neva.ru	153	54551	1503	50418	2630	2%	71:34:53
6	newsfeed.fu-berlin.de	45	22977	1088	21242	647	4%	28:31:47
7	news-out.mixmin.net	4	25990	803	24929	258	3%	24:11:51
8	newsfeeds.xmission.com	2	20786	624	20153	9	3%	21:04:06
9	news.redatomik.org	1	20491	536	19951	4	2%	22:02:44
10	nntp.pasdenom.info	3	23083	145	22921	17	0%	24:07:20
11	news.fcku.it	2	10544	104	10354	86	0%	23:59:55
12	news.freedyn.net	3	27040	99	26823	118	0%	24:09:42
13	xanadu-bbs.net	11	796	96	694	6	12%	20:21:07
14	news.swapon.de	2	2536	78	2415	43	3%	22:04:37
15	news.bbs.geek.nz	1	16925	24	16901	0	0%	23:59:32
16	localhost	43	9	9	0	0	100%	00:00:00
TOTAL: 16		1433	471778	23532	429051	19195	4%	500:08:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	23.5 MB	306.6 KB	68.6 MB	92.4 MB	25%	5.2 KB
2	csiph-out.news.weretis.net	14.5 MB	21.3 KB	10.8 MB	25.3 MB	57%	4.8 KB
3	feeder.eternal-september.org	9.1 MB	93.4 KB	53.3 MB	62.6 MB	14%	8.9 KB
4	news-out.aioe.org	7.8 MB	5.1 KB	135.8 KB	7.9 MB	98%	2.8 KB
5	news-out.mixmin.net	4.4 MB	21.2 KB	940.0 KB	5.4 MB	82%	5.2 KB
6	newsfeed.neva.ru	3.9 MB	59.2 KB	23.7 MB	27.7 MB	14%	6.9 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	1.7 MB	4.1 MB	58%	2.4 KB
8	newsfeeds.xmission.com	1.9 MB	0.0 KB	256.1 KB	2.1 MB	88%	3.4 KB
9	news.redatomik.org	1.6 MB	18.8 KB	425.0 KB	2.0 MB	78%	3.8 KB
10	news.freedyn.net	438.1 KB	0.0 KB	2.8 MB	3.2 MB	13%	15.1 KB
11	nntp.pasdenom.info	360.8 KB	5.2 KB	41.4 KB	407.5 KB	88%	2.5 KB
12	xanadu-bbs.net	266.6 KB	0.0 KB	65.5 KB	332.1 KB	80%	3.3 KB
13	news.swapon.de	190.5 KB	0.0 KB	134.3 KB	324.8 KB	58%	2.7 KB
14	news.fcku.it	179.8 KB	0.0 KB	447.3 KB	627.1 KB	28%	3.3 KB
15	news.bbs.geek.nz	53.7 KB	0.0 KB	0.0 KB	53.7 KB	100%	2.2 KB
16	localhost	11.4 KB	0.0 KB	0.0 KB	11.4 KB	100%	1.3 KB
TOTAL: 16		70.6 MB	530.9 KB	163.2 MB	234.3 MB	30%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 22 04:15:00 - 04:59:59	356	1.5%	0.13	1.2 MB	1.7%	0.47
Feb 22 05:00:00 - 05:59:59	559	2.4%	0.16	2.0 MB	2.8%	0.57
Feb 22 06:00:00 - 06:59:59	566	2.4%	0.16	2.0 MB	2.9%	0.58
Feb 22 07:00:00 - 07:59:59	1207	5.1%	0.34	5.4 MB	7.6%	1.54
Feb 22 08:00:00 - 08:59:59	1006	4.3%	0.28	2.8 MB	4.0%	0.80
Feb 22 09:00:00 - 09:59:59	1247	5.3%	0.35	3.3 MB	4.6%	0.93
Feb 22 10:00:00 - 10:59:59	1189	5.0%	0.33	3.1 MB	4.3%	0.87
Feb 22 11:00:00 - 11:59:59	1059	4.5%	0.29	2.9 MB	4.1%	0.82
Feb 22 12:00:00 - 12:59:59	1037	4.4%	0.29	2.8 MB	4.0%	0.81
Feb 22 13:00:00 - 13:59:59	1233	5.2%	0.34	3.8 MB	5.4%	1.08
Feb 22 14:00:00 - 14:59:59	1335	5.7%	0.37	3.3 MB	4.7%	0.95
Feb 22 15:00:00 - 15:59:59	1291	5.5%	0.36	3.6 MB	5.1%	1.02
Feb 22 16:00:00 - 16:59:59	1208	5.1%	0.34	3.4 MB	4.8%	0.96
Feb 22 17:00:00 - 17:59:59	1468	6.2%	0.41	4.8 MB	6.7%	1.36
Feb 22 18:00:00 - 18:59:59	1364	5.8%	0.38	3.9 MB	5.5%	1.10
Feb 22 19:00:00 - 19:59:59	1185	5.0%	0.33	3.7 MB	5.2%	1.06
Feb 22 20:00:00 - 20:59:59	1094	4.6%	0.30	3.0 MB	4.2%	0.86
Feb 22 21:00:00 - 21:59:59	1140	4.8%	0.32	3.1 MB	4.4%	0.89
Feb 22 22:00:00 - 22:59:59	1017	4.3%	0.28	3.0 MB	4.2%	0.84
Feb 22 23:00:00 - 23:59:59	787	3.3%	0.22	2.4 MB	3.4%	0.70
Feb 23 00:00:00 - 00:59:59	697	3.0%	0.19	2.3 MB	3.2%	0.65
Feb 23 01:00:00 - 01:59:59	595	2.5%	0.17	2.1 MB	3.0%	0.60
Feb 23 02:00:00 - 02:59:59	469	2.0%	0.13	1.3 MB	1.9%	0.38
Feb 23 03:00:00 - 03:59:59	389	1.6%	0.11	1.3 MB	1.8%	0.37
Feb 23 04:00:00 - 04:15:00	116	0.5%	0.13	332.7 KB	0.5%	0.37
TOTAL: 24:00:00	23614	100.0%	0.27	71.0 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9242	454	44	1	8743
2	feeder.eternal-september.org	3653	47	8	0	3598
3	newsfeed.neva.ru	2645	264	8	0	2373
4	newsfeed.fu-berlin.de	998	20	0	0	978
5	csiph-out.news.weretis.net	902	213	2	0	687
6	news-out.mixmin.net	264	5	2	0	257
7	news.freedyn.net	118	6	0	0	112
8	news-out.aioe.org	91	52	1	0	38
9	news.fcku.it	86	0	0	1	85
10	news.swapon.de	58	0	0	0	58
11	nntp.pasdenom.info	16	3	1	0	12
12	newsfeeds.xmission.com	15	2	0	0	13
13	xanadu-bbs.net	6	0	0	0	6
14	news.redatomik.org	6	0	2	0	4
15	news.bbs.geek.nz	1	0	0	0	1
TOTAL: 15		18101	1066	68	2	16965

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	140
lada.talk	119
it-alt.sport.calcio.juventus	114
eagle.userchat.eng	52
it.annunci.commerciali	45
it.annunci.varie	44
it.annunci.immobiliari	44
mpc.lists.freebsd.ports.bugs	38
lada.auto	21
mozilla.support.seamonkey	21
mozilla.dev.platform	18
gmane.linux.alsa.devel	17
alt.computer.workshop	16
alt.politics.scorched-earth	16
gmane.linux.kernel	15
alt.windows7.general	14
alt.survival	13
lada.photo	12
mozilla.dev.security.policy	12
mpc.lists.freebsd.x11	12
TOTAL: 139	1066

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

Groups	Count
sci.space.news	1
alt.culture.china	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13678
EMP (phn path)	1859
EMP (phl)	371
EMP (phn nph)	193
Too many newsgroups	52
User-issued cancel	15
HTML Multipart	14
Binary Payload	13
Scoring filter (10)	11
Scoring filter (8)	7
Too many newsgroups (meow)	6
Scoring filter (19)	5
Scoring filter (20)	4
HTML post	3
Scoring filter (14)	3
Scoring filter (9)	2
Scoring filter (18)	1
Scoring filter (13)	1
HTML Image Tags	1
Binary Image: misplaced jpg	1
TOTAL: 21	16241

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	98	0	111	111
TOTAL: 1	98	1	111	111

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	70
	TOTAL: 1	70
Huge articles
	feeder.eternal-september.org	109
	csiph-out.news.weretis.net	37
	news.freedyn.net	28
	news-out.mixmin.net	5
	news.fcku.it	4
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	3
	xanadu-bbs.net	1
	news.redatomik.org	1
	TOTAL: 9	192
Including strange strings
	newsfeed.fu-berlin.de	329
	csiph-out.feeder.erje.net	43
	news-out.aioe.org	21
	feeder.eternal-september.org	17
	csiph-out.news.weretis.net	17
	news.swapon.de	14
	news-out.mixmin.net	6
	newsfeeds.xmission.com	6
	newsfeed.neva.ru	4
	news.redatomik.org	2
	TOTAL: 10	459
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		722

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39525	5607	17302	105	0	14%	23:50:02
2	news.freedyn.net	24372	616	22885	2	0	2%	23:57:25
3	xanadu-bbs	26194	614	22887	1	0	2%	23:59:49
4	fu-berlin.de	22188	493	21695	0	0	2%	23:50:41
5	usenet.pasdenom.info	26197	236	22147	1082	13	0%	23:59:55
6	xmission	26654	121	22141	15	1	0%	23:59:55
7	news.redatomik.org	23497	41	22361	5	0	0%	23:50:02
8	aioe	20573	40	19164	14	0	0%	23:50:02
9	news.swapon.de	2242	14	2142	0	0	0%	23:52:50
10	weretis.net	17716	13	17062	3	0	0%	23:50:02
11	news.mixmin.net	22128	12	20929	0	0	0%	23:50:02
12	eternal-september	21457	10	19372	2	0	0%	23:58:58
13	neva.ru	22625	6	21185	24	0	0%	23:59:53
14	erje	15926	2	15743	16	0	0%	23:51:37
15	news.fcku.it	3080	1	2873	0	0	0%	21:57:05
16	bwh	0	0	0	0	23042	0%	23:50:05
17	au2pb	0	0	0	0	23043	0%	23:50:05
TOTAL: 17		314374	7826	269888	1269	46099	2%	404:18:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	15.1 MB	606.1 KB	15.7 MB	0.2 KB/s	2.8 KB	23:50:02
2	news.freedyn.net	5.0 MB	8.8 KB	5.0 MB	0.1 KB/s	8.3 KB	23:57:25
3	xanadu-bbs	4.9 MB	1.8 KB	4.9 MB	0.1 KB/s	8.2 KB	23:59:49
4	fu-berlin.de	4.6 MB	0.0 KB	4.6 MB	0.1 KB/s	9.6 KB	23:50:41
5	usenet.pasdenom.info	635.9 KB	5.7 MB	6.3 MB	0.1 KB/s	4.9 KB	23:59:55
6	xmission	277.7 KB	28.8 KB	306.5 KB	0.0 KB/s	2.3 KB	23:59:55
7	news.redatomik.org	115.5 KB	25.5 KB	141.1 KB	0.0 KB/s	3.1 KB	23:50:02
8	aioe	95.4 KB	40.1 KB	135.5 KB	0.0 KB/s	2.5 KB	23:50:02
9	news.mixmin.net	23.2 KB	0.0 KB	23.2 KB	0.0 KB/s	1.9 KB	23:50:02
10	news.swapon.de	20.6 KB	0.0 KB	20.6 KB	0.0 KB/s	1.5 KB	23:52:50
11	weretis.net	18.2 KB	4.6 KB	22.8 KB	0.0 KB/s	1.4 KB	23:50:02
12	eternal-september	15.3 KB	3.1 KB	18.3 KB	0.0 KB/s	1.5 KB	23:58:58
13	neva.ru	7.5 KB	54.3 KB	61.8 KB	0.0 KB/s	2.1 KB	23:59:53
14	erje	6.7 KB	170.2 KB	176.9 KB	0.0 KB/s	9.8 KB	23:51:37
15	news.fcku.it	1.4 KB	0.0 KB	1.4 KB	0.0 KB/s	1.4 KB	21:57:05
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
TOTAL: 17		30.9 MB	6.6 MB	37.4 MB	0.0 KB/s	4.2 KB	404:18:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	32	21	52.7 KB	8	0	0	06:44:20
2	68-185-181-64.dhcp.mdsn.wi.charter.com	34	13	21.9 KB	9	8	7	07:51:47
3	dhcp-089-099-203-254.chello.nl	59	13	23.2 KB	11	0	0	15:36:09
4	g235242.upc-g.chello.nl	2	10	21.0 KB	1	0	0	00:15:07
TOTAL: 4		127	57	118.7 KB	29	8	7	34:35:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	32	21	52.7 KB	8	0	0	06:44:20
2	*.chello.nl	59	13	23.2 KB	11	0	0	15:36:09
3	*.dhcp.mdsn.wi.charter.com	34	13	21.9 KB	9	8	7	07:51:47
4	*.upc-g.chello.nl	2	10	21.0 KB	1	0	0	00:15:07
5	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
6	*.cust.centrio.cz	25	0	0.0 KB	0	0	0	03:38:29
7	unresolved	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 7		154	57	118.7 KB	29	8	7	34:35:55

NNRP auth users [Top 20]:

User	Conn
ctomczyk	8
ohlandl	7
moussa	1
TOTAL: 3	16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.352	0.995	0.000	15:36:09
68-185-181-64.dhcp.mdsn.wi.charter.com	2.966	0.707	0.032	07:51:47
tempe0.bbox.io	2.792	0.678	0.000	06:44:20
194-73-239-109.cust.centrio.cz	1.631	0.487	0.039	03:38:29
c-174-61-130-222.hsd1.wa.comcast.net	0.082	0.008	0.000	00:30:00
g235242.upc-g.chello.nl	0.197	0.084	0.000	00:15:07
TOTAL: 6	13.020	2.959	0.071	34:35:55

Curious NNRP server explorers [Top 20]:

System	Conn
194-73-239-109.cust.centrio.cz	25
c-174-61-130-222.hsd1.wa.comcast.net	1
1.126.48.100	1
TOTAL: 3	27

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
194-73-239-109.cust.centrio.cz	7	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
TOTAL: 4	25	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	51	89.5%
2	muc	6	10.5%
TOTAL: 2		57	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	51
2	muc.lists.freebsd.stable	3
3	muc.lists.freebsd.current	2
4	muc.lists.freebsd.fs	1
TOTAL: 4		57

Daily Usenet report for csiph.com

Feb 22 04:15:00 -- Feb 23 04:15:00