Daily Usenet report for csiph.com: Aug 17 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 17 11:10:01 csiph pgpverify[87422]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	60270	50.2%	8.4 MB	60.5%
nnrpd	33634	28.0%	2.8 MB	20.6%
innd	24418	20.3%	2.4 MB	17.1%
innfeed	1725	1.4%	247.0 KB	1.7%
controlchan	4	0.0%	0.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	120052	100.0%	13.8 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	296382	47.1%
Positive hits	268583	42.7%
Do not exist	59711	9.5%
Cache misses	3948	0.6%
TOTAL: 4	628624	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.302	0.0%	1101	0.000	0.274	10.000
article cleanup	00:00:00.348	0.0%	58280	0.000	0.006	0.025
article logging	00:00:00.693	0.0%	58489	0.000	0.012	0.038
article parse	00:00:02.721	0.0%	320873	0.003	0.008	0.017
article write	00:00:07.612	0.0%	31795	0.164	0.239	0.333
artlog/artcncl	00:00:00.011	0.0%	909	0.000	0.012	0.056
artlog/artparse	00:00:00.008	0.0%	927	0.000	0.009	0.333
data move	00:00:00.328	0.0%	825909	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.006	0.0%	394	0.000	0.015	1.000
hishave/artcncl	00:00:00.002	0.0%	1101	0.000	0.002	0.034
hishave/artparse	00:00:00.003	0.0%	927	0.000	0.003	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.513	0.0%	628751	0.001	0.002	0.009
history sync	00:00:00.501	0.0%	358	0.000	1.399	2.667
history write	00:00:13.761	0.0%	58275	0.079	0.236	0.311
hiswrite/artcncl	00:00:00.167	0.0%	707	0.174	0.236	0.272
hiswrite/artparse	00:00:00.224	0.0%	923	0.000	0.243	2.000
idle	23:57:34.434	99.8%	807923	30.006	106.761	345.025
nntp read	00:00:02.690	0.0%	806701	0.001	0.003	0.007
overview write	00:00:05.236	0.0%	31795	0.105	0.165	0.541
perl filter	00:02:01.155	0.1%	58275	0.989	2.079	4.048
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.559	0.0%	63591	0.000	0.009	0.023
TOTAL: 24:00:53.934	24:00:12.274	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.080	0.0%	31741	0.000	0.003	0.023
article prepare	00:00:00.006	0.0%	9118	0.000	0.001	0.062
article read	00:00:00.271	0.0%	1910	0.000	0.142	15.333
backlog stats	00:00:00.117	0.0%	328898	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.427	0.0%	244458	0.002	0.006	0.015
data write	00:00:01.899	0.0%	215214	0.002	0.009	0.017
idle	23:52:29.678	100.0%	328898	77.274	261.326	889.981
status file	00:00:00.073	0.0%	187	0.000	0.390	1.000
TOTAL: 23:52:37.559	23:52:33.551	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.191	0.0%	58691	0.000	0.020	0.056
idle	106:02:37.225	100.0%	91456	0.000	4174.217	600326.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.335	0.0%	183948	0.000	0.007	2.603
readart	00:00:00.371	0.0%	88	0.000	4.216	57.000
TOTAL: 106:04:36.582	106:02:40.122	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	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	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	feeder.eternal-september.org	2	54532	7794	43237	3501	14%	24:48:10
2	news-out.mixmin.net	4	38844	6468	28628	3748	16%	24:12:31
3	au2pb.net	1	34201	5617	27549	1035	16%	23:39:29
4	xanadu-bbs.net	3	58740	4991	44934	8815	8%	23:54:21
5	newsfeed.neva.ru	141	67007	4040	52882	10085	6%	71:43:49
6	newsfeeds.xmission.com	2	30765	1935	28741	89	6%	22:48:46
7	nntp.pasdenom.info	5	43689	592	43039	58	1%	35:12:21
8	news.swapon.de	1	2587	454	2102	31	17%	23:42:49
9	localhost	51	11280	1	11279	0	0%	00:01:04
TOTAL: 9		210	341645	31892	282391	27362	9%	250:03:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.mixmin.net	26.5 MB	0.0 KB	13.6 MB	40.1 MB	66%	4.0 KB
2	feeder.eternal-september.org	24.8 MB	0.0 KB	32.7 MB	57.5 MB	43%	5.2 KB
3	xanadu-bbs.net	14.7 MB	21.1 KB	150.3 MB	164.9 MB	8%	12.2 KB
4	au2pb.net	14.5 MB	2.6 KB	4.7 MB	19.3 MB	75%	3.0 KB
5	newsfeed.neva.ru	14.1 MB	17.0 KB	78.4 MB	92.4 MB	15%	6.7 KB
6	newsfeeds.xmission.com	5.7 MB	10.0 KB	1.6 MB	7.4 MB	77%	3.7 KB
7	nntp.pasdenom.info	1.4 MB	0.0 KB	774.8 KB	2.2 MB	65%	3.5 KB
8	news.swapon.de	1.1 MB	0.0 KB	111.7 KB	1.2 MB	91%	2.6 KB
9	localhost	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	2.2 KB
TOTAL: 9		102.9 MB	50.7 KB	282.1 MB	385.1 MB	26%	6.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 17 04:15:00 - 04:59:59	434	1.4%	0.16	1.5 MB	1.4%	0.56
Aug 17 05:00:00 - 05:59:59	566	1.8%	0.16	3.1 MB	3.0%	0.87
Aug 17 06:00:00 - 06:59:59	773	2.4%	0.21	2.0 MB	2.0%	0.58
Aug 17 07:00:00 - 07:59:59	1165	3.7%	0.32	3.1 MB	3.1%	0.89
Aug 17 08:00:00 - 08:59:59	1823	5.7%	0.51	7.6 MB	7.4%	2.16
Aug 17 09:00:00 - 09:59:59	2089	6.6%	0.58	8.9 MB	8.7%	2.54
Aug 17 10:00:00 - 10:59:59	1670	5.3%	0.46	5.9 MB	5.8%	1.68
Aug 17 11:00:00 - 11:59:59	1840	5.8%	0.51	6.8 MB	6.6%	1.93
Aug 17 12:00:00 - 12:59:59	2037	6.4%	0.57	7.2 MB	7.0%	2.06
Aug 17 13:00:00 - 13:59:59	2739	8.6%	0.76	9.7 MB	9.4%	2.76
Aug 17 14:00:00 - 14:59:59	1316	4.1%	0.37	3.4 MB	3.3%	0.97
Aug 17 15:00:00 - 15:59:59	1506	4.7%	0.42	4.4 MB	4.2%	1.24
Aug 17 16:00:00 - 16:59:59	1578	5.0%	0.44	4.1 MB	3.9%	1.15
Aug 17 17:00:00 - 17:59:59	1729	5.4%	0.48	4.4 MB	4.3%	1.27
Aug 17 18:00:00 - 18:59:59	1707	5.4%	0.47	4.4 MB	4.3%	1.25
Aug 17 19:00:00 - 19:59:59	1618	5.1%	0.45	4.4 MB	4.3%	1.26
Aug 17 20:00:00 - 20:59:59	1531	4.8%	0.43	4.3 MB	4.2%	1.22
Aug 17 21:00:00 - 21:59:59	1275	4.0%	0.35	3.7 MB	3.6%	1.04
Aug 17 22:00:00 - 22:59:59	1020	3.2%	0.28	2.9 MB	2.9%	0.84
Aug 17 23:00:00 - 23:59:59	781	2.5%	0.22	2.4 MB	2.3%	0.67
Aug 18 00:00:00 - 00:59:59	713	2.2%	0.20	2.3 MB	2.2%	0.66
Aug 18 01:00:00 - 01:59:59	675	2.1%	0.19	2.2 MB	2.1%	0.63
Aug 18 02:00:00 - 02:59:59	546	1.7%	0.15	1.9 MB	1.8%	0.54
Aug 18 03:00:00 - 03:59:59	513	1.6%	0.14	1.6 MB	1.6%	0.47
Aug 18 04:00:00 - 04:15:00	136	0.4%	0.15	404.7 KB	0.4%	0.45
TOTAL: 24:00:00	31780	100.0%	0.37	102.7 MB	100.0%	1.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	10108	1872	1	1	8234
2	xanadu-bbs.net	8873	1143	2	0	7728
3	news-out.mixmin.net	3812	21	0	0	3791
4	feeder.eternal-september.org	3470	348	0	0	3122
5	au2pb.net	1073	986	1	0	86
6	news.swapon.de	101	0	0	0	101
7	newsfeeds.xmission.com	97	69	1	0	27
8	nntp.pasdenom.info	49	35	0	0	14
TOTAL: 8		27583	4474	5	1	23103

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	652
mailing.freebsd.pkg-fallout	452
lada.talk	257
ut.test	165
mailing.freebsd.svn-ports-all	161
mailing.freebsd.svn-ports-head	142
lada.auto	128
alt.politics	121
mailing.freebsd.ports-bugs	118
alt.politics.scorched-earth	101
alt.windows7.general	93
free.it.calcio.juventus	78
alt.job	78
mpc.lists.freebsd.ports.bugs	73
alt.survival	67
gmane.linux.alsa.devel	51
mailing.freebsd.svn-src-all	50
mozilla.dev.tree-alerts	49
mailing.freebsd.stable	48
mailing.dragonflybsd.commits	42
TOTAL: 279	4474

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

Groups	Count
it.scienza.astronomia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16944
EMP (phn path)	3889
EMP (phl)	838
Too many newsgroups	103
EMP (phn nph)	100
HTML Multipart	59
Binary Image: misplaced jpg	19
HTML post	6
HTML Image Tags	5
Too many newsgroups (low_xpost)	2
Binary: misplaced par	1
Binary: misplaced sfv	1
Binary Payload	1
TOTAL: 13	21968

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	728
	feeder.eternal-september.org	74
	au2pb.net	49
	news-out.mixmin.net	49
	newsfeeds.xmission.com	20
	nntp.pasdenom.info	6
	TOTAL: 6	926
Including strange strings
	news.swapon.de	67
	news-out.mixmin.net	64
	feeder.eternal-september.org	49
	au2pb.net	14
	newsfeeds.xmission.com	6
	xanadu-bbs.net	5
	newsfeed.neva.ru	4
	TOTAL: 7	209
TOTAL: 2		1135

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	usenet.pasdenom.info	38113	378	29753	1195	0	7	0%	23:59:58
2	au2pb	27530	246	25694	183	1	0	0%	23:59:58
3	xanadu-bbs	30222	111	26629	36	0	13	0%	23:59:58
4	news.mixmin.net	26130	23	25188	27	0	0	0%	23:59:53
5	xmission	37384	10	29604	1	0	0	0%	23:59:58
6	eternal-september	24738	8	23508	4	0	0	0%	23:59:53
7	neva.ru	28352	7	27408	50	0	0	0%	23:59:56
8	news.swapon.de	3034	2	3004	5	0	0	0%	23:51:55
TOTAL: 8		215503	785	190788	1501	1	20	0%	191:51:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.2 MB	2.2 MB	3.4 MB	2.2 KB	23:59:58
2	au2pb	1.1 MB	587.1 KB	1.6 MB	3.9 KB	23:59:58
3	xanadu-bbs	609.8 KB	65.4 KB	675.3 KB	4.6 KB	23:59:58
4	news.mixmin.net	119.4 KB	56.8 KB	176.2 KB	3.5 KB	23:59:53
5	eternal-september	41.5 KB	4.7 KB	46.3 KB	3.9 KB	23:59:53
6	neva.ru	31.5 KB	259.2 KB	290.7 KB	5.1 KB	23:59:56
7	xmission	22.4 KB	2.5 KB	24.9 KB	2.3 KB	23:59:58
8	news.swapon.de	6.4 KB	12.2 KB	18.6 KB	2.7 KB	23:51:55
TOTAL: 8		3.1 MB	3.1 MB	6.2 MB	2.8 KB	191:51:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	202.89.176.220	288	54	143.5 KB	51	1	04:25:54
2	tempe0.bbox.io	78	13	21.6 KB	2	0	13:05:42
3	71.94.21.184	8	12	13.4 KB	1	0	08:59:16
4	92-108-162-83.dynamic.upc.nl	2	3	11.6 KB	1	0	00:06:38
5	llnw-corp-src.phx2.llnw.com	59	3	6.1 KB	2	0	08:32:44
6	challenger.nielsenmedia.com	6	1	2.3 KB	1	0	02:26:03
7	pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	1	2.3 KB	1	0	01:01:24
8	dhcp-089-099-203-254.chello.nl	66	1	1.7 KB	1	0	25:13:35
TOTAL: 8		512	88	202.5 KB	60	1	106:04:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	321	66	156.9 KB	52	1	55:37:42
2	*.bbox.io	78	13	21.6 KB	2	0	13:05:42
3	*.dynamic.upc.nl	2	3	11.6 KB	1	0	00:06:38
4	*.phx2.llnw.com	59	3	6.1 KB	2	0	08:32:44
5	*.chello.nl	66	1	1.7 KB	1	0	25:13:35
6	*.nielsenmedia.com	6	1	2.3 KB	1	0	02:26:03
7	*.tampfl.dsl-w.verizon.net	5	1	2.3 KB	1	0	01:01:24
8	*.ip-188-165-239.eu	2	0	0.0 KB	0	0	00:00:39
TOTAL: 8		539	88	202.5 KB	60	1	106:04:30

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.198	0.765	0.956	0.000	42:12:28
dhcp-089-099-203-254.chello.nl	5.229	1.005	0.000	25:13:35
tempe0.bbox.io	6.475	1.086	0.000	13:05:42
71.94.21.184	1.500	1.688	0.000	08:59:16
llnw-corp-src.phx2.llnw.com	5.089	0.781	0.000	08:32:44
202.89.176.220	27.405	5.138	0.697	04:25:54
challenger.nielsenmedia.com	0.556	0.055	0.000	02:26:03
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.509	0.063	0.000	01:01:24
92-108-162-83.dynamic.upc.nl	0.254	0.024	0.000	00:06:38
ns342002.ip-188-165-239.eu	0.134	0.016	0.000	00:00:39
69.160.37.54	1.881	0.381	0.000	00:00:02
TOTAL: 11	49.797	11.193	0.697	106:04:30

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
ns342002.ip-188-165-239.eu	2
TOTAL: 2	27

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	7
TOTAL: 1	7

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	7
TOTAL: 1	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	42	0
challenger.nielsenmedia.com	4	0
llnw-corp-src.phx2.llnw.com	2	0
tempe0.bbox.io	1	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 5	50	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	61	69.3%
2	alt	25	28.4%
3	news	2	2.3%
TOTAL: 3		88	100.0%

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

Newsgroup		Count
1	comp.os.vms	27
2	alt.free.newsservers	21
3	comp.sys.ibm.ps2.hardware	9
4	comp.sys.ibm.pc.games.rpg	7
5	comp.arch	6
6	comp.sys.ibm.pc.games.action	5
7	alt.test	4
8	comp.os.os2.apps	2
9	comp.lang.awk	2
10	news.admin.peering	2
11	comp.sys.ibm.pc.hardware.misc	1
12	comp.ai.philosophy	1
13	comp.ai.neural-nets	1
TOTAL: 13		88

Daily Usenet report for csiph.com

Aug 17 04:15:00 -- Aug 18 04:15:00