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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 17 11:10:03 csiph pgpverify[10554]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	54889	49.2%	7.9 MB	60.3%
nnrpd	32085	28.7%	2.7 MB	20.6%
innd	22733	20.4%	2.2 MB	16.9%
innfeed	1895	1.7%	286.8 KB	2.2%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	111607	100.0%	13.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	288041	46.4%
Negative hits	272308	43.9%
Do not exist	53970	8.7%
Cache misses	5860	0.9%
TOTAL: 4	620179	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.582	0.0%	216	0.000	2.694	141.000
article cleanup	00:00:00.298	0.0%	53514	0.000	0.006	0.017
article logging	00:00:00.614	0.0%	54026	0.000	0.011	0.031
article parse	00:00:02.280	0.0%	278252	0.001	0.008	0.018
article write	00:00:06.857	0.0%	29525	0.133	0.232	0.351
artlog/artcncl	00:00:00.001	0.0%	207	0.000	0.005	1.000
artlog/artparse	00:00:00.011	0.0%	693	0.000	0.016	0.250
data move	00:00:00.315	0.0%	777461	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.003	0.0%	215	0.000	0.014	0.167
hishave/artcncl	00:00:00.000	0.0%	216	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	693	0.000	0.004	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.460	0.0%	622285	0.000	0.002	0.008
history sync	00:00:00.578	0.0%	335	0.000	1.725	3.000
history write	00:00:14.534	0.0%	53504	0.060	0.272	0.358
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.194	0.0%	691	0.000	0.281	3.000
idle	23:57:49.021	99.8%	768016	26.370	112.327	338.840
nntp read	00:00:02.526	0.0%	766713	0.001	0.003	0.006
overview write	00:00:04.775	0.0%	29525	0.082	0.162	0.346
perl filter	00:01:45.980	0.1%	53504	1.093	1.981	3.707
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.557	0.0%	59051	0.000	0.009	0.039
TOTAL: 24:00:46.913	24:00:10.589	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	29464	0.000	0.002	0.016
article prepare	00:00:00.000	0.0%	9168	0.000	0.000	0.000
article read	00:00:00.263	0.0%	1674	0.000	0.157	9.000
backlog stats	00:00:00.095	0.0%	327466	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.392	0.0%	251968	0.001	0.006	0.011
data write	00:00:01.932	0.0%	225351	0.003	0.009	0.019
idle	23:54:24.930	100.0%	327466	164.363	262.821	768.137
status file	00:00:00.094	0.0%	200	0.000	0.470	1.000
TOTAL: 23:54:33.078	23:54:28.771	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.169	0.0%	9234	0.000	0.018	0.062
idle	36:09:37.837	99.9%	40653	0.000	3202.170	600150.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.507	0.0%	82276	0.000	0.006	0.333
readart	00:00:00.215	0.0%	92	0.000	2.337	47.000
TOTAL: 36:10:56.510	36:09:38.728	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	166

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	5	48124	7755	37685	2684	16%	23:46:23
2	xanadu-bbs.net	1	53918	5932	38646	9340	11%	23:58:39
3	news-out.mixmin.net	6	35488	4319	28305	2864	12%	24:25:27
4	au2pb.net	1	31540	3534	27404	602	11%	23:22:26
5	newsfeed.neva.ru	149	64927	3018	53484	8425	4%	71:41:35
6	newsfeeds.xmission.com	1	30459	2281	27927	251	7%	24:14:47
7	newsfeed.fu-berlin.de	70	31388	1299	29853	236	4%	33:15:59
8	nntp.pasdenom.info	2	24490	769	23674	47	3%	17:30:27
9	news.swapon.de	3	3027	290	2729	8	9%	31:17:54
10	localhost	50	16285	0	16285	0	0%	00:01:03
TOTAL: 10		288	339646	29197	285992	24457	8%	273:34:40

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.2 MB	15.7 KB	16.5 MB	37.7 MB	56%	3.7 KB
2	xanadu-bbs.net	17.0 MB	0.0 KB	136.7 MB	153.6 MB	11%	10.3 KB
3	news-out.mixmin.net	12.6 MB	1.8 KB	7.2 MB	19.9 MB	63%	2.8 KB
4	newsfeed.neva.ru	10.2 MB	29.8 KB	67.2 MB	77.4 MB	13%	6.9 KB
5	au2pb.net	8.6 MB	0.0 KB	2.9 MB	11.5 MB	74%	2.9 KB
6	newsfeeds.xmission.com	7.0 MB	0.0 KB	1.2 MB	8.2 MB	85%	3.3 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	745.5 KB	3.6 MB	79%	2.4 KB
8	nntp.pasdenom.info	1.7 MB	0.0 KB	224.9 KB	2.0 MB	88%	2.5 KB
9	news.swapon.de	729.6 KB	0.0 KB	18.4 KB	748.1 KB	97%	2.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		81.9 MB	47.3 KB	232.7 MB	314.6 MB	26%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 04:15:00 - 04:59:59	521	1.8%	0.19	1.7 MB	2.0%	0.63
Sep 17 05:00:00 - 05:59:59	667	2.3%	0.19	2.1 MB	2.6%	0.60
Sep 17 06:00:00 - 06:59:59	900	3.0%	0.25	2.3 MB	2.8%	0.67
Sep 17 07:00:00 - 07:59:59	1184	4.0%	0.33	3.1 MB	3.8%	0.88
Sep 17 08:00:00 - 08:59:59	1267	4.3%	0.35	3.4 MB	4.1%	0.96
Sep 17 09:00:00 - 09:59:59	1418	4.8%	0.39	3.5 MB	4.3%	1.01
Sep 17 10:00:00 - 10:59:59	1562	5.3%	0.43	4.5 MB	5.5%	1.29
Sep 17 11:00:00 - 11:59:59	1502	5.1%	0.42	4.1 MB	5.0%	1.18
Sep 17 12:00:00 - 12:59:59	1566	5.3%	0.43	4.0 MB	4.8%	1.14
Sep 17 13:00:00 - 13:59:59	1742	5.9%	0.48	4.6 MB	5.6%	1.32
Sep 17 14:00:00 - 14:59:59	1599	5.4%	0.44	4.3 MB	5.1%	1.21
Sep 17 15:00:00 - 15:59:59	1695	5.7%	0.47	4.7 MB	5.7%	1.35
Sep 17 16:00:00 - 16:59:59	1535	5.2%	0.43	4.3 MB	5.2%	1.22
Sep 17 17:00:00 - 17:59:59	1583	5.4%	0.44	4.0 MB	4.9%	1.14
Sep 17 18:00:00 - 18:59:59	1684	5.7%	0.47	4.6 MB	5.6%	1.32
Sep 17 19:00:00 - 19:59:59	1632	5.5%	0.45	4.8 MB	5.8%	1.35
Sep 17 20:00:00 - 20:59:59	1449	4.9%	0.40	3.9 MB	4.7%	1.11
Sep 17 21:00:00 - 21:59:59	1464	5.0%	0.41	3.9 MB	4.7%	1.10
Sep 17 22:00:00 - 22:59:59	1024	3.5%	0.28	3.2 MB	3.9%	0.92
Sep 17 23:00:00 - 23:59:59	810	2.7%	0.23	3.2 MB	3.8%	0.90
Sep 18 00:00:00 - 00:59:59	690	2.3%	0.19	2.0 MB	2.4%	0.57
Sep 18 01:00:00 - 01:59:59	648	2.2%	0.18	1.7 MB	2.1%	0.50
Sep 18 02:00:00 - 02:59:59	644	2.2%	0.18	2.2 MB	2.6%	0.62
Sep 18 03:00:00 - 03:59:59	555	1.9%	0.15	2.0 MB	2.4%	0.58
Sep 18 04:00:00 - 04:15:00	173	0.6%	0.19	504.7 KB	0.6%	0.56
TOTAL: 24:00:00	29514	100.0%	0.34	82.7 MB	100.0%	0.98

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9481	967	0	8514
2	newsfeed.neva.ru	8438	1529	4	6905
3	news-out.mixmin.net	2876	6	1	2869
4	feeder.eternal-september.org	2792	285	2	2505
5	au2pb.net	628	601	1	26
6	newsfeed.fu-berlin.de	585	38	0	547
7	newsfeeds.xmission.com	254	235	1	18
8	nntp.pasdenom.info	82	70	1	11
9	news.swapon.de	32	0	0	32
TOTAL: 9		25168	3731	10	21427

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	824
lada.talk	220
ut.test	154
it-alt.sport.calcio.juventus	149
alt.politics	128
mailing.freebsd.svn-ports-all	119
mailing.freebsd.svn-ports-head	117
lada.auto	75
mozilla.dev.tree-alerts	72
gmane.linux.alsa.devel	68
mailing.freebsd.svn-src-all	62
alt.survival	60
fa.freebsd.cvs-all	59
mailing.freebsd.svn-src-head	56
mailing.freebsd.current	52
mailing.gcc.gcc-cvs	51
mailing.database.pgsql-general	50
mpc.lists.nanog	46
mailing.freebsd.ports-bugs	43
fa.freebsd.current	43
TOTAL: 250	3731

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16191
EMP (phn path)	2541
EMP (phl)	901
EMP (phn nph)	324
PFM bot misc	113
Too many newsgroups	65
HTML Multipart	54
Binary Image: misplaced jpg	15
HTML post	6
HTML Image Tags	6
Too many newsgroups (low_xpost)	5
Binary: misplaced zip	1
Binary Payload	1
TOTAL: 13	20223

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	586
	feeder.eternal-september.org	46
	news-out.mixmin.net	40
	nntp.pasdenom.info	6
	au2pb.net	5
	newsfeed.fu-berlin.de	5
	newsfeeds.xmission.com	4
	TOTAL: 7	692
Including strange strings
	newsfeed.fu-berlin.de	310
	xanadu-bbs.net	85
	feeder.eternal-september.org	41
	news.swapon.de	26
	news-out.mixmin.net	16
	au2pb.net	14
	newsfeeds.xmission.com	12
	newsfeed.neva.ru	7
	nntp.pasdenom.info	1
	TOTAL: 9	512
TOTAL: 2		1204

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	34679	410	27608	600	4	1%	23:59:57
2	au2pb	27139	407	25085	404	0	1%	23:59:50
3	xanadu-bbs	25286	157	23381	26	13	0%	23:59:52
4	fu-berlin.de	27755	70	27647	37	0	0%	23:59:52
5	news.mixmin.net	25719	41	24911	30	0	0%	23:59:57
6	xmission	33001	10	27072	3	0	0%	23:59:53
7	eternal-september	22664	7	21158	5	0	0%	23:59:54
8	neva.ru	27643	4	26265	73	0	0%	23:59:56
9	news.swapon.de	2280	0	2257	11	0	0%	23:55:34
TOTAL: 9		226166	1106	205384	1189	17	0%	215:54:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.8 MB	1.2 MB	3.1 MB	3.9 KB	23:59:50
2	usenet.pasdenom.info	1.4 MB	1.5 MB	2.9 MB	2.9 KB	23:59:57
3	xanadu-bbs	910.7 KB	49.8 KB	960.6 KB	5.2 KB	23:59:52
4	fu-berlin.de	608.6 KB	47.9 KB	656.5 KB	6.1 KB	23:59:52
5	news.mixmin.net	197.8 KB	56.2 KB	254.0 KB	3.6 KB	23:59:57
6	eternal-september	32.0 KB	6.6 KB	38.6 KB	3.2 KB	23:59:54
7	xmission	15.3 KB	77.7 KB	93.0 KB	7.2 KB	23:59:53
8	neva.ru	14.7 KB	323.5 KB	338.2 KB	4.4 KB	23:59:56
9	news.swapon.de	0.0 KB	22.2 KB	22.2 KB	2.0 KB	23:55:34
TOTAL: 9		5.0 MB	3.3 MB	8.3 MB	3.7 KB	215:54:45

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	58	162.2 KB	53	04:44:06
2	challenger.nielsenmedia.com	12	29	58.2 KB	6	03:40:59
3	pool-71-98-160-84.tampfl.dsl-w.verizon.net	11	4	9.5 KB	4	02:51:28
4	156.188.193.178.dynamic.wline.res.cust.swisscom.ch	1	1	2.0 KB	1	00:30:04
TOTAL: 4		312	92	231.8 KB	64	36:10:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	367	58	162.2 KB	53	26:17:49
2	*.nielsenmedia.com	12	29	58.2 KB	6	03:40:59
3	*.tampfl.dsl-w.verizon.net	11	4	9.5 KB	4	02:51:28
4	*.188.193.178.dynamic.wline.res.cust.swisscom.ch	1	1	2.0 KB	1	00:30:04
5	*.chello.nl	5	0	0.0 KB	0	01:50:03
6	*.tu.ok.cox.net	2	0	0.0 KB	0	01:00:26
TOTAL: 6		398	92	231.8 KB	64	36:10:52

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
108.175.241.207	3.530	1.052	0.000	14:18:05
162.212.88.198	0.422	0.116	0.000	07:15:35
202.89.176.220	26.890	5.643	0.694	04:44:06
challenger.nielsenmedia.com	1.396	0.175	0.000	03:40:59
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.176	0.182	0.000	02:51:28
dhcp-089-099-203-254.chello.nl	0.370	0.048	0.000	01:50:03
ip72-222-107-224.tu.ok.cox.net	0.185	0.031	0.000	01:00:26
156.188.193.178.dynamic.wline.res.cust.swisscom.ch	0.168	0.000	0.000	00:30:04
69.160.37.54	1.721	0.422	0.000	00:00:01
TOTAL: 9	35.858	7.669	0.694	36:10:52

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	50
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	5
162.212.88.198	4
ip72-222-107-224.tu.ok.cox.net	2
TOTAL: 5	86

NNRP client timeouts [Top 20]:

System	Conn	Peer
challenger.nielsenmedia.com	7	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	0
dhcp-089-099-203-254.chello.nl	3	0
ip72-222-107-224.tu.ok.cox.net	2	0
156.188.193.178.dynamic.wline.res.cust.swisscom.ch	1	0
TOTAL: 5	18	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	91	98.9%
2	news	1	1.1%
TOTAL: 2		92	100.0%

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

Newsgroup		Count
1	comp.lang.awk	33
2	comp.os.vms	30
3	comp.arch	8
4	comp.sys.ibm.pc.games.action	4
5	comp.os.linux.networking	4
6	comp.arch.embedded	2
7	comp.sys.ibm.as400.misc	1
8	comp.ai.neural-nets	1
9	comp.ai.genetic	1
10	comp.ai.doc-analysis.ocr	1
11	comp.ai.shells	1
12	comp.ai.philosophy	1
13	comp.sys.ibm.pc.rt	1
14	comp.sys.ibm.ps2.hardware	1
15	comp.arch.fpga	1
16	news.admin.peering	1
17	comp.os.os2.misc	1
TOTAL: 17		92

Daily Usenet report for csiph.com

Sep 17 04:15:00 -- Sep 18 04:15:00