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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 4 10:50:03 csiph pgpverify[82893]: pgpverify: verification failed
Feb 4 23:44:37 csiph controlchan[65631]: skipping newgroup free.binaries.3d.daz-studio y user@domain.com (would have a new description): Empty description

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50815	45.0%	9.8 MB	62.1%
nnrpd	34746	30.8%	2.9 MB	18.5%
innd	24021	21.3%	2.5 MB	15.9%
innfeed	3208	2.8%	553.2 KB	3.4%
nocem	52	0.0%	5.0 KB	0.0%
controlchan	7	0.0%	1.0 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	112850	100.0%	15.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	596337	57.4%
Negative hits	390940	37.6%
Do not exist	49773	4.8%
Cache misses	1630	0.2%
TOTAL: 4	1038680	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.574	0.0%	181	0.000	3.171	126.000
article cleanup	00:00:00.336	0.0%	49333	0.000	0.007	0.027
article logging	00:00:00.751	0.0%	49935	0.000	0.015	0.048
article parse	00:00:04.236	0.0%	209250	0.003	0.020	0.137
article write	00:00:53.233	0.1%	28665	0.233	1.857	8.056
artlog/artcncl	00:00:00.003	0.0%	166	0.000	0.018	0.100
artlog/artparse	00:00:00.007	0.0%	730	0.000	0.010	0.167
data move	00:00:00.347	0.0%	1013193	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.055	0.0%	180	0.000	0.306	5.000
hishave/artcncl	00:00:00.001	0.0%	181	0.000	0.006	0.077
hishave/artparse	00:00:00.000	0.0%	256	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:28.388	0.0%	1042424	0.001	0.027	0.152
history sync	00:00:03.306	0.0%	349	0.000	9.473	121.667
history write	00:02:23.902	0.2%	49755	0.061	2.892	21.495
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.934	0.0%	256	0.000	3.648	66.000
idle	23:51:17.375	99.3%	1033127	47.685	83.124	238.323
nntp read	00:00:03.363	0.0%	1046404	0.002	0.003	0.006
overview write	00:02:13.306	0.2%	28665	0.847	4.650	25.838
perl filter	00:02:10.649	0.2%	49260	1.610	2.652	4.728
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.491	0.0%	57380	0.000	0.009	0.028
TOTAL: 24:00:47.288	23:59:41.257	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	34300	0.000	0.002	0.019
article prepare	00:00:00.006	0.0%	32952	0.000	0.000	0.015
article read	00:00:00.623	0.0%	3982	0.000	0.156	7.667
backlog stats	00:00:00.331	0.0%	670127	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.459	0.0%	486275	0.001	0.005	0.009
data write	00:00:03.212	0.0%	470224	0.004	0.007	0.012
idle	23:52:45.203	100.0%	670127	66.437	128.282	320.297
status file	00:00:00.216	0.0%	307	0.000	0.704	1.000
TOTAL: 23:53:04.857	23:52:52.135	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:14.184	0.0%	18380	0.000	0.772	1.021
idle	90:35:06.126	100.0%	52393	0.000	6224.231	600187.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.567	0.0%	105858	0.000	0.005	0.056
readart	00:00:00.198	0.0%	102	0.000	1.941	71.000
TOTAL: 90:36:58.946	90:35:21.075	100.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	Bad PGP	DoIt	OK
admin@openwatcom.news-admin.org	0	1	0	0
user@domain.com	2	0	2	2
TOTAL	2	1	2	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	34680	6297	25657	2726	18%	23:44:56
2	csiph-out.feeder.erje.net	1152	110429	4475	103065	2889	4%	94:57:52
3	feeder.eternal-september.org	5	43764	4029	37531	2204	9%	24:10:56
4	csiph-out.news.weretis.net	3	57527	2927	54090	510	5%	44:16:32
5	news-out.aioe.org	3	27746	2653	25053	40	9%	24:09:58
6	news-out.mixmin.net	2	32680	2607	28174	1899	7%	24:08:52
7	newsfeed.neva.ru	150	63320	2140	55633	5547	3%	71:45:57
8	newsfeed.fu-berlin.de	64	27620	1125	26295	200	4%	29:32:37
9	newsfeeds.xmission.com	1	30300	509	29778	13	1%	24:56:08
10	news.freedyn.net	12	35358	459	34715	184	1%	22:07:32
11	news.fcku.it	1	6801	395	6402	4	5%	18:19:17
12	news.redatomik.org	1	37261	371	36881	9	0%	30:10:43
13	xanadu-bbs.net	2	47887	216	42659	5012	0%	24:44:59
14	news.swapon.de	3	2481	181	2294	6	7%	24:30:55
15	au2pb.net	1	35933	147	35756	30	0%	26:13:07
16	nntp.pasdenom.info	4	29521	21	29490	10	0%	24:08:22
17	localhost	32	0	0	0	0	0%	00:00:00
18	news.bbs.geek.nz	1	31374	0	31373	1	0%	23:59:45
TOTAL: 18		1725	654682	28552	604846	21284	4%	555:58:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	18.1 MB	2.0 KB	33.0 MB	51.1 MB	35%	5.8 KB
2	csiph-out.feeder.erje.net	16.3 MB	83.3 KB	12.3 MB	28.7 MB	56%	4.0 KB
3	feeder.eternal-september.org	10.1 MB	16.7 KB	25.8 MB	35.9 MB	28%	5.9 KB
4	news-out.mixmin.net	8.9 MB	6.2 KB	2.9 MB	11.8 MB	75%	2.7 KB
5	csiph-out.news.weretis.net	8.6 MB	5.0 KB	11.5 MB	20.1 MB	42%	6.0 KB
6	news-out.aioe.org	6.5 MB	2.6 KB	118.3 KB	6.6 MB	98%	2.5 KB
7	newsfeed.neva.ru	5.5 MB	83.7 KB	38.4 MB	44.0 MB	12%	5.9 KB
8	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	522.1 KB	3.2 MB	84%	2.5 KB
9	newsfeeds.xmission.com	2.1 MB	0.0 KB	562.4 KB	2.6 MB	79%	5.2 KB
10	news.freedyn.net	1.9 MB	24.8 KB	1.7 MB	3.7 MB	52%	5.9 KB
11	news.redatomik.org	889.8 KB	0.0 KB	78.3 KB	968.2 KB	91%	2.5 KB
12	news.fcku.it	669.8 KB	0.0 KB	9.5 KB	679.3 KB	98%	1.7 KB
13	xanadu-bbs.net	529.4 KB	62.2 KB	49.1 MB	49.6 MB	1%	9.7 KB
14	news.swapon.de	497.1 KB	0.0 KB	156.0 KB	653.1 KB	76%	3.5 KB
15	au2pb.net	378.5 KB	1.9 KB	140.3 KB	520.6 KB	72%	2.9 KB
16	nntp.pasdenom.info	53.4 KB	0.0 KB	271.5 KB	324.9 KB	16%	10.5 KB
17	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	news.bbs.geek.nz	0.0 KB	0.0 KB	1.1 KB	1.1 KB	0%	1.1 KB
TOTAL: 18		83.6 MB	288.3 KB	176.6 MB	260.5 MB	32%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 4 04:15:00 - 04:59:59	378	1.3%	0.14	1.0 MB	1.2%	0.40
Feb 4 05:00:00 - 05:59:59	628	2.2%	0.17	2.8 MB	3.3%	0.79
Feb 4 06:00:00 - 06:59:59	563	2.0%	0.16	1.8 MB	2.2%	0.52
Feb 4 07:00:00 - 07:59:59	930	3.2%	0.26	2.9 MB	3.5%	0.83
Feb 4 08:00:00 - 08:59:59	1365	4.8%	0.38	4.1 MB	4.9%	1.16
Feb 4 09:00:00 - 09:59:59	1394	4.9%	0.39	3.6 MB	4.3%	1.03
Feb 4 10:00:00 - 10:59:59	1319	4.6%	0.37	3.5 MB	4.2%	1.01
Feb 4 11:00:00 - 11:59:59	1504	5.2%	0.42	4.1 MB	4.9%	1.17
Feb 4 12:00:00 - 12:59:59	1331	4.6%	0.37	3.8 MB	4.5%	1.08
Feb 4 13:00:00 - 13:59:59	1445	5.0%	0.40	3.8 MB	4.6%	1.09
Feb 4 14:00:00 - 14:59:59	1449	5.1%	0.40	4.1 MB	4.8%	1.15
Feb 4 15:00:00 - 15:59:59	1680	5.9%	0.47	5.0 MB	5.9%	1.41
Feb 4 16:00:00 - 16:59:59	1639	5.7%	0.46	4.8 MB	5.8%	1.37
Feb 4 17:00:00 - 17:59:59	1723	6.0%	0.48	5.0 MB	6.0%	1.43
Feb 4 18:00:00 - 18:59:59	1618	5.6%	0.45	4.7 MB	5.6%	1.33
Feb 4 19:00:00 - 19:59:59	1574	5.5%	0.44	4.6 MB	5.5%	1.32
Feb 4 20:00:00 - 20:59:59	1306	4.6%	0.36	3.5 MB	4.2%	1.01
Feb 4 21:00:00 - 21:59:59	1556	5.4%	0.43	4.1 MB	4.9%	1.17
Feb 4 22:00:00 - 22:59:59	1254	4.4%	0.35	3.7 MB	4.4%	1.05
Feb 4 23:00:00 - 23:59:59	1160	4.0%	0.32	3.5 MB	4.2%	1.00
Feb 5 00:00:00 - 00:59:59	935	3.3%	0.26	3.1 MB	3.7%	0.87
Feb 5 01:00:00 - 01:59:59	640	2.2%	0.18	2.0 MB	2.3%	0.56
Feb 5 02:00:00 - 02:59:59	578	2.0%	0.16	1.7 MB	2.1%	0.49
Feb 5 03:00:00 - 03:59:59	520	1.8%	0.14	1.6 MB	1.9%	0.45
Feb 5 04:00:00 - 04:15:00	176	0.6%	0.20	863.6 KB	1.0%	0.96
TOTAL: 24:00:00	28665	100.0%	0.33	83.7 MB	100.0%	0.99

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	5561	1569	22	0	3970
2	xanadu-bbs.net	5002	14	5	0	4983
3	csiph-out.feeder.erje.net	2935	1090	14	1	1830
4	usenet.blueworldhosting.com	2724	1035	1	1	1687
5	feeder.eternal-september.org	2251	204	3	0	2044
6	news-out.mixmin.net	1907	2	2	0	1903
7	newsfeed.fu-berlin.de	671	41	0	0	630
8	csiph-out.news.weretis.net	565	311	1	0	253
9	news.freedyn.net	187	1	2	0	184
10	news-out.aioe.org	58	34	1	0	23
11	news.swapon.de	49	0	0	0	49
12	au2pb.net	28	19	1	0	8
13	newsfeeds.xmission.com	17	10	0	0	7
14	nntp.pasdenom.info	11	5	0	0	6
15	news.fcku.it	9	0	0	0	9
16	news.redatomik.org	8	0	0	0	8
17	news.bbs.geek.nz	1	1	0	0	0
TOTAL: 17		21984	4336	52	2	17594

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	999
mailing.freebsd.pkg-fallout	284
ut.test	219
lada.talk	142
mailing.freebsd.ports-bugs	137
mailing.freebsd.svn-ports-all	135
mailing.freebsd.svn-ports-head	129
alt.politics.scorched-earth	115
mailing.openssl.dev	106
lada.auto	97
mozilla.dev.tree-alerts	79
mailing.openssl.cvs	73
it-alt.sport.calcio.juventus	48
mailing.freebsd.svn-src-all	45
fa.freebsd.cvs-all	42
mailing.gcc.gcc-cvs	41
mailing.freebsd.svn-src-head	40
mailing.database.pgsql-general	39
gmane.linux.kernel	37
alt.survival	32
TOTAL: 261	4336

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

Groups	Count
alt.culture.china	1
it.politica.m5s	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11947
EMP (phn path)	2591
EMP (phl)	889
PFM bot misc	223
EMP (phn nph)	174
Too many newsgroups (meow)	59
Too many newsgroups	49
Scoring filter (25)	38
Scoring filter (18)	29
HTML Multipart	26
Binary Payload	19
User-issued cancel	18
Scoring filter (20)	16
Scoring filter (26)	14
Scoring filter (28)	14
Scoring filter (10)	13
HTML Image Tags	11
Scoring filter (19)	11
Scoring filter (16)	9
Scoring filter (27)	9
TOTAL: 42	16241

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	15
	usenet.blueworldhosting.com	5
	TOTAL: 2	20
Bad Message-ID's offered
	csiph-out.feeder.erje.net	3
	usenet.blueworldhosting.com	1
	TOTAL: 2	4
Huge articles
	usenet.blueworldhosting.com	96
	csiph-out.news.weretis.net	77
	feeder.eternal-september.org	32
	xanadu-bbs.net	30
	news.freedyn.net	7
	newsfeeds.xmission.com	6
	nntp.pasdenom.info	4
	news-out.mixmin.net	3
	newsfeed.fu-berlin.de	1
	news.swapon.de	1
	TOTAL: 10	257
Including strange strings
	newsfeed.fu-berlin.de	407
	csiph-out.feeder.erje.net	46
	news.swapon.de	43
	csiph-out.news.weretis.net	35
	news-out.mixmin.net	21
	feeder.eternal-september.org	18
	news-out.aioe.org	17
	newsfeed.neva.ru	5
	news.fcku.it	4
	au2pb.net	2
	TOTAL: 12	601
No colon-space in header
	csiph-out.feeder.erje.net	432
	TOTAL: 1	432
TOTAL: 5		1314

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	31896	1448	26684	73	5231	4%	23:59:52
2	xanadu-bbs	31210	1258	27189	4	485	4%	23:59:53
3	fu-berlin.de	27010	1002	26008	0	0	3%	23:59:51
4	news.bbs.geek.nz	52890	965	26612	13	0	1%	23:59:45
5	aioe	26911	574	22603	62	0	2%	23:59:52
6	usenet.pasdenom.info	36913	306	26755	1583	4	0%	23:59:52
7	news.redatomik.org	35170	215	27134	10	0	0%	23:59:52
8	au2pb	28295	158	27294	18	0	0%	23:59:53
9	neva.ru	30928	29	24856	243	0	0%	23:59:52
10	news.mixmin.net	28651	15	24650	0	0	0%	23:59:51
11	eternal-september	28996	15	23044	0	0	0%	23:59:52
12	xmission	33121	14	26987	1	0	0%	23:59:53
13	news.fcku.it	4164	11	3573	0	0	0%	23:25:36
14	erje	27199	9	23855	203	3	0%	23:59:43
15	weretis.net	24610	8	24081	3	0	0%	23:59:44
16	news.swapon.de	2697	1	2398	1	0	0%	23:55:27
17	bwh	22350	0	21191	0	0	0%	23:59:44
TOTAL: 17		473011	6028	384914	2214	5723	1%	407:18:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.1 MB	260.7 KB	9.4 MB	0.1 KB/s	6.3 KB	23:59:52
2	xanadu-bbs	8.5 MB	16.0 KB	8.6 MB	0.1 KB/s	6.9 KB	23:59:53
3	fu-berlin.de	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	7.8 KB	23:59:51
4	news.bbs.geek.nz	2.8 MB	63.4 KB	2.8 MB	0.0 KB/s	3.0 KB	23:59:45
5	aioe	1.7 MB	183.3 KB	1.9 MB	0.0 KB/s	3.1 KB	23:59:52
6	usenet.pasdenom.info	1.1 MB	8.6 MB	9.7 MB	0.1 KB/s	5.2 KB	23:59:52
7	news.redatomik.org	697.6 KB	33.5 KB	731.1 KB	0.0 KB/s	3.2 KB	23:59:52
8	au2pb	683.0 KB	167.6 KB	850.6 KB	0.0 KB/s	4.8 KB	23:59:53
9	neva.ru	106.7 KB	840.2 KB	946.9 KB	0.0 KB/s	3.5 KB	23:59:52
10	xmission	49.1 KB	1.7 KB	50.8 KB	0.0 KB/s	3.4 KB	23:59:53
11	news.mixmin.net	39.6 KB	0.0 KB	39.6 KB	0.0 KB/s	2.6 KB	23:59:51
12	eternal-september	32.8 KB	0.0 KB	32.8 KB	0.0 KB/s	2.2 KB	23:59:52
13	news.fcku.it	21.6 KB	0.0 KB	21.6 KB	0.0 KB/s	2.0 KB	23:25:36
14	erje	20.3 KB	583.3 KB	603.5 KB	0.0 KB/s	2.8 KB	23:59:43
15	weretis.net	20.1 KB	33.0 KB	53.0 KB	0.0 KB/s	4.8 KB	23:59:44
16	news.swapon.de	1.6 KB	3.6 KB	5.2 KB	0.0 KB/s	2.6 KB	23:55:27
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:44
TOTAL: 17		32.5 MB	10.7 MB	43.3 MB	0.0 KB/s	5.4 KB	407:18:32

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	91	239.7 KB	85	04:25:06
2	tempe0.bbox.io	132	7	13.4 KB	4	24:34:17
3	challenger.nielsenmedia.com	4	2	2.5 KB	2	01:00:24
4	pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	1	1.0 KB	1	00:30:25
5	138.108.60.60	1	1	1.5 KB	1	00:30:35
TOTAL: 5		427	102	258.0 KB	93	90:36:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	311	92	241.2 KB	86	49:49:55
2	*.bbox.io	132	7	13.4 KB	4	24:34:17
3	*.nielsenmedia.com	4	2	2.5 KB	2	01:00:24
4	*.tampfl.dsl-w.verizon.net	2	1	1.0 KB	1	00:30:25
5	*.chello.nl	25	0	0.0 KB	0	10:40:00
6	*.ip-188-165-239.eu	3	0	0.0 KB	0	00:01:28
7	*.dynamic.caiway.nl	8	0	0.0 KB	0	04:00:21
TOTAL: 7		485	102	258.0 KB	93	90:36:53

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	1.082	0.829	0.000	35:08:16
tempe0.bbox.io	11.170	2.314	0.000	24:34:17
dhcp-089-099-203-254.chello.nl	2.081	0.433	0.000	10:40:00
71.94.21.184	0.635	0.549	0.000	09:45:45
202.89.176.220	29.453	7.013	0.725	04:25:06
041-020-158-163.dynamic.caiway.nl	0.729	0.237	0.000	04:00:21
challenger.nielsenmedia.com	0.331	0.085	0.000	01:00:24
138.108.60.60	0.148	0.039	0.000	00:30:35
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.219	0.055	0.000	00:30:25
ns342002.ip-188-165-239.eu	0.218	0.063	0.000	00:01:28
104.233.75.49	0.108	0.023	0.000	00:00:10
TOTAL: 11	46.174	11.640	0.725	90:36:53

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	25
162.212.88.221	16
041-020-158-163.dynamic.caiway.nl	8
71.94.21.184	5
ns342002.ip-188-165-239.eu	3
104.233.75.49	1
TOTAL: 6	58

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	5
TOTAL: 1	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	20	0
tempe0.bbox.io	9	0
041-020-158-163.dynamic.caiway.nl	8	0
challenger.nielsenmedia.com	2	0
138.108.60.60	1	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 6	41	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	97	95.1%
2	news	5	4.9%
TOTAL: 2		102	100.0%

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

Newsgroup		Count
1	comp.arch	30
2	comp.os.vms	17
3	comp.arch.embedded	13
4	comp.sys.ibm.pc.games.action	13
5	comp.ai.neural-nets	4
6	comp.lang.awk	4
7	news.software.nntp	4
8	comp.os.os2.apps	3
9	comp.os.msdos.4dos	2
10	comp.sys.ibm.pc.hardware.storage	2
11	comp.sys.ibm.ps2.hardware	2
12	comp.answers	1
13	comp.ai.nat-lang	1
14	news.admin.peering	1
15	comp.os.os2.announce	1
16	comp.os.cpm	1
17	comp.arch.fpga	1
18	comp.ai.genetic	1
19	comp.unix.aix	1
TOTAL: 19		102

Daily Usenet report for csiph.com

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