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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 4 10:50:03 csiph pgpverify[16484]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52951	48.1%	7.9 MB	60.3%
nnrpd	32103	29.1%	2.7 MB	20.3%
innd	23256	21.1%	2.3 MB	17.3%
innfeed	1873	1.7%	280.8 KB	2.1%
controlchan	5	0.0%	0.7 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	110189	100.0%	13.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	247084	46.4%
Negative hits	224857	42.2%
Do not exist	52185	9.8%
Cache misses	8648	1.6%
TOTAL: 4	532774	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.371	0.0%	210	0.000	1.767	17.000
article cleanup	00:00:00.280	0.0%	44164	0.000	0.006	0.023
article logging	00:00:00.584	0.0%	44610	0.000	0.013	0.111
article parse	00:00:19.080	0.0%	1320228	0.002	0.014	0.327
article write	00:01:15.871	0.1%	27051	0.195	2.805	10.323
artlog/artcncl	00:00:00.002	0.0%	182	0.000	0.011	0.333
artlog/artparse	00:00:00.111	0.0%	8171	0.000	0.014	0.333
data move	00:00:01.064	0.0%	1758306	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.051	0.0%	206	0.000	0.248	17.000
hishave/artcncl	00:00:00.001	0.0%	210	0.000	0.005	0.125
hishave/artparse	00:00:00.028	0.0%	8171	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.501	0.0%	526196	0.001	0.005	0.043
history sync	00:00:01.469	0.0%	341	0.000	4.308	61.000
history write	00:00:29.645	0.0%	44163	0.103	0.671	10.888
hiswrite/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hiswrite/artparse	00:00:08.604	0.0%	8166	0.000	1.054	28.429
idle	23:55:38.038	99.6%	1719957	9.621	50.082	326.801
nntp read	00:00:05.336	0.0%	1722532	0.001	0.003	0.006
overview write	00:00:56.905	0.1%	27051	0.258	2.104	13.505
perl filter	00:01:15.579	0.1%	44163	1.012	1.711	3.426
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.477	0.0%	54104	0.000	0.009	0.026
TOTAL: 24:00:48.217	24:00:15.997	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.110	0.0%	39761	0.000	0.003	0.028
article prepare	00:00:00.000	0.0%	6748	0.000	0.000	0.000
article read	00:00:05.881	0.0%	1439	0.000	4.087	15.000
backlog stats	00:00:00.115	0.0%	284442	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.207	0.0%	219384	0.000	0.006	0.018
data write	00:00:01.547	0.0%	196150	0.003	0.008	0.017
idle	23:53:51.533	100.0%	284442	83.635	302.457	905.194
status file	00:00:00.093	0.0%	197	0.000	0.472	1.000
TOTAL: 23:54:04.262	23:54:00.486	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.622	0.0%	21037	0.000	0.030	0.333
idle	31:55:07.966	99.9%	52600	0.000	2184.562	600155.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.650	0.0%	106357	0.000	0.006	0.500
readart	00:00:00.094	0.0%	137	0.000	0.686	22.333
TOTAL: 31:56:51.238	31:55:09.332	99.9%	-	-	-	-

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	OK
admin@openwatcom.news-admin.org	0	1	0
ymer@mulakaj.com	1	0	1
TOTAL	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	2	40604	7231	31281	2092	17%	24:12:35
2	au2pb.net	1	29223	5880	22180	1163	20%	22:40:28
3	news-out.mixmin.net	4	32956	4250	25625	3081	12%	24:17:54
4	xanadu-bbs.net	10	52204	3224	35348	13632	6%	39:45:03
5	newsfeed.neva.ru	150	54972	2667	47692	4613	4%	71:31:32
6	newsfeeds.xmission.com	1	27769	2203	25261	305	7%	22:47:08
7	newsfeed.fu-berlin.de	56	33598	1330	31894	374	3%	35:29:53
8	news.swapon.de	4	2274	210	2060	4	9%	23:38:22
9	nntp.pasdenom.info	7	28906	92	28763	51	0%	18:07:23
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		261	302506	27087	250104	25315	8%	282:30:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.3 MB	0.0 KB	15.3 MB	34.6 MB	55%	3.8 KB
2	au2pb.net	15.7 MB	0.0 KB	5.6 MB	21.3 MB	73%	3.1 KB
3	news-out.mixmin.net	14.7 MB	0.0 KB	5.9 MB	20.6 MB	71%	2.9 KB
4	xanadu-bbs.net	9.1 MB	0.0 KB	2.0 GB	2.0 GB	0%	123.4 KB
5	newsfeed.neva.ru	8.3 MB	0.0 KB	27.6 MB	35.9 MB	23%	5.1 KB
6	newsfeeds.xmission.com	6.6 MB	0.0 KB	3.4 MB	10.0 MB	65%	4.1 KB
7	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	988.6 KB	4.0 MB	75%	2.4 KB
8	news.swapon.de	573.6 KB	2.9 KB	175.2 KB	751.7 KB	76%	3.5 KB
9	nntp.pasdenom.info	189.9 KB	0.0 KB	959.1 KB	1.1 MB	16%	8.0 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		77.5 MB	2.9 KB	2.0 GB	2.1 GB	3%	42.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 4 04:15:00 - 04:59:59	355	1.3%	0.13	1.0 MB	1.3%	0.39
Dec 4 05:00:00 - 05:59:59	634	2.3%	0.18	1.9 MB	2.5%	0.55
Dec 4 06:00:00 - 06:59:59	588	2.2%	0.16	1.9 MB	2.4%	0.53
Dec 4 07:00:00 - 07:59:59	757	2.8%	0.21	1.8 MB	2.4%	0.53
Dec 4 08:00:00 - 08:59:59	1131	4.2%	0.31	3.3 MB	4.2%	0.93
Dec 4 09:00:00 - 09:59:59	1250	4.6%	0.35	3.2 MB	4.1%	0.91
Dec 4 10:00:00 - 10:59:59	1341	5.0%	0.37	3.6 MB	4.6%	1.01
Dec 4 11:00:00 - 11:59:59	1244	4.6%	0.35	3.2 MB	4.2%	0.92
Dec 4 12:00:00 - 12:59:59	1289	4.8%	0.36	3.7 MB	4.8%	1.05
Dec 4 13:00:00 - 13:59:59	1480	5.5%	0.41	3.9 MB	5.1%	1.12
Dec 4 14:00:00 - 14:59:59	1549	5.7%	0.43	4.3 MB	5.5%	1.21
Dec 4 15:00:00 - 15:59:59	1657	6.1%	0.46	4.9 MB	6.4%	1.40
Dec 4 16:00:00 - 16:59:59	1723	6.4%	0.48	4.7 MB	6.0%	1.33
Dec 4 17:00:00 - 17:59:59	1708	6.3%	0.47	5.2 MB	6.8%	1.49
Dec 4 18:00:00 - 18:59:59	1442	5.3%	0.40	4.0 MB	5.2%	1.14
Dec 4 19:00:00 - 19:59:59	1521	5.6%	0.42	4.2 MB	5.5%	1.20
Dec 4 20:00:00 - 20:59:59	1354	5.0%	0.38	4.2 MB	5.4%	1.20
Dec 4 21:00:00 - 21:59:59	1131	4.2%	0.31	3.1 MB	4.1%	0.90
Dec 4 22:00:00 - 22:59:59	1152	4.3%	0.32	3.2 MB	4.2%	0.92
Dec 4 23:00:00 - 23:59:59	935	3.5%	0.26	2.7 MB	3.5%	0.76
Dec 5 00:00:00 - 00:59:59	756	2.8%	0.21	2.2 MB	2.8%	0.62
Dec 5 01:00:00 - 01:59:59	807	3.0%	0.22	2.9 MB	3.8%	0.83
Dec 5 02:00:00 - 02:59:59	563	2.1%	0.16	1.9 MB	2.5%	0.54
Dec 5 03:00:00 - 03:59:59	529	2.0%	0.15	1.8 MB	2.4%	0.52
Dec 5 04:00:00 - 04:15:00	148	0.5%	0.16	482.8 KB	0.6%	0.54
TOTAL: 24:00:00	27044	100.0%	0.31	77.4 MB	100.0%	0.92

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	13601	520	0	13081
2	newsfeed.neva.ru	4615	1470	0	3145
3	news-out.mixmin.net	3114	5	0	3109
4	feeder.eternal-september.org	2133	245	0	1888
5	au2pb.net	1191	1113	0	78
6	newsfeed.fu-berlin.de	685	49	0	636
7	newsfeeds.xmission.com	310	273	0	37
8	nntp.pasdenom.info	48	33	0	15
9	news.swapon.de	28	0	1	27
TOTAL: 9		25725	3708	1	22016

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	784
ut.test	247
lada.talk	132
alt.politics	128
mailing.freebsd.svn-ports-all	126
mailing.freebsd.svn-ports-head	123
mailing.freebsd.pkg-fallout	119
mailing.freebsd.svn-src-all	111
fa.freebsd.cvs-all	97
lada.auto	77
mailing.gcc.gcc-cvs	70
mailing.freebsd.svn-src-stable	61
mailing.openbsd.source-changes	60
mailing.freebsd.ports-bugs	59
pld.cvs.commit	57
alt.survival	55
mailing.freebsd.svn-src-head	51
it-alt.sport.calcio.juventus	46
mailing.freebsd.svn-src-stable-10	40
fa.openbsd.source-changes	38
TOTAL: 254	3708

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9489
EMP (phn path)	2161
EMP (phl)	994
EMP (phn nph)	336
PFM bot misc	226
Too many newsgroups	69
HTML Multipart	68
Binary: misplaced pdf	15
HTML Image Tags	13
Binary Image: misplaced jpg	11
HTML post	11
Binary: misplaced rar	5
User-issued spam cancel	1
TOTAL: 13	13399

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	8010
	feeder.eternal-september.org	65
	newsfeeds.xmission.com	30
	au2pb.net	29
	news-out.mixmin.net	22
	nntp.pasdenom.info	9
	newsfeed.fu-berlin.de	4
	news.swapon.de	2
	TOTAL: 8	8171
Including strange strings
	newsfeed.fu-berlin.de	350
	feeder.eternal-september.org	38
	news.swapon.de	24
	news-out.mixmin.net	15
	au2pb.net	9
	xanadu-bbs.net	4
	newsfeeds.xmission.com	4
	newsfeed.neva.ru	2
	TOTAL: 8	446
TOTAL: 2		8617

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	27310	130	23475	159	0	0%	23:59:47
2	au2pb	21582	129	20937	31	0	0%	23:59:56
3	usenet.pasdenom.info	28956	100	25872	983	12746	0%	23:59:57
4	xmission	30779	43	24692	5	0	0%	23:59:54
5	fu-berlin.de	25411	31	25370	9	0	0%	23:59:49
6	news.mixmin.net	23625	11	22769	0	0	0%	23:59:57
7	neva.ru	26335	10	24164	39	25	0%	23:59:56
8	eternal-september	20106	8	19065	0	0	0%	23:59:57
9	news.swapon.de	2112	0	2100	0	0	0%	23:52:23
TOTAL: 9		206216	462	188444	1226	12771	0%	215:51:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	778.8 KB	344.9 KB	1.1 MB	3.9 KB	23:59:47
2	au2pb	665.7 KB	143.5 KB	809.2 KB	5.1 KB	23:59:56
3	usenet.pasdenom.info	429.7 KB	1.8 MB	2.2 MB	2.1 KB	23:59:57
4	fu-berlin.de	151.5 KB	12.2 KB	163.7 KB	4.1 KB	23:59:49
5	xmission	137.8 KB	55.8 KB	193.7 KB	4.0 KB	23:59:54
6	eternal-september	51.8 KB	0.0 KB	51.8 KB	6.5 KB	23:59:57
7	neva.ru	47.1 KB	149.8 KB	196.9 KB	4.0 KB	23:59:56
8	news.mixmin.net	32.1 KB	0.0 KB	32.1 KB	2.9 KB	23:59:57
9	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0	23:52:23
TOTAL: 9		2.2 MB	2.5 MB	4.7 MB	2.9 KB	215:51:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	130	379.1 KB	120	04:22:08
2	tempe0.bbox.io	29	4	6.3 KB	2	06:35:42
3	187.a2c-250-241.astra2connect.com	5	3	4.2 KB	1	01:02:11
TOTAL: 3		322	137	389.5 KB	123	31:56:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	130	379.1 KB	120	16:44:28
2	*.bbox.io	29	4	6.3 KB	2	06:35:42
3	*.a2c-250-241.astra2connect.com	5	3	4.2 KB	1	01:02:11
4	*.ip-188-165-239.eu	11	0	0.0 KB	0	00:45:48
5	*.250.91.75.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:38:38
6	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	00:39:20
7	*.chello.nl	10	0	0.0 KB	0	04:30:36
TOTAL: 7		380	137	389.5 KB	123	31:56:47

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
216.246.166.29	0.331	0.227	0.000	12:22:17
tempe0.bbox.io	2.554	0.520	0.000	06:35:42
dhcp-089-099-203-254.chello.nl	0.840	0.159	0.000	04:30:36
202.89.176.220	31.052	6.805	0.726	04:22:08
h56.250.91.75.dynamic.ip.windstream.net	0.145	0.064	0.000	01:38:38
187.a2c-250-241.astra2connect.com	0.383	0.096	0.000	01:02:11
ns342002.ip-188-165-239.eu	1.185	0.959	0.000	00:45:48
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.118	0.051	0.000	00:39:20
69.160.37.54	2.046	0.389	0.000	00:00:02
TOTAL: 9	38.654	9.270	0.726	31:56:47

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
ns342002.ip-188-165-239.eu	11
dhcp-089-099-203-254.chello.nl	10
216.246.166.29	8
h56.250.91.75.dynamic.ip.windstream.net	2
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
TOTAL: 6	58

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
dhcp-089-099-203-254.chello.nl	9	0
187.a2c-250-241.astra2connect.com	2	0
h56.250.91.75.dynamic.ip.windstream.net	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 5	24	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	136	99.3%
2	news	1	0.7%
TOTAL: 2		137	100.0%

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

Newsgroup		Count
1	comp.os.vms	35
2	comp.arch	31
3	comp.ai.philosophy	31
4	comp.arch.embedded	11
5	comp.sys.ibm.ps2.hardware	8
6	comp.sys.ibm.pc.games.war-historical	6
7	comp.arch.fpga	5
8	comp.os.os2.misc	4
9	comp.ai.neural-nets	2
10	comp.answers	1
11	comp.ai.shells	1
12	news.software.nntp	1
13	comp.os.linux.networking	1
TOTAL: 13		137

Daily Usenet report for csiph.com

Dec 4 04:15:00 -- Dec 5 04:15:00