Daily Usenet report for csiph.com: Dec 22 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 22 07:30:02 csiph pgpverify[92366]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52149	46.7%	7.8 MB	58.7%
nnrpd	31939	28.6%	2.6 MB	19.6%
innd	24928	22.3%	2.5 MB	18.6%
innfeed	2549	2.3%	420.5 KB	3.1%
controlchan	6	0.0%	0.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	111572	100.0%	13.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	330646	53.4%
Negative hits	237197	38.3%
Do not exist	50937	8.2%
Cache misses	376	0.1%
TOTAL: 4	619156	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.335	0.0%	186	0.000	1.801	47.000
article cleanup	00:00:00.308	0.0%	50118	0.000	0.006	0.022
article logging	00:00:00.649	0.0%	50609	0.000	0.013	0.039
article parse	00:00:03.314	0.0%	322487	0.000	0.010	0.061
article write	00:01:05.992	0.1%	27325	0.156	2.415	9.873
artlog/artcncl	00:00:00.002	0.0%	151	0.000	0.013	1.000
artlog/artparse	00:00:00.007	0.0%	935	0.000	0.007	1.000
data move	00:00:00.295	0.0%	739283	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.048	0.0%	184	0.000	0.261	4.500
hishave/artcncl	00:00:00.001	0.0%	186	0.000	0.005	0.056
hishave/artparse	00:00:00.003	0.0%	833	0.000	0.004	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.048	0.0%	619472	0.001	0.005	0.078
history sync	00:00:01.652	0.0%	345	0.000	4.788	58.000
history write	00:00:40.679	0.0%	50201	0.092	0.810	6.108
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.714	0.0%	830	0.000	0.860	26.000
idle	23:45:16.493	99.6%	766968	15.459	111.499	442.723
nntp read	00:00:02.521	0.0%	771631	0.001	0.003	0.006
overview write	00:01:03.128	0.1%	27325	0.407	2.310	16.016
perl filter	00:01:58.727	0.1%	50094	0.928	2.370	4.638
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.465	0.0%	54651	0.000	0.009	0.029
TOTAL: 23:50:54.141	23:50:18.381	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.077	0.0%	27350	0.000	0.003	0.031
article prepare	00:00:00.003	0.0%	6952	0.000	0.000	0.036
article read	00:00:01.014	0.0%	1351	0.000	0.751	50.267
backlog stats	00:00:00.121	0.0%	347200	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.473	0.0%	266958	0.002	0.006	0.013
data write	00:00:01.825	0.0%	244995	0.002	0.007	0.019
idle	23:43:09.492	100.0%	347200	142.575	245.937	690.613
status file	00:00:00.159	0.0%	313	0.000	0.508	3.000
TOTAL: 23:43:18.737	23:43:14.164	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.035	0.0%	1595	0.000	0.022	0.034
idle	23:09:46.211	99.9%	32999	0.000	2526.931	600169.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.464	0.0%	66893	0.000	0.007	0.975
readart	00:00:00.210	0.0%	72	0.000	2.917	78.000
TOTAL: 23:10:55.245	23:09:46.920	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
shutdown	6
TOTAL: 11	172

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ba-mod@panix.com	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	usenet.blueworldhosting.com	740	49465	7248	28571	13646	14%	23:37:32
2	feeder.eternal-september.org	12	46082	5811	37927	2344	12%	24:21:55
3	news-out.mixmin.net	5	31211	4343	25487	1381	13%	24:15:53
4	au2pb.net	2	28265	3816	24053	396	13%	23:39:40
5	newsfeed.neva.ru	143	83908	2339	77783	3786	2%	71:29:26
6	newsfeed.fu-berlin.de	60	31853	1390	30333	130	4%	35:52:44
7	newsfeeds.xmission.com	3	26845	803	25979	63	2%	23:43:36
8	news.fcku.it	1	6435	742	5634	59	11%	16:25:54
9	xanadu-bbs.net	6	47931	684	45379	1868	1%	22:50:18
10	nntp.pasdenom.info	5	26082	133	25901	48	0%	24:05:37
11	news.swapon.de	3	380	26	354	0	6%	07:24:22
12	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 12		1006	378457	27335	327401	23721	7%	297:46:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.8 MB	11.2 KB	142.2 MB	161.9 MB	12%	7.9 KB
2	feeder.eternal-september.org	16.9 MB	117.4 KB	28.6 MB	45.6 MB	37%	5.7 KB
3	news-out.mixmin.net	13.2 MB	0.0 KB	2.5 MB	15.7 MB	84%	2.8 KB
4	au2pb.net	8.9 MB	0.0 KB	1.6 MB	10.5 MB	85%	2.5 KB
5	newsfeed.neva.ru	6.8 MB	23.4 KB	23.9 MB	30.7 MB	22%	5.1 KB
6	newsfeed.fu-berlin.de	3.0 MB	9.4 KB	219.1 KB	3.2 MB	93%	2.2 KB
7	newsfeeds.xmission.com	2.7 MB	0.0 KB	787.2 KB	3.4 MB	77%	4.1 KB
8	xanadu-bbs.net	2.4 MB	46.6 KB	197.0 MB	199.5 MB	1%	80.0 KB
9	news.fcku.it	1.4 MB	0.0 KB	212.0 KB	1.6 MB	86%	2.0 KB
10	nntp.pasdenom.info	305.9 KB	0.0 KB	225.6 KB	531.4 KB	57%	2.9 KB
11	news.swapon.de	68.3 KB	0.0 KB	0.0 KB	68.3 KB	100%	2.6 KB
12	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 12		75.4 MB	207.9 KB	397.0 MB	472.7 MB	15%	9.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 22 04:15:00 - 04:59:59	437	1.6%	0.16	1.4 MB	1.8%	0.51
Dec 22 05:00:00 - 05:59:59	495	1.8%	0.14	1.7 MB	2.2%	0.48
Dec 22 06:00:00 - 06:59:59	628	2.3%	0.17	1.7 MB	2.2%	0.49
Dec 22 07:00:00 - 07:59:59	903	3.3%	0.25	2.6 MB	3.4%	0.74
Dec 22 08:00:00 - 08:59:59	1029	3.7%	0.29	2.8 MB	3.7%	0.80
Dec 22 09:00:00 - 09:59:59	1632	5.9%	0.45	6.7 MB	8.7%	1.91
Dec 22 10:00:00 - 10:59:59	1447	5.2%	0.40	3.4 MB	4.4%	0.96
Dec 22 11:00:00 - 11:59:59	1252	4.5%	0.35	3.2 MB	4.2%	0.92
Dec 22 12:00:00 - 12:59:59	1305	4.7%	0.36	3.3 MB	4.3%	0.93
Dec 22 13:00:00 - 13:59:59	1291	4.7%	0.36	3.3 MB	4.3%	0.95
Dec 22 14:00:00 - 14:59:59	1410	5.1%	0.39	3.4 MB	4.5%	0.98
Dec 22 15:00:00 - 15:59:59	1402	5.1%	0.39	3.6 MB	4.6%	1.02
Dec 22 16:00:00 - 16:59:59	1680	6.1%	0.47	4.4 MB	5.8%	1.26
Dec 22 17:00:00 - 17:59:59	1635	5.9%	0.45	4.6 MB	6.0%	1.32
Dec 22 18:00:00 - 18:59:59	1640	5.9%	0.46	4.4 MB	5.7%	1.25
Dec 22 19:00:00 - 19:59:59	1425	5.2%	0.40	3.8 MB	5.0%	1.09
Dec 22 20:00:00 - 20:59:59	1557	5.6%	0.43	4.0 MB	5.2%	1.14
Dec 22 21:00:00 - 21:59:59	1367	5.0%	0.38	3.3 MB	4.2%	0.93
Dec 22 22:00:00 - 22:59:59	1213	4.4%	0.34	3.2 MB	4.2%	0.91
Dec 22 23:00:00 - 23:59:59	940	3.4%	0.26	2.5 MB	3.2%	0.71
Dec 23 00:00:00 - 00:59:59	787	2.9%	0.22	2.2 MB	2.8%	0.61
Dec 23 01:00:00 - 01:59:59	808	2.9%	0.22	2.6 MB	3.4%	0.73
Dec 23 02:00:00 - 02:59:59	602	2.2%	0.17	1.8 MB	2.4%	0.53
Dec 23 03:00:00 - 03:59:59	537	1.9%	0.15	1.9 MB	2.4%	0.53
Dec 23 04:00:00 - 04:15:00	190	0.7%	0.21	1.1 MB	1.4%	1.25
TOTAL: 24:00:00	27612	100.0%	0.32	76.9 MB	100.0%	0.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	usenet.blueworldhosting.com	13735	1108	1	1	0	12625
2	newsfeed.neva.ru	3820	1478	2	0	0	2340
3	feeder.eternal-september.org	2416	215	10	0	0	2191
4	xanadu-bbs.net	1896	117	2	0	0	1777
5	news-out.mixmin.net	1401	3	0	0	0	1398
6	newsfeed.fu-berlin.de	508	65	4	0	0	439
7	au2pb.net	409	391	0	0	1	17
8	news.fcku.it	69	0	0	0	0	69
9	newsfeeds.xmission.com	66	51	0	0	0	15
10	nntp.pasdenom.info	48	46	0	0	0	2
11	news.swapon.de	18	0	0	0	0	18
TOTAL: 11		24386	3474	19	1	1	20891

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	702
lada.talk	237
ut.test	193
lada.auto	132
mailing.freebsd.ports-bugs	89
mpc.lists.freebsd.ports.bugs	89
mailing.freebsd.svn-ports-all	71
mailing.freebsd.pkg-fallout	67
gmane.linux.alsa.devel	67
mailing.freebsd.svn-ports-head	66
it-alt.sport.calcio.juventus	53
mailing.openbsd.misc	53
mailing.openbsd.source-changes	51
mozilla.dev.tree-alerts	50
fa.openbsd.source-changes	50
fa.freebsd.cvs-all	50
mailing.freebsd.svn-src-all	50
spline.eisfair	44
mailing.freebsd.svn-src-head	42
pld.cvs.commit	37
TOTAL: 260	3474

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

Groups	Count
alt.assassination.jfk	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13842
EMP (phn path)	4495
EMP (phl)	874
PFM bot misc	141
Too many newsgroups	37
HTML Multipart	35
Binary: misplaced par2	9
HTML post	7
Too many newsgroups (low_xpost)	2
Binary: misplaced nfo	1
Binary: misplaced rar	1
Angle-bracket bot	1
Binary: misplaced mp4	1
TOTAL: 13	19446

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	usenet.blueworldhosting.com	4
	TOTAL: 1	4
Huge articles
	xanadu-bbs.net	647
	usenet.blueworldhosting.com	110
	feeder.eternal-september.org	65
	newsfeeds.xmission.com	10
	news-out.mixmin.net	5
	au2pb.net	4
	news.fcku.it	1
	nntp.pasdenom.info	1
	TOTAL: 8	843
Including strange strings
	newsfeed.fu-berlin.de	384
	news-out.mixmin.net	28
	feeder.eternal-september.org	25
	news.swapon.de	18
	news.fcku.it	10
	usenet.blueworldhosting.com	9
	au2pb.net	9
	newsfeed.neva.ru	6
	newsfeeds.xmission.com	5
	xanadu-bbs.net	2
	TOTAL: 10	496
No colon-space in header
	usenet.blueworldhosting.com	102
	TOTAL: 1	102
TOTAL: 4		1445

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	33213	269	26373	836	15	0%	23:58:31
2	xanadu-bbs	28572	125	26669	100	32	0%	23:58:31
3	au2pb	24226	79	23563	100	0	0%	23:58:31
4	xmission	31961	32	26320	4	0	0%	23:58:31
5	fu-berlin.de	25894	28	25832	33	1	0%	23:58:31
6	neva.ru	28134	28	24785	125	1	0%	23:58:31
7	eternal-september	24336	20	21030	4	1	0%	23:58:31
8	news.mixmin.net	26626	12	23182	3	1	0%	23:58:31
9	news.swapon.de	2156	2	2070	6	0	0%	23:54:48
10	news.fcku.it	2516	2	2258	0	138	0%	18:09:14
11	bwh	20301	0	19980	2	0	0%	23:58:31
TOTAL: 11		247935	597	222062	1213	189	0%	257:50:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1014.6 KB	2.5 MB	3.5 MB	3.2 KB	23:58:31
2	xanadu-bbs	740.5 KB	195.8 KB	936.3 KB	4.2 KB	23:58:31
3	au2pb	389.6 KB	338.3 KB	727.9 KB	4.1 KB	23:58:31
4	neva.ru	213.0 KB	730.2 KB	943.2 KB	6.2 KB	23:58:31
5	fu-berlin.de	159.9 KB	43.1 KB	203.0 KB	3.3 KB	23:58:31
6	eternal-september	142.8 KB	4.5 KB	147.2 KB	6.1 KB	23:58:31
7	xmission	114.9 KB	28.8 KB	143.7 KB	4.0 KB	23:58:31
8	news.mixmin.net	43.6 KB	37.7 KB	81.3 KB	5.4 KB	23:58:31
9	news.swapon.de	4.6 KB	14.4 KB	19.0 KB	2.4 KB	23:54:48
10	news.fcku.it	4.2 KB	0.0 KB	4.2 KB	2.1 KB	18:09:14
11	bwh	0.0 KB	3.9 KB	3.9 KB	1.9 KB	23:58:31
TOTAL: 11		2.8 MB	3.8 MB	6.6 MB	3.7 KB	257:50:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	289	64	156.5 KB	62	04:35:27
2	tempe0.bbox.io	26	8	14.3 KB	2	05:28:20
TOTAL: 2		315	72	170.7 KB	64	23:10:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	318	64	156.5 KB	62	08:17:04
2	*.bbox.io	26	8	14.3 KB	2	05:28:20
3	*.chello.nl	12	0	0.0 KB	0	05:13:25
4	*.136.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:03:58
5	*.ip-188-165-239.eu	2	0	0.0 KB	0	00:00:15
6	*.hsd1.nj.comcast.net	8	0	0.0 KB	0	03:07:45
TOTAL: 6		368	72	170.7 KB	64	23:10:51

NNRP auth users [Top 20]:

User	Conn
moussa	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tempe0.bbox.io	2.290	0.403	0.000	05:28:20
dhcp-089-099-203-254.chello.nl	1.057	0.158	0.000	05:13:25
202.89.176.220	29.145	5.982	0.727	04:35:27
162.212.88.221	0.365	0.106	0.000	03:41:35
c-73-195-247-24.hsd1.nj.comcast.net	0.696	0.126	0.000	03:07:45
h71.136.22.98.dynamic.ip.windstream.net	0.162	0.035	0.000	01:03:58
ns342002.ip-188-165-239.eu	0.193	0.032	0.000	00:00:15
69.160.37.54	1.922	0.354	0.000	00:00:02
TOTAL: 8	35.830	7.196	0.727	23:10:51

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	12
c-73-195-247-24.hsd1.nj.comcast.net	8
162.212.88.221	4
ns342002.ip-188-165-239.eu	2
h71.136.22.98.dynamic.ip.windstream.net	2
TOTAL: 6	53

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	10	0
tempe0.bbox.io	9	0
c-73-195-247-24.hsd1.nj.comcast.net	6	0
h71.136.22.98.dynamic.ip.windstream.net	2	0
TOTAL: 4	27	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	71	98.6%
2	news	1	1.4%
TOTAL: 2		72	100.0%

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

Newsgroup		Count
1	comp.arch	18
2	comp.arch.embedded	8
3	comp.sys.ibm.pc.games.action	8
4	comp.unix.aix	8
5	comp.sys.ibm.pc.games.war-historical	7
6	comp.ai.philosophy	6
7	comp.os.os2.apps	5
8	comp.ai.neural-nets	3
9	comp.os.vms	2
10	comp.sys.ibm.pc.hardware.storage	2
11	comp.bbs.misc	1
12	comp.ai.shells	1
13	news.admin.peering	1
14	comp.ai.fuzzy	1
15	comp.ai.nat-lang	1
TOTAL: 15		72

Daily Usenet report for csiph.com

Dec 22 04:15:00 -- Dec 23 04:15:00