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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 17 12:10:02 csiph pgpverify[52772]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43635	60.5%	8.2 MB	71.7%
innd	23966	33.2%	2.5 MB	22.3%
innfeed	3215	4.5%	554.1 KB	4.8%
nnrpd	1273	1.8%	140.4 KB	1.2%
nocem	86	0.1%	8.7 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	72180	100.0%	11.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	430683	56.5%
Negative hits	289200	37.9%
Do not exist	42446	5.6%
Cache misses	542	0.1%
TOTAL: 4	762871	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.692	0.0%	265	0.000	2.611	26.000
article cleanup	00:00:00.298	0.0%	42883	0.000	0.007	0.034
article logging	00:00:04.690	0.0%	43357	0.000	0.108	2.235
article parse	00:00:02.744	0.0%	142355	0.004	0.019	0.404
article write	00:03:14.762	0.2%	23797	0.189	8.184	70.964
artlog/artcncl	00:00:00.000	0.0%	246	0.000	0.000	0.000
artlog/artparse	00:00:00.003	0.0%	138	0.000	0.022	0.333
data move	00:00:00.296	0.0%	700682	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.156	0.0%	259	0.000	0.602	15.000
hishave/artcncl	00:00:00.055	0.0%	265	0.000	0.208	2.389
hishave/artparse	00:00:00.001	0.0%	137	0.000	0.007	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:12.116	0.2%	767347	0.002	0.172	1.480
history sync	00:00:03.471	0.0%	361	0.000	9.615	80.000
history write	00:03:33.490	0.2%	42640	0.648	5.007	36.542
hiswrite/artcncl	00:00:00.018	0.0%	6	1.000	3.000	3.400
hiswrite/artparse	00:00:01.052	0.0%	137	0.000	7.679	50.000
idle	23:44:15.879	98.8%	683220	78.703	125.078	376.649
nntp read	00:00:03.384	0.0%	714928	0.002	0.005	0.011
overview write	00:04:16.409	0.3%	23797	0.956	10.775	133.783
perl filter	00:01:51.642	0.1%	42454	1.532	2.630	6.753
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.436	0.0%	47676	0.000	0.009	0.027
TOTAL: 24:01:02.863	23:59:41.594	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.096	0.0%	23775	0.000	0.004	0.032
article prepare	00:00:00.006	0.0%	41356	0.000	0.000	0.003
article read	00:00:01.106	0.0%	7268	0.000	0.152	1.696
backlog stats	00:00:00.238	0.0%	450664	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.349	0.0%	340678	0.002	0.007	0.015
data write	00:00:01.971	0.0%	325165	0.002	0.006	0.014
idle	23:53:43.186	100.0%	450664	119.511	190.881	483.258
status file	00:00:00.327	0.0%	293	0.000	1.116	12.000
TOTAL: 23:53:59.445	23:53:49.279	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.513	0.0%	2533	0.000	0.597	37.000
idle	68:19:15.710	99.8%	3201	0.000	76837.148	900205.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.022	0.0%	6585	0.000	0.003	0.500
readart	00:00:00.959	0.0%	69	0.000	13.899	70.000
TOTAL: 68:27:43.408	68:19:18.204	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	135308	8317	116195	10796	6%	94:57:30
2	csiph-out.news.weretis.net	3	49668	4037	44835	796	8%	47:44:27
3	feeder.eternal-september.org	7	38108	3763	29694	4651	9%	25:20:55
4	newsfeed.neva.ru	143	55206	1661	50222	3323	3%	71:49:51
5	news-out.aioe.org	3	24004	1589	22384	31	6%	24:09:56
6	news.redatomik.org	1	22178	1149	21016	13	5%	23:56:05
7	newsfeed.fu-berlin.de	45	24914	1031	23441	442	4%	31:40:46
8	news-out.mixmin.net	1	26125	974	24791	360	3%	23:59:40
9	newsfeeds.xmission.com	3	20332	543	19780	9	2%	20:52:21
10	xanadu-bbs.net	7	1718	237	1473	8	13%	38:32:59
11	nntp.pasdenom.info	4	22424	134	22284	6	0%	24:07:31
12	news.fcku.it	1	9549	117	9388	44	1%	23:59:59
13	news.freedyn.net	4	26593	107	26323	163	0%	24:09:44
14	news.swapon.de	2	2254	56	2189	9	2%	23:44:48
15	news.bbs.geek.nz	1	16414	17	16396	1	0%	23:59:31
16	localhost	47	13	13	0	0	100%	00:00:14
TOTAL: 16		1424	474808	23745	430411	20652	5%	523:06:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	25.0 MB	2.3 MB	60.1 MB	87.4 MB	28%	4.7 KB
2	csiph-out.news.weretis.net	13.3 MB	0.0 KB	11.0 MB	24.3 MB	54%	5.1 KB
3	feeder.eternal-september.org	9.8 MB	193.2 KB	59.3 MB	69.3 MB	14%	8.4 KB
4	news-out.mixmin.net	4.6 MB	23.6 KB	1.1 MB	5.6 MB	81%	4.3 KB
5	news-out.aioe.org	4.4 MB	0.0 KB	74.4 KB	4.4 MB	98%	2.8 KB
6	newsfeed.neva.ru	4.0 MB	446.2 KB	30.2 MB	34.6 MB	11%	7.1 KB
7	news.redatomik.org	3.0 MB	0.0 KB	1.2 MB	4.2 MB	71%	3.7 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	1.2 MB	3.4 MB	64%	2.4 KB
9	newsfeeds.xmission.com	1.8 MB	0.0 KB	390.7 KB	2.2 MB	82%	4.1 KB
10	xanadu-bbs.net	651.1 KB	0.0 KB	280.4 KB	931.5 KB	69%	3.8 KB
11	news.freedyn.net	471.6 KB	0.0 KB	1.8 MB	2.3 MB	20%	8.6 KB
12	nntp.pasdenom.info	419.7 KB	0.0 KB	22.9 KB	442.6 KB	94%	3.2 KB
13	news.fcku.it	244.2 KB	0.0 KB	910.3 KB	1.1 MB	21%	7.2 KB
14	news.swapon.de	150.2 KB	0.0 KB	19.0 KB	169.2 KB	88%	2.6 KB
15	news.bbs.geek.nz	47.9 KB	0.0 KB	1.4 KB	49.3 KB	97%	2.7 KB
16	localhost	19.8 KB	0.0 KB	0.0 KB	19.8 KB	100%	1.5 KB
TOTAL: 16		70.0 MB	3.0 MB	167.5 MB	240.5 MB	29%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 17 04:15:00 - 04:59:59	284	1.2%	0.11	846.8 KB	1.2%	0.31
Feb 17 05:00:00 - 05:59:59	645	2.7%	0.18	2.0 MB	2.8%	0.56
Feb 17 06:00:00 - 06:59:59	722	3.0%	0.20	2.5 MB	3.6%	0.72
Feb 17 07:00:00 - 07:59:59	652	2.7%	0.18	1.9 MB	2.7%	0.55
Feb 17 08:00:00 - 08:59:59	1044	4.4%	0.29	2.7 MB	3.9%	0.77
Feb 17 09:00:00 - 09:59:59	1103	4.6%	0.31	3.2 MB	4.6%	0.92
Feb 17 10:00:00 - 10:59:59	1243	5.2%	0.35	3.5 MB	5.0%	1.00
Feb 17 11:00:00 - 11:59:59	1116	4.7%	0.31	2.9 MB	4.2%	0.83
Feb 17 12:00:00 - 12:59:59	1086	4.6%	0.30	3.4 MB	4.8%	0.96
Feb 17 13:00:00 - 13:59:59	1143	4.8%	0.32	3.0 MB	4.2%	0.84
Feb 17 14:00:00 - 14:59:59	1163	4.9%	0.32	3.1 MB	4.5%	0.89
Feb 17 15:00:00 - 15:59:59	1244	5.2%	0.35	3.7 MB	5.2%	1.04
Feb 17 16:00:00 - 16:59:59	1280	5.4%	0.36	3.8 MB	5.4%	1.08
Feb 17 17:00:00 - 17:59:59	1369	5.8%	0.38	3.8 MB	5.5%	1.09
Feb 17 18:00:00 - 18:59:59	1339	5.6%	0.37	3.8 MB	5.4%	1.08
Feb 17 19:00:00 - 19:59:59	1303	5.5%	0.36	3.9 MB	5.6%	1.11
Feb 17 20:00:00 - 20:59:59	1338	5.6%	0.37	3.9 MB	5.6%	1.11
Feb 17 21:00:00 - 21:59:59	1258	5.3%	0.35	3.7 MB	5.3%	1.06
Feb 17 22:00:00 - 22:59:59	1127	4.7%	0.31	3.4 MB	4.8%	0.96
Feb 17 23:00:00 - 23:59:59	900	3.8%	0.25	2.8 MB	4.1%	0.81
Feb 18 00:00:00 - 00:59:59	681	2.9%	0.19	1.9 MB	2.7%	0.54
Feb 18 01:00:00 - 01:59:59	596	2.5%	0.17	2.5 MB	3.5%	0.70
Feb 18 02:00:00 - 02:59:59	555	2.3%	0.15	1.8 MB	2.6%	0.51
Feb 18 03:00:00 - 03:59:59	500	2.1%	0.14	1.6 MB	2.4%	0.47
Feb 18 04:00:00 - 04:15:00	99	0.4%	0.11	336.0 KB	0.5%	0.37
TOTAL: 24:00:00	23790	100.0%	0.28	70.1 MB	100.0%	0.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9364	433	191	4	8736
2	feeder.eternal-september.org	4666	58	15	0	4593
3	newsfeed.neva.ru	3297	252	33	0	3012
4	csiph-out.news.weretis.net	837	189	0	0	648
5	newsfeed.fu-berlin.de	783	33	0	0	750
6	news-out.mixmin.net	360	1	5	0	354
7	news.freedyn.net	163	16	0	0	147
8	news.fcku.it	43	0	0	0	43
9	news-out.aioe.org	31	21	0	0	10
10	news.swapon.de	17	0	0	0	17
11	news.redatomik.org	17	0	0	0	17
12	newsfeeds.xmission.com	15	4	0	0	11
13	nntp.pasdenom.info	6	4	0	0	2
14	xanadu-bbs.net	4	0	0	0	4
TOTAL: 14		19603	1011	244	4	18344

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.auto	106
lada.talk	95
ut.test	94
it.annunci.immobiliari	43
gmane.linux.alsa.devel	42
it.annunci.commerciali	41
it.annunci.varie	41
it-alt.sport.calcio.juventus	37
mpc.lists.freebsd.ports.bugs	34
alt.politics.scorched-earth	24
spline.eisfair	23
mozilla.support.thunderbird	21
mozilla.dev.platform	19
cakewalk.audio	18
alt.windows7.general	18
alt.courts.pretrial-services	16
it-alt.comp.software.mozilla	16
mpc.lists.nanog	14
eagle.userchat.eng	14
it-alt.comp.software.openoffice	13
TOTAL: 124	1011

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15182
EMP (phn path)	1567
EMP (phl)	553
EMP (phn nph)	71
Too many newsgroups	57
HTML Multipart	22
Scoring filter (11)	19
Scoring filter (8)	19
Scoring filter (10)	15
Binary Payload	12
Scoring filter (9)	7
Scoring filter (21)	5
HTML post	4
Scoring filter (15)	4
Scoring filter (18)	3
Scoring filter (14)	2
User-issued cancel	2
Binary Image: misplaced jpg	1
Scoring filter (13)	1
Angle-bracket bot	1
TOTAL: 21	17548

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	79	0	88	88
TOTAL: 1	79	0	88	88

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	185
	TOTAL: 1	185
Huge articles
	csiph-out.news.weretis.net	67
	feeder.eternal-september.org	31
	news.freedyn.net	10
	news.fcku.it	10
	newsfeeds.xmission.com	6
	news-out.mixmin.net	6
	newsfeed.fu-berlin.de	4
	news.redatomik.org	2
	xanadu-bbs.net	1
	TOTAL: 9	137
Including strange strings
	newsfeed.fu-berlin.de	335
	csiph-out.feeder.erje.net	64
	feeder.eternal-september.org	29
	csiph-out.news.weretis.net	11
	newsfeed.neva.ru	11
	news.swapon.de	8
	news.redatomik.org	6
	newsfeeds.xmission.com	5
	news-out.mixmin.net	3
	news-out.aioe.org	1
	TOTAL: 10	473
TOTAL: 3		795

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39375	5525	17401	94	1	0	14%	23:56:51
2	news.freedyn.net	24818	1133	22487	5	1	0	4%	23:50:06
3	xanadu-bbs	27323	893	22770	1	0	0	3%	23:59:54
4	fu-berlin.de	22476	676	21800	0	0	0	3%	23:52:09
5	usenet.pasdenom.info	26304	253	21998	1401	0	21	0%	23:59:55
6	xmission	28463	106	22297	7	0	0	0%	23:59:55
7	aioe	21443	66	19888	6	0	0	0%	23:50:05
8	news.redatomik.org	22973	41	21807	12	0	0	0%	23:56:43
9	weretis.net	18263	23	17305	2	0	0	0%	23:54:10
10	news.mixmin.net	22435	20	21146	0	0	0	0%	23:50:06
11	eternal-september	21156	19	18937	0	0	0	0%	23:59:31
12	news.swapon.de	2209	14	2128	0	0	0	0%	23:50:06
13	neva.ru	22475	12	20999	9	0	0	0%	23:59:54
14	erje	15482	1	15317	26	0	0	0%	23:59:01
15	news.fcku.it	2667	0	2507	0	0	0	0%	22:58:10
16	au2pb	0	0	0	0	0	23005	0%	23:50:07
17	bwh	0	0	0	0	0	22997	0%	23:50:07
TOTAL: 17		317862	8782	268787	1563	2	46023	2%	405:36:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	14.8 MB	481.5 KB	15.3 MB	0.2 KB/s	2.8 KB	23:56:51
2	news.freedyn.net	6.8 MB	19.9 KB	6.8 MB	0.1 KB/s	6.1 KB	23:50:06
3	xanadu-bbs	6.1 MB	5.6 KB	6.1 MB	0.1 KB/s	7.0 KB	23:59:54
4	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	8.3 KB	23:52:09
5	usenet.pasdenom.info	662.1 KB	6.9 MB	7.6 MB	0.1 KB/s	4.7 KB	23:59:55
6	xmission	243.2 KB	31.0 KB	274.2 KB	0.0 KB/s	2.4 KB	23:59:55
7	aioe	156.2 KB	27.4 KB	183.6 KB	0.0 KB/s	2.5 KB	23:50:05
8	news.redatomik.org	87.9 KB	86.2 KB	174.1 KB	0.0 KB/s	3.3 KB	23:56:43
9	news.mixmin.net	78.6 KB	0.0 KB	78.6 KB	0.0 KB/s	3.9 KB	23:50:06
10	weretis.net	52.8 KB	20.7 KB	73.5 KB	0.0 KB/s	2.9 KB	23:54:10
11	eternal-september	42.1 KB	0.0 KB	42.1 KB	0.0 KB/s	2.2 KB	23:59:31
12	news.swapon.de	21.7 KB	0.0 KB	21.7 KB	0.0 KB/s	1.5 KB	23:50:06
13	neva.ru	19.4 KB	15.6 KB	35.0 KB	0.0 KB/s	1.7 KB	23:59:54
14	erje	3.1 KB	60.3 KB	63.4 KB	0.0 KB/s	2.3 KB	23:59:01
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:58:10
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
TOTAL: 17		34.6 MB	7.6 MB	42.2 MB	0.0 KB/s	4.2 KB	405:36:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	31	23	39.7 KB	7	1	1	07:56:33
2	dhcp-089-099-203-254.chello.nl	21	23	41.2 KB	4	0	0	04:36:15
3	68-185-181-64.dhcp.mdsn.wi.charter.com	31	16	29.2 KB	15	12	12	05:06:55
4	194-73-239-109.cust.centrio.cz	12	7	16.6 KB	6	0	0	03:32:54
TOTAL: 4		95	69	126.7 KB	32	13	13	68:27:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.chello.nl	21	23	41.2 KB	4	0	0	04:36:15
2	*.bbox.io	31	23	39.7 KB	7	1	1	07:56:33
3	*.dhcp.mdsn.wi.charter.com	31	16	29.2 KB	15	12	12	05:06:55
4	*.cust.centrio.cz	12	7	16.6 KB	6	0	0	03:32:54
5	unresolved	92	0	0.0 KB	0	0	0	47:15:02
6	*.137.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 6		188	69	126.7 KB	32	13	13	68:27:41

NNRP auth users [Top 20]:

User	Conn
ohlandl	12
ctomczyk	9
kev009	1
TOTAL: 3	22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	6.905	1.405	0.000	44:34:47
tempe0.bbox.io	2.895	0.465	0.003	07:56:33
68-185-181-64.dhcp.mdsn.wi.charter.com	3.341	0.587	0.047	05:06:55
dhcp-089-099-203-254.chello.nl	1.931	0.326	0.000	04:36:15
194-73-239-109.cust.centrio.cz	1.128	0.191	0.034	03:32:54
50.96.157.204	0.141	0.203	0.000	02:40:15
TOTAL: 6	16.341	3.177	0.084	68:27:41

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	91
h201.137.22.98.dynamic.ip.windstream.net	1
50.96.157.204	1
TOTAL: 3	93

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	87	0
tempe0.bbox.io	12	0
194-73-239-109.cust.centrio.cz	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	1	0
TOTAL: 4	107	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	60	87.0%
2	bit	5	7.2%
3	pl	4	5.8%
TOTAL: 3		69	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	56
2	bit.listserv.ibm-main	5
3	pl.comp.lang.javascript	3
4	comp.lang.javascript	3
5	comp.sys.ibm.as400.misc	1
6	pl.comp.www.nowe-strony	1
TOTAL: 6		69

Daily Usenet report for csiph.com

Feb 17 04:15:00 -- Feb 18 04:15:00