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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43704	61.0%	8.2 MB	72.3%
innd	23890	33.3%	2.6 MB	22.4%
innfeed	3258	4.5%	535.2 KB	4.6%
nnrpd	752	1.0%	82.8 KB	0.7%
nocem	56	0.1%	5.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	71661	100.0%	11.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	440617	56.3%
Negative hits	298518	38.2%
Do not exist	42737	5.5%
Cache misses	604	0.1%
TOTAL: 4	782476	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.783	0.0%	204	0.000	3.838	19.000
article cleanup	00:00:00.273	0.0%	42700	0.000	0.006	0.026
article logging	00:00:04.163	0.0%	43280	0.000	0.096	1.606
article parse	00:00:02.707	0.0%	145738	0.003	0.019	0.335
article write	00:03:06.100	0.2%	24114	0.216	7.718	45.217
artlog/artcncl	00:00:00.000	0.0%	188	0.000	0.000	0.000
artlog/artparse	00:00:00.005	0.0%	253	0.000	0.020	1.000
data move	00:00:00.301	0.0%	728460	0.000	0.000	0.011
hisgrep/artcncl	00:00:00.158	0.0%	200	0.000	0.790	10.000
hishave/artcncl	00:00:00.034	0.0%	204	0.000	0.167	1.389
hishave/artparse	00:00:00.000	0.0%	253	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:23.189	0.2%	785078	0.002	0.182	1.859
history sync	00:00:03.137	0.0%	335	0.000	9.364	78.000
history write	00:03:26.985	0.2%	42626	0.115	4.856	39.382
hiswrite/artcncl	00:00:00.015	0.0%	4	0.000	3.750	15.000
hiswrite/artparse	00:00:01.073	0.0%	253	0.000	4.241	39.000
idle	23:44:17.504	98.9%	710518	53.954	120.275	431.537
nntp read	00:00:03.394	0.0%	741834	0.002	0.005	0.012
overview write	00:04:06.095	0.3%	24114	1.228	10.205	114.899
perl filter	00:01:46.847	0.1%	42478	1.618	2.515	3.839
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.407	0.0%	48275	0.000	0.008	0.027
TOTAL: 24:00:42.387	23:59:23.170	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.081	0.0%	24085	0.000	0.003	0.017
article prepare	00:00:00.009	0.0%	39780	0.000	0.000	0.005
article read	00:00:01.319	0.0%	7359	0.000	0.179	5.833
backlog stats	00:00:00.262	0.0%	455420	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.446	0.0%	347478	0.003	0.007	0.012
data write	00:00:01.965	0.0%	330117	0.001	0.006	0.013
idle	23:53:55.822	100.0%	455420	92.974	188.915	570.606
status file	00:00:00.266	0.0%	311	0.000	0.855	11.000
TOTAL: 23:54:14.288	23:54:02.170	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	32:07:59.478	99.9%	569	178.000	203303.125	454634.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.019	0.0%	1899	0.000	0.010	0.167
readart	00:01:39.611	0.1%	26251	0.000	3.795	39.000
TOTAL: 32:10:20.545	32:09:39.108	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	137916	8149	118715	11052	5%	95:02:44
2	csiph-out.news.weretis.net	6	52025	4309	47010	706	8%	49:48:46
3	feeder.eternal-september.org	4	37983	3561	30002	4420	9%	24:13:39
4	news-out.aioe.org	3	23387	2126	21241	20	9%	24:19:55
5	newsfeed.neva.ru	153	55420	1485	50782	3153	2%	71:44:25
6	newsfeed.fu-berlin.de	44	24743	1131	22806	806	4%	30:50:04
7	news-out.mixmin.net	3	27085	969	25911	205	3%	24:08:52
8	news.redatomik.org	1	21286	849	20431	6	3%	22:02:44
9	newsfeeds.xmission.com	3	22663	709	21942	12	3%	23:58:56
10	news.freedyn.net	3	27605	220	27226	159	0%	24:09:49
11	xanadu-bbs.net	13	1065	175	887	3	16%	23:56:31
12	news.fcku.it	2	10460	163	10278	19	1%	23:59:38
13	news.swapon.de	5	5379	155	5148	76	2%	46:13:51
14	nntp.pasdenom.info	3	23115	120	22977	18	0%	24:07:58
15	news.bbs.geek.nz	3	16741	30	16710	1	0%	24:08:03
16	localhost	43	8	8	0	0	100%	00:00:13
TOTAL: 16		1441	486881	24159	442066	20656	4%	532:46:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.7 MB	620.7 KB	61.1 MB	86.4 MB	28%	4.6 KB
2	csiph-out.news.weretis.net	13.2 MB	0.0 KB	10.0 MB	23.3 MB	56%	4.8 KB
3	feeder.eternal-september.org	9.7 MB	80.1 KB	63.8 MB	73.5 MB	13%	9.4 KB
4	news-out.aioe.org	5.3 MB	0.0 KB	42.5 KB	5.3 MB	99%	2.5 KB
5	news-out.mixmin.net	5.0 MB	3.1 KB	1.0 MB	6.0 MB	82%	5.2 KB
6	newsfeed.neva.ru	3.6 MB	89.8 KB	25.7 MB	29.5 MB	12%	6.5 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	2.2 MB	4.7 MB	53%	2.5 KB
8	news.redatomik.org	2.2 MB	0.0 KB	53.4 KB	2.2 MB	97%	2.7 KB
9	newsfeeds.xmission.com	2.1 MB	0.0 KB	264.3 KB	2.3 MB	88%	3.3 KB
10	news.freedyn.net	752.0 KB	1.8 KB	1.4 MB	2.1 MB	34%	5.7 KB
11	xanadu-bbs.net	471.7 KB	0.0 KB	56.7 KB	528.4 KB	89%	3.0 KB
12	news.fcku.it	449.1 KB	39.9 KB	680.6 KB	1.1 MB	38%	6.4 KB
13	news.swapon.de	399.6 KB	0.0 KB	234.5 KB	634.1 KB	63%	2.7 KB
14	nntp.pasdenom.info	333.7 KB	0.0 KB	44.2 KB	377.9 KB	88%	2.7 KB
15	news.bbs.geek.nz	41.8 KB	0.0 KB	1.9 KB	43.7 KB	95%	1.4 KB
16	localhost	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	1.5 KB
TOTAL: 16		70.7 MB	835.4 KB	166.6 MB	238.1 MB	29%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 20 04:15:00 - 04:59:59	379	1.6%	0.14	1.1 MB	1.5%	0.41
Feb 20 05:00:00 - 05:59:59	587	2.4%	0.16	2.2 MB	3.1%	0.62
Feb 20 06:00:00 - 06:59:59	492	2.0%	0.14	1.6 MB	2.2%	0.44
Feb 20 07:00:00 - 07:59:59	761	3.2%	0.21	3.0 MB	4.2%	0.85
Feb 20 08:00:00 - 08:59:59	942	3.9%	0.26	3.0 MB	4.2%	0.84
Feb 20 09:00:00 - 09:59:59	1145	4.7%	0.32	3.1 MB	4.4%	0.89
Feb 20 10:00:00 - 10:59:59	1594	6.6%	0.44	3.6 MB	5.0%	1.01
Feb 20 11:00:00 - 11:59:59	1146	4.8%	0.32	2.9 MB	4.2%	0.84
Feb 20 12:00:00 - 12:59:59	1079	4.5%	0.30	2.8 MB	4.0%	0.80
Feb 20 13:00:00 - 13:59:59	1118	4.6%	0.31	2.9 MB	4.2%	0.84
Feb 20 14:00:00 - 14:59:59	1098	4.6%	0.30	3.0 MB	4.2%	0.84
Feb 20 15:00:00 - 15:59:59	1368	5.7%	0.38	4.3 MB	6.1%	1.23
Feb 20 16:00:00 - 16:59:59	1483	6.1%	0.41	3.7 MB	5.3%	1.06
Feb 20 17:00:00 - 17:59:59	1375	5.7%	0.38	3.6 MB	5.1%	1.03
Feb 20 18:00:00 - 18:59:59	1436	6.0%	0.40	4.3 MB	6.0%	1.22
Feb 20 19:00:00 - 19:59:59	1323	5.5%	0.37	4.2 MB	6.0%	1.20
Feb 20 20:00:00 - 20:59:59	1273	5.3%	0.35	3.8 MB	5.4%	1.09
Feb 20 21:00:00 - 21:59:59	1215	5.0%	0.34	3.7 MB	5.3%	1.07
Feb 20 22:00:00 - 22:59:59	1079	4.5%	0.30	3.1 MB	4.4%	0.89
Feb 20 23:00:00 - 23:59:59	859	3.6%	0.24	2.7 MB	3.9%	0.78
Feb 21 00:00:00 - 00:59:59	698	2.9%	0.19	2.1 MB	3.0%	0.60
Feb 21 01:00:00 - 01:59:59	643	2.7%	0.18	2.6 MB	3.7%	0.74
Feb 21 02:00:00 - 02:59:59	453	1.9%	0.13	1.2 MB	1.8%	0.35
Feb 21 03:00:00 - 03:59:59	437	1.8%	0.12	1.6 MB	2.2%	0.44
Feb 21 04:00:00 - 04:15:00	133	0.6%	0.15	429.6 KB	0.6%	0.48
TOTAL: 24:00:00	24116	100.0%	0.28	70.7 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9340	449	57	3	8831
2	feeder.eternal-september.org	4422	70	6	0	4346
3	newsfeed.neva.ru	3190	355	7	0	2828
4	newsfeed.fu-berlin.de	1200	18	0	0	1182
5	csiph-out.news.weretis.net	722	122	0	0	600
6	news-out.mixmin.net	212	2	2	0	208
7	news.freedyn.net	157	16	1	0	140
8	news.swapon.de	50	0	0	0	50
9	news-out.aioe.org	27	17	0	0	10
10	newsfeeds.xmission.com	25	9	0	0	16
11	news.fcku.it	20	0	1	0	19
12	nntp.pasdenom.info	18	10	0	0	8
13	news.redatomik.org	13	0	0	0	13
14	xanadu-bbs.net	3	0	0	0	3
15	news.bbs.geek.nz	1	0	0	0	1
TOTAL: 15		19400	1068	74	3	18255

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.auto	155
ut.test	129
lada.talk	95
it.annunci.varie	58
it.annunci.commerciali	57
it.annunci.immobiliari	53
alt.windows7.general	30
gmane.linux.alsa.devel	28
it-alt.sport.calcio.juventus	25
alt.politics.scorched-earth	25
alt.survival	24
eagle.userchat.eng	23
lada.commerce	17
alt.philosophy	14
alt.binaries.moovee	13
mozilla.support.firefox	13
alt.pl.nieruchomosci.gielda	12
spline.eisfair	11
gmane.comp.gis.openstreetmap.tagging	10
lada.photo	10
TOTAL: 135	1068

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

Groups	Count
nz.politics.announce	2
alt.culture.china	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14441
EMP (phn path)	1915
EMP (phl)	488
EMP (phn nph)	223
Too many newsgroups	49
Scoring filter (12)	24
HTML Multipart	23
User-issued cancel	16
Binary Payload	15
Scoring filter (8)	13
Scoring filter (19)	10
Scoring filter (11)	9
Scoring filter (9)	8
Scoring filter (13)	8
Scoring filter (18)	8
Scoring filter (10)	5
Scoring filter (14)	5
User-issued spam cancel	4
Scoring filter (20)	4
Scoring filter (21)	1
TOTAL: 25	17274

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	47	0	57	57
TOTAL: 1	47	0	57	57

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	148
	TOTAL: 1	148
Huge articles
	feeder.eternal-september.org	175
	csiph-out.news.weretis.net	49
	news-out.mixmin.net	12
	news.fcku.it	8
	news.freedyn.net	3
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	1
	news.redatomik.org	1
	xanadu-bbs.net	1
	TOTAL: 9	252
Including strange strings
	newsfeed.fu-berlin.de	393
	csiph-out.feeder.erje.net	79
	feeder.eternal-september.org	35
	csiph-out.news.weretis.net	15
	newsfeeds.xmission.com	14
	newsfeed.neva.ru	12
	news.swapon.de	9
	news-out.mixmin.net	8
	news.redatomik.org	7
	news-out.aioe.org	7
	TOTAL: 12	581
TOTAL: 3		981

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40068	5835	17445	78	0	14%	23:50:04
2	news.freedyn.net	24765	781	23030	9	0	3%	23:51:16
3	xanadu-bbs	26992	749	23181	2	0	2%	23:59:53
4	fu-berlin.de	22693	621	22072	0	0	2%	23:59:51
5	usenet.pasdenom.info	27450	265	22460	1274	17	0%	23:59:53
6	xmission	27061	104	22490	12	0	0%	23:59:51
7	news.redatomik.org	23845	46	22491	6	0	0%	23:56:10
8	aioe	21625	27	19952	11	0	0%	23:52:43
9	news.mixmin.net	23093	19	21688	0	0	0%	23:59:53
10	weretis.net	18253	18	17446	1	0	0%	23:56:17
11	eternal-september	21836	16	19549	0	0	0%	23:59:53
12	erje	15914	14	15821	12	0	0%	23:58:17
13	news.swapon.de	2473	8	2364	0	0	0%	23:45:47
14	neva.ru	23468	8	21608	15	0	0%	23:59:48
15	news.fcku.it	3161	4	2927	0	0	0%	22:23:47
16	au2pb	0	0	0	0	23468	0%	23:58:42
17	bwh	0	0	0	0	23386	0%	23:50:03
TOTAL: 17		322697	8515	274524	1420	46871	2%	405:22:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	15.1 MB	371.1 KB	15.5 MB	0.2 KB/s	2.7 KB	23:50:04
2	news.freedyn.net	6.2 MB	18.5 KB	6.2 MB	0.1 KB/s	8.0 KB	23:51:16
3	xanadu-bbs	6.1 MB	9.1 KB	6.1 MB	0.1 KB/s	8.3 KB	23:59:53
4	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	9.5 KB	23:59:51
5	usenet.pasdenom.info	712.6 KB	6.9 MB	7.6 MB	0.1 KB/s	5.1 KB	23:59:53
6	xmission	234.8 KB	25.6 KB	260.4 KB	0.0 KB/s	2.2 KB	23:59:51
7	news.redatomik.org	102.4 KB	21.2 KB	123.5 KB	0.0 KB/s	2.4 KB	23:56:10
8	aioe	56.6 KB	48.3 KB	104.9 KB	0.0 KB/s	2.8 KB	23:52:43
9	news.mixmin.net	52.4 KB	0.0 KB	52.4 KB	0.0 KB/s	2.8 KB	23:59:53
10	eternal-september	36.8 KB	0.0 KB	36.8 KB	0.0 KB/s	2.3 KB	23:59:53
11	weretis.net	32.7 KB	1.3 KB	34.0 KB	0.0 KB/s	1.8 KB	23:56:17
12	erje	22.9 KB	72.2 KB	95.0 KB	0.0 KB/s	3.7 KB	23:58:17
13	neva.ru	12.7 KB	44.5 KB	57.1 KB	0.0 KB/s	2.5 KB	23:59:48
14	news.swapon.de	10.7 KB	0.0 KB	10.7 KB	0.0 KB/s	1.3 KB	23:45:47
15	news.fcku.it	7.5 KB	0.0 KB	7.5 KB	0.0 KB/s	1.9 KB	22:23:47
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		34.4 MB	7.5 MB	41.9 MB	0.0 KB/s	4.3 KB	405:22:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	dhcp-089-099-203-254.chello.nl	64	14	27.4 KB	4	0	0	19:17:19
2	tempe0.bbox.io	13	14	22.8 KB	5	1	1	04:07:54
3	68-185-181-64.dhcp.mdsn.wi.charter.com	16	14	27.4 KB	7	5	4	04:23:32
4	194-73-239-109.cust.centrio.cz	20	7	15.1 KB	6	2	0	04:19:45
TOTAL: 4		113	49	92.6 KB	22	8	5	32:10:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.chello.nl	64	14	27.4 KB	4	0	0	19:17:19
2	*.dhcp.mdsn.wi.charter.com	16	14	27.4 KB	7	5	4	04:23:32
3	*.bbox.io	13	14	22.8 KB	5	1	1	04:07:54
4	*.cust.centrio.cz	20	7	15.1 KB	6	2	0	04:19:45
5	unresolved	1	0	0.0 KB	0	0	0	00:01:47
TOTAL: 5		114	49	92.6 KB	22	8	5	32:10:19

NNRP auth users [Top 20]:

User	Conn
ctomczyk	12
ohlandl	4
kev009	1
TOTAL: 3	17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.185	1.191	0.000	19:17:19
68-185-181-64.dhcp.mdsn.wi.charter.com	1.642	0.335	0.018	04:23:32
194-73-239-109.cust.centrio.cz	1.718	0.381	0.045	04:19:45
tempe0.bbox.io	1.273	0.164	0.004	04:07:54
196.52.19.16	1.028	3.371	0.000	00:01:47
TOTAL: 5	10.846	5.442	0.067	32:10:19

Curious NNRP server explorers [Top 20]:

System	Conn
196.52.19.16	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	8	0
194-73-239-109.cust.centrio.cz	8	0
68-185-181-64.dhcp.mdsn.wi.charter.com	6	0
TOTAL: 3	22	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	44	89.8%
2	pl	5	10.2%
TOTAL: 2		49	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	42
2	pl.comp.lang.javascript	5
3	comp.lang.javascript	2
TOTAL: 3		49

Daily Usenet report for csiph.com

Feb 20 04:15:00 -- Feb 21 04:15:00