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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 4 17:00:02 csiph pgpverify[91027]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	41062	50.1%	7.6 MB	62.6%
innd	23082	28.2%	2.4 MB	20.2%
nnrpd	14541	17.7%	1.6 MB	12.8%
innfeed	3211	3.9%	539.4 KB	4.3%
nocem	81	0.1%	8.8 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	81982	100.0%	12.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	396972	55.0%
Negative hits	285072	39.5%
Do not exist	39504	5.5%
Cache misses	563	0.1%
TOTAL: 4	722111	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.652	0.0%	175	0.000	3.726	31.000
article cleanup	00:00:00.258	0.0%	40475	0.000	0.006	0.024
article logging	00:00:04.859	0.0%	41121	0.000	0.118	0.870
article parse	00:00:03.340	0.0%	145344	0.000	0.023	0.223
article write	00:02:40.085	0.2%	22193	0.184	7.213	19.941
artlog/artcncl	00:00:00.004	0.0%	120	0.000	0.033	0.167
artlog/artparse	00:00:00.011	0.0%	253	0.000	0.043	1.000
data move	00:00:00.257	0.0%	672136	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.187	0.0%	171	0.000	1.094	16.000
hishave/artcncl	00:00:00.052	0.0%	175	0.000	0.297	8.000
hishave/artparse	00:00:00.001	0.0%	250	0.000	0.004	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:52.764	0.1%	731053	0.002	0.154	0.321
history sync	00:00:05.845	0.0%	364	0.000	16.058	93.500
history write	00:03:58.361	0.3%	39898	0.158	5.974	15.532
hiswrite/artcncl	00:00:00.041	0.0%	4	0.000	10.250	32.000
hiswrite/artparse	00:00:01.706	0.0%	250	0.000	6.824	49.500
idle	23:43:39.397	98.8%	642216	71.881	133.007	460.413
nntp read	00:00:03.242	0.0%	678511	0.003	0.005	0.021
overview write	00:05:32.583	0.4%	22193	0.542	14.986	260.644
perl filter	00:01:42.661	0.1%	39895	1.229	2.573	5.362
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.406	0.0%	44460	0.000	0.009	0.028
TOTAL: 24:01:06.629	23:59:46.712	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	22150	0.000	0.004	0.028
article prepare	00:00:00.007	0.0%	41628	0.000	0.000	0.007
article read	00:00:01.579	0.0%	7683	0.045	0.206	3.059
backlog stats	00:00:00.256	0.0%	400985	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.213	0.0%	313094	0.002	0.007	0.013
data write	00:00:01.896	0.0%	296442	0.003	0.006	0.012
idle	23:54:15.337	100.0%	400985	91.896	214.610	642.124
status file	00:00:00.277	0.0%	313	0.000	0.885	10.000
TOTAL: 23:54:32.957	23:54:21.650	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:09.153	0.0%	5334	0.000	1.716	89.000
idle	55:54:17.950	99.7%	93356	79.598	2155.812	900198.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.746	0.0%	187024	0.000	0.009	39.429
readart	00:00:48.874	0.0%	10601	0.000	4.610	56.000
TOTAL: 56:03:22.662	55:55:17.723	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
moderator@dana.de	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	129397	6702	113900	8795	5%	95:07:49
2	csiph-out.news.weretis.net	4	39839	4157	34935	747	10%	40:12:02
3	feeder.eternal-september.org	7	36067	3314	26301	6452	9%	25:38:28
4	news-out.aioe.org	5	21143	2034	19080	29	9%	24:32:21
5	newsfeed.neva.ru	151	48433	1362	43961	3110	2%	72:21:27
6	newsfeed.fu-berlin.de	25	22347	1121	20907	319	5%	27:50:16
7	news-out.mixmin.net	2	24451	916	23257	278	3%	23:59:41
8	newsfeeds.xmission.com	2	19492	724	18765	3	3%	21:49:27
9	news.redatomik.org	1	18353	611	17727	15	3%	21:00:03
10	news.fcku.it	1	8895	330	8350	215	3%	23:59:56
11	xanadu-bbs.net	9	1683	321	1354	8	19%	38:13:43
12	nntp.pasdenom.info	3	20550	173	20365	12	0%	24:08:31
13	news.freedyn.net	4	25538	132	25226	180	0%	24:18:56
14	news.bbs.geek.nz	1	17877	37	17840	0	0%	23:59:52
15	localhost	46	11	11	0	0	100%	00:00:00
16	news.swapon.de	1	11	0	11	0	0%	00:32:11
TOTAL: 16		1414	434087	21945	391979	20163	5%	487:44:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	21.5 MB	2.5 MB	44.4 MB	68.4 MB	31%	4.5 KB
2	csiph-out.news.weretis.net	13.0 MB	42.6 KB	11.6 MB	24.7 MB	52%	5.1 KB
3	feeder.eternal-september.org	8.9 MB	4.2 MB	86.1 MB	99.2 MB	9%	10.4 KB
4	news-out.aioe.org	5.5 MB	3.7 KB	51.0 KB	5.5 MB	99%	2.7 KB
5	news-out.mixmin.net	4.8 MB	3.0 KB	1.0 MB	5.8 MB	82%	5.0 KB
6	newsfeed.neva.ru	3.4 MB	512.8 KB	28.7 MB	32.6 MB	10%	7.5 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	1.2 MB	3.7 MB	68%	2.6 KB
8	newsfeeds.xmission.com	2.2 MB	3.0 KB	51.2 KB	2.2 MB	97%	3.1 KB
9	news.redatomik.org	1.7 MB	0.0 KB	607.8 KB	2.3 MB	73%	3.7 KB
10	xanadu-bbs.net	1.2 MB	0.0 KB	31.8 KB	1.2 MB	97%	3.7 KB
11	news.fcku.it	679.9 KB	0.0 KB	3.0 MB	3.6 MB	18%	6.8 KB
12	news.freedyn.net	561.4 KB	7.0 KB	1.5 MB	2.0 MB	26%	6.7 KB
13	nntp.pasdenom.info	428.5 KB	0.0 KB	30.8 KB	459.2 KB	93%	2.5 KB
14	news.bbs.geek.nz	81.5 KB	0.0 KB	0.0 KB	81.5 KB	100%	2.2 KB
15	localhost	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	1.2 KB
16	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		66.3 MB	7.2 MB	178.1 MB	251.7 MB	26%	6.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 4 04:15:00 - 04:59:59	330	1.5%	0.12	1.0 MB	1.5%	0.39
Feb 4 05:00:00 - 05:59:59	414	1.9%	0.12	1.4 MB	2.0%	0.39
Feb 4 06:00:00 - 06:59:59	557	2.5%	0.15	2.0 MB	2.9%	0.56
Feb 4 07:00:00 - 07:59:59	689	3.1%	0.19	3.3 MB	4.9%	0.94
Feb 4 08:00:00 - 08:59:59	805	3.6%	0.22	2.3 MB	3.5%	0.67
Feb 4 09:00:00 - 09:59:59	992	4.5%	0.28	3.0 MB	4.4%	0.85
Feb 4 10:00:00 - 10:59:59	1318	5.9%	0.37	3.5 MB	5.2%	0.99
Feb 4 11:00:00 - 11:59:59	1128	5.1%	0.31	3.5 MB	5.2%	1.00
Feb 4 12:00:00 - 12:59:59	1048	4.7%	0.29	2.9 MB	4.4%	0.84
Feb 4 13:00:00 - 13:59:59	1113	5.0%	0.31	3.1 MB	4.6%	0.89
Feb 4 14:00:00 - 14:59:59	931	4.2%	0.26	3.0 MB	4.5%	0.86
Feb 4 15:00:00 - 15:59:59	1220	5.5%	0.34	4.2 MB	6.2%	1.19
Feb 4 16:00:00 - 16:59:59	1302	5.9%	0.36	3.7 MB	5.6%	1.06
Feb 4 17:00:00 - 17:59:59	1278	5.8%	0.35	3.6 MB	5.3%	1.02
Feb 4 18:00:00 - 18:59:59	1156	5.2%	0.32	3.0 MB	4.5%	0.85
Feb 4 19:00:00 - 19:59:59	1166	5.3%	0.32	3.4 MB	5.0%	0.96
Feb 4 20:00:00 - 20:59:59	999	4.5%	0.28	2.8 MB	4.2%	0.81
Feb 4 21:00:00 - 21:59:59	1008	4.5%	0.28	3.2 MB	4.7%	0.90
Feb 4 22:00:00 - 22:59:59	1009	4.5%	0.28	3.0 MB	4.4%	0.84
Feb 4 23:00:00 - 23:59:59	1001	4.5%	0.28	2.6 MB	3.9%	0.74
Feb 5 00:00:00 - 00:59:59	809	3.6%	0.22	2.3 MB	3.4%	0.65
Feb 5 01:00:00 - 01:59:59	574	2.6%	0.16	1.8 MB	2.7%	0.52
Feb 5 02:00:00 - 02:59:59	754	3.4%	0.21	2.3 MB	3.4%	0.65
Feb 5 03:00:00 - 03:59:59	465	2.1%	0.13	1.7 MB	2.5%	0.48
Feb 5 04:00:00 - 04:15:00	124	0.6%	0.14	429.4 KB	0.6%	0.48
TOTAL: 24:00:00	22190	100.0%	0.26	67.0 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	6995	292	197	1	6505
2	feeder.eternal-september.org	6584	45	332	1	6206
3	newsfeed.neva.ru	2876	143	29	0	2704
4	newsfeed.fu-berlin.de	753	12	0	0	741
5	csiph-out.news.weretis.net	730	162	1	0	567
6	news-out.mixmin.net	294	11	1	0	282
7	news.fcku.it	226	2	0	0	224
8	news.freedyn.net	172	18	2	0	152
9	news-out.aioe.org	51	25	2	0	24
10	news.redatomik.org	21	0	0	0	21
11	news.swapon.de	20	0	0	0	20
12	nntp.pasdenom.info	12	7	0	0	5
13	newsfeeds.xmission.com	11	1	1	0	9
14	xanadu-bbs.net	7	0	0	0	7
TOTAL: 14		18752	718	565	2	17467

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	101
mozilla.general	75
lada.talk	47
it-alt.sport.calcio.juventus	40
lada.auto	36
alt.courts.pretrial-services	25
alt.windows7.general	19
it-alt.comp.software.mozilla	18
lada.commerce	18
mozilla.support.thunderbird	17
mozilla.support.firefox	16
gmane.linux.alsa.devel	15
mozilla.support.seamonkey	15
mpc.lists.freebsd.wireless	12
alt.computer.workshop	12
alt.survival	10
alt.philosophy	10
alt.politics.scorched-earth	10
alt.2600	9
alt.politics	9
TOTAL: 124	718

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

Groups	Count
muc.lists.freebsd.questions	1
uk.legal.moderated	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13690
EMP (phn path)	1643
EMP (phl)	720
EMP (phn nph)	171
Binary Payload	121
Too many newsgroups	102
EMP (fsl)	34
User-issued cancel	15
Scoring filter (9)	14
HTML Multipart	10
Scoring filter (14)	8
Too many newsgroups (meow)	8
Scoring filter (11)	6
Too many newsgroups (low_xpost)	5
Scoring filter (8)	5
Scoring filter (16)	5
Scoring filter (13)	3
HTML Image Tags	2
Scoring filter (10)	2
Binary: misplaced zip	1
TOTAL: 23	16568

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	73	0	81	81
TOTAL: 1	73	0	81	81

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	122
	csiph-out.news.weretis.net	74
	news.fcku.it	34
	newsfeed.fu-berlin.de	9
	news-out.mixmin.net	4
	news.redatomik.org	4
	newsfeeds.xmission.com	2
	news.freedyn.net	1
	TOTAL: 8	250
Including strange strings
	newsfeed.fu-berlin.de	438
	csiph-out.feeder.erje.net	73
	feeder.eternal-september.org	37
	news-out.aioe.org	22
	csiph-out.news.weretis.net	20
	news.swapon.de	16
	news-out.mixmin.net	11
	newsfeed.neva.ru	9
	newsfeeds.xmission.com	7
	news.redatomik.org	7
	TOTAL: 12	646
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 4		902

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36234	6006	15338	73	1	16%	23:53:16
2	news.freedyn.net	22837	884	21090	1	0	3%	23:50:03
3	xanadu-bbs	24802	815	21205	0	0	3%	23:59:49
4	fu-berlin.de	20746	671	20075	0	0	3%	23:53:07
5	usenet.pasdenom.info	23940	140	20455	1419	4	0%	23:59:53
6	aioe	19368	119	18338	5	0	0%	23:50:02
7	xmission	24463	90	20330	2	1	0%	23:59:40
8	news.redatomik.org	21487	60	20691	7	0	0%	23:50:03
9	weretis.net	15815	18	15275	1	0	0%	23:52:17
10	eternal-september	19165	15	17840	2	0	0%	23:59:53
11	news.swapon.de	2184	12	2118	2	0	0%	23:54:25
12	news.mixmin.net	20372	12	19634	0	0	0%	23:57:07
13	neva.ru	20765	8	19738	11	0	0%	23:59:43
14	erje	15450	2	15284	26	0	0%	23:50:03
15	news.fcku.it	1535	0	1508	0	0	0%	21:42:12
16	au2pb	0	0	0	0	21414	0%	23:50:07
17	bwh	0	0	0	0	21402	0%	23:50:06
TOTAL: 17		289163	8852	248919	1549	42822	3%	404:11:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	16.5 MB	260.6 KB	16.7 MB	0.2 KB/s	2.8 KB	23:53:16
2	news.freedyn.net	6.9 MB	7.2 KB	6.9 MB	0.1 KB/s	8.0 KB	23:50:03
3	xanadu-bbs	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	8.3 KB	23:59:49
4	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	9.6 KB	23:53:07
5	usenet.pasdenom.info	449.5 KB	7.6 MB	8.1 MB	0.1 KB/s	5.3 KB	23:59:53
6	aioe	366.9 KB	12.7 KB	379.5 KB	0.0 KB/s	3.1 KB	23:50:02
7	xmission	219.1 KB	5.1 KB	224.2 KB	0.0 KB/s	2.4 KB	23:59:40
8	news.redatomik.org	139.8 KB	50.8 KB	190.6 KB	0.0 KB/s	2.8 KB	23:50:03
9	weretis.net	28.4 KB	1.2 KB	29.6 KB	0.0 KB/s	1.6 KB	23:52:17
10	eternal-september	25.1 KB	5.1 KB	30.2 KB	0.0 KB/s	1.8 KB	23:59:53
11	news.swapon.de	25.0 KB	3.8 KB	28.7 KB	0.0 KB/s	2.1 KB	23:54:25
12	news.mixmin.net	17.1 KB	0.0 KB	17.1 KB	0.0 KB/s	1.4 KB	23:57:07
13	neva.ru	9.1 KB	22.8 KB	31.9 KB	0.0 KB/s	1.7 KB	23:59:43
14	erje	3.9 KB	62.8 KB	66.7 KB	0.0 KB/s	2.4 KB	23:50:03
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:42:12
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:06
TOTAL: 17		37.5 MB	8.1 MB	45.6 MB	0.0 KB/s	4.5 KB	404:11:46

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	300	96	290.1 KB	70	0	0	02:14:05
2	68-185-181-64.dhcp.mdsn.wi.charter.com	19	16	29.0 KB	8	11	10	04:03:14
3	5.172.235.23	4	15	42.3 KB	2	0	0	00:27:25
4	dhcp-089-099-203-254.chello.nl	5	7	19.9 KB	3	0	0	00:54:18
5	g235242.upc-g.chello.nl	2	1	1.7 KB	1	0	0	00:02:40
TOTAL: 5		330	135	382.9 KB	84	11	10	56:03:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	300	96	290.1 KB	70	0	0	02:14:05
2	*.dhcp.mdsn.wi.charter.com	19	16	29.0 KB	8	11	10	04:03:14
3	unresolved	86	15	42.3 KB	2	0	0	40:33:48
4	*.chello.nl	5	7	19.9 KB	3	0	0	00:54:18
5	*.upc-g.chello.nl	2	1	1.7 KB	1	0	0	00:02:40
6	*.19-1.cable.virginm.net	1	0	0.0 KB	0	0	0	00:30:01
7	*.171.131.174.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	05:45:04
8	*.bbox.io	2	0	0.0 KB	0	0	0	02:00:05
TOTAL: 8		417	135	382.9 KB	84	11	10	56:03:18

NNRP auth users [Top 20]:

User	Conn
ohlandl	10
ctomczyk	4
TOTAL: 2	14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	6.036	1.183	0.000	40:06:22
h231.171.131.174.dynamic.ip.windstream.net	0.296	0.421	0.000	05:45:04
68-185-181-64.dhcp.mdsn.wi.charter.com	1.987	0.372	0.038	04:03:14
mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	30.197	8.176	0.000	02:14:05
tempe0.bbox.io	0.519	0.098	0.000	02:00:05
dhcp-089-099-203-254.chello.nl	0.556	0.060	0.000	00:54:18
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.096	0.007	0.000	00:30:01
5.172.235.23	0.406	0.048	0.032	00:27:25
g235242.upc-g.chello.nl	0.220	0.042	0.000	00:02:40
TOTAL: 9	40.313	10.407	0.070	56:03:18

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	82
h231.171.131.174.dynamic.ip.windstream.net	2
tempe0.bbox.io	2
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1
TOTAL: 4	87

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	78	0
tempe0.bbox.io	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	0
TOTAL: 4	86	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	54	40.0%
2	comp	41	30.4%
3	uk	11	8.1%
4	talk	8	5.9%
5	can	6	4.4%
6	misc	5	3.7%
7	sci	4	3.0%
8	soc	2	1.5%
9	fr	1	0.7%
10	linux	1	0.7%
11	rec	1	0.7%
12	aus	1	0.7%
TOTAL: 12		135	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	24
2	alt.atheism	20
3	comp.lang.javascript	15
4	alt.society.liberalism	14
5	uk.legal	6
6	can.politics	6
7	misc.survivalism	5
8	uk.politics.misc	5
9	talk.politics.guns	5
10	alt.comp.freeware	4
11	alt.comp.os.windows-10	4
12	alt.christnet.christianlife	4
13	talk.politics.misc	3
14	alt.california	3
15	alt.home.repair	3
16	soc.culture.usa	2
17	sci.math	2
18	alt.fan.rush-limbaugh	1
19	sci.military.naval	1
20	linux.kernel	1
21	rec.arts.tv	1
22	fr.soc.politique	1
23	comp.programming	1
24	aus.politics	1
25	sci.logic	1
26	comp.sys.mac.system	1
27	alt.support.sleep-disorder	1
TOTAL: 27		135

Daily Usenet report for csiph.com

Feb 4 04:15:00 -- Feb 5 04:15:00