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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40771	59.1%	7.4 MB	70.0%
innd	24063	34.9%	2.5 MB	24.0%
innfeed	3197	4.6%	541.0 KB	5.0%
nnrpd	861	1.2%	96.9 KB	0.9%
nocem	83	0.1%	8.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	68976	100.0%	10.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	394242	56.7%
Negative hits	261450	37.6%
Do not exist	39492	5.7%
Cache misses	567	0.1%
TOTAL: 4	695751	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.773	0.0%	249	0.000	3.104	61.000
article cleanup	00:00:00.273	0.0%	39675	0.000	0.007	0.026
article logging	00:00:04.633	0.0%	40316	0.000	0.115	2.099
article parse	00:00:03.334	0.0%	134083	0.000	0.025	0.177
article write	00:02:55.024	0.2%	21064	0.135	8.309	43.492
artlog/artcncl	00:00:00.014	0.0%	221	0.000	0.063	1.286
artlog/artparse	00:00:00.030	0.0%	228	0.000	0.132	4.000
data move	00:00:00.289	0.0%	650080	0.000	0.000	0.010
hisgrep/artcncl	00:00:00.169	0.0%	248	0.000	0.681	28.333
hishave/artcncl	00:00:00.012	0.0%	249	0.000	0.048	1.000
hishave/artparse	00:00:00.004	0.0%	221	0.000	0.018	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:10.495	0.2%	699568	0.002	0.187	1.191
history sync	00:00:04.459	0.0%	366	0.000	12.183	131.500
history write	00:03:42.197	0.3%	39504	0.305	5.625	56.471
hiswrite/artcncl	00:00:00.046	0.0%	1	46.000	46.000	46.000
hiswrite/artparse	00:00:01.778	0.0%	221	0.000	8.045	41.000
idle	23:44:08.165	98.8%	636210	77.402	134.308	432.746
nntp read	00:00:03.043	0.0%	665333	0.001	0.005	0.008
overview write	00:04:46.215	0.3%	21064	1.217	13.588	754.500
perl filter	00:01:42.154	0.1%	39483	1.591	2.587	5.980
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.376	0.0%	42202	0.000	0.009	0.027
TOTAL: 24:00:58.343	23:59:43.483	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	21143	0.000	0.003	0.015
article prepare	00:00:00.004	0.0%	45736	0.000	0.000	0.006
article read	00:00:01.754	0.0%	8255	0.000	0.212	5.222
backlog stats	00:00:00.249	0.0%	408116	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.156	0.0%	307586	0.002	0.007	0.014
data write	00:00:01.901	0.0%	289520	0.001	0.007	0.013
idle	23:53:58.951	100.0%	408116	116.410	210.820	559.721
status file	00:00:00.258	0.0%	285	0.000	0.905	22.000
TOTAL: 23:54:14.394	23:54:05.335	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:08.560	0.0%	3949	0.000	2.168	10.000
idle	49:48:06.390	99.8%	4509	1702.667	39761.896	900225.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.066	0.0%	9028	0.000	0.007	0.250
readart	00:00:00.763	0.0%	66	0.000	11.561	73.000
TOTAL: 49:53:46.578	49:48:15.779	99.8%	-	-	-	-

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	1152	128148	7859	109054	11235	6%	95:08:29
2	csiph-out.news.weretis.net	4	44192	3401	40111	680	7%	47:15:10
3	feeder.eternal-september.org	2	35256	3081	28374	3801	8%	24:05:38
4	news-out.aioe.org	2	20897	2654	18159	84	12%	24:09:57
5	newsfeed.fu-berlin.de	18	24130	1127	22245	758	4%	30:31:25
6	newsfeed.neva.ru	149	54668	913	50411	3344	1%	72:33:30
7	newsfeeds.xmission.com	3	19167	652	18510	5	3%	23:12:12
8	news-out.mixmin.net	2	23953	634	23049	270	2%	24:08:57
9	news.redatomik.org	2	20691	446	20236	9	2%	26:40:16
10	nntp.pasdenom.info	3	20066	165	19886	15	0%	24:07:41
11	news.freedyn.net	3	24207	91	23970	146	0%	24:08:56
12	news.fcku.it	2	8923	78	8807	38	0%	23:59:55
13	news.swapon.de	1	2423	65	2348	10	2%	22:01:09
14	xanadu-bbs.net	10	778	55	716	7	7%	22:37:01
15	localhost	52	16	16	0	0	100%	00:00:09
16	news.bbs.geek.nz	2	16341	15	16326	0	0%	23:59:30
TOTAL: 16		1407	443856	21252	402202	20402	4%	508:39:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	23.8 MB	1.6 MB	67.5 MB	92.9 MB	25%	5.0 KB
2	csiph-out.news.weretis.net	11.6 MB	12.1 KB	10.4 MB	22.0 MB	52%	5.5 KB
3	feeder.eternal-september.org	8.1 MB	100.2 KB	51.4 MB	59.6 MB	13%	8.9 KB
4	news-out.aioe.org	7.0 MB	7.5 KB	210.0 KB	7.2 MB	97%	2.7 KB
5	news-out.mixmin.net	3.0 MB	0.0 KB	903.8 KB	3.9 MB	77%	4.4 KB
6	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	1.8 MB	4.3 MB	57%	2.3 KB
7	newsfeed.neva.ru	2.2 MB	206.5 KB	29.4 MB	31.8 MB	6%	7.7 KB
8	newsfeeds.xmission.com	1.7 MB	0.0 KB	221.9 KB	1.9 MB	88%	3.0 KB
9	news.redatomik.org	1.3 MB	4.1 KB	128.4 KB	1.4 MB	90%	3.2 KB
10	nntp.pasdenom.info	389.6 KB	1.1 KB	26.3 KB	417.0 KB	93%	2.3 KB
11	news.freedyn.net	308.9 KB	0.0 KB	2.0 MB	2.3 MB	12%	10.1 KB
12	xanadu-bbs.net	208.7 KB	0.0 KB	55.7 KB	264.5 KB	78%	4.3 KB
13	news.swapon.de	202.9 KB	0.0 KB	108.5 KB	311.4 KB	65%	4.2 KB
14	news.fcku.it	202.6 KB	0.0 KB	2.2 MB	2.4 MB	8%	21.3 KB
15	localhost	47.7 KB	0.0 KB	0.0 KB	47.7 KB	100%	3.0 KB
16	news.bbs.geek.nz	33.9 KB	0.0 KB	0.0 KB	33.9 KB	100%	2.3 KB
TOTAL: 16		62.5 MB	1.9 MB	166.5 MB	230.9 MB	27%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 18 04:15:00 - 04:59:59	306	1.5%	0.11	961.3 KB	1.5%	0.36
Feb 18 05:00:00 - 05:59:59	431	2.0%	0.12	1.2 MB	1.9%	0.33
Feb 18 06:00:00 - 06:59:59	467	2.2%	0.13	1.8 MB	3.0%	0.52
Feb 18 07:00:00 - 07:59:59	596	2.8%	0.17	2.0 MB	3.3%	0.58
Feb 18 08:00:00 - 08:59:59	716	3.4%	0.20	2.0 MB	3.3%	0.58
Feb 18 09:00:00 - 09:59:59	850	4.0%	0.24	2.4 MB	3.8%	0.67
Feb 18 10:00:00 - 10:59:59	963	4.6%	0.27	2.6 MB	4.1%	0.73
Feb 18 11:00:00 - 11:59:59	1044	5.0%	0.29	3.2 MB	5.1%	0.91
Feb 18 12:00:00 - 12:59:59	1139	5.4%	0.32	3.5 MB	5.6%	0.99
Feb 18 13:00:00 - 13:59:59	1121	5.3%	0.31	3.0 MB	4.9%	0.86
Feb 18 14:00:00 - 14:59:59	1170	5.6%	0.33	2.9 MB	4.6%	0.81
Feb 18 15:00:00 - 15:59:59	1082	5.1%	0.30	3.3 MB	5.3%	0.93
Feb 18 16:00:00 - 16:59:59	1267	6.0%	0.35	3.8 MB	6.2%	1.09
Feb 18 17:00:00 - 17:59:59	1309	6.2%	0.36	3.6 MB	5.8%	1.02
Feb 18 18:00:00 - 18:59:59	1213	5.8%	0.34	3.5 MB	5.5%	0.98
Feb 18 19:00:00 - 19:59:59	1137	5.4%	0.32	3.2 MB	5.1%	0.90
Feb 18 20:00:00 - 20:59:59	1073	5.1%	0.30	3.2 MB	5.2%	0.92
Feb 18 21:00:00 - 21:59:59	1137	5.4%	0.32	3.1 MB	5.1%	0.89
Feb 18 22:00:00 - 22:59:59	942	4.5%	0.26	2.7 MB	4.4%	0.78
Feb 18 23:00:00 - 23:59:59	824	3.9%	0.23	2.6 MB	4.1%	0.73
Feb 19 00:00:00 - 00:59:59	622	3.0%	0.17	1.7 MB	2.8%	0.49
Feb 19 01:00:00 - 01:59:59	581	2.8%	0.16	1.9 MB	3.0%	0.53
Feb 19 02:00:00 - 02:59:59	556	2.6%	0.15	2.3 MB	3.6%	0.64
Feb 19 03:00:00 - 03:59:59	429	2.0%	0.12	1.6 MB	2.5%	0.45
Feb 19 04:00:00 - 04:15:00	93	0.4%	0.10	267.2 KB	0.4%	0.30
TOTAL: 24:00:00	21068	100.0%	0.24	62.2 MB	100.0%	0.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9906	270	140	0	9496
2	feeder.eternal-september.org	3843	65	9	1	3768
3	newsfeed.neva.ru	3307	260	22	0	3025
4	newsfeed.fu-berlin.de	1085	40	0	0	1045
5	csiph-out.news.weretis.net	684	93	1	0	590
6	news-out.mixmin.net	281	2	0	0	279
7	news.freedyn.net	152	12	0	0	140
8	news-out.aioe.org	107	35	3	0	69
9	news.fcku.it	42	0	0	0	42
10	news.swapon.de	20	0	0	0	20
11	nntp.pasdenom.info	17	8	1	0	8
12	news.redatomik.org	16	0	2	0	14
13	newsfeeds.xmission.com	15	1	0	0	14
14	xanadu-bbs.net	7	0	0	0	7
TOTAL: 14		19482	786	178	1	18517

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	148
ut.test	137
alt.windows7.general	47
it-alt.sport.calcio.juventus	34
lada.auto	30
spline.eisfair	22
lada.movies	19
mpc.lists.freebsd.ports.bugs	16
mozilla.support.thunderbird	16
alt.politics.scorched-earth	15
alt.survival	15
gmane.comp.gis.openstreetmap	15
cakewalk.audio	14
eagle.userchat.eng	12
mozilla.support.seamonkey	11
gmane.comp.gis.openstreetmap.tagging	10
mozilla.dev.l10n	10
oesterreich.tratsch	8
alt.politics.usa	7
alt.computer.workshop	7
TOTAL: 112	786

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

Groups	Count
muc.lists.freebsd.questions	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14668
EMP (phn path)	1866
EMP (phl)	558
EMP (phn nph)	201
Binary Payload	177
Too many newsgroups	83
User-issued cancel	20
Scoring filter (8)	11
HTML Multipart	11
Scoring filter (10)	9
Scoring filter (9)	5
Scoring filter (15)	4
Scoring filter (14)	4
HTML post	4
Scoring filter (12)	3
Too many newsgroups (low_xpost)	2
Binary: misplaced gz	2
Scoring filter (16)	1
Cancel for rejected article	1
Binary: misplaced txt	1
TOTAL: 22	17633

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	13
	TOTAL: 1	13
Huge articles
	feeder.eternal-september.org	110
	csiph-out.news.weretis.net	52
	news.fcku.it	32
	news.freedyn.net	14
	news-out.mixmin.net	8
	newsfeeds.xmission.com	3
	news.swapon.de	1
	news.redatomik.org	1
	TOTAL: 8	221
Including strange strings
	newsfeed.fu-berlin.de	378
	csiph-out.feeder.erje.net	153
	feeder.eternal-september.org	32
	news-out.aioe.org	23
	newsfeeds.xmission.com	11
	newsfeed.neva.ru	10
	news.swapon.de	10
	csiph-out.news.weretis.net	9
	news.redatomik.org	7
	news-out.mixmin.net	7
	TOTAL: 11	642
No colon-space in header
	csiph-out.feeder.erje.net	7
	TOTAL: 1	7
TOTAL: 4		883

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33454	6543	13644	95	0	19%	23:50:03
2	news.freedyn.net	22211	963	19997	10	0	4%	23:58:58
3	xanadu-bbs	24018	826	20166	6	0	3%	23:59:52
4	fu-berlin.de	19752	567	19185	0	0	2%	23:51:27
5	erje	13566	312	12719	28	23	2%	23:55:31
6	usenet.pasdenom.info	23767	236	19139	1525	93	0%	23:59:55
7	xmission	24092	93	19550	9	0	0%	23:59:53
8	news.redatomik.org	21001	48	19794	15	0	0%	23:50:03
9	aioe	17990	31	16648	6	0	0%	23:54:19
10	weretis.net	16231	22	15494	1	0	0%	23:51:32
11	eternal-september	18908	20	16959	0	1	0%	23:59:55
12	news.swapon.de	2309	18	2202	0	0	0%	23:49:41
13	news.mixmin.net	19581	18	18400	0	0	0%	23:50:03
14	neva.ru	20667	7	19229	22	0	0%	23:59:55
15	news.fcku.it	1746	2	1678	0	0	0%	22:48:29
16	bwh	0	0	0	0	20294	0%	23:50:09
17	au2pb	0	0	0	0	20299	0%	23:50:09
TOTAL: 17		279293	9706	234804	1717	40710	3%	405:19:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	17.9 MB	349.0 KB	18.2 MB	0.2 KB/s	2.8 KB	23:50:03
2	news.freedyn.net	6.3 MB	79.5 KB	6.4 MB	0.1 KB/s	6.7 KB	23:58:58
3	xanadu-bbs	5.9 MB	20.4 KB	5.9 MB	0.1 KB/s	7.3 KB	23:59:52
4	fu-berlin.de	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	9.3 KB	23:51:27
5	erje	1.4 MB	169.5 KB	1.5 MB	0.0 KB/s	4.6 KB	23:55:31
6	usenet.pasdenom.info	589.0 KB	7.0 MB	7.6 MB	0.1 KB/s	4.4 KB	23:59:55
7	xmission	208.5 KB	21.8 KB	230.3 KB	0.0 KB/s	2.3 KB	23:59:53
8	news.redatomik.org	135.5 KB	38.8 KB	174.3 KB	0.0 KB/s	2.8 KB	23:50:03
9	aioe	98.7 KB	15.8 KB	114.5 KB	0.0 KB/s	3.1 KB	23:54:19
10	eternal-september	77.0 KB	0.0 KB	77.0 KB	0.0 KB/s	3.8 KB	23:59:55
11	weretis.net	60.8 KB	1.2 KB	62.1 KB	0.0 KB/s	2.7 KB	23:51:32
12	news.swapon.de	55.1 KB	0.0 KB	55.1 KB	0.0 KB/s	3.1 KB	23:49:41
13	news.mixmin.net	52.0 KB	0.0 KB	52.0 KB	0.0 KB/s	2.9 KB	23:50:03
14	neva.ru	22.9 KB	55.0 KB	77.9 KB	0.0 KB/s	2.7 KB	23:59:55
15	news.fcku.it	4.7 KB	0.0 KB	4.7 KB	0.0 KB/s	2.3 KB	22:48:29
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
TOTAL: 17		37.9 MB	7.8 MB	45.7 MB	0.0 KB/s	4.1 KB	405:19:54

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	10	23	73.6 KB	2	0	0	03:32:28
2	dhcp-089-099-203-254.chello.nl	32	22	62.1 KB	6	0	0	08:07:27
3	68-185-181-64.dhcp.mdsn.wi.charter.com	24	20	59.3 KB	16	16	13	04:38:47
4	194-73-239-109.cust.centrio.cz	2	1	2.6 KB	1	0	0	00:11:15
TOTAL: 4		68	66	197.6 KB	25	16	13	49:53:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	10	23	73.6 KB	2	0	0	03:32:28
2	*.chello.nl	32	22	62.1 KB	6	0	0	08:07:27
3	*.dhcp.mdsn.wi.charter.com	24	20	59.3 KB	16	16	13	04:38:47
4	*.cust.centrio.cz	2	1	2.6 KB	1	0	0	00:11:15
5	*.137.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	04:18:32
6	unresolved	58	0	0.0 KB	0	0	0	29:05:14
TOTAL: 6		128	66	197.6 KB	25	16	13	49:53:45

NNRP auth users [Top 20]:

User	Conn
ohlandl	13
ctomczyk	2
TOTAL: 2	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	4.448	0.806	0.000	29:05:14
dhcp-089-099-203-254.chello.nl	2.858	0.566	0.000	08:07:27
68-185-181-64.dhcp.mdsn.wi.charter.com	2.749	0.442	0.048	04:38:47
h201.137.22.98.dynamic.ip.windstream.net	0.253	0.451	0.000	04:18:32
tempe0.bbox.io	0.799	0.217	0.000	03:32:28
194-73-239-109.cust.centrio.cz	0.265	0.046	0.011	00:11:15
TOTAL: 6	11.372	2.528	0.059	49:53:45

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	58
h201.137.22.98.dynamic.ip.windstream.net	2
TOTAL: 2	60

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	58	0
tempe0.bbox.io	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 3	67	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	63	95.5%
2	muc	3	4.5%
TOTAL: 2		66	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	62
2	muc.lists.freebsd.announce	3
3	comp.lang.javascript	1
TOTAL: 3		66

Daily Usenet report for csiph.com

Feb 18 04:15:00 -- Feb 19 04:15:00