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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 1 05:04:04 csiph pgpverify[8315]: pgpverify: verification failed
Sep 1 07:51:46 csiph pgpverify[15696]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52359	49.1%	7.6 MB	60.7%
nnrpd	31868	29.9%	2.7 MB	21.3%
innd	20623	19.3%	2.0 MB	16.0%
innfeed	1885	1.8%	261.5 KB	2.0%
controlchan	9	0.0%	1.3 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
TOTAL: 6	106746	100.0%	12.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	278069	47.2%
Negative hits	254679	43.2%
Do not exist	51063	8.7%
Cache misses	5464	0.9%
TOTAL: 4	589275	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.548	0.0%	585	0.000	0.937	25.200
article cleanup	00:00:00.288	0.0%	50248	0.000	0.006	0.030
article logging	00:00:00.617	0.0%	50724	0.000	0.012	0.045
article parse	00:00:02.898	0.0%	313087	0.003	0.009	0.017
article write	00:00:06.626	0.0%	28583	0.148	0.232	0.352
artlog/artcncl	00:00:00.008	0.0%	548	0.000	0.015	0.167
artlog/artparse	00:00:00.018	0.0%	1091	0.000	0.016	1.000
data move	00:00:00.297	0.0%	795885	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.007	0.0%	574	0.000	0.012	0.053
hishave/artcncl	00:00:00.000	0.0%	585	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	1091	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.440	0.0%	590650	0.001	0.002	0.006
history sync	00:00:00.452	0.0%	302	0.000	1.497	3.000
history write	00:00:12.970	0.0%	50244	0.073	0.258	0.327
hiswrite/artcncl	00:00:00.001	0.0%	11	0.000	0.091	0.500
hiswrite/artparse	00:00:00.299	0.0%	1091	0.000	0.274	2.000
idle	23:57:40.249	99.8%	783403	18.320	110.110	314.527
nntp read	00:00:02.558	0.0%	782315	0.001	0.003	0.006
overview write	00:00:04.695	0.0%	28583	0.089	0.164	0.489
perl filter	00:01:36.670	0.1%	50244	1.012	1.924	3.703
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.489	0.0%	57169	0.000	0.009	0.024
TOTAL: 24:00:26.936	23:59:51.133	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	28747	0.000	0.003	0.030
article prepare	00:00:00.002	0.0%	8332	0.000	0.000	0.017
article read	00:00:00.224	0.0%	1676	0.000	0.134	8.000
backlog stats	00:00:00.092	0.0%	319867	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.223	0.0%	245027	0.001	0.005	0.011
data write	00:00:01.872	0.0%	219329	0.003	0.009	0.018
idle	23:52:50.370	100.0%	319867	140.557	268.769	735.760
status file	00:00:00.072	0.0%	193	0.000	0.373	1.000
TOTAL: 23:53:00.003	23:52:53.927	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.550	0.0%	93095	0.000	0.017	0.250
idle	71:09:51.172	100.0%	124381	1.000	2059.729	600343.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.734	0.0%	330660	0.000	0.005	0.500
readart	00:00:00.063	0.0%	50	0.000	1.260	60.000
TOTAL: 71:11:03.530	71:09:54.519	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	Other	Bad PGP	OK
bofh-control@killfile.org	1	0	1
moderator@dana.de	0	1	0
us-control@lists.killfile.org	0	1	0
TOTAL	1	2	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	6	47143	8430	36175	2538	17%	25:32:51
2	au2pb.net	4	32014	4684	26502	828	14%	25:30:51
3	xanadu-bbs.net	2	50759	4662	36424	9673	9%	24:50:16
4	newsfeed.neva.ru	147	64041	3165	53337	7539	4%	71:56:10
5	news-out.mixmin.net	7	33540	2854	29168	1518	8%	24:31:21
6	newsfeeds.xmission.com	1	29048	2797	25956	295	9%	23:56:05
7	newsfeed.fu-berlin.de	71	32760	1428	31090	242	4%	36:17:38
8	nntp.pasdenom.info	2	20119	335	19697	87	1%	15:16:37
9	news.swapon.de	1	2559	250	2307	2	9%	23:37:57
10	localhost	48	13627	0	13627	0	0%	00:01:00
TOTAL: 10		289	325610	28605	274283	22722	8%	271:30:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	23.4 MB	10.1 KB	16.9 MB	40.3 MB	58%	3.8 KB
2	xanadu-bbs.net	12.5 MB	10.2 KB	204.2 MB	216.7 MB	5%	15.5 KB
3	au2pb.net	11.9 MB	10.1 KB	3.7 MB	15.6 MB	75%	2.9 KB
4	newsfeed.neva.ru	9.7 MB	0.0 KB	52.5 MB	62.2 MB	15%	6.0 KB
5	news-out.mixmin.net	9.1 MB	0.0 KB	5.5 MB	14.7 MB	62%	3.4 KB
6	newsfeeds.xmission.com	7.9 MB	10.1 KB	2.4 MB	10.3 MB	76%	3.4 KB
7	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	948.6 KB	4.2 MB	78%	2.6 KB
8	nntp.pasdenom.info	750.9 KB	0.0 KB	398.6 KB	1.1 MB	65%	2.7 KB
9	news.swapon.de	697.1 KB	0.0 KB	581.7 KB	1.2 MB	54%	5.1 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		79.2 MB	40.5 KB	287.3 MB	366.5 MB	21%	7.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 04:15:00 - 04:59:59	433	1.5%	0.16	1.3 MB	1.6%	0.49
Sep 1 05:00:00 - 05:59:59	558	2.0%	0.15	1.6 MB	2.0%	0.45
Sep 1 06:00:00 - 06:59:59	869	3.0%	0.24	2.4 MB	3.1%	0.69
Sep 1 07:00:00 - 07:59:59	1101	3.9%	0.31	3.1 MB	4.0%	0.89
Sep 1 08:00:00 - 08:59:59	1228	4.3%	0.34	3.5 MB	4.4%	0.99
Sep 1 09:00:00 - 09:59:59	1316	4.6%	0.37	3.4 MB	4.3%	0.97
Sep 1 10:00:00 - 10:59:59	1336	4.7%	0.37	3.7 MB	4.7%	1.06
Sep 1 11:00:00 - 11:59:59	1290	4.5%	0.36	3.4 MB	4.2%	0.95
Sep 1 12:00:00 - 12:59:59	1346	4.7%	0.37	3.4 MB	4.3%	0.97
Sep 1 13:00:00 - 13:59:59	1506	5.3%	0.42	4.0 MB	5.0%	1.14
Sep 1 14:00:00 - 14:59:59	1564	5.5%	0.43	4.1 MB	5.2%	1.16
Sep 1 15:00:00 - 15:59:59	1764	6.2%	0.49	4.6 MB	5.8%	1.31
Sep 1 16:00:00 - 16:59:59	1543	5.4%	0.43	4.0 MB	5.0%	1.13
Sep 1 17:00:00 - 17:59:59	1666	5.8%	0.46	4.2 MB	5.3%	1.21
Sep 1 18:00:00 - 18:59:59	1825	6.4%	0.51	4.9 MB	6.2%	1.40
Sep 1 19:00:00 - 19:59:59	1643	5.7%	0.46	4.3 MB	5.5%	1.23
Sep 1 20:00:00 - 20:59:59	1446	5.1%	0.40	3.9 MB	5.0%	1.12
Sep 1 21:00:00 - 21:59:59	1335	4.7%	0.37	3.7 MB	4.7%	1.06
Sep 1 22:00:00 - 22:59:59	1052	3.7%	0.29	3.2 MB	4.0%	0.90
Sep 1 23:00:00 - 23:59:59	715	2.5%	0.20	2.1 MB	2.6%	0.60
Sep 2 00:00:00 - 00:59:59	740	2.6%	0.21	2.5 MB	3.1%	0.71
Sep 2 01:00:00 - 01:59:59	744	2.6%	0.21	2.3 MB	2.9%	0.65
Sep 2 02:00:00 - 02:59:59	526	1.8%	0.15	1.7 MB	2.2%	0.50
Sep 2 03:00:00 - 03:59:59	568	2.0%	0.16	1.7 MB	2.2%	0.49
Sep 2 04:00:00 - 04:15:00	470	1.6%	0.52	2.2 MB	2.7%	2.47
TOTAL: 24:00:00	28584	100.0%	0.33	79.3 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9740	842	1	8897
2	newsfeed.neva.ru	7507	1528	0	5979
3	feeder.eternal-september.org	2573	355	1	2217
4	news-out.mixmin.net	1539	10	0	1529
5	au2pb.net	860	788	1	71
6	newsfeed.fu-berlin.de	583	43	0	540
7	newsfeeds.xmission.com	303	275	1	27
8	nntp.pasdenom.info	108	98	0	10
9	news.swapon.de	12	0	0	12
TOTAL: 9		23225	3939	4	19282

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	695
ut.test	151
lada.talk	151
alt.politics	114
it-alt.sport.calcio.juventus	105
mailing.freebsd.pkg-fallout	104
lada.auto	102
pld.cvs.commit	102
alt.politics.scorched-earth	94
mailing.freebsd.svn-ports-all	85
mailing.openbsd.source-changes	78
mailing.freebsd.svn-ports-head	72
mpc.lists.nanog	59
mailing.gcc.gcc	58
alt.windows7.general	58
mailing.freebsd.svn-src-all	53
mailing.freebsd.ports-bugs	53
fa.openbsd.source-changes	52
fa.freebsd.cvs-all	50
mailing.freebsd.svn-src-head	50
TOTAL: 384	3939

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14299
EMP (phn path)	2306
EMP (phl)	759
EMP (phn nph)	166
HTML Multipart	62
Too many newsgroups	54
PFM bot misc	44
Binary Image: misplaced jpg	17
HTML post	4
HTML Image Tags	1
Binary: misplaced zip	1
TOTAL: 11	17713

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	940
	feeder.eternal-september.org	55
	news-out.mixmin.net	53
	newsfeeds.xmission.com	18
	au2pb.net	14
	newsfeed.fu-berlin.de	8
	nntp.pasdenom.info	4
	news.swapon.de	1
	TOTAL: 8	1093
Including strange strings
	newsfeed.fu-berlin.de	329
	feeder.eternal-september.org	51
	au2pb.net	45
	xanadu-bbs.net	18
	news-out.mixmin.net	10
	news.swapon.de	9
	newsfeeds.xmission.com	7
	nntp.pasdenom.info	4
	newsfeed.neva.ru	3
	TOTAL: 9	476
TOTAL: 2		1569

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	34999	387	26797	939	67	1%	23:59:56
2	au2pb	25528	248	23559	212	161	0%	23:59:56
3	xanadu-bbs	25724	98	23765	35	6	0%	23:59:56
4	fu-berlin.de	26809	60	26722	27	0	0%	23:59:55
5	news.mixmin.net	26692	17	25537	8	0	0%	23:59:46
6	xmission	30524	6	25621	1	0	0%	23:59:56
7	neva.ru	26300	6	25153	36	0	0%	23:59:55
8	eternal-september	21136	2	19740	2	0	0%	23:59:40
9	news.swapon.de	2440	0	2394	7	0	0%	23:59:42
TOTAL: 9		220152	824	199288	1267	234	0%	215:58:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.3 MB	2.2 MB	3.5 MB	2.7 KB	23:59:56
2	au2pb	1016.6 KB	926.5 KB	1.9 MB	4.2 KB	23:59:56
3	fu-berlin.de	567.3 KB	36.2 KB	603.5 KB	6.9 KB	23:59:55
4	xanadu-bbs	560.0 KB	69.5 KB	629.5 KB	4.7 KB	23:59:56
5	news.mixmin.net	102.6 KB	11.3 KB	113.9 KB	4.6 KB	23:59:46
6	neva.ru	33.5 KB	204.5 KB	237.9 KB	5.7 KB	23:59:55
7	xmission	17.9 KB	3.8 KB	21.6 KB	3.1 KB	23:59:56
8	eternal-september	9.2 KB	3.0 KB	12.2 KB	3.1 KB	23:59:40
9	news.swapon.de	0.0 KB	36.9 KB	36.9 KB	5.3 KB	23:59:42
TOTAL: 9		3.6 MB	3.4 MB	7.0 MB	3.4 KB	215:58:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	287	47	147.1 KB	44	04:35:26
2	pool-71-98-160-84.tampfl.dsl-w.verizon.net	11	1	1.3 KB	1	01:43:42
3	dhcp-089-099-203-254.chello.nl	4	1	2.3 KB	1	01:16:58
4	challenger.nielsenmedia.com	9	1	1.3 KB	1	01:38:55
TOTAL: 4		311	50	152.0 KB	47	71:10:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	327	47	147.1 KB	44	41:59:37
2	*.tampfl.dsl-w.verizon.net	11	1	1.3 KB	1	01:43:42
3	*.chello.nl	4	1	2.3 KB	1	01:16:58
4	*.nielsenmedia.com	9	1	1.3 KB	1	01:38:55
5	*.bbox.io	10	0	0.0 KB	0	01:07:21
6	*.dynamic.caiway.nl	8	0	0.0 KB	0	22:54:18
7	*.snarked.org	2	0	0.0 KB	0	00:00:01
8	*.ok.ok.cox.net	1	0	0.0 KB	0	00:30:03
TOTAL: 8		372	50	152.0 KB	47	71:10:59

NNRP auth users [Top 20]:

User	Conn
moussa	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
041-020-158-163.dynamic.caiway.nl	2.068	2.503	0.000	22:54:18
71.94.21.184	0.866	1.057	0.000	21:12:39
162.212.88.198	0.947	0.369	0.000	16:11:30
202.89.176.220	26.436	5.806	0.705	04:35:26
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.939	0.126	0.000	01:43:42
challenger.nielsenmedia.com	0.622	0.144	0.000	01:38:55
dhcp-089-099-203-254.chello.nl	0.441	0.078	0.000	01:16:58
tempe0.bbox.io	0.713	0.156	0.000	01:07:21
ip68-14-171-134.ok.ok.cox.net	0.092	0.015	0.000	00:30:03
69.160.37.54	1.981	0.279	0.000	00:00:02
news.snarked.org	0.205	0.152	0.000	00:00:01
TOTAL: 11	35.310	10.685	0.705	71:10:59

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.198	12
tempe0.bbox.io	10
041-020-158-163.dynamic.caiway.nl	8
71.94.21.184	3
news.snarked.org	2
ip68-14-171-134.ok.ok.cox.net	1
TOTAL: 7	61

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
041-020-158-163.dynamic.caiway.nl	8	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
challenger.nielsenmedia.com	3	0
tempe0.bbox.io	2	0
ip68-14-171-134.ok.ok.cox.net	1	0
dhcp-089-099-203-254.chello.nl	1	0
TOTAL: 6	18	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	17
2	comp.sys.ibm.pc.games.action	15
3	comp.os.vms	9
4	comp.lang.awk	2
5	comp.ai.neural-nets	2
6	comp.archives.ms-windows.discuss	1
7	comp.sys.ibm.as400.misc	1
8	comp.arch.embedded	1
9	comp.sys.ibm.ps2.hardware	1
10	comp.sys.ibm.pc.hardware.chips	1
TOTAL: 10		50

Daily Usenet report for csiph.com

Sep 1 04:15:00 -- Sep 2 04:15:00