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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 4 09:50:04 csiph pgpverify[15653]: pgpverify: verification failed
Sep 5 02:19:55 csiph controlchan[1730]: skipping newgroup alt.binaries.666 y 153fishare@gmail.com (would be created): All-numeric name component

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50143	47.4%	7.2 MB	58.6%
nnrpd	32922	31.1%	2.7 MB	22.4%
innd	20886	19.7%	2.0 MB	16.6%
innfeed	1862	1.8%	308.3 KB	2.5%
controlchan	6	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	105820	100.0%	12.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	262427	46.0%
Negative hits	253335	44.4%
Do not exist	49269	8.6%
Cache misses	5936	1.0%
TOTAL: 4	570967	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.442	0.0%	187	0.000	2.364	100.000
article cleanup	00:00:00.275	0.0%	48811	0.000	0.006	0.025
article logging	00:00:00.588	0.0%	49249	0.000	0.012	0.049
article parse	00:00:02.127	0.0%	253152	0.002	0.008	0.017
article write	00:00:06.442	0.0%	26862	0.145	0.240	0.431
artlog/artcncl	00:00:00.001	0.0%	171	0.000	0.006	0.500
artlog/artparse	00:00:00.007	0.0%	751	0.000	0.009	0.059
data move	00:00:00.303	0.0%	723252	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.004	0.0%	186	0.000	0.022	1.000
hishave/artcncl	00:00:00.000	0.0%	187	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	751	0.000	0.004	0.042
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.393	0.0%	572932	0.000	0.002	0.009
history sync	00:00:00.517	0.0%	310	0.000	1.668	3.000
history write	00:00:12.380	0.0%	48783	0.056	0.254	0.330
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.193	0.0%	749	0.000	0.258	3.000
idle	23:57:50.973	99.8%	718042	24.615	120.148	432.459
nntp read	00:00:02.383	0.0%	716327	0.001	0.003	0.006
overview write	00:00:04.461	0.0%	26862	0.096	0.166	0.368
perl filter	00:01:31.378	0.1%	48783	1.047	1.873	3.871
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.508	0.0%	53726	0.000	0.009	0.027
TOTAL: 24:00:29.488	23:59:54.378	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.073	0.0%	26814	0.000	0.003	0.023
article prepare	00:00:00.001	0.0%	6992	0.000	0.000	0.010
article read	00:00:00.278	0.0%	1322	0.000	0.210	22.500
backlog stats	00:00:00.095	0.0%	301048	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.244	0.0%	230325	0.001	0.005	0.016
data write	00:00:01.804	0.0%	205939	0.002	0.009	0.016
idle	23:55:01.321	100.0%	301048	116.851	286.005	817.250
status file	00:00:00.089	0.0%	180	0.000	0.494	1.000
TOTAL: 23:55:09.042	23:55:04.905	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.454	0.0%	17082	0.000	0.027	0.056
idle	79:34:56.453	100.0%	48647	1.000	5889.293	600177.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.650	0.0%	98278	0.000	0.007	0.200
readart	00:00:00.051	0.0%	53	0.000	0.962	23.500
TOTAL: 79:36:35.136	79:34:57.608	100.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	Bad PGP	DoIt	OK
153fishare@gmail.com	1	0	1	1
admin@openwatcom.news-admin.org	0	1	0	0
TOTAL	1	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	7	44764	6127	36248	2389	13%	25:16:48
2	au2pb.net	1	29443	6002	22478	963	20%	23:20:23
3	xanadu-bbs.net	1	49323	3902	39354	6067	7%	23:55:29
4	news-out.mixmin.net	4	32661	3840	26366	2455	11%	24:08:47
5	newsfeed.neva.ru	153	58113	3498	44494	10121	6%	71:20:06
6	newsfeeds.xmission.com	1	28398	1546	26775	77	5%	25:50:09
7	newsfeed.fu-berlin.de	59	31780	1272	30099	409	4%	35:00:05
8	nntp.pasdenom.info	3	25461	472	24813	176	1%	21:38:52
9	news.swapon.de	0	1932	190	1732	10	9%	16:54:39
10	localhost	48	15326	0	15326	0	0%	00:00:57
TOTAL: 10		277	317201	26849	267685	22667	8%	267:26:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	18.0 MB	13.3 KB	14.9 MB	32.9 MB	54%	4.0 KB
2	au2pb.net	14.7 MB	31.2 KB	7.2 MB	22.0 MB	66%	3.2 KB
3	news-out.mixmin.net	11.9 MB	24.0 KB	7.3 MB	19.2 MB	62%	3.1 KB
4	newsfeed.neva.ru	11.8 MB	152.1 KB	74.5 MB	86.5 MB	13%	6.5 KB
5	xanadu-bbs.net	10.4 MB	89.5 KB	113.2 MB	123.7 MB	8%	12.7 KB
6	newsfeeds.xmission.com	4.8 MB	0.0 KB	1.4 MB	6.2 MB	77%	3.9 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	1.3 MB	4.1 MB	68%	2.5 KB
8	nntp.pasdenom.info	1.1 MB	0.0 KB	634.3 KB	1.7 MB	63%	2.7 KB
9	news.swapon.de	535.0 KB	0.0 KB	60.4 KB	595.4 KB	89%	3.0 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		76.0 MB	310.1 KB	220.5 MB	296.8 MB	25%	6.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 04:15:00 - 04:59:59	443	1.7%	0.16	1.5 MB	1.9%	0.56
Sep 4 05:00:00 - 05:59:59	611	2.3%	0.17	2.0 MB	2.7%	0.58
Sep 4 06:00:00 - 06:59:59	839	3.1%	0.23	2.2 MB	2.9%	0.62
Sep 4 07:00:00 - 07:59:59	1088	4.1%	0.30	3.1 MB	4.1%	0.88
Sep 4 08:00:00 - 08:59:59	1299	4.8%	0.36	4.1 MB	5.4%	1.18
Sep 4 09:00:00 - 09:59:59	1362	5.1%	0.38	3.6 MB	4.8%	1.03
Sep 4 10:00:00 - 10:59:59	1272	4.7%	0.35	3.4 MB	4.5%	0.96
Sep 4 11:00:00 - 11:59:59	1282	4.8%	0.36	3.5 MB	4.6%	0.98
Sep 4 12:00:00 - 12:59:59	1440	5.4%	0.40	3.9 MB	5.2%	1.12
Sep 4 13:00:00 - 13:59:59	1569	5.8%	0.44	4.4 MB	5.8%	1.26
Sep 4 14:00:00 - 14:59:59	1426	5.3%	0.40	3.9 MB	5.1%	1.10
Sep 4 15:00:00 - 15:59:59	1622	6.0%	0.45	5.1 MB	6.7%	1.45
Sep 4 16:00:00 - 16:59:59	1765	6.6%	0.49	4.6 MB	6.1%	1.31
Sep 4 17:00:00 - 17:59:59	1565	5.8%	0.43	4.2 MB	5.6%	1.21
Sep 4 18:00:00 - 18:59:59	1365	5.1%	0.38	3.6 MB	4.7%	1.01
Sep 4 19:00:00 - 19:59:59	1356	5.1%	0.38	3.7 MB	4.8%	1.04
Sep 4 20:00:00 - 20:59:59	1252	4.7%	0.35	3.4 MB	4.4%	0.96
Sep 4 21:00:00 - 21:59:59	1166	4.3%	0.32	3.2 MB	4.3%	0.92
Sep 4 22:00:00 - 22:59:59	934	3.5%	0.26	2.8 MB	3.7%	0.80
Sep 4 23:00:00 - 23:59:59	690	2.6%	0.19	2.0 MB	2.7%	0.58
Sep 5 00:00:00 - 00:59:59	778	2.9%	0.22	2.3 MB	3.1%	0.66
Sep 5 01:00:00 - 01:59:59	691	2.6%	0.19	2.1 MB	2.8%	0.60
Sep 5 02:00:00 - 02:59:59	422	1.6%	0.12	1.5 MB	2.0%	0.44
Sep 5 03:00:00 - 03:59:59	454	1.7%	0.13	1.3 MB	1.7%	0.37
Sep 5 04:00:00 - 04:15:00	138	0.5%	0.15	361.0 KB	0.5%	0.40
TOTAL: 24:00:00	26829	100.0%	0.31	75.9 MB	100.0%	0.90

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	10137	1599	16	8522
2	xanadu-bbs.net	6108	545	6	5557
3	news-out.mixmin.net	2484	1	2	2481
4	feeder.eternal-september.org	2420	230	1	2189
5	au2pb.net	1004	926	2	76
6	newsfeed.fu-berlin.de	712	16	0	696
7	nntp.pasdenom.info	168	158	1	9
8	newsfeeds.xmission.com	78	62	0	16
9	news.swapon.de	32	0	0	32
TOTAL: 9		23143	3537	28	19578

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	737
ut.test	215
pld.cvs.commit	213
lada.auto	164
lada.talk	113
alt.politics	110
mailing.freebsd.svn-ports-all	106
mailing.freebsd.svn-ports-head	101
mailing.freebsd.pkg-fallout	61
mozilla.general	53
alt.survival	52
it-alt.sport.calcio.juventus	51
tin.it.sport.calcio	46
mozilla.dev.tree-alerts	43
mailing.freebsd.ports-bugs	40
mpc.lists.freebsd.ports.bugs	40
mailing.openbsd.misc	38
alt.politics.scorched-earth	37
mailing.dragonflybsd.commits	35
mailing.openbsd.source-changes	34
TOTAL: 259	3537

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14240
EMP (phn path)	2808
EMP (phl)	860
EMP (phn nph)	228
PFM bot misc	113
Too many newsgroups	61
HTML Multipart	46
Binary Image: misplaced jpg	16
Too many newsgroups (low_xpost)	7
HTML post	4
Too many newsgroups (meow)	3
U2 violation - invalid distribution	1
Binary: misplaced cst	1
Binary Payload	1
TOTAL: 14	18389

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	586
	feeder.eternal-september.org	66
	news-out.mixmin.net	48
	au2pb.net	34
	newsfeeds.xmission.com	12
	newsfeed.fu-berlin.de	5
	TOTAL: 6	751
Including strange strings
	newsfeed.fu-berlin.de	306
	feeder.eternal-september.org	34
	news-out.mixmin.net	28
	au2pb.net	28
	news.swapon.de	16
	newsfeed.neva.ru	13
	xanadu-bbs.net	10
	newsfeeds.xmission.com	2
	nntp.pasdenom.info	1
	TOTAL: 9	438
TOTAL: 2		1189

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	32501	366	25191	764	3	1%	23:59:57
2	au2pb	21507	122	20466	140	0	0%	23:59:57
3	xanadu-bbs	24611	71	22776	70	23	0%	23:59:57
4	fu-berlin.de	25238	54	25148	36	0	0%	23:59:49
5	news.mixmin.net	23573	15	22799	21	0	0%	23:59:46
6	xmission	31767	15	25163	7	3	0%	23:59:57
7	eternal-september	21339	5	20302	5	0	0%	23:59:51
8	neva.ru	23879	1	23044	54	0	0%	23:59:44
9	news.swapon.de	2179	0	2167	2	0	0%	23:59:57
TOTAL: 9		206594	649	187056	1099	29	0%	215:58:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.2 MB	1.9 MB	3.1 MB	2.8 KB	23:59:57
2	au2pb	518.3 KB	819.6 KB	1.3 MB	5.1 KB	23:59:57
3	fu-berlin.de	492.4 KB	51.3 KB	543.7 KB	6.0 KB	23:59:49
4	xanadu-bbs	473.4 KB	152.4 KB	625.8 KB	4.4 KB	23:59:57
5	news.mixmin.net	57.4 KB	47.7 KB	105.1 KB	2.9 KB	23:59:46
6	xmission	29.0 KB	36.4 KB	65.5 KB	3.0 KB	23:59:57
7	eternal-september	16.7 KB	6.5 KB	23.2 KB	2.3 KB	23:59:51
8	neva.ru	2.9 KB	246.9 KB	249.8 KB	4.5 KB	23:59:44
9	news.swapon.de	0.0 KB	3.8 KB	3.8 KB	1.9 KB	23:59:57
TOTAL: 9		2.8 MB	3.2 MB	6.0 MB	3.5 KB	215:58:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	49	148.9 KB	49	04:19:35
2	pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	2	4.2 KB	1	00:28:28
3	challenger.nielsenmedia.com	7	2	8.7 KB	1	01:13:47
TOTAL: 3		300	53	161.7 KB	51	79:36:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	539	49	148.9 KB	49	66:02:01
2	*.nielsenmedia.com	7	2	8.7 KB	1	01:13:47
3	*.tampfl.dsl-w.verizon.net	5	2	4.2 KB	1	00:28:28
4	*.bbox.io	3	0	0.0 KB	0	00:30:21
5	*.dsl.klmzmi.ameritech.net	1	0	0.0 KB	0	00:00:03
6	*.br.br.cox.net	3	0	0.0 KB	0	00:00:44
7	*.tu.ok.cox.net	1	0	0.0 KB	0	00:30:04
8	*.chello.nl	30	0	0.0 KB	0	10:50:58
TOTAL: 8		589	53	161.7 KB	51	79:36:29

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
108.175.241.207	13.533	3.647	0.000	55:53:37
dhcp-089-099-203-254.chello.nl	2.401	0.423	0.000	10:50:58
162.212.88.198	0.334	0.136	0.000	05:48:45
202.89.176.220	26.193	5.762	0.701	04:19:35
challenger.nielsenmedia.com	0.664	0.077	0.000	01:13:47
tempe0.bbox.io	0.218	0.069	0.000	00:30:21
ip72-222-107-224.tu.ok.cox.net	0.055	0.023	0.000	00:30:04
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.412	0.070	0.000	00:28:28
ip70-186-189-146.br.br.cox.net	0.304	0.047	0.000	00:00:44
ppp-69-214-2-96.dsl.klmzmi.ameritech.net	0.065	0.008	0.000	00:00:03
69.160.37.54	1.832	0.312	0.000	00:00:01
TOTAL: 11	46.011	10.574	0.701	79:36:29

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	226
dhcp-089-099-203-254.chello.nl	30
69.160.37.54	25
ip70-186-189-146.br.br.cox.net	3
tempe0.bbox.io	3
ppp-69-214-2-96.dsl.klmzmi.ameritech.net	1
ip72-222-107-224.tu.ok.cox.net	1
TOTAL: 7	289

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	18	0
challenger.nielsenmedia.com	2	0
tempe0.bbox.io	1	0
ip72-222-107-224.tu.ok.cox.net	1	0
TOTAL: 4	22	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	53	100.0%
TOTAL: 1		53	100.0%

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

Newsgroup		Count
1	comp.os.vms	20
2	comp.sys.ibm.pc.games.action	14
3	comp.arch	5
4	comp.lang.awk	4
5	comp.ai.neural-nets	2
6	comp.sys.ibm.pc.games.war-historical	2
7	comp.ai.edu	1
8	comp.ai.nat-lang	1
9	comp.ai.genetic	1
10	comp.sys.ibm.pc.games.rpg	1
11	comp.ai.doc-analysis.ocr	1
12	comp.ai.fuzzy	1
TOTAL: 12		53

Daily Usenet report for csiph.com

Sep 4 04:15:00 -- Sep 5 04:15:00