Daily Usenet report for csiph.com: Mar 23 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	26248	57.4%	5.7 MB	71.1%
innd	11023	24.1%	1.4 MB	17.0%
nnrpd	5435	11.9%	459.7 KB	5.6%
innfeed	2930	6.4%	505.5 KB	6.2%
nocem	66	0.1%	6.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	45703	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	312715	56.9%
Negative hits	211371	38.4%
Do not exist	25483	4.6%
Cache misses	342	0.1%
TOTAL: 4	549911	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.616	0.0%	194	0.000	3.175	91.000
article cleanup	00:00:00.147	0.0%	25272	0.000	0.006	0.026
article logging	00:00:00.590	0.0%	25820	0.000	0.023	0.323
article parse	00:00:01.781	0.0%	64937	0.000	0.027	0.241
article write	00:01:00.202	0.1%	19851	0.232	3.033	39.738
artlog/artcncl	00:00:00.001	0.0%	153	0.000	0.007	0.062
artlog/artparse	00:00:00.019	0.0%	304	0.000	0.062	5.333
data move	00:00:00.171	0.0%	527544	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.097	0.0%	194	0.000	0.500	60.000
hishave/artcncl	00:00:00.046	0.0%	194	0.000	0.237	19.000
hishave/artparse	00:00:00.001	0.0%	300	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:02.321	0.1%	551175	0.004	0.113	0.847
history sync	00:00:01.871	0.0%	369	0.000	5.070	31.000
history write	00:01:07.359	0.1%	25270	0.193	2.666	10.155
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.927	0.0%	300	0.000	3.090	29.000
idle	23:52:14.034	99.4%	528216	86.754	162.687	487.258
nntp read	00:00:02.643	0.0%	534911	0.002	0.005	0.010
overview write	00:03:33.894	0.2%	19851	0.462	10.775	899.108
perl filter	00:00:49.438	0.1%	25064	1.448	1.972	4.563
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.345	0.0%	39761	0.000	0.009	0.024
TOTAL: 24:01:06.350	23:59:56.503	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.044	0.0%	20558	0.000	0.002	0.026
article prepare	00:00:00.001	0.0%	18204	0.000	0.000	0.005
article read	00:00:00.396	0.0%	2406	0.000	0.165	3.077
backlog stats	00:00:00.204	0.0%	378973	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.916	0.0%	287439	0.003	0.007	0.015
data write	00:00:01.652	0.0%	270520	0.000	0.006	0.013
idle	23:54:24.083	100.0%	378973	118.494	227.098	676.329
status file	00:00:00.243	0.0%	277	0.000	0.877	17.000
TOTAL: 23:54:38.182	23:54:28.539	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:13:44.103	100.0%	5363	346.248	8880.124	602198.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.069	0.0%	11128	0.000	0.006	0.167
readart	00:00:00.047	0.0%	67	0.000	0.701	15.000
TOTAL: 13:14:06.276	13:13:44.219	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	40560	6189	33940	431	15%	47:58:43
2	csiph-out.feeder.erje.net	1152	83702	4259	77403	2040	5%	95:02:42
3	feeder.eternal-september.org	2	22195	2759	19024	412	12%	24:13:33
4	news-out.aioe.org	6	19717	1632	18005	80	8%	24:21:53
5	newsfeed.fu-berlin.de	14	18380	1040	17059	281	5%	24:39:26
6	news.redatomik.org	1	17378	1038	16325	15	5%	21:46:17
7	newsfeed.neva.ru	146	26561	958	23666	1937	3%	72:37:29
8	newsfeeds.xmission.com	4	19825	930	18888	7	4%	28:14:00
9	news.fcku.it	1	8714	805	7690	219	9%	23:59:58
10	news-out.mixmin.net	2	20773	202	20313	258	0%	23:59:48
11	news.swapon.de	3	1727	60	1664	3	3%	23:48:43
12	news.freedyn.net	2	22514	37	22450	27	0%	26:35:28
13	nntp.pasdenom.info	2	18467	25	18429	13	0%	23:59:13
14	localhost	36	3	3	0	0	100%	00:00:04
15	news.bbs.geek.nz	1	18679	3	18676	0	0%	23:59:50
16	xanadu-bbs.net	14	697	0	696	1	0%	23:19:19
TOTAL: 16		1392	339892	19940	314228	5724	5%	508:36:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	20.7 MB	0.0 KB	10.2 MB	30.9 MB	67%	4.8 KB
2	csiph-out.feeder.erje.net	18.1 MB	8.5 KB	11.4 MB	29.5 MB	61%	4.8 KB
3	feeder.eternal-september.org	7.2 MB	0.0 KB	3.2 MB	10.4 MB	69%	3.4 KB
4	news-out.aioe.org	4.1 MB	0.0 KB	356.4 KB	4.5 MB	92%	2.7 KB
5	newsfeeds.xmission.com	3.2 MB	0.0 KB	408.4 KB	3.6 MB	88%	3.9 KB
6	newsfeed.neva.ru	2.5 MB	7.4 KB	5.4 MB	7.9 MB	31%	2.8 KB
7	news.redatomik.org	2.3 MB	0.0 KB	67.2 KB	2.4 MB	97%	2.3 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	648.9 KB	2.9 MB	78%	2.3 KB
9	news.fcku.it	1.9 MB	0.0 KB	1.7 MB	3.6 MB	53%	3.6 KB
10	news-out.mixmin.net	894.0 KB	0.0 KB	590.0 KB	1.4 MB	60%	3.2 KB
11	news.swapon.de	147.3 KB	0.0 KB	78.9 KB	226.2 KB	65%	3.6 KB
12	news.freedyn.net	139.8 KB	0.0 KB	872.6 KB	1012.4 KB	13%	15.8 KB
13	nntp.pasdenom.info	52.6 KB	0.0 KB	26.2 KB	78.8 KB	66%	2.1 KB
14	news.bbs.geek.nz	8.8 KB	0.0 KB	0.0 KB	8.8 KB	100%	2.9 KB
15	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.9 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	2.7 KB	2.7 KB	0%	2.7 KB
TOTAL: 16		63.6 MB	15.9 KB	34.9 MB	98.5 MB	64%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 23 04:15:00 - 04:59:59	279	1.4%	0.10	986.9 KB	1.5%	0.37
Mar 23 05:00:00 - 05:59:59	371	1.9%	0.10	1.7 MB	2.7%	0.49
Mar 23 06:00:00 - 06:59:59	461	2.3%	0.13	1.4 MB	2.2%	0.40
Mar 23 07:00:00 - 07:59:59	508	2.6%	0.14	1.7 MB	2.6%	0.47
Mar 23 08:00:00 - 08:59:59	933	4.7%	0.26	3.2 MB	5.0%	0.90
Mar 23 09:00:00 - 09:59:59	1010	5.1%	0.28	2.8 MB	4.4%	0.78
Mar 23 10:00:00 - 10:59:59	1008	5.1%	0.28	2.4 MB	3.8%	0.68
Mar 23 11:00:00 - 11:59:59	941	4.7%	0.26	2.5 MB	4.0%	0.72
Mar 23 12:00:00 - 12:59:59	894	4.5%	0.25	2.4 MB	3.9%	0.69
Mar 23 13:00:00 - 13:59:59	956	4.8%	0.27	2.6 MB	4.1%	0.74
Mar 23 14:00:00 - 14:59:59	1234	6.2%	0.34	4.0 MB	6.3%	1.13
Mar 23 15:00:00 - 15:59:59	1482	7.5%	0.41	5.3 MB	8.4%	1.50
Mar 23 16:00:00 - 16:59:59	1359	6.9%	0.38	4.9 MB	7.7%	1.38
Mar 23 17:00:00 - 17:59:59	1173	5.9%	0.33	3.1 MB	4.9%	0.88
Mar 23 18:00:00 - 18:59:59	998	5.0%	0.28	2.8 MB	4.4%	0.79
Mar 23 19:00:00 - 19:59:59	940	4.7%	0.26	3.0 MB	4.7%	0.85
Mar 23 20:00:00 - 20:59:59	951	4.8%	0.26	2.9 MB	4.7%	0.84
Mar 23 21:00:00 - 21:59:59	967	4.9%	0.27	3.1 MB	5.0%	0.89
Mar 23 22:00:00 - 22:59:59	812	4.1%	0.23	2.6 MB	4.1%	0.73
Mar 23 23:00:00 - 23:59:59	554	2.8%	0.15	2.3 MB	3.7%	0.66
Mar 24 00:00:00 - 00:59:59	532	2.7%	0.15	1.9 MB	3.0%	0.53
Mar 24 01:00:00 - 01:59:59	468	2.4%	0.13	1.8 MB	2.8%	0.50
Mar 24 02:00:00 - 02:59:59	492	2.5%	0.14	1.8 MB	2.9%	0.51
Mar 24 03:00:00 - 03:59:59	395	2.0%	0.11	1.9 MB	3.1%	0.55
Mar 24 04:00:00 - 04:15:00	105	0.5%	0.12	345.8 KB	0.5%	0.38
TOTAL: 24:00:00	19823	100.0%	0.23	63.2 MB	100.0%	0.75

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	2048	126	4	1918
2	newsfeed.neva.ru	1931	165	2	1764
3	newsfeed.fu-berlin.de	765	23	0	742
4	csiph-out.news.weretis.net	474	184	0	290
5	feeder.eternal-september.org	417	63	0	354
6	news-out.mixmin.net	263	0	0	263
7	news.fcku.it	206	0	0	206
8	news-out.aioe.org	83	64	0	19
9	news.redatomik.org	27	0	0	27
10	news.freedyn.net	20	0	0	20
11	news.swapon.de	15	0	0	15
12	nntp.pasdenom.info	13	10	0	3
13	newsfeeds.xmission.com	8	2	0	6
14	news.bbs.geek.nz	1	0	0	1
15	xanadu-bbs.net	1	0	0	1
TOTAL: 15		6272	637	6	5629

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	78
alt.windows7.general	76
lada.auto	53
gmane.linux.alsa.devel	45
mozilla.support.thunderbird	24
mpc.lists.freebsd.ports.bugs	21
it-alt.sport.calcio.juventus	16
gmane.comp.gis.openstreetmap.tagging	16
alt.bbs.synchronet	15
mozilla.dev.security.policy	14
mozilla.support.firefox	12
alt.computer.workshop	12
mozilla.dev.platform	11
spline.eisfair	11
alt.politics.usa	10
gmane.comp.gis.openstreetmap	9
mpc.lists.nanog	9
alt.survival	9
lada.commerce	9
oesterreich.tratsch	9
TOTAL: 97	637

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3989
EMP (phn path)	378
Too many newsgroups	39
User-issued cancel	37
EMP (phn nph)	34
HTML Multipart	24
EMP (phl)	23
Binary Payload	14
Scoring filter (10)	13
Scoring filter (12)	8
Scoring filter (9)	5
Scoring filter (11)	2
Too many newsgroups (meow)	2
HTML post	1
Scoring filter (8)	1
Binary Image: misplaced jpg	1
Too many newsgroups (low_xpost)	1
User-issued spam cancel	1
Scoring filter (17)	1
TOTAL: 19	4574

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	42	0	44	44
TOTAL: 1	42	17	44	44

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	202
	TOTAL: 1	202
Huge articles
	csiph-out.feeder.erje.net	158
	csiph-out.news.weretis.net	98
	feeder.eternal-september.org	20
	news.freedyn.net	5
	newsfeeds.xmission.com	5
	news-out.mixmin.net	5
	news.fcku.it	4
	newsfeed.fu-berlin.de	2
	news.redatomik.org	1
	news.swapon.de	1
	TOTAL: 11	300
Including strange strings
	newsfeed.fu-berlin.de	469
	csiph-out.news.weretis.net	43
	news.swapon.de	12
	news.redatomik.org	10
	csiph-out.feeder.erje.net	9
	news-out.aioe.org	2
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	1
	news-out.mixmin.net	1
	TOTAL: 9	549
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 4		1055

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36833	970	18269	12	3	2%	23:59:41
2	news.freedyn.net	21330	735	19057	7	3	3%	23:59:53
3	xanadu-bbs	22340	679	19144	0	0	3%	23:59:53
4	fu-berlin.de	18570	543	18026	0	1	2%	23:59:52
5	usenet.pasdenom.info	22013	208	18422	1166	7	0%	23:59:53
6	aioe	17563	59	16518	0	1	0%	23:59:48
7	news.redatomik.org	19002	15	18186	1	0	0%	23:59:23
8	weretis.net	14444	14	12698	8	24	0%	23:59:53
9	erje	15819	10	15471	2	0	0%	23:59:50
10	xmission	20333	9	18358	1	731	0%	23:59:53
11	neva.ru	18595	7	17941	95	0	0%	23:59:44
12	news.mixmin.net	19153	4	18600	0	1	0%	23:59:48
13	news.swapon.de	1877	3	1814	0	0	0%	23:44:26
14	eternal-september	17473	3	16274	0	1	0%	23:59:39
15	news.fcku.it	1827	0	1790	0	0	0%	21:54:35
TOTAL: 15		267172	3259	230568	1292	772	1%	357:36:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.8 MB	73.2 KB	7.9 MB	0.1 KB/s	10.9 KB	23:59:53
2	xanadu-bbs	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	11.5 KB	23:59:53
3	fu-berlin.de	7.1 MB	0.0 KB	7.1 MB	0.1 KB/s	13.4 KB	23:59:52
4	news.bbs.geek.nz	3.0 MB	50.7 KB	3.1 MB	0.0 KB/s	3.2 KB	23:59:41
5	usenet.pasdenom.info	752.9 KB	8.1 MB	8.8 MB	0.1 KB/s	6.6 KB	23:59:53
6	aioe	158.2 KB	0.0 KB	158.2 KB	0.0 KB/s	2.7 KB	23:59:48
7	weretis.net	38.2 KB	26.1 KB	64.3 KB	0.0 KB/s	2.9 KB	23:59:53
8	news.redatomik.org	35.4 KB	15.0 KB	50.5 KB	0.0 KB/s	3.2 KB	23:59:23
9	erje	29.6 KB	47.4 KB	76.9 KB	0.0 KB/s	6.4 KB	23:59:50
10	xmission	18.9 KB	4.4 KB	23.2 KB	0.0 KB/s	2.3 KB	23:59:53
11	neva.ru	15.2 KB	285.1 KB	300.3 KB	0.0 KB/s	2.9 KB	23:59:44
12	news.mixmin.net	7.4 KB	0.0 KB	7.4 KB	0.0 KB/s	1.9 KB	23:59:48
13	news.swapon.de	5.7 KB	0.0 KB	5.7 KB	0.0 KB/s	1.9 KB	23:44:26
14	eternal-september	5.7 KB	0.0 KB	5.7 KB	0.0 KB/s	1.9 KB	23:59:39
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:54:35
TOTAL: 15		26.6 MB	8.5 MB	35.1 MB	0.0 KB/s	7.9 KB	357:36:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	43	62	167.4 KB	51	0	0	00:37:33
2	96-42-18-226.dhcp.mdsn.wi.charter.com	21	3	5.7 KB	3	3	3	04:31:04
3	194-73-239-109.cust.centrio.cz	3	2	2.3 KB	2	0	0	00:23:52
TOTAL: 3		67	67	175.4 KB	56	3	3	13:14:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	43	62	167.4 KB	51	0	0	00:37:33
2	*.dhcp.mdsn.wi.charter.com	21	3	5.7 KB	3	3	3	04:31:04
3	*.cust.centrio.cz	3	2	2.3 KB	2	0	0	00:23:52
4	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:07
5	*.ph.ph.cox.net	8	0	0.0 KB	0	0	0	03:10:12
6	*.upc-a.chello.nl	25	0	0.0 KB	0	0	0	04:01:15
TOTAL: 6		101	67	175.4 KB	56	3	3	13:14:05

NNRP auth users [Top 20]:

User	Conn
moussa	43
ohlandl	3
ctomczyk	3
TOTAL: 3	49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
96-42-18-226.dhcp.mdsn.wi.charter.com	1.656	0.455	0.012	04:31:04
a131033.upc-a.chello.nl	1.828	0.348	0.000	04:01:15
ip72-222-200-59.ph.ph.cox.net	0.661	0.115	0.000	03:10:12
141.168.117.108	4.813	0.924	0.142	00:37:33
challenger.nielsenmedia.com	0.052	0.015	0.000	00:30:07
194-73-239-109.cust.centrio.cz	0.325	0.065	0.010	00:23:52
TOTAL: 6	9.335	1.922	0.164	13:14:05

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	25
ip72-222-200-59.ph.ph.cox.net	8
challenger.nielsenmedia.com	1
TOTAL: 3	34

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-59.ph.ph.cox.net	6	0
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	52	77.6%
2	alt	13	19.4%
3	pl	1	1.5%
4	soc	1	1.5%
TOTAL: 4		67	100.0%

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

Newsgroup		Count
1	comp.os.vms	16
2	alt.comp.freeware	13
3	comp.arch	12
4	comp.ai.philosophy	8
5	comp.sys.ibm.ps2.hardware	6
6	comp.arch.embedded	4
7	comp.sys.hp.hardware	3
8	comp.os.cpm	1
9	soc.culture.lebanon	1
10	comp.sys.ibm.pc.games.action	1
11	pl.comp.www.nowe-strony	1
12	comp.lang.javascript	1
TOTAL: 12		67

Daily Usenet report for csiph.com

Mar 23 04:15:00 -- Mar 24 04:15:00