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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 1 06:04:08 csiph pgpverify[10963]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	48884	48.3%	7.4 MB	60.7%
nnrpd	33120	32.7%	2.8 MB	22.8%
innd	17278	17.1%	1.7 MB	14.1%
innfeed	1901	1.9%	297.4 KB	2.4%
controlchan	10	0.0%	1.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	101194	100.0%	12.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	264662	48.6%
Negative hits	231406	42.5%
Do not exist	48136	8.8%
Cache misses	170	0.0%
TOTAL: 4	544374	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.348	0.0%	304	0.000	4.434	49.000
article cleanup	00:00:00.294	0.0%	45305	0.000	0.006	0.034
article logging	00:00:00.618	0.0%	45816	0.000	0.013	0.152
article parse	00:00:05.656	0.0%	501197	0.002	0.011	0.096
article write	00:01:44.526	0.1%	28623	0.248	3.652	10.054
artlog/artcncl	00:00:00.005	0.0%	229	0.000	0.022	1.000
artlog/artparse	00:00:00.032	0.0%	2846	0.000	0.011	0.500
data move	00:00:00.480	0.0%	971298	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.264	0.0%	299	0.000	0.883	43.000
hishave/artcncl	00:00:00.000	0.0%	304	0.000	0.000	0.000
hishave/artparse	00:00:00.006	0.0%	2846	0.000	0.002	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.323	0.0%	541541	0.002	0.010	0.142
history sync	00:00:01.949	0.0%	344	0.000	5.666	47.500
history write	00:00:48.093	0.1%	45303	0.073	1.062	7.500
hiswrite/artcncl	00:00:00.036	0.0%	5	0.000	7.200	12.000
hiswrite/artparse	00:00:01.733	0.0%	2845	0.000	0.609	36.000
idle	23:54:36.859	99.6%	941826	12.296	91.394	496.289
nntp read	00:00:02.882	0.0%	944515	0.001	0.003	0.007
overview write	00:01:19.510	0.1%	28623	0.624	2.778	16.339
perl filter	00:01:20.290	0.1%	45303	1.025	1.772	3.286
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.538	0.0%	57251	0.000	0.009	0.032
TOTAL: 24:00:50.132	24:00:10.442	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.091	0.0%	28593	0.000	0.003	0.022
article prepare	00:00:00.001	0.0%	4816	0.000	0.000	0.028
article read	00:00:00.306	0.0%	927	0.000	0.330	22.333
backlog stats	00:00:00.101	0.0%	304726	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.290	0.0%	240485	0.000	0.005	0.011
data write	00:00:01.650	0.0%	215970	0.003	0.008	0.018
idle	23:53:43.539	100.0%	304726	148.142	282.298	918.357
status file	00:00:00.079	0.0%	183	0.000	0.432	1.000
TOTAL: 23:53:56.033	23:53:47.057	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.371	0.0%	97475	0.000	0.035	0.700
idle	37:38:38.594	99.9%	129298	0.000	1048.111	600196.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.554	0.0%	259874	0.000	0.006	0.167
readart	00:00:00.077	0.0%	152	0.000	0.507	69.000
TOTAL: 37:40:48.863	37:38:43.596	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	146
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	166

Newsgroups created:

Group	Mode
alt.bitcoins	y
TOTAL	1

Control Channel:

Sender	newgroup	Other	Bad PGP	DoIt	OK
bofh-control@killfile.org	0	1	0	0	1
donot@spame.com	1	0	0	1	1
us-control@lists.killfile.org	0	0	1	0	0
usenet@ripco.com	1	0	0	1	1
ymer@mulakaj.com	1	0	0	0	0
TOTAL	3	1	1	2	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	3	31672	8687	21488	1497	27%	24:32:14
2	feeder.eternal-september.org	5	41743	6944	33079	1720	16%	24:22:47
3	news-out.mixmin.net	6	33181	3936	26904	2341	11%	24:29:46
4	xanadu-bbs.net	4	47847	3205	35168	9474	6%	26:13:24
5	newsfeed.neva.ru	147	62306	2663	55509	4134	4%	72:05:18
6	newsfeeds.xmission.com	3	29072	1495	27453	124	5%	22:54:20
7	newsfeed.fu-berlin.de	61	30090	1287	28595	208	4%	31:33:59
8	nntp.pasdenom.info	6	29848	219	29569	60	0%	24:15:05
9	news.swapon.de	2	2235	160	2059	16	7%	26:15:23
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		263	307994	28596	259824	19574	9%	276:42:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	22.8 MB	0.0 KB	6.5 MB	29.3 MB	77%	2.9 KB
2	feeder.eternal-september.org	18.5 MB	2.9 KB	15.1 MB	33.5 MB	55%	4.0 KB
3	news-out.mixmin.net	13.6 MB	0.0 KB	5.4 MB	19.0 MB	71%	3.1 KB
4	newsfeed.neva.ru	9.0 MB	0.0 KB	22.6 MB	31.6 MB	28%	4.8 KB
5	xanadu-bbs.net	8.7 MB	0.0 KB	608.0 MB	616.7 MB	1%	49.8 KB
6	newsfeeds.xmission.com	5.2 MB	0.0 KB	2.0 MB	7.2 MB	72%	4.5 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	780.0 KB	3.7 MB	79%	2.5 KB
8	nntp.pasdenom.info	523.1 KB	0.0 KB	909.1 KB	1.4 MB	36%	5.1 KB
9	news.swapon.de	481.0 KB	0.0 KB	133.6 KB	614.6 KB	78%	3.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		81.6 MB	2.9 KB	661.3 MB	742.9 MB	10%	15.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 1 04:15:00 - 04:59:59	397	1.4%	0.15	1.3 MB	1.5%	0.47
Dec 1 05:00:00 - 05:59:59	582	2.0%	0.16	2.3 MB	2.8%	0.65
Dec 1 06:00:00 - 06:59:59	655	2.3%	0.18	2.6 MB	3.1%	0.73
Dec 1 07:00:00 - 07:59:59	763	2.7%	0.21	2.3 MB	2.8%	0.65
Dec 1 08:00:00 - 08:59:59	1152	4.0%	0.32	3.3 MB	4.1%	0.94
Dec 1 09:00:00 - 09:59:59	1303	4.6%	0.36	3.3 MB	4.0%	0.93
Dec 1 10:00:00 - 10:59:59	1329	4.6%	0.37	3.5 MB	4.3%	0.99
Dec 1 11:00:00 - 11:59:59	1300	4.5%	0.36	3.5 MB	4.3%	1.00
Dec 1 12:00:00 - 12:59:59	1428	5.0%	0.40	3.9 MB	4.8%	1.12
Dec 1 13:00:00 - 13:59:59	1548	5.4%	0.43	4.3 MB	5.3%	1.22
Dec 1 14:00:00 - 14:59:59	1609	5.6%	0.45	3.8 MB	4.7%	1.09
Dec 1 15:00:00 - 15:59:59	1800	6.3%	0.50	5.0 MB	6.1%	1.41
Dec 1 16:00:00 - 16:59:59	1845	6.4%	0.51	5.1 MB	6.3%	1.46
Dec 1 17:00:00 - 17:59:59	1756	6.1%	0.49	4.8 MB	5.9%	1.37
Dec 1 18:00:00 - 18:59:59	1726	6.0%	0.48	5.2 MB	6.4%	1.48
Dec 1 19:00:00 - 19:59:59	1398	4.9%	0.39	3.5 MB	4.4%	1.01
Dec 1 20:00:00 - 20:59:59	1499	5.2%	0.42	4.1 MB	5.1%	1.18
Dec 1 21:00:00 - 21:59:59	1549	5.4%	0.43	4.1 MB	5.1%	1.17
Dec 1 22:00:00 - 22:59:59	1399	4.9%	0.39	3.9 MB	4.8%	1.11
Dec 1 23:00:00 - 23:59:59	926	3.2%	0.26	2.7 MB	3.3%	0.77
Dec 2 00:00:00 - 00:59:59	824	2.9%	0.23	2.6 MB	3.1%	0.73
Dec 2 01:00:00 - 01:59:59	735	2.6%	0.20	2.5 MB	3.1%	0.72
Dec 2 02:00:00 - 02:59:59	501	1.8%	0.14	1.9 MB	2.4%	0.55
Dec 2 03:00:00 - 03:59:59	478	1.7%	0.13	1.5 MB	1.9%	0.43
Dec 2 04:00:00 - 04:15:00	113	0.4%	0.13	293.4 KB	0.4%	0.33
TOTAL: 24:00:00	28615	100.0%	0.33	81.3 MB	100.0%	0.96

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9468	684	0	8784
2	newsfeed.neva.ru	4141	1431	0	2710
3	news-out.mixmin.net	2354	8	0	2346
4	feeder.eternal-september.org	1743	259	2	1482
5	au2pb.net	1512	1402	0	110
6	newsfeed.fu-berlin.de	606	33	0	573
7	newsfeeds.xmission.com	122	96	0	26
8	nntp.pasdenom.info	62	49	0	13
9	news.swapon.de	32	0	0	32
TOTAL: 9		20040	3962	2	16076

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	841
lada.talk	194
mailing.freebsd.pkg-fallout	147
alt.politics	117
mailing.freebsd.svn-src-all	97
ut.test	94
mailing.freebsd.svn-ports-all	93
fa.freebsd.cvs-all	90
mailing.freebsd.svn-ports-head	90
mailing.freebsd.svn-src-head	87
alt.survival	86
gmane.linux.alsa.devel	84
mailing.openbsd.source-changes	80
mailing.freebsd.ports-bugs	72
mailing.openbsd.misc	65
lada.auto	62
mailing.gcc.gcc-cvs	50
fa.openbsd.source-changes	50
it-alt.sport.calcio.juventus	49
mailing.freebsd.kde	37
TOTAL: 379	3962

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9231
EMP (phn path)	2147
EMP (phl)	852
PFM bot misc	178
EMP (phn nph)	114
HTML Multipart	86
Too many newsgroups	78
HTML post	32
User-issued spam cancel	2
Binary Image: misplaced gif	1
TOTAL: 10	12721

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	2690
	feeder.eternal-september.org	76
	au2pb.net	30
	news-out.mixmin.net	23
	newsfeeds.xmission.com	16
	nntp.pasdenom.info	7
	newsfeed.fu-berlin.de	3
	news.swapon.de	1
	TOTAL: 8	2846
Including strange strings
	newsfeed.fu-berlin.de	382
	au2pb.net	44
	feeder.eternal-september.org	34
	news-out.mixmin.net	20
	news.swapon.de	16
	xanadu-bbs.net	7
	newsfeeds.xmission.com	4
	nntp.pasdenom.info	2
	newsfeed.neva.ru	2
	TOTAL: 9	511
TOTAL: 2		3357

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	32122	143	27630	624	3	0%	23:59:44
2	xanadu-bbs	27572	110	25250	50	12	0%	23:59:50
3	au2pb	20357	106	19758	53	0	0%	23:59:50
4	fu-berlin.de	26846	24	26809	13	0	0%	23:59:49
5	xmission	32887	14	26912	0	0	0%	23:59:50
6	news.mixmin.net	25610	12	24640	0	1	0%	23:59:50
7	neva.ru	27011	10	25724	31	0	0%	23:59:42
8	eternal-september	21688	5	20811	5	1	0%	23:59:50
9	news.swapon.de	2003	0	1975	4	0	0%	23:58:35
TOTAL: 9		216096	424	199509	780	17	0%	215:57:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	806.4 KB	1.4 MB	2.2 MB	2.9 KB	23:59:44
2	xanadu-bbs	665.2 KB	90.8 KB	756.0 KB	4.7 KB	23:59:50
3	au2pb	476.2 KB	305.3 KB	781.5 KB	4.9 KB	23:59:50
4	fu-berlin.de	143.2 KB	17.0 KB	160.1 KB	4.3 KB	23:59:49
5	neva.ru	75.5 KB	130.5 KB	206.0 KB	5.0 KB	23:59:42
6	news.mixmin.net	59.8 KB	0.0 KB	59.8 KB	5.0 KB	23:59:50
7	xmission	30.2 KB	0.0 KB	30.2 KB	2.2 KB	23:59:50
8	eternal-september	18.4 KB	8.2 KB	26.6 KB	2.7 KB	23:59:50
9	news.swapon.de	0.0 KB	14.9 KB	14.9 KB	3.7 KB	23:58:35
TOTAL: 9		2.2 MB	1.9 MB	4.2 MB	3.5 KB	215:57:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	150	460.1 KB	143	04:25:44
2	92-108-162-83.dynamic.upc.nl	2	1	1.4 KB	1	00:02:01
3	dhcp-089-099-203-254.chello.nl	13	1	1.4 KB	1	04:16:48
TOTAL: 3		303	152	462.9 KB	145	37:40:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	319	150	460.1 KB	143	23:43:51
2	*.chello.nl	13	1	1.4 KB	1	04:16:48
3	*.dynamic.upc.nl	2	1	1.4 KB	1	00:02:01
4	*.149.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:00:36
5	*.br.br.cox.net	1	0	0.0 KB	0	00:09:57
6	*.bbox.io	13	0	0.0 KB	0	03:51:53
7	*.tampfl.dsl-w.verizon.net	1	0	0.0 KB	0	00:28:20
8	*.nielsenmedia.com	1	0	0.0 KB	0	00:06:02
9	*.ip-188-165-239.eu	284	0	0.0 KB	0	04:01:11
TOTAL: 9		636	152	462.9 KB	145	37:40:42

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.214	0.388	0.349	0.000	18:41:09
202.89.176.220	31.831	6.093	0.707	04:25:44
dhcp-089-099-203-254.chello.nl	1.171	0.254	0.000	04:16:48
ns342002.ip-188-165-239.eu	24.769	8.544	0.000	04:01:11
tempe0.bbox.io	1.243	0.231	0.000	03:51:53
h188.149.22.98.dynamic.ip.windstream.net	0.144	0.016	0.000	01:00:36
138.108.60.60	0.185	0.040	0.000	00:36:54
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.102	0.008	0.000	00:28:20
ip68-111-51-194.br.br.cox.net	0.101	0.008	0.000	00:09:57
challenger.nielsenmedia.com	0.105	0.008	0.000	00:06:02
92-108-162-83.dynamic.upc.nl	0.204	0.024	0.000	00:02:01
69.160.37.54	1.892	0.453	0.000	00:00:02
TOTAL: 12	62.135	16.028	0.707	37:40:42

Curious NNRP server explorers [Top 20]:

System	Conn
ns342002.ip-188-165-239.eu	284
69.160.37.54	25
tempe0.bbox.io	13
162.212.88.214	4
138.108.60.60	2
h188.149.22.98.dynamic.ip.windstream.net	2
challenger.nielsenmedia.com	1
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1
ip68-111-51-194.br.br.cox.net	1
TOTAL: 9	333

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
dhcp-089-099-203-254.chello.nl	7	0
h188.149.22.98.dynamic.ip.windstream.net	2	0
ip68-111-51-194.br.br.cox.net	1	1
138.108.60.60	1	0
TOTAL: 5	18	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	151	99.3%
2	news	1	0.7%
TOTAL: 2		152	100.0%

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

Newsgroup		Count
1	comp.os.vms	42
2	comp.arch	40
3	comp.arch.fpga	17
4	comp.arch.embedded	14
5	comp.sys.ibm.pc.games.action	14
6	comp.sys.ibm.as400.misc	5
7	comp.sys.ibm.pc.games.war-historical	5
8	comp.sys.ibm.pc.games.rpg	4
9	comp.os.os2.misc	3
10	comp.ai.neural-nets	2
11	comp.os.linux.networking	2
12	comp.sys.ibm.ps2.hardware	2
13	comp.ai.philosophy	1
14	news.admin.peering	1
TOTAL: 14		152

Daily Usenet report for csiph.com

Dec 1 04:15:00 -- Dec 2 04:15:00