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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 17 11:10:02 csiph pgpverify[51768]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	53961	44.0%	5.1 MB	32.5%
inn	40234	32.8%	7.4 MB	47.3%
innd	24970	20.4%	2.6 MB	16.8%
innfeed	3279	2.7%	519.2 KB	3.3%
nocem	85	0.1%	9.0 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	122534	100.0%	15.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	399912	54.5%
Negative hits	289499	39.5%
Do not exist	39227	5.3%
Cache misses	5030	0.7%
TOTAL: 4	733668	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.212	0.0%	266	0.000	4.556	24.200
article cleanup	00:00:00.277	0.0%	39363	0.000	0.007	0.024
article logging	00:00:06.162	0.0%	39920	0.000	0.154	1.312
article parse	00:00:02.596	0.0%	133021	0.000	0.020	0.152
article write	00:03:07.234	0.2%	20896	0.207	8.960	39.400
artlog/artcncl	00:00:00.005	0.0%	187	0.000	0.027	0.667
artlog/artparse	00:00:00.057	0.0%	192	0.000	0.297	15.000
data move	00:00:00.267	0.0%	669945	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.146	0.0%	266	0.000	0.549	4.800
hishave/artcncl	00:00:00.070	0.0%	266	0.000	0.263	2.700
hishave/artparse	00:00:00.000	0.0%	188	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:19.388	0.2%	736076	0.003	0.189	0.690
history sync	00:00:05.635	0.0%	361	0.000	15.609	141.500
history write	00:04:43.249	0.3%	39161	0.093	7.233	31.564
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.020	0.0%	188	0.000	5.426	40.250
idle	23:41:29.270	98.6%	645737	75.985	132.081	537.510
nntp read	00:00:03.176	0.0%	679254	0.002	0.005	0.008
overview write	00:05:39.080	0.4%	20896	0.667	16.227	135.154
perl filter	00:01:41.863	0.1%	39143	1.556	2.602	3.751
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.376	0.0%	41867	0.000	0.009	0.024
TOTAL: 24:00:57.561	23:59:21.083	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	20927	0.000	0.003	0.025
article prepare	00:00:00.002	0.0%	27000	0.000	0.000	0.008
article read	00:00:01.946	0.0%	4041	0.000	0.482	10.600
backlog stats	00:00:00.203	0.0%	382765	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.059	0.0%	295826	0.002	0.007	0.013
data write	00:00:01.815	0.0%	278910	0.003	0.007	0.012
idle	23:52:48.648	100.0%	382765	132.898	224.599	754.026
status file	00:00:00.356	0.0%	297	0.000	1.199	20.000
TOTAL: 23:53:03.868	23:52:55.088	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:27:20.529	99.6%	127056	78.571	551.257	600089.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.512	0.0%	255091	0.000	0.006	0.091
readart	00:01:28.295	0.1%	4537	0.000	19.461	230.000
TOTAL: 19:31:50.855	19:28:50.336	99.7%	-	-	-	-

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

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	1440	141913	6468	126969	8476	4%	118:43:46
2	csiph-out.news.weretis.net	4	43427	4987	37226	1214	11%	47:57:35
3	feeder.eternal-september.org	6	35657	2608	27654	5395	7%	24:52:46
4	news-out.mixmin.net	2	22752	1588	20423	741	6%	24:08:49
5	newsfeed.neva.ru	145	46579	1363	42816	2400	2%	71:59:36
6	news.redatomik.org	1	22503	1185	21307	11	5%	29:32:28
7	newsfeed.fu-berlin.de	28	21214	1053	19907	254	4%	28:16:49
8	news-out.aioe.org	3	20519	786	19726	7	3%	24:09:50
9	newsfeeds.xmission.com	3	18027	630	17394	3	3%	22:31:02
10	nntp.pasdenom.info	3	18979	80	18884	15	0%	24:07:45
11	news.fcku.it	1	7643	68	7553	22	0%	23:59:57
12	news.freedyn.net	3	24034	58	23896	80	0%	24:09:47
13	news.swapon.de	1	2035	37	1997	1	1%	23:18:44
14	xanadu-bbs.net	10	253	17	236	0	6%	10:13:17
15	news.bbs.geek.nz	1	16619	3	16616	0	0%	23:59:47
16	localhost	37	1	1	0	0	100%	00:00:00
TOTAL: 16		1688	442155	20932	402604	18619	4%	522:01:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.6 MB	2.1 MB	55.0 MB	81.7 MB	30%	5.6 KB
2	csiph-out.news.weretis.net	15.5 MB	0.0 KB	13.4 MB	28.9 MB	53%	4.8 KB
3	feeder.eternal-september.org	6.9 MB	305.1 KB	71.0 MB	78.2 MB	8%	10.0 KB
4	news-out.mixmin.net	5.9 MB	0.0 KB	1.8 MB	7.7 MB	76%	3.4 KB
5	newsfeed.neva.ru	3.2 MB	153.3 KB	22.4 MB	25.7 MB	12%	7.0 KB
6	news.redatomik.org	2.8 MB	0.0 KB	16.1 KB	2.8 MB	99%	2.4 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	604.9 KB	3.0 MB	80%	2.4 KB
8	newsfeeds.xmission.com	2.2 MB	0.0 KB	90.0 KB	2.3 MB	96%	3.7 KB
9	news-out.aioe.org	2.1 MB	0.0 KB	12.1 KB	2.1 MB	99%	2.7 KB
10	news.freedyn.net	310.5 KB	0.0 KB	722.5 KB	1.0 MB	30%	7.5 KB
11	news.fcku.it	201.2 KB	0.0 KB	917.1 KB	1.1 MB	17%	12.4 KB
12	nntp.pasdenom.info	163.7 KB	0.0 KB	46.7 KB	210.4 KB	77%	2.2 KB
13	news.swapon.de	91.5 KB	0.0 KB	35.6 KB	127.1 KB	71%	3.3 KB
14	xanadu-bbs.net	43.0 KB	0.0 KB	0.0 KB	43.0 KB	100%	2.5 KB
15	news.bbs.geek.nz	5.6 KB	0.0 KB	0.0 KB	5.6 KB	100%	1.9 KB
16	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 16		66.4 MB	2.5 MB	166.1 MB	235.0 MB	28%	6.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 17 04:15:00 - 04:59:59	251	1.2%	0.09	824.8 KB	1.2%	0.31
Apr 17 05:00:00 - 05:59:59	500	2.4%	0.14	1.5 MB	2.3%	0.43
Apr 17 06:00:00 - 06:59:59	677	3.2%	0.19	2.6 MB	3.9%	0.74
Apr 17 07:00:00 - 07:59:59	766	3.7%	0.21	3.0 MB	4.4%	0.84
Apr 17 08:00:00 - 08:59:59	916	4.4%	0.25	2.8 MB	4.2%	0.80
Apr 17 09:00:00 - 09:59:59	975	4.7%	0.27	2.5 MB	3.8%	0.72
Apr 17 10:00:00 - 10:59:59	1038	5.0%	0.29	3.7 MB	5.6%	1.06
Apr 17 11:00:00 - 11:59:59	945	4.5%	0.26	3.0 MB	4.5%	0.86
Apr 17 12:00:00 - 12:59:59	924	4.4%	0.26	2.5 MB	3.8%	0.72
Apr 17 13:00:00 - 13:59:59	1071	5.1%	0.30	3.4 MB	5.1%	0.97
Apr 17 14:00:00 - 14:59:59	1058	5.1%	0.29	3.1 MB	4.6%	0.87
Apr 17 15:00:00 - 15:59:59	1244	6.0%	0.35	4.1 MB	6.1%	1.15
Apr 17 16:00:00 - 16:59:59	1183	5.7%	0.33	3.5 MB	5.3%	1.00
Apr 17 17:00:00 - 17:59:59	1249	6.0%	0.35	4.4 MB	6.6%	1.24
Apr 17 18:00:00 - 18:59:59	1242	5.9%	0.34	3.8 MB	5.8%	1.09
Apr 17 19:00:00 - 19:59:59	1217	5.8%	0.34	4.1 MB	6.2%	1.18
Apr 17 20:00:00 - 20:59:59	1063	5.1%	0.30	2.9 MB	4.4%	0.83
Apr 17 21:00:00 - 21:59:59	933	4.5%	0.26	2.6 MB	3.8%	0.73
Apr 17 22:00:00 - 22:59:59	785	3.8%	0.22	2.4 MB	3.5%	0.67
Apr 17 23:00:00 - 23:59:59	723	3.5%	0.20	2.5 MB	3.8%	0.72
Apr 18 00:00:00 - 00:59:59	564	2.7%	0.16	1.9 MB	2.9%	0.55
Apr 18 01:00:00 - 01:59:59	482	2.3%	0.13	1.6 MB	2.5%	0.47
Apr 18 02:00:00 - 02:59:59	495	2.4%	0.14	1.8 MB	2.7%	0.51
Apr 18 03:00:00 - 03:59:59	454	2.2%	0.13	1.5 MB	2.3%	0.43
Apr 18 04:00:00 - 04:15:00	125	0.6%	0.14	410.7 KB	0.6%	0.46
TOTAL: 24:00:00	20880	100.0%	0.24	66.5 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.feeder.erje.net	8522	210	160	6	0	8146
2	feeder.eternal-september.org	5368	59	23	0	0	5286
3	newsfeed.neva.ru	2378	290	12	0	0	2076
4	csiph-out.news.weretis.net	1316	202	0	0	0	1114
5	news-out.mixmin.net	765	3	0	0	0	762
6	newsfeed.fu-berlin.de	663	46	0	0	0	617
7	news.freedyn.net	79	4	0	0	0	75
8	news.fcku.it	17	0	0	0	0	17
9	nntp.pasdenom.info	15	0	0	0	13	2
10	news.redatomik.org	15	0	0	0	0	15
11	news-out.aioe.org	12	4	0	0	0	8
12	newsfeeds.xmission.com	6	1	0	0	0	5
13	news.swapon.de	3	0	0	0	0	3
14	xanadu-bbs.net	1	0	0	0	0	1
TOTAL: 14		19160	819	195	6	13	18127

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	118
lada.talk	113
lada.auto	73
mpc.lists.freebsd.ports.bugs	39
cakewalk.audio	25
alt.survival	21
gmane.linux.drivers.devicetree	20
mpc.lists.freebsd.gecko	19
alt.tv.star-trek	16
mozilla.dev.platform	16
spline.eisfair	16
spline.eisfair.dev	15
gmane.comp.gis.openstreetmap.tagging	15
alt.windows7.general	15
spline.fli4l	13
mozilla.support.firefox	12
mozilla.support.seamonkey	12
gmane.comp.bacula.user	11
mozilla.support.thunderbird	10
alt.politics.scorched-earth	10
TOTAL: 128	819

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

Groups	Count
nz.politics.announce	6
TOTAL: 1	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15538
EMP (phn path)	1518
User-issued cancel	95
EMP (phn nph)	90
Too many newsgroups	25
Binary Payload	24
Scoring filter (12)	19
HTML Multipart	15
Scoring filter (10)	9
Scoring filter (11)	9
Scoring filter (8)	7
Scoring filter (9)	6
Too many newsgroups (low_xpost)	5
Scoring filter (14)	5
Binary: non-binary distribution	3
Scoring filter (13)	2
Binary: misplaced par2	2
HTML post	2
Binary: misplaced nfo	1
Too many newsgroups (meow)	1
TOTAL: 23	17379

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	92
	csiph-out.news.weretis.net	76
	news-out.mixmin.net	9
	news.fcku.it	5
	newsfeeds.xmission.com	3
	xanadu-bbs.net	1
	newsfeed.fu-berlin.de	1
	news.freedyn.net	1
	TOTAL: 8	188
Including strange strings
	newsfeed.fu-berlin.de	385
	csiph-out.news.weretis.net	65
	csiph-out.feeder.erje.net	47
	news-out.mixmin.net	29
	newsfeed.neva.ru	12
	news-out.aioe.org	5
	feeder.eternal-september.org	4
	news.redatomik.org	4
	news.swapon.de	2
	newsfeeds.xmission.com	2
	TOTAL: 10	555
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 4		792

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37792	1148	18863	119	1	3%	23:59:55
2	news.freedyn.net	21609	914	19907	1	0	4%	23:59:55
3	xanadu-bbs	22557	814	20010	23	91	3%	23:59:55
4	fu-berlin.de	19579	736	18843	0	0	3%	23:59:55
5	erje	14957	535	13678	142	0	3%	23:59:55
6	usenet.pasdenom.info	22626	174	18565	2064	10	0%	23:59:37
7	news.redatomik.org	19802	27	19085	2	0	0%	23:59:25
8	aioe	18977	17	18115	3	0	0%	23:59:54
9	xmission	21660	16	19406	3	0	0%	23:59:54
10	weretis.net	15118	9	14622	1	0	0%	23:59:55
11	eternal-september	18704	3	17287	0	0	0%	23:59:55
12	news.mixmin.net	18857	2	18145	0	0	0%	23:59:23
13	news.swapon.de	2164	1	2133	1	0	0%	23:59:55
14	news.fcku.it	951	0	938	0	0	0%	21:58:13
15	neva.ru	19088	0	18569	7	0	0%	23:59:34
16	bwh	0	0	0	0	20125	0%	23:59:33
17	au2pb	0	0	0	0	20134	0%	23:58:42
TOTAL: 17		274441	4396	238166	2366	40361	1%	405:53:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.0 MB	1.7 KB	8.0 MB	0.1 KB/s	9.0 KB	23:59:55
2	xanadu-bbs	7.6 MB	56.9 KB	7.7 MB	0.1 KB/s	9.4 KB	23:59:55
3	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	10.3 KB	23:59:55
4	news.bbs.geek.nz	3.1 MB	521.1 KB	3.6 MB	0.0 KB/s	2.9 KB	23:59:55
5	erje	2.2 MB	546.7 KB	2.8 MB	0.0 KB/s	4.2 KB	23:59:55
6	usenet.pasdenom.info	617.6 KB	9.7 MB	10.3 MB	0.1 KB/s	4.7 KB	23:59:37
7	news.redatomik.org	102.1 KB	3.0 KB	105.2 KB	0.0 KB/s	3.6 KB	23:59:25
8	aioe	46.2 KB	4.5 KB	50.7 KB	0.0 KB/s	2.5 KB	23:59:54
9	xmission	39.5 KB	3.9 KB	43.4 KB	0.0 KB/s	2.3 KB	23:59:54
10	eternal-september	28.7 KB	0.0 KB	28.7 KB	0.0 KB/s	9.6 KB	23:59:55
11	weretis.net	20.0 KB	2.2 KB	22.2 KB	0.0 KB/s	2.2 KB	23:59:55
12	news.mixmin.net	5.3 KB	0.0 KB	5.3 KB	0.0 KB/s	2.7 KB	23:59:23
13	news.swapon.de	3.0 KB	2.0 KB	5.0 KB	0.0 KB/s	2.5 KB	23:59:55
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:58:13
15	neva.ru	0.0 KB	20.6 KB	20.6 KB	0.0 KB/s	2.9 KB	23:59:34
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:33
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		29.3 MB	10.9 MB	40.1 MB	0.0 KB/s	6.1 KB	405:53:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	1698	67	228.4 KB	47	0	0	03:33:19
2	144.138.90.254	288	54	164.1 KB	48	0	0	03:39:31
3	204-73-239-109.cust.centrio.cz	2	11	23.3 KB	3	0	0	00:47:29
4	68-185-181-64.dhcp.mdsn.wi.charter.com	8	1	0.9 KB	1	1	1	03:01:20
5	tempe0.bbox.io	6	1	0.9 KB	1	0	0	03:00:06
6	dhcp-089-099-203-254.chello.nl	28	1	0.9 KB	1	0	0	05:29:46
TOTAL: 6		2030	135	418.6 KB	101	1	1	19:31:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	1698	67	228.4 KB	47	0	0	03:33:19
2	unresolved	288	54	164.1 KB	48	0	0	03:39:31
3	*.cust.centrio.cz	2	11	23.3 KB	3	0	0	00:47:29
4	*.chello.nl	28	1	0.9 KB	1	0	0	05:29:46
5	*.dhcp.mdsn.wi.charter.com	8	1	0.9 KB	1	1	1	03:01:20
6	*.bbox.io	6	1	0.9 KB	1	0	0	03:00:06
TOTAL: 6		2030	135	418.6 KB	101	1	1	19:31:34

NNRP auth users [Top 20]:

User	Conn
moussa	288
ctomczyk	2
ohlandl	1
TOTAL: 3	291

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	1.946	0.450	0.000	05:29:46
144.138.90.254	24.056	6.660	1.063	03:39:31
mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	138.020	26.682	0.000	03:33:19
68-185-181-64.dhcp.mdsn.wi.charter.com	0.708	0.122	0.004	03:01:20
tempe0.bbox.io	0.650	0.111	0.000	03:00:06
204-73-239-109.cust.centrio.cz	0.254	0.040	0.006	00:47:29
TOTAL: 6	165.634	34.065	1.073	19:31:34

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
TOTAL: 2	10	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	69	51.1%
2	alt	26	19.3%
3	rec	11	8.1%
4	fr	8	5.9%
5	uk	7	5.2%
6	sci	6	4.4%
7	talk	3	2.2%
8	soc	3	2.2%
9	can	1	0.7%
10	misc	1	0.7%
TOTAL: 10		135	100.0%

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

Newsgroup		Count
1	comp.os.vms	29
2	comp.lang.javascript	11
3	comp.arch	10
4	alt.atheism	9
5	alt.home.repair	8
6	fr.soc.politique	8
7	rec.arts.tv	7
8	comp.sys.ibm.pc.games.action	5
9	comp.sys.ibm.ps2.hardware	4
10	comp.os.msdos.programmer	4
11	talk.politics.guns	3
12	sci.physics	3
13	uk.rec.drugs.cannabis	3
14	comp.os.cpm	3
15	soc.retirement	2
16	uk.politics.misc	2
17	alt.messianic	2
18	alt.buddha.short.fat.guy	2
19	comp.arch.embedded	2
20	alt.fan.rush-limbaugh	2
21	alt.abortion	1
22	rec.sport.pro-wrestling	1
23	uk.legal	1
24	rec.music.rock-pop-r+b.1950s	1
25	rec.arts.tv.uk.coronation-st	1
26	can.politics	1
27	misc.survivalism	1
28	uk.rec.cycling	1
29	alt.war.vietnam	1
30	sci.military.naval	1
31	alt.society.liberalism	1
32	rec.arts.drwho	1
33	sci.logic	1
34	sci.electronics.design	1
35	soc.culture.usa	1
36	comp.soft-sys.sas	1
TOTAL: 36		135

Daily Usenet report for csiph.com

Apr 17 04:15:00 -- Apr 18 04:15:00