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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 7 11:34:06 csiph pgpverify[62404]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51258	48.9%	7.5 MB	60.7%
nnrpd	31987	30.5%	2.6 MB	21.4%
innd	19588	18.7%	1.9 MB	15.7%
innfeed	1887	1.8%	291.3 KB	2.3%
controlchan	4	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	104725	100.0%	12.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	277485	48.5%
Negative hits	241097	42.1%
Do not exist	50327	8.8%
Cache misses	3642	0.6%
TOTAL: 4	572551	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.698	0.0%	240	0.000	2.908	20.000
article cleanup	00:00:00.302	0.0%	49562	0.000	0.006	0.027
article logging	00:00:00.638	0.0%	50131	0.000	0.013	0.042
article parse	00:00:02.259	0.0%	272672	0.003	0.008	0.016
article write	00:00:06.848	0.0%	28906	0.148	0.237	0.470
artlog/artcncl	00:00:00.003	0.0%	215	0.000	0.014	0.125
artlog/artparse	00:00:00.013	0.0%	936	0.000	0.014	0.250
data move	00:00:00.258	0.0%	762078	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.000	0.0%	239	0.000	0.000	0.000
hishave/artcncl	00:00:00.002	0.0%	240	0.000	0.008	0.222
hishave/artparse	00:00:00.005	0.0%	936	0.000	0.005	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.775	0.0%	573287	0.000	0.003	0.009
history sync	00:00:00.595	0.0%	328	0.000	1.814	3.500
history write	00:00:14.075	0.0%	49554	0.069	0.284	0.370
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.281	0.0%	934	0.000	0.301	1.500
idle	23:58:01.525	99.8%	744720	34.962	115.858	306.227
nntp read	00:00:02.467	0.0%	743367	0.001	0.003	0.007
overview write	00:00:07.673	0.0%	28906	0.083	0.265	25.017
perl filter	00:01:24.052	0.1%	49554	0.961	1.696	3.601
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.506	0.0%	57813	0.000	0.009	0.027
TOTAL: 24:00:38.715	24:00:03.975	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.096	0.0%	28887	0.000	0.003	0.015
article prepare	00:00:00.000	0.0%	8556	0.000	0.000	0.000
article read	00:00:00.251	0.0%	1685	0.000	0.149	6.571
backlog stats	00:00:00.121	0.0%	323188	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.403	0.0%	248678	0.001	0.006	0.011
data write	00:00:01.888	0.0%	224206	0.003	0.008	0.017
idle	23:54:04.593	100.0%	323188	149.940	266.237	714.471
status file	00:00:00.101	0.0%	192	0.000	0.526	2.000
TOTAL: 23:54:12.607	23:54:08.453	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.514	0.0%	17022	0.000	0.030	0.333
idle	105:35:34.665	100.0%	48418	0.000	7851.102	600196.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.320	0.0%	97747	0.000	0.014	6.000
readart	00:00:00.264	0.0%	3940	0.000	0.067	1.000
TOTAL: 105:36:39.736	105:35:36.763	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
moderator@dana.de	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	4	45695	7755	34903	3037	16%	24:38:36
2	au2pb.net	1	30926	6252	23869	805	20%	24:07:48
3	xanadu-bbs.net	2	49934	4707	34796	10431	9%	24:39:47
4	news-out.mixmin.net	4	34600	3941	28265	2394	11%	24:16:36
5	newsfeed.neva.ru	149	66174	2850	59071	4253	4%	73:17:38
6	newsfeeds.xmission.com	1	29987	1653	28254	80	5%	25:49:23
7	newsfeed.fu-berlin.de	68	34455	1542	32491	422	4%	37:31:37
8	news.swapon.de	0	1677	200	1471	6	11%	14:16:48
9	nntp.pasdenom.info	9	32585	111	32384	90	0%	29:05:16
10	localhost	50	10518	0	10518	0	0%	00:01:05
TOTAL: 10		288	336551	29011	286022	21518	8%	277:44:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.6 MB	5.4 KB	13.0 MB	34.6 MB	62%	3.3 KB
2	au2pb.net	16.4 MB	0.0 KB	4.2 MB	20.6 MB	79%	3.0 KB
3	xanadu-bbs.net	12.9 MB	1.3 KB	175.8 MB	188.7 MB	6%	12.8 KB
4	news-out.mixmin.net	12.0 MB	1.2 KB	6.7 MB	18.7 MB	64%	3.0 KB
5	newsfeed.neva.ru	10.2 MB	3.8 KB	19.6 MB	29.9 MB	34%	4.3 KB
6	newsfeeds.xmission.com	5.0 MB	0.0 KB	1.3 MB	6.3 MB	79%	3.7 KB
7	newsfeed.fu-berlin.de	3.6 MB	0.0 KB	1.2 MB	4.8 MB	74%	2.5 KB
8	news.swapon.de	556.5 KB	0.0 KB	3.7 MB	4.3 MB	12%	21.2 KB
9	nntp.pasdenom.info	254.9 KB	0.0 KB	333.9 KB	588.7 KB	43%	2.9 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		82.6 MB	11.7 KB	225.9 MB	308.5 MB	26%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 04:15:00 - 04:59:59	461	1.6%	0.17	1.4 MB	1.8%	0.55
Oct 7 05:00:00 - 05:59:59	565	2.0%	0.16	1.6 MB	2.0%	0.46
Oct 7 06:00:00 - 06:59:59	755	2.6%	0.21	1.9 MB	2.3%	0.54
Oct 7 07:00:00 - 07:59:59	1219	4.2%	0.34	3.4 MB	4.2%	0.98
Oct 7 08:00:00 - 08:59:59	1325	4.6%	0.37	3.5 MB	4.2%	0.99
Oct 7 09:00:00 - 09:59:59	1581	5.5%	0.44	4.4 MB	5.4%	1.25
Oct 7 10:00:00 - 10:59:59	1552	5.4%	0.43	4.3 MB	5.3%	1.24
Oct 7 11:00:00 - 11:59:59	1527	5.3%	0.42	4.6 MB	5.6%	1.30
Oct 7 12:00:00 - 12:59:59	1518	5.3%	0.42	5.3 MB	6.5%	1.51
Oct 7 13:00:00 - 13:59:59	1607	5.6%	0.45	4.2 MB	5.2%	1.21
Oct 7 14:00:00 - 14:59:59	1578	5.5%	0.44	3.9 MB	4.7%	1.10
Oct 7 15:00:00 - 15:59:59	1612	5.6%	0.45	4.7 MB	5.7%	1.32
Oct 7 16:00:00 - 16:59:59	1471	5.1%	0.41	4.1 MB	5.0%	1.17
Oct 7 17:00:00 - 17:59:59	1780	6.2%	0.49	4.6 MB	5.6%	1.31
Oct 7 18:00:00 - 18:59:59	1631	5.6%	0.45	4.2 MB	5.1%	1.19
Oct 7 19:00:00 - 19:59:59	1624	5.6%	0.45	4.2 MB	5.1%	1.19
Oct 7 20:00:00 - 20:59:59	1492	5.2%	0.41	4.2 MB	5.1%	1.19
Oct 7 21:00:00 - 21:59:59	1216	4.2%	0.34	3.4 MB	4.1%	0.96
Oct 7 22:00:00 - 22:59:59	1124	3.9%	0.31	3.4 MB	4.1%	0.95
Oct 7 23:00:00 - 23:59:59	707	2.4%	0.20	2.2 MB	2.7%	0.62
Oct 8 00:00:00 - 00:59:59	711	2.5%	0.20	2.7 MB	3.3%	0.78
Oct 8 01:00:00 - 01:59:59	585	2.0%	0.16	2.0 MB	2.5%	0.57
Oct 8 02:00:00 - 02:59:59	532	1.8%	0.15	1.6 MB	2.0%	0.46
Oct 8 03:00:00 - 03:59:59	563	1.9%	0.16	1.7 MB	2.1%	0.50
Oct 8 04:00:00 - 04:15:00	159	0.6%	0.18	435.7 KB	0.5%	0.48
TOTAL: 24:00:00	28895	100.0%	0.33	82.0 MB	100.0%	0.97

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	10565	621	1	9943
2	newsfeed.neva.ru	4249	1884	3	2362
3	feeder.eternal-september.org	3060	369	2	2689
4	news-out.mixmin.net	2408	1	1	2406
5	newsfeed.fu-berlin.de	835	30	0	805
6	au2pb.net	828	768	0	60
7	nntp.pasdenom.info	85	84	0	1
8	newsfeeds.xmission.com	82	54	1	27
9	news.swapon.de	37	0	0	37
TOTAL: 9		22149	3811	8	18330

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	760
lada.talk	255
lada.auto	211
ut.test	136
it-alt.sport.calcio.juventus	101
alt.politics	94
alt.politics.scorched-earth	79
mailing.freebsd.ports-bugs	78
mpc.lists.freebsd.ports.bugs	78
alt.survival	73
gmane.linux.alsa.devel	73
lada.photo	68
mailing.openbsd.source-changes	67
mailing.database.pgsql-general	61
alt.job	61
mailing.freebsd.svn-ports-all	55
mailing.freebsd.svn-ports-head	52
alt.windows7.general	45
nemo.abuse	45
fa.openbsd.source-changes	44
TOTAL: 251	3811

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13423
EMP (phn path)	2004
EMP (phl)	859
PFM bot misc	226
EMP (phn nph)	66
HTML post	64
HTML Multipart	60
Too many newsgroups	60
Binary Image: misplaced jpg	28
HTML Image Tags	13
Binary: misplaced par2	6
Binary: misplaced torr	6
Too many newsgroups (low_xpost)	4
Binary: misplaced zip	2
Too many newsgroups (meow)	2
Binary: non-binary distribution	1
U2 violation - invalid distribution	1
TOTAL: 17	16825

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	769
	feeder.eternal-september.org	53
	news-out.mixmin.net	50
	au2pb.net	40
	newsfeeds.xmission.com	13
	news.swapon.de	6
	newsfeed.fu-berlin.de	4
	nntp.pasdenom.info	1
	TOTAL: 8	936
Including strange strings
	newsfeed.fu-berlin.de	453
	news.swapon.de	31
	feeder.eternal-september.org	26
	news-out.mixmin.net	17
	au2pb.net	16
	newsfeed.neva.ru	11
	newsfeeds.xmission.com	9
	xanadu-bbs.net	6
	TOTAL: 8	569
TOTAL: 2		1505

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	23621	337	22051	319	0	1%	23:59:56
2	usenet.pasdenom.info	35955	205	27700	893	51	0%	23:59:57
3	xanadu-bbs	25922	119	23990	50	4	0%	23:59:57
4	fu-berlin.de	27019	52	26951	16	0	0%	23:59:55
5	news.mixmin.net	25557	27	24760	27	0	0%	23:59:57
6	xmission	33887	25	27109	8	0	0%	23:59:58
7	neva.ru	27350	10	25895	45	0	0%	23:59:57
8	eternal-september	21965	2	20637	6	0	0%	23:59:57
9	news.swapon.de	2444	0	2424	1	0	0%	23:52:21
TOTAL: 9		223720	777	201517	1365	55	0%	215:51:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.4 MB	1.3 MB	2.6 MB	4.1 KB	23:59:56
2	usenet.pasdenom.info	903.7 KB	2.3 MB	3.2 MB	2.9 KB	23:59:57
3	xanadu-bbs	711.2 KB	99.0 KB	810.2 KB	4.8 KB	23:59:57
4	fu-berlin.de	671.1 KB	20.1 KB	691.3 KB	10.2 KB	23:59:55
5	news.mixmin.net	165.5 KB	65.3 KB	230.8 KB	4.3 KB	23:59:57
6	xmission	55.7 KB	139.1 KB	194.8 KB	5.9 KB	23:59:58
7	neva.ru	43.1 KB	199.9 KB	243.1 KB	4.4 KB	23:59:57
8	eternal-september	8.1 KB	26.1 KB	34.1 KB	4.3 KB	23:59:57
9	news.swapon.de	0.0 KB	3.6 KB	3.6 KB	3.6 KB	23:52:21
TOTAL: 9		3.9 MB	4.1 MB	7.9 MB	3.8 KB	215:51:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	48	123.4 KB	45	0	0	04:16:19
TOTAL: 1		288	48	123.4 KB	45	0	0	105:36:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	330	48	123.4 KB	45	92:19:16
2	*.chello.nl	23	0	0.0 KB	0	09:22:39
3	*.br.br.cox.net	1	0	0.0 KB	0	00:01:40
4	*.tu.ok.cox.net	1	0	0.0 KB	0	00:30:04
5	*.nielsenmedia.com	6	0	0.0 KB	0	02:01:23
6	*.tampfl.dsl-w.verizon.net	5	0	0.0 KB	0	01:17:57
7	*.ip-188-165-239.eu	20	0	0.0 KB	0	00:03:31
TOTAL: 7		386	48	123.4 KB	45	105:36:35

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
71.94.21.184	0.961	0.487	0.000	51:19:22
162.212.88.198	0.589	0.522	0.000	36:43:26
dhcp-089-099-203-254.chello.nl	1.879	0.336	0.000	09:22:39
202.89.176.220	25.593	5.861	0.697	04:16:19
challenger.nielsenmedia.com	0.443	0.073	0.000	02:01:23
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.397	0.070	0.000	01:17:57
ip72-222-107-224.tu.ok.cox.net	0.070	0.008	0.000	00:30:04
ns342002.ip-188-165-239.eu	1.543	0.353	0.000	00:03:31
ip70-186-183-22.br.br.cox.net	0.114	0.007	0.000	00:01:40
173.0.15.147	0.174	0.191	0.000	00:00:06
69.160.37.54	1.779	0.321	0.000	00:00:01
TOTAL: 11	33.542	8.229	0.697	105:36:35

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	23
ns342002.ip-188-165-239.eu	20
162.212.88.198	8
71.94.21.184	8
challenger.nielsenmedia.com	6
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5
ip70-186-183-22.br.br.cox.net	1
ip72-222-107-224.tu.ok.cox.net	1
173.0.15.147	1
TOTAL: 10	98

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	8
TOTAL: 1	8

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	8
TOTAL: 1	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	17	0
challenger.nielsenmedia.com	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
ip72-222-107-224.tu.ok.cox.net	1	0
71.94.21.184	1	1
TOTAL: 5	24	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	48	100.0%
TOTAL: 1		48	100.0%

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

Newsgroup		Count
1	comp.os.vms	13
2	comp.arch.embedded	12
3	comp.arch.fpga	6
4	comp.sys.ibm.pc.games.war-historical	6
5	comp.sys.ibm.pc.hardware.storage	4
6	comp.sys.ibm.pc.games.action	3
7	comp.os.linux.networking	3
8	comp.sys.ibm.pc.classic	1
TOTAL: 8		48

Daily Usenet report for csiph.com

Oct 7 04:15:00 -- Oct 8 04:15:00