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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 1 05:04:05 csiph pgpverify[37601]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	48837	48.8%	7.2 MB	60.7%
nnrpd	31687	31.6%	2.8 MB	23.5%
innd	17758	17.7%	1.6 MB	13.7%
innfeed	1872	1.9%	265.8 KB	2.2%
controlchan	6	0.0%	0.9 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	100161	100.0%	11.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	268991	48.3%
Negative hits	235831	42.3%
Do not exist	47879	8.6%
Cache misses	4203	0.8%
TOTAL: 4	556904	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.632	0.0%	270	0.000	2.341	22.333
article cleanup	00:00:00.257	0.0%	47241	0.000	0.005	0.017
article logging	00:00:00.609	0.0%	47866	0.000	0.013	0.040
article parse	00:00:01.969	0.0%	235469	0.003	0.008	0.019
article write	00:00:07.458	0.0%	27845	0.174	0.268	0.568
artlog/artcncl	00:00:00.001	0.0%	208	0.000	0.005	0.250
artlog/artparse	00:00:00.010	0.0%	779	0.000	0.013	1.000
data move	00:00:00.295	0.0%	709453	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.001	0.0%	264	0.000	0.004	0.091
hishave/artcncl	00:00:00.001	0.0%	270	0.000	0.004	0.333
hishave/artparse	00:00:00.004	0.0%	779	0.000	0.005	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.486	0.0%	557676	0.001	0.003	0.008
history sync	00:00:00.632	0.0%	349	0.000	1.811	3.500
history write	00:00:13.172	0.0%	47239	0.088	0.279	0.383
hiswrite/artcncl	00:00:00.001	0.0%	6	0.000	0.167	0.333
hiswrite/artparse	00:00:00.149	0.0%	776	0.000	0.192	3.000
idle	23:58:22.350	99.8%	695809	30.293	124.032	332.415
nntp read	00:00:02.373	0.0%	694460	0.001	0.003	0.007
overview write	00:00:04.518	0.0%	27845	0.086	0.162	0.350
perl filter	00:01:23.987	0.1%	47239	1.038	1.778	3.274
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.504	0.0%	55692	0.000	0.009	0.032
TOTAL: 24:00:55.747	24:00:20.409	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.060	0.0%	27795	0.000	0.002	0.022
article prepare	00:00:00.001	0.0%	6952	0.000	0.000	0.012
article read	00:00:00.212	0.0%	1225	0.000	0.173	23.000
backlog stats	00:00:00.107	0.0%	314453	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.277	0.0%	239563	0.002	0.005	0.010
data write	00:00:01.794	0.0%	214472	0.001	0.008	0.016
idle	23:53:15.709	100.0%	314453	164.575	273.477	646.090
status file	00:00:00.079	0.0%	185	0.000	0.427	1.000
TOTAL: 23:53:23.087	23:53:19.239	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.326	0.0%	16884	0.000	0.019	0.030
idle	59:12:20.033	100.0%	48030	1.000	4437.644	600135.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.018	0.0%	178210	0.000	0.017	10.944
readart	00:00:00.753	0.0%	7037	0.000	0.107	8.000
TOTAL: 59:13:27.783	59:12:24.130	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	Other	Bad PGP	OK
bofh-control@killfile.org	1	0	1
us-control@lists.killfile.org	0	1	0
TOTAL	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	4	44276	7868	33869	2539	17%	25:17:54
2	au2pb.net	1	31024	5022	25061	941	16%	24:37:46
3	xanadu-bbs.net	2	47902	4232	34889	8781	8%	25:02:49
4	news-out.mixmin.net	3	33323	3995	26789	2539	11%	24:09:35
5	newsfeed.neva.ru	152	60527	2907	52750	4870	4%	72:03:37
6	newsfeeds.xmission.com	1	28012	1759	26116	137	6%	22:42:58
7	newsfeed.fu-berlin.de	70	33275	1367	31550	358	4%	37:29:56
8	nntp.pasdenom.info	3	24446	612	23711	123	2%	20:31:13
9	news.swapon.de	4	2230	222	1998	10	9%	22:36:15
10	localhost	48	12571	0	12571	0	0%	00:00:56
TOTAL: 10		288	317586	27984	269304	20298	8%	274:32:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	22.2 MB	0.0 KB	13.3 MB	35.5 MB	62%	3.5 KB
2	au2pb.net	14.3 MB	0.0 KB	6.6 MB	20.9 MB	68%	3.6 KB
3	xanadu-bbs.net	12.8 MB	12.6 KB	126.2 MB	139.0 MB	9%	10.9 KB
4	news-out.mixmin.net	12.4 MB	10.6 KB	7.0 MB	19.3 MB	63%	3.0 KB
5	newsfeed.neva.ru	9.6 MB	0.0 KB	25.7 MB	35.3 MB	27%	4.7 KB
6	newsfeeds.xmission.com	5.5 MB	0.0 KB	1.9 MB	7.4 MB	74%	4.0 KB
7	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	1.0 MB	4.4 MB	76%	2.6 KB
8	nntp.pasdenom.info	1.4 MB	0.0 KB	1.4 MB	2.8 MB	49%	3.9 KB
9	news.swapon.de	565.5 KB	0.0 KB	128.3 KB	693.7 KB	81%	3.0 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		82.0 MB	23.2 KB	183.2 MB	265.2 MB	30%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:00 - 04:59:59	458	1.6%	0.17	1.5 MB	1.8%	0.56
Oct 1 05:00:00 - 05:59:59	655	2.4%	0.18	3.0 MB	3.7%	0.85
Oct 1 06:00:00 - 06:59:59	976	3.5%	0.27	2.9 MB	3.6%	0.84
Oct 1 07:00:00 - 07:59:59	1194	4.3%	0.33	3.3 MB	4.0%	0.94
Oct 1 08:00:00 - 08:59:59	1240	4.5%	0.34	3.4 MB	4.2%	0.97
Oct 1 09:00:00 - 09:59:59	1363	4.9%	0.38	4.0 MB	4.8%	1.12
Oct 1 10:00:00 - 10:59:59	1426	5.1%	0.40	3.6 MB	4.4%	1.02
Oct 1 11:00:00 - 11:59:59	1408	5.1%	0.39	4.0 MB	4.8%	1.12
Oct 1 12:00:00 - 12:59:59	1487	5.3%	0.41	4.5 MB	5.6%	1.29
Oct 1 13:00:00 - 13:59:59	1570	5.6%	0.44	4.6 MB	5.6%	1.29
Oct 1 14:00:00 - 14:59:59	1580	5.7%	0.44	4.4 MB	5.4%	1.25
Oct 1 15:00:00 - 15:59:59	1561	5.6%	0.43	4.0 MB	4.9%	1.13
Oct 1 16:00:00 - 16:59:59	1546	5.6%	0.43	4.5 MB	5.5%	1.27
Oct 1 17:00:00 - 17:59:59	1558	5.6%	0.43	4.5 MB	5.6%	1.29
Oct 1 18:00:00 - 18:59:59	1484	5.3%	0.41	4.5 MB	5.5%	1.28
Oct 1 19:00:00 - 19:59:59	1438	5.2%	0.40	4.2 MB	5.2%	1.20
Oct 1 20:00:00 - 20:59:59	1378	4.9%	0.38	3.5 MB	4.3%	1.00
Oct 1 21:00:00 - 21:59:59	1297	4.7%	0.36	3.7 MB	4.5%	1.05
Oct 1 22:00:00 - 22:59:59	905	3.3%	0.25	2.9 MB	3.6%	0.83
Oct 1 23:00:00 - 23:59:59	755	2.7%	0.21	2.4 MB	2.9%	0.68
Oct 2 00:00:00 - 00:59:59	619	2.2%	0.17	2.1 MB	2.5%	0.59
Oct 2 01:00:00 - 01:59:59	636	2.3%	0.18	1.8 MB	2.2%	0.52
Oct 2 02:00:00 - 02:59:59	592	2.1%	0.16	2.3 MB	2.9%	0.67
Oct 2 03:00:00 - 03:59:59	556	2.0%	0.15	1.5 MB	1.8%	0.43
Oct 2 04:00:00 - 04:15:00	160	0.6%	0.18	526.7 KB	0.6%	0.59
TOTAL: 24:00:00	27842	100.0%	0.32	81.6 MB	100.0%	0.97

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8782	925	1	7856
2	newsfeed.neva.ru	4862	1768	0	3094
3	news-out.mixmin.net	2575	6	1	2568
4	feeder.eternal-september.org	2545	372	0	2173
5	au2pb.net	935	870	0	65
6	newsfeed.fu-berlin.de	767	38	0	729
7	newsfeeds.xmission.com	156	119	0	37
8	nntp.pasdenom.info	123	105	0	18
9	news.swapon.de	41	0	0	41
TOTAL: 9		20786	4203	2	16581

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	886
it-alt.sport.calcio.juventus	195
lada.auto	183
lada.talk	166
mailing.freebsd.svn-ports-all	154
mailing.freebsd.svn-ports-head	148
time.nuts	147
alt.politics	141
mozilla.dev.tree-alerts	78
mailing.freebsd.ports-bugs	77
mpc.lists.nanog	63
mailing.freebsd.pkg-fallout	61
volt.nuts	59
mailing.openbsd.source-changes	57
fa.openbsd.source-changes	55
mailing.gcc.gcc-cvs	54
alt.survival	49
free.it.calcio.juventus	49
alt.job	46
tin.it.sport.calcio	43
TOTAL: 375	4203

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11519
EMP (phn path)	2123
EMP (phl)	855
EMP (phn nph)	401
PFM bot misc	145
HTML Multipart	53
Too many newsgroups	36
Binary Image: misplaced jpg	27
HTML post	15
Too many newsgroups (low_xpost)	2
Binary: misplaced mp3	1
Binary: misplaced nfo	1
Binary: misplaced m3u	1
Binary: misplaced 000	1
MID-Bot	1
TOTAL: 15	15181

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	566
	feeder.eternal-september.org	74
	au2pb.net	54
	news-out.mixmin.net	43
	newsfeeds.xmission.com	17
	nntp.pasdenom.info	16
	newsfeed.fu-berlin.de	5
	news.swapon.de	1
	TOTAL: 8	776
Including strange strings
	newsfeed.fu-berlin.de	422
	xanadu-bbs.net	64
	feeder.eternal-september.org	47
	news-out.mixmin.net	37
	news.swapon.de	31
	newsfeeds.xmission.com	14
	au2pb.net	7
	newsfeed.neva.ru	2
	TOTAL: 8	624
TOTAL: 2		1400

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	24423	329	22421	189	0	1%	23:59:47
2	usenet.pasdenom.info	32726	203	26450	556	4	0%	23:59:58
3	xanadu-bbs	25919	190	23375	45	15	0%	23:59:57
4	fu-berlin.de	26157	49	26082	26	0	0%	23:59:57
5	xmission	32096	20	25775	1	0	0%	23:59:58
6	news.mixmin.net	24477	16	23650	12	0	0%	23:59:57
7	neva.ru	27071	14	24685	84	0	0%	23:59:57
8	eternal-september	20856	6	19658	1	0	0%	23:59:58
9	news.swapon.de	2094	0	2062	4	0	0%	23:45:44
TOTAL: 9		215819	827	194158	918	19	0%	215:45:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.4 MB	506.8 KB	1.9 MB	3.8 KB	23:59:47
2	xanadu-bbs	1.0 MB	93.5 KB	1.1 MB	4.8 KB	23:59:57
3	usenet.pasdenom.info	723.2 KB	1.7 MB	2.4 MB	3.2 KB	23:59:58
4	fu-berlin.de	226.8 KB	33.1 KB	259.9 KB	3.5 KB	23:59:57
5	news.mixmin.net	93.9 KB	22.7 KB	116.6 KB	4.2 KB	23:59:57
6	xmission	75.6 KB	3.8 KB	79.4 KB	3.8 KB	23:59:58
7	neva.ru	63.6 KB	471.9 KB	535.5 KB	5.5 KB	23:59:57
8	eternal-september	22.5 KB	1.0 KB	23.5 KB	3.4 KB	23:59:58
9	news.swapon.de	0.0 KB	46.1 KB	46.1 KB	11.5 KB	23:45:44
TOTAL: 9		3.6 MB	2.8 MB	6.5 MB	3.8 KB	215:45:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	281	58	164.7 KB	55	19:33:54
2	tempe0.bbox.io	10	7	13.9 KB	4	00:39:27
TOTAL: 2		291	65	178.6 KB	59	59:13:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	315	58	164.7 KB	55	40:25:28
2	*.bbox.io	10	7	13.9 KB	4	00:39:27
3	*.tu.ok.cox.net	4	0	0.0 KB	0	10:32:25
4	*.nielsenmedia.com	6	0	0.0 KB	0	01:00:29
5	*.snarked.org	2	0	0.0 KB	0	00:00:01
6	*.tampfl.dsl-w.verizon.net	16	0	0.0 KB	0	02:34:17
7	*.ip-188-165-239.eu	2	0	0.0 KB	0	00:00:10
8	*.br.br.cox.net	1	0	0.0 KB	0	00:00:18
9	*.chello.nl	13	0	0.0 KB	0	04:00:44
TOTAL: 9		369	65	178.6 KB	59	59:13:24

NNRP auth users [Top 20]:

User	Conn
moussa	281
TOTAL: 1	281

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.198	0.659	0.460	0.000	20:51:20
202.89.176.220	26.674	5.789	0.686	19:33:54
ip72-222-107-224.tu.ok.cox.net	0.670	0.444	0.000	10:32:25
dhcp-089-099-203-254.chello.nl	0.994	0.183	0.000	04:00:44
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.203	0.266	0.000	02:34:17
challenger.nielsenmedia.com	0.388	0.101	0.000	01:00:29
tempe0.bbox.io	0.842	0.146	0.000	00:39:27
ip70-186-183-22.br.br.cox.net	0.157	0.017	0.000	00:00:18
173.0.15.140	0.263	0.376	0.000	00:00:10
ns342002.ip-188-165-239.eu	0.179	0.038	0.000	00:00:10
69.160.37.54	1.932	0.449	0.000	00:00:02
news.snarked.org	0.194	0.118	0.000	00:00:01
TOTAL: 12	34.155	8.387	0.686	59:13:24

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	16
dhcp-089-099-203-254.chello.nl	13
162.212.88.198	8
challenger.nielsenmedia.com	6
ip72-222-107-224.tu.ok.cox.net	4
news.snarked.org	2
ns342002.ip-188-165-239.eu	2
173.0.15.140	1
ip70-186-183-22.br.br.cox.net	1
TOTAL: 10	78

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	19
2	comp.arch.embedded	11
3	comp.os.vms	9
4	comp.arch.fpga	5
5	comp.unix.bsd.freebsd.misc	5
6	comp.sys.ibm.pc.games.action	3
7	comp.sys.ibm.ps2.hardware	3
8	comp.sys.ibm.pc.hardware.storage	3
9	comp.os.msdos.misc	2
10	comp.os.os2.misc	2
11	comp.os.os2.ecomstation	1
12	comp.windows.x.kde	1
13	comp.ai.fuzzy	1
TOTAL: 13		65

Daily Usenet report for csiph.com

Oct 1 04:15:00 -- Oct 2 04:15:00