Daily Usenet report for csiph.com: Apr 3 04:15:06

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	25987	57.0%	5.6 MB	70.6%
innd	11041	24.2%	1.4 MB	17.5%
nnrpd	5538	12.2%	471.0 KB	5.8%
innfeed	2904	6.4%	483.0 KB	6.0%
nocem	105	0.2%	11.4 KB	0.1%
TOTAL: 5	45575	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	295946	54.9%
Negative hits	217497	40.4%
Do not exist	24766	4.6%
Cache misses	370	0.1%
TOTAL: 4	538579	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.906	0.0%	253	0.000	7.534	519.000
article cleanup	00:00:00.159	0.0%	24855	0.000	0.006	0.036
article logging	00:00:03.542	0.0%	25486	0.000	0.139	5.733
article parse	00:00:04.484	0.0%	63852	0.000	0.070	1.878
article write	00:06:45.816	0.5%	19266	0.254	21.064	234.581
artlog/artcncl	00:00:00.002	0.0%	214	0.000	0.009	0.062
artlog/artparse	00:00:00.477	0.0%	290	0.000	1.645	22.333
data move	00:00:00.175	0.0%	515994	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.074	0.0%	252	0.000	0.294	11.000
hishave/artcncl	00:00:00.298	0.0%	253	0.000	1.178	37.143
hishave/artparse	00:00:00.001	0.0%	290	0.000	0.003	0.048
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:06.090	0.1%	543404	0.004	0.232	2.104
history sync	00:00:05.116	0.0%	377	0.000	13.570	227.500
history write	00:03:14.685	0.2%	24740	0.274	7.869	53.559
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
hiswrite/artparse	00:00:03.195	0.0%	286	0.000	11.171	199.000
idle	23:25:42.478	97.7%	499386	101.513	168.892	477.380
nntp read	00:00:02.489	0.0%	522946	0.002	0.005	0.008
overview write	00:18:39.267	1.3%	19266	1.016	58.095	6523.098
perl filter	00:00:47.522	0.1%	24610	1.205	1.931	2.816
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.344	0.0%	38630	0.000	0.009	0.034
TOTAL: 23:59:03.720	23:57:38.121	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.044	0.0%	19401	0.000	0.002	0.020
article prepare	00:00:00.002	0.0%	27220	0.000	0.000	0.004
article read	00:00:01.622	0.0%	5001	0.000	0.324	13.067
backlog stats	00:00:00.192	0.0%	341353	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.811	0.0%	270006	0.001	0.007	0.014
data write	00:00:01.479	0.0%	254255	0.001	0.006	0.013
idle	23:54:12.256	100.0%	341353	145.608	252.092	826.769
status file	00:00:03.281	0.0%	374	0.000	8.773	55.071
TOTAL: 23:54:27.837	23:54:20.687	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:05:36.953	100.0%	5463	375.667	12582.272	600219.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.059	0.0%	11266	0.000	0.005	0.167
readart	00:00:00.083	0.0%	7	7.833	11.857	36.000
TOTAL: 19:06:09.472	19:05:37.095	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	8	38665	7237	30910	518	18%	47:04:31
2	csiph-out.feeder.erje.net	1153	81041	3136	75620	2285	3%	95:05:20
3	feeder.eternal-september.org	5	21603	2276	19029	298	10%	24:32:27
4	news.redatomik.org	2	21798	1869	19895	34	8%	30:48:09
5	newsfeed.neva.ru	146	22855	1161	19832	1862	5%	71:24:17
6	news-out.aioe.org	1	19074	1017	17971	86	5%	23:59:57
7	newsfeed.fu-berlin.de	19	16147	1015	15000	132	6%	23:23:54
8	news.fcku.it	1	8826	709	7930	187	8%	23:59:57
9	newsfeeds.xmission.com	3	31595	362	31232	1	1%	39:39:04
10	news-out.mixmin.net	2	20174	334	19469	371	1%	23:59:50
11	news.swapon.de	3	1625	37	1585	3	2%	26:42:19
12	nntp.pasdenom.info	4	18008	22	17978	8	0%	23:58:26
13	news.freedyn.net	11	1691	21	1653	17	1%	03:56:10
14	news.bbs.geek.nz	2	14774	6	14758	10	0%	24:01:58
15	xanadu-bbs.net	7	577	5	567	5	0%	21:46:48
16	localhost	34	0	0	0	0	0%	00:00:05
TOTAL: 16		1401	318453	19207	293429	5817	6%	504:23:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	23.7 MB	36.1 KB	7.9 MB	31.7 MB	74%	4.2 KB
2	csiph-out.feeder.erje.net	15.0 MB	106.6 KB	12.5 MB	27.6 MB	54%	5.2 KB
3	feeder.eternal-september.org	6.0 MB	92.9 KB	1.9 MB	8.0 MB	74%	3.2 KB
4	news.redatomik.org	4.1 MB	25.0 KB	540.4 KB	4.6 MB	88%	2.5 KB
5	news-out.aioe.org	3.1 MB	31.2 KB	280.9 KB	3.4 MB	91%	3.2 KB
6	newsfeed.neva.ru	3.0 MB	0.0 KB	6.0 MB	9.0 MB	32%	3.0 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	431.6 KB	2.7 MB	84%	2.5 KB
8	news.fcku.it	1.8 MB	7.7 KB	1.6 MB	3.4 MB	53%	3.9 KB
9	news-out.mixmin.net	1.4 MB	34.8 KB	850.6 KB	2.3 MB	61%	3.3 KB
10	newsfeeds.xmission.com	1.1 MB	0.0 KB	1.6 KB	1.1 MB	99%	3.2 KB
11	news.swapon.de	93.6 KB	0.0 KB	93.3 KB	186.9 KB	50%	4.7 KB
12	news.freedyn.net	56.2 KB	36.0 KB	240.7 KB	333.0 KB	16%	8.8 KB
13	nntp.pasdenom.info	53.3 KB	19.0 KB	20.6 KB	92.9 KB	57%	3.1 KB
14	news.bbs.geek.nz	16.1 KB	28.8 KB	28.8 KB	73.7 KB	21%	4.6 KB
15	xanadu-bbs.net	14.1 KB	0.0 KB	98.7 KB	112.8 KB	12%	11.3 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		61.7 MB	418.0 KB	32.5 MB	94.6 MB	65%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 3 04:15:06 - 04:59:59	420	2.2%	0.16	1.8 MB	3.0%	0.70
Apr 3 05:00:00 - 05:59:59	604	3.1%	0.17	2.7 MB	4.4%	0.77
Apr 3 06:00:00 - 06:59:59	510	2.6%	0.14	1.6 MB	2.5%	0.45
Apr 3 07:00:00 - 07:59:59	597	3.1%	0.17	1.6 MB	2.5%	0.44
Apr 3 08:00:00 - 08:59:59	725	3.8%	0.20	1.9 MB	3.1%	0.55
Apr 3 09:00:00 - 09:59:59	879	4.6%	0.24	2.6 MB	4.2%	0.73
Apr 3 10:00:00 - 10:59:59	870	4.5%	0.24	2.5 MB	4.0%	0.71
Apr 3 11:00:00 - 11:59:59	928	4.8%	0.26	2.7 MB	4.3%	0.76
Apr 3 12:00:00 - 12:59:59	951	4.9%	0.26	2.9 MB	4.7%	0.82
Apr 3 13:00:00 - 13:59:59	938	4.9%	0.26	3.2 MB	5.2%	0.91
Apr 3 14:00:00 - 14:59:59	1101	5.7%	0.31	4.2 MB	6.7%	1.19
Apr 3 15:00:00 - 15:59:59	1012	5.3%	0.28	3.1 MB	5.0%	0.88
Apr 3 16:00:00 - 16:59:59	1204	6.2%	0.33	3.6 MB	5.8%	1.02
Apr 3 17:00:00 - 17:59:59	1007	5.2%	0.28	3.0 MB	4.8%	0.84
Apr 3 18:00:00 - 18:59:59	1093	5.7%	0.30	3.9 MB	6.2%	1.10
Apr 3 19:00:00 - 19:59:59	999	5.2%	0.28	3.3 MB	5.3%	0.93
Apr 3 20:00:00 - 20:59:59	996	5.2%	0.28	2.9 MB	4.6%	0.82
Apr 3 21:00:00 - 21:59:59	1039	5.4%	0.29	3.4 MB	5.6%	0.98
Apr 3 22:00:00 - 22:59:59	845	4.4%	0.23	2.8 MB	4.6%	0.80
Apr 3 23:00:00 - 23:59:59	624	3.2%	0.17	2.0 MB	3.2%	0.55
Apr 4 00:00:00 - 00:59:59	507	2.6%	0.14	1.5 MB	2.5%	0.44
Apr 4 01:00:00 - 01:59:59	481	2.5%	0.13	1.7 MB	2.7%	0.47
Apr 4 02:00:00 - 02:59:59	398	2.1%	0.11	1.4 MB	2.3%	0.41
Apr 4 03:00:00 - 03:59:59	424	2.2%	0.12	1.5 MB	2.4%	0.42
Apr 4 04:00:00 - 04:15:00	117	0.6%	0.13	364.3 KB	0.6%	0.40
TOTAL: 23:59:54	19269	100.0%	0.22	61.9 MB	100.0%	0.73

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	2296	170	28	2098
2	newsfeed.neva.ru	1865	111	0	1754
3	newsfeed.fu-berlin.de	656	27	0	629
4	csiph-out.news.weretis.net	562	326	7	229
5	news-out.mixmin.net	380	1	13	366
6	feeder.eternal-september.org	297	28	28	241
7	news.fcku.it	218	8	4	206
8	news-out.aioe.org	88	79	5	4
9	news.redatomik.org	73	0	4	69
10	news.freedyn.net	37	4	9	24
11	news.bbs.geek.nz	12	0	10	2
12	nntp.pasdenom.info	8	1	7	0
13	news.swapon.de	6	0	0	6
14	xanadu-bbs.net	5	0	0	5
TOTAL: 14		6503	755	115	5633

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	93
it.annunci.immobiliari	68
it.annunci.commerciali	68
it.annunci.varie	67
mpc.lists.nanog	51
lada.talk	46
alt.survival	30
lada.auto	27
gmane.linux.alsa.devel	21
alt.bbs.synchronet	17
lada.commerce	15
mozilla.dev.security.policy	14
gmane.linux.kernel	14
alt.english.usage	12
alt.2600	11
oesterreich.tratsch	9
it-alt.comp.software.openoffice	9
lada.soft	8
mozilla.support.seamonkey	8
alt.politics.usa	7
TOTAL: 102	755

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4080
EMP (phn path)	183
EMP (phn nph)	154
Too many newsgroups	39
HTML Multipart	23
EMP (phl)	19
Scoring filter (8)	12
Binary: misplaced par2	11
Binary Payload	11
Scoring filter (9)	9
Scoring filter (11)	8
Scoring filter (10)	6
Scoring filter (16)	5
User-issued cancel	4
Binary: misplaced 005	3
Binary Image: misplaced png	3
Binary: misplaced 002	2
Binary: misplaced nfo	2
Binary: misplaced 004	1
HTML post	1
TOTAL: 27	4583

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	72	0	83	83
TOTAL: 1	72	26	83	83

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	130
	TOTAL: 1	130
Huge articles
	csiph-out.feeder.erje.net	158
	csiph-out.news.weretis.net	93
	feeder.eternal-september.org	13
	news.fcku.it	12
	news.freedyn.net	3
	news-out.mixmin.net	3
	news.bbs.geek.nz	2
	news.redatomik.org	2
	newsfeed.fu-berlin.de	2
	xanadu-bbs.net	1
	TOTAL: 11	290
Including strange strings
	newsfeed.fu-berlin.de	517
	news.redatomik.org	47
	csiph-out.news.weretis.net	43
	csiph-out.feeder.erje.net	12
	news.swapon.de	3
	news-out.aioe.org	2
	news-out.mixmin.net	2
	newsfeed.neva.ru	2
	feeder.eternal-september.org	2
	TOTAL: 9	630
TOTAL: 3		1050

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	31881	4007	14716	19	0	12%	23:59:55
2	news.freedyn.net	20117	582	18643	1	41	2%	23:59:55
3	xanadu-bbs	20449	543	18721	0	0	2%	23:59:55
4	fu-berlin.de	17926	498	17427	0	0	2%	23:59:55
5	usenet.pasdenom.info	20389	77	18233	936	32	0%	23:59:49
6	aioe	16406	37	16025	0	0	0%	23:59:55
7	news.redatomik.org	17178	14	16977	0	0	0%	23:59:55
8	erje	16870	11	15899	3	30	0%	23:59:55
9	weretis.net	11072	11	10705	5	0	0%	23:59:55
10	news.mixmin.net	18041	8	17808	0	30	0%	23:59:55
11	xmission	20263	5	18363	1	0	0%	23:59:55
12	neva.ru	17720	2	17349	62	0	0%	23:59:55
13	eternal-september	16557	0	16209	0	29	0%	23:59:55
14	news.swapon.de	1632	0	1618	0	0	0%	23:54:55
15	news.fcku.it	1903	0	1894	0	0	0%	20:57:15
TOTAL: 15		248404	5795	220587	1027	162	2%	356:50:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	12.8 MB	102.2 KB	12.9 MB	0.2 KB/s	3.3 KB	23:59:55
2	news.freedyn.net	7.6 MB	5.0 KB	7.6 MB	0.1 KB/s	13.4 KB	23:59:55
3	xanadu-bbs	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	14.1 KB	23:59:55
4	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	15.2 KB	23:59:55
5	usenet.pasdenom.info	235.9 KB	8.1 MB	8.4 MB	0.1 KB/s	8.5 KB	23:59:49
6	aioe	105.7 KB	0.0 KB	105.7 KB	0.0 KB/s	2.9 KB	23:59:55
7	news.redatomik.org	55.2 KB	0.0 KB	55.2 KB	0.0 KB/s	3.9 KB	23:59:55
8	news.mixmin.net	47.3 KB	0.0 KB	47.3 KB	0.0 KB/s	5.9 KB	23:59:55
9	erje	39.1 KB	8.1 KB	47.2 KB	0.0 KB/s	3.4 KB	23:59:55
10	weretis.net	29.7 KB	10.6 KB	40.3 KB	0.0 KB/s	2.5 KB	23:59:55
11	xmission	19.6 KB	3.7 KB	23.3 KB	0.0 KB/s	3.9 KB	23:59:55
12	neva.ru	14.6 KB	196.1 KB	210.7 KB	0.0 KB/s	3.3 KB	23:59:55
13	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
14	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:57:15
TOTAL: 15		35.9 MB	8.5 MB	44.3 MB	0.0 KB/s	6.7 KB	356:50:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	34	7	18.6 KB	2	0	0	07:51:26
TOTAL: 1		34	7	18.6 KB	2	0	0	19:06:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	34	7	18.6 KB	2	07:51:26
2	unresolved	46	0	0.0 KB	0	00:45:54
3	*.cust.centrio.cz	6	0	0.0 KB	0	02:52:23
4	*.ph.ph.cox.net	7	0	0.0 KB	0	03:03:17
5	*.dhcp.mdsn.wi.charter.com	15	0	0.0 KB	0	04:03:00
6	*.nielsenmedia.com	1	0	0.0 KB	0	00:30:05
TOTAL: 6		109	7	18.6 KB	2	19:06:08

NNRP auth users [Top 20]:

User	Conn
moussa	44
ctomczyk	6
TOTAL: 2	50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.497	0.564	0.000	07:51:26
96-42-18-226.dhcp.mdsn.wi.charter.com	1.080	0.222	0.000	04:03:00
ip72-222-200-59.ph.ph.cox.net	0.644	0.139	0.000	03:03:17
194-73-239-109.cust.centrio.cz	0.449	0.106	0.020	02:52:23
141.168.117.108	5.013	0.990	0.157	00:45:51
challenger.nielsenmedia.com	0.088	0.016	0.000	00:30:05
5.86.1.227	0.072	0.032	0.000	00:00:02
94.163.57.163	0.081	0.016	0.000	00:00:01
TOTAL: 8	9.924	2.085	0.177	19:06:08

Curious NNRP server explorers [Top 20]:

System	Conn
141.168.117.108	44
96-42-18-226.dhcp.mdsn.wi.charter.com	15
ip72-222-200-59.ph.ph.cox.net	7
194-73-239-109.cust.centrio.cz	6
5.86.1.227	1
challenger.nielsenmedia.com	1
94.163.57.163	1
TOTAL: 7	75

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-59.ph.ph.cox.net	5	0
194-73-239-109.cust.centrio.cz	4	0
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
141.168.117.108	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	7	100.0%
TOTAL: 1		7	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	7
TOTAL: 1		7

Daily Usenet report for csiph.com

Apr 3 04:15:06 -- Apr 4 04:15:00