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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 4 09:50:13 csiph pgpverify[52590]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40369	44.7%	6.0 MB	57.0%
nnrpd	32437	35.9%	2.7 MB	25.4%
innd	15636	17.3%	1.6 MB	15.1%
innfeed	1841	2.0%	270.4 KB	2.5%
controlchan	4	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	90288	100.0%	10.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	223685	48.0%
Negative hits	199990	42.9%
Do not exist	39648	8.5%
Cache misses	3008	0.6%
TOTAL: 4	466331	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.547	0.0%	259	0.000	2.112	29.000
article cleanup	00:00:00.231	0.0%	39191	0.000	0.006	0.033
article logging	00:00:00.488	0.0%	39640	0.000	0.012	0.154
article parse	00:00:01.529	0.0%	178450	0.001	0.009	0.032
article write	00:00:05.845	0.0%	23663	0.175	0.247	0.786
artlog/artcncl	00:00:00.001	0.0%	163	0.000	0.006	0.077
artlog/artparse	00:00:00.002	0.0%	587	0.000	0.003	0.007
data move	00:00:00.217	0.0%	573524	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.064	0.0%	258	0.000	0.248	29.000
hishave/artcncl	00:00:00.000	0.0%	259	0.000	0.000	0.000
hishave/artparse	00:00:00.002	0.0%	587	0.000	0.003	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.311	0.0%	466757	0.000	0.003	0.009
history sync	00:00:00.605	0.0%	355	0.000	1.704	20.000
history write	00:00:13.309	0.0%	39190	0.067	0.340	3.616
hiswrite/artcncl	00:00:00.003	0.0%	1	3.000	3.000	3.000
hiswrite/artparse	00:00:00.127	0.0%	587	0.000	0.216	18.000
idle	23:58:22.244	99.8%	564633	32.844	152.847	482.765
nntp read	00:00:01.933	0.0%	563186	0.001	0.003	0.008
overview write	00:00:24.053	0.0%	23663	0.101	1.016	5.937
perl filter	00:01:08.950	0.1%	39190	0.934	1.759	3.483
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.430	0.0%	47327	0.000	0.009	0.026
TOTAL: 24:00:54.672	24:00:21.891	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.082	0.0%	23632	0.000	0.003	0.031
article prepare	00:00:00.001	0.0%	7308	0.000	0.000	0.008
article read	00:00:00.199	0.0%	1327	0.000	0.150	22.000
backlog stats	00:00:00.106	0.0%	276000	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.209	0.0%	207939	0.000	0.006	0.013
data write	00:00:01.600	0.0%	186519	0.000	0.009	0.019
idle	23:53:30.622	100.0%	276000	178.947	311.633	838.008
status file	00:00:00.077	0.0%	174	0.000	0.443	2.000
TOTAL: 23:53:38.660	23:53:33.896	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.540	0.0%	18966	0.000	0.028	0.116
idle	68:08:32.609	100.0%	50554	0.000	4852.487	600145.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:13.625	0.0%	435995	0.000	0.031	1.259
readart	00:00:04.596	0.0%	20959	0.000	0.219	57.000
TOTAL: 68:10:21.270	68:08:51.370	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
admin@openwatcom.news-admin.org	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	5	36632	6549	26932	3151	17%	24:37:37
2	au2pb.net	1	24305	5422	18284	599	22%	22:54:01
3	xanadu-bbs.net	1	39600	3823	30176	5601	9%	23:59:58
4	news-out.mixmin.net	4	30876	2944	24976	2956	9%	24:12:33
5	newsfeed.neva.ru	141	47527	1608	42639	3280	3%	71:29:41
6	newsfeeds.xmission.com	1	23656	1442	22165	49	6%	24:03:12
7	newsfeed.fu-berlin.de	42	27692	1339	25896	457	4%	30:41:45
8	news.swapon.de	4	3886	439	3447	0	11%	46:02:58
9	nntp.pasdenom.info	4	38903	240	38640	23	0%	38:29:26
10	localhost	49	9840	1	9839	0	0%	00:00:56
TOTAL: 10		252	282917	23807	242994	16116	8%	306:32:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	17.6 MB	0.0 KB	20.3 MB	37.9 MB	46%	4.0 KB
2	au2pb.net	14.3 MB	0.0 KB	2.7 MB	17.0 MB	83%	2.9 KB
3	xanadu-bbs.net	10.7 MB	0.0 KB	78.9 MB	89.6 MB	11%	9.7 KB
4	news-out.mixmin.net	9.6 MB	0.0 KB	6.9 MB	16.5 MB	58%	2.9 KB
5	newsfeed.neva.ru	5.4 MB	1.1 KB	23.1 MB	28.5 MB	19%	6.0 KB
6	newsfeeds.xmission.com	4.3 MB	0.0 KB	414.6 KB	4.7 MB	91%	3.2 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	1.0 MB	3.9 MB	73%	2.2 KB
8	news.swapon.de	1.2 MB	0.0 KB	0.0 KB	1.2 MB	100%	2.8 KB
9	nntp.pasdenom.info	493.0 KB	1.3 KB	472.4 KB	966.7 KB	51%	3.7 KB
10	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 10		66.4 MB	2.4 KB	133.8 MB	200.2 MB	33%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 4 04:15:00 - 04:59:59	253	1.1%	0.09	685.0 KB	1.0%	0.25
Oct 4 05:00:00 - 05:59:59	434	1.8%	0.12	1.3 MB	2.0%	0.37
Oct 4 06:00:00 - 06:59:59	636	2.7%	0.18	2.6 MB	3.9%	0.73
Oct 4 07:00:00 - 07:59:59	876	3.7%	0.24	2.5 MB	3.7%	0.70
Oct 4 08:00:00 - 08:59:59	951	4.0%	0.26	2.3 MB	3.5%	0.65
Oct 4 09:00:00 - 09:59:59	1114	4.7%	0.31	2.8 MB	4.3%	0.81
Oct 4 10:00:00 - 10:59:59	1126	4.8%	0.31	3.0 MB	4.6%	0.86
Oct 4 11:00:00 - 11:59:59	1022	4.3%	0.28	2.7 MB	4.0%	0.76
Oct 4 12:00:00 - 12:59:59	1167	4.9%	0.32	3.1 MB	4.7%	0.89
Oct 4 13:00:00 - 13:59:59	1116	4.7%	0.31	2.9 MB	4.3%	0.82
Oct 4 14:00:00 - 14:59:59	1181	5.0%	0.33	3.3 MB	4.9%	0.93
Oct 4 15:00:00 - 15:59:59	1314	5.6%	0.36	3.2 MB	4.8%	0.91
Oct 4 16:00:00 - 16:59:59	1450	6.1%	0.40	4.2 MB	6.4%	1.20
Oct 4 17:00:00 - 17:59:59	1483	6.3%	0.41	4.2 MB	6.3%	1.19
Oct 4 18:00:00 - 18:59:59	1520	6.4%	0.42	4.2 MB	6.3%	1.19
Oct 4 19:00:00 - 19:59:59	1479	6.3%	0.41	4.0 MB	6.1%	1.14
Oct 4 20:00:00 - 20:59:59	1342	5.7%	0.37	3.4 MB	5.2%	0.98
Oct 4 21:00:00 - 21:59:59	1214	5.1%	0.34	3.2 MB	4.9%	0.92
Oct 4 22:00:00 - 22:59:59	890	3.8%	0.25	2.5 MB	3.7%	0.70
Oct 4 23:00:00 - 23:59:59	673	2.8%	0.19	1.9 MB	2.9%	0.54
Oct 5 00:00:00 - 00:59:59	690	2.9%	0.19	2.1 MB	3.1%	0.59
Oct 5 01:00:00 - 01:59:59	507	2.1%	0.14	1.7 MB	2.6%	0.49
Oct 5 02:00:00 - 02:59:59	593	2.5%	0.16	2.5 MB	3.8%	0.71
Oct 5 03:00:00 - 03:59:59	489	2.1%	0.14	1.5 MB	2.3%	0.44
Oct 5 04:00:00 - 04:15:00	134	0.6%	0.15	537.7 KB	0.8%	0.60
TOTAL: 24:00:00	23654	100.0%	0.27	66.2 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	xanadu-bbs.net	5567	432	0	0	5135
2	newsfeed.neva.ru	3293	694	1	1	2597
3	feeder.eternal-september.org	3183	269	0	0	2914
4	news-out.mixmin.net	2971	4	0	0	2967
5	newsfeed.fu-berlin.de	792	28	0	0	764
6	au2pb.net	654	613	0	0	41
7	newsfeeds.xmission.com	51	42	0	0	9
8	news.swapon.de	31	0	0	0	31
9	nntp.pasdenom.info	10	6	0	0	4
TOTAL: 9		16552	2088	1	1	14462

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	244
alt.politics.scorched-earth	122
alt.politics	114
lada.talk	105
mailing.freebsd.svn-ports-all	69
mailing.freebsd.svn-ports-head	67
it-alt.sport.calcio.juventus	64
free.it.calcio.juventus	51
ut.test	50
mailing.openbsd.source-changes	50
alt.survival	47
pld.cvs.commit	43
mpc.lists.freebsd.ports.bugs	42
mailing.freebsd.ports-bugs	42
mailing.freebsd.pkg-fallout	36
mozilla.support.seamonkey	36
fa.openbsd.source-changes	35
mozilla.dev.tree-alerts	33
mozilla.support.thunderbird	30
mpc.lists.nanog	29
TOTAL: 210	2088

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

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9618
EMP (phn path)	2531
EMP (phl)	914
EMP (phn nph)	127
PFM bot misc	75
Too many newsgroups	57
Binary Image: misplaced jpg	28
HTML post	27
HTML Multipart	17
Binary: misplaced torr	9
Too many newsgroups (low_xpost)	8
User-issued spam cancel	7
Binary Payload	4
HTML Image Tags	3
Binary: misplaced par2	2
Binary: misplaced doc	1
TOTAL: 16	13428

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	424
	feeder.eternal-september.org	94
	news-out.mixmin.net	36
	au2pb.net	22
	newsfeeds.xmission.com	5
	newsfeed.fu-berlin.de	3
	nntp.pasdenom.info	1
	TOTAL: 7	585
Including strange strings
	newsfeed.fu-berlin.de	344
	news.swapon.de	31
	feeder.eternal-september.org	27
	news-out.mixmin.net	19
	au2pb.net	13
	xanadu-bbs.net	10
	newsfeed.neva.ru	4
	newsfeeds.xmission.com	1
	TOTAL: 8	449
TOTAL: 2		1034

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	18997	236	17675	179	0	1%	23:59:50
2	usenet.pasdenom.info	30212	187	22588	724	15	0%	23:59:56
3	xanadu-bbs	21205	102	19665	61	4	0%	23:59:57
4	fu-berlin.de	21967	45	21882	40	0	0%	23:59:56
5	neva.ru	23569	22	21792	149	0	0%	23:59:56
6	news.mixmin.net	21463	22	20595	22	0	0%	23:59:56
7	xmission	28282	19	22045	0	0	0%	23:59:56
8	eternal-september	17956	13	16791	6	0	0%	23:59:55
9	news.swapon.de	1939	1	1921	2	0	0%	23:57:59
TOTAL: 9		185590	647	164954	1183	19	0%	215:57:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	970.7 KB	433.6 KB	1.4 MB	3.4 KB	23:59:50
2	usenet.pasdenom.info	753.7 KB	1.4 MB	2.1 MB	2.4 KB	23:59:56
3	xanadu-bbs	563.1 KB	127.1 KB	690.2 KB	4.2 KB	23:59:57
4	fu-berlin.de	260.0 KB	54.0 KB	314.0 KB	3.7 KB	23:59:56
5	news.mixmin.net	139.9 KB	45.1 KB	184.9 KB	4.2 KB	23:59:56
6	neva.ru	120.4 KB	618.7 KB	739.1 KB	4.3 KB	23:59:56
7	eternal-september	38.7 KB	9.4 KB	48.2 KB	2.5 KB	23:59:55
8	xmission	33.3 KB	0.0 KB	33.3 KB	1.8 KB	23:59:56
9	news.swapon.de	1.1 KB	4.1 KB	5.2 KB	1.7 KB	23:57:59
TOTAL: 9		2.8 MB	2.7 MB	5.5 MB	3.1 KB	215:57:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	202.89.176.220	288	64	172.4 KB	58	0	04:19:03
2	tempe0.bbox.io	4	40	84.2 KB	6	1	00:13:56
3	dhcp-089-099-203-254.chello.nl	16	1	3.4 KB	1	0	07:33:43
4	71.94.21.184	14	1	0.6 KB	1	0	18:04:45
TOTAL: 4		322	106	260.7 KB	66	1	68:10:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	435	65	173.0 KB	59	0	57:05:35
2	*.bbox.io	4	40	84.2 KB	6	1	00:13:56
3	*.chello.nl	16	1	3.4 KB	1	0	07:33:43
4	*.tampfl.dsl-w.verizon.net	8	0	0.0 KB	0	0	03:17:02
TOTAL: 4		463	106	260.7 KB	66	1	68:10:16

NNRP auth users [Top 20]:

User	Conn
moussa	288
kev009	1
TOTAL: 2	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71.94.21.184	1.630	0.922	0.000	18:04:45
162.212.88.198	0.787	0.448	0.000	18:01:15
108.175.241.207	8.580	2.062	0.000	16:40:28
dhcp-089-099-203-254.chello.nl	1.438	0.250	0.000	07:33:43
202.89.176.220	27.443	5.795	0.728	04:19:03
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.564	0.137	0.000	03:17:02
tempe0.bbox.io	1.252	1.009	0.006	00:13:56
69.160.37.54	1.839	0.402	0.000	00:00:02
TOTAL: 8	43.533	11.025	0.734	68:10:16

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	100
69.160.37.54	25
162.212.88.198	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	8
TOTAL: 4	141

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	12
TOTAL: 1	12

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	12
TOTAL: 1	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	15	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6	0
TOTAL: 2	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	65	72.2%
2	news	24	26.7%
3	alt	1	1.1%
TOTAL: 3		90	100.0%

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

Newsgroup		Count
1	comp.os.vms	29
2	news.software.nntp	23
3	comp.arch.embedded	14
4	comp.arch	7
5	comp.sys.ibm.pc.games.action	5
6	comp.sys.ibm.pc.hardware.storage	3
7	comp.arch.fpga	2
8	comp.sys.ibm.as400.misc	2
9	comp.sys.ibm.pc.games.war-historical	1
10	comp.sys.ibm.ps2.hardware	1
11	comp.answers	1
12	alt.test	1
13	news.answers	1
TOTAL: 13		90

Daily Usenet report for csiph.com

Oct 4 04:15:00 -- Oct 5 04:15:00