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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 1 05:04:04 csiph pgpverify[834]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	38834	44.9%	5.9 MB	57.4%
nnrpd	32150	37.2%	2.7 MB	26.0%
innd	13632	15.8%	1.4 MB	13.8%
innfeed	1918	2.2%	299.0 KB	2.8%
controlchan	6	0.0%	0.9 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	86541	100.0%	10.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	210829	47.3%
Negative hits	196935	44.2%
Do not exist	37834	8.5%
Cache misses	110	0.0%
TOTAL: 4	445708	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.700	0.0%	232	0.000	3.017	19.000
article cleanup	00:00:00.257	0.0%	37521	0.000	0.007	0.026
article logging	00:00:00.521	0.0%	37995	0.000	0.014	0.031
article parse	00:00:01.552	0.0%	149995	0.000	0.010	0.031
article write	00:00:12.619	0.0%	23462	0.183	0.538	1.253
artlog/artcncl	00:00:00.003	0.0%	178	0.000	0.017	1.000
artlog/artparse	00:00:00.005	0.0%	530	0.000	0.009	0.333
data move	00:00:00.197	0.0%	545258	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.067	0.0%	227	0.000	0.295	13.000
hishave/artcncl	00:00:00.002	0.0%	232	0.000	0.009	0.100
hishave/artparse	00:00:00.001	0.0%	530	0.000	0.002	0.071
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.557	0.0%	447360	0.001	0.003	0.011
history sync	00:00:00.697	0.0%	319	0.000	2.185	38.500
history write	00:00:11.903	0.0%	37517	0.060	0.317	2.426
hiswrite/artcncl	00:00:00.003	0.0%	5	0.000	0.600	1.000
hiswrite/artparse	00:00:00.154	0.0%	528	0.000	0.292	2.000
idle	23:58:16.727	99.8%	541021	46.647	159.507	577.925
nntp read	00:00:01.718	0.0%	541575	0.001	0.003	0.006
overview write	00:00:22.221	0.0%	23462	0.168	0.947	5.970
perl filter	00:00:47.681	0.1%	37517	0.909	1.271	2.759
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.405	0.0%	46926	0.000	0.009	0.023
TOTAL: 24:00:28.400	23:59:58.990	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.069	0.0%	23626	0.000	0.003	0.015
article prepare	00:00:00.001	0.0%	8224	0.000	0.000	0.011
article read	00:00:00.232	0.0%	1381	0.000	0.168	22.000
backlog stats	00:00:00.093	0.0%	262193	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.112	0.0%	202410	0.000	0.005	0.011
data write	00:00:01.432	0.0%	180731	0.002	0.008	0.017
idle	23:54:07.683	100.0%	262193	190.780	328.185	988.172
status file	00:00:00.093	0.0%	204	0.000	0.456	2.000
TOTAL: 23:54:14.356	23:54:10.715	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.257	0.0%	7582	0.000	0.034	0.094
idle	35:19:14.631	99.9%	38954	0.000	3264.225	600952.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.999	0.0%	78846	0.000	0.025	56.286
readart	00:00:00.104	0.0%	74	0.000	1.405	19.667
TOTAL: 35:22:25.157	35:19:16.991	99.9%	-	-	-	-

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	3	34082	5684	26074	2324	16%	24:12:00
2	au2pb.net	1	24267	4326	19383	558	17%	23:10:15
3	xanadu-bbs.net	1	37634	3810	27914	5910	10%	23:40:05
4	news-out.mixmin.net	3	30486	3471	24599	2416	11%	24:08:31
5	newsfeed.neva.ru	152	46661	2207	41804	2650	4%	71:38:31
6	newsfeeds.xmission.com	1	23083	1807	21206	70	7%	23:38:08
7	newsfeed.fu-berlin.de	29	27353	1233	25636	484	4%	28:25:00
8	nntp.pasdenom.info	14	22663	475	22003	185	2%	24:30:12
9	news.swapon.de	2	2521	247	2250	24	9%	24:25:44
10	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 10		232	248750	23260	210869	14621	9%	267:48:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	15.7 MB	2.8 KB	10.1 MB	25.7 MB	60%	3.3 KB
2	news-out.mixmin.net	10.7 MB	0.0 KB	5.1 MB	15.8 MB	67%	2.8 KB
3	au2pb.net	10.6 MB	0.0 KB	3.7 MB	14.3 MB	74%	3.0 KB
4	xanadu-bbs.net	10.3 MB	2.5 KB	80.2 MB	90.4 MB	11%	9.5 KB
5	newsfeed.neva.ru	7.6 MB	4.2 KB	9.2 MB	16.8 MB	45%	3.5 KB
6	newsfeeds.xmission.com	5.9 MB	15.3 KB	519.4 KB	6.5 MB	91%	3.5 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	1.2 MB	3.9 MB	70%	2.3 KB
8	nntp.pasdenom.info	1.2 MB	0.0 KB	2.6 MB	3.8 MB	32%	5.9 KB
9	news.swapon.de	627.4 KB	0.0 KB	758.5 KB	1.4 MB	45%	5.1 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		65.3 MB	24.8 KB	113.3 MB	178.6 MB	36%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 1 04:15:00 - 04:59:59	315	1.3%	0.12	997.2 KB	1.5%	0.37
Nov 1 05:00:00 - 05:59:59	389	1.7%	0.11	1.2 MB	1.8%	0.33
Nov 1 06:00:00 - 06:59:59	464	2.0%	0.13	1.3 MB	1.9%	0.37
Nov 1 07:00:00 - 07:59:59	749	3.2%	0.21	2.2 MB	3.3%	0.62
Nov 1 08:00:00 - 08:59:59	807	3.4%	0.22	1.9 MB	2.9%	0.55
Nov 1 09:00:00 - 09:59:59	1045	4.4%	0.29	2.5 MB	3.7%	0.71
Nov 1 10:00:00 - 10:59:59	1051	4.5%	0.29	2.8 MB	4.2%	0.79
Nov 1 11:00:00 - 11:59:59	1112	4.7%	0.31	3.0 MB	4.5%	0.85
Nov 1 12:00:00 - 12:59:59	1082	4.6%	0.30	2.8 MB	4.2%	0.79
Nov 1 13:00:00 - 13:59:59	1172	5.0%	0.33	3.3 MB	4.9%	0.93
Nov 1 14:00:00 - 14:59:59	1154	4.9%	0.32	3.1 MB	4.7%	0.90
Nov 1 15:00:00 - 15:59:59	1267	5.4%	0.35	3.4 MB	5.1%	0.97
Nov 1 16:00:00 - 16:59:59	1329	5.7%	0.37	4.0 MB	6.0%	1.14
Nov 1 17:00:00 - 17:59:59	1282	5.5%	0.36	3.7 MB	5.5%	1.04
Nov 1 18:00:00 - 18:59:59	1506	6.4%	0.42	4.0 MB	6.0%	1.14
Nov 1 19:00:00 - 19:59:59	1259	5.4%	0.35	3.3 MB	5.0%	0.95
Nov 1 20:00:00 - 20:59:59	1320	5.6%	0.37	3.6 MB	5.3%	1.01
Nov 1 21:00:00 - 21:59:59	1332	5.7%	0.37	3.5 MB	5.2%	1.00
Nov 1 22:00:00 - 22:59:59	1142	4.9%	0.32	3.3 MB	4.9%	0.94
Nov 1 23:00:00 - 23:59:59	869	3.7%	0.24	2.6 MB	3.9%	0.74
Nov 2 00:00:00 - 00:59:59	726	3.1%	0.20	2.2 MB	3.3%	0.62
Nov 2 01:00:00 - 01:59:59	839	3.6%	0.23	2.7 MB	4.0%	0.77
Nov 2 02:00:00 - 02:59:59	552	2.3%	0.15	2.0 MB	3.0%	0.56
Nov 2 03:00:00 - 03:59:59	569	2.4%	0.16	2.6 MB	3.9%	0.75
Nov 2 04:00:00 - 04:15:00	164	0.7%	0.18	859.2 KB	1.3%	0.95
TOTAL: 24:00:00	23496	100.0%	0.27	66.7 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	5880	650	1	5229
2	newsfeed.neva.ru	2742	822	1	1919
3	news-out.mixmin.net	2438	25	0	2413
4	feeder.eternal-september.org	2357	266	1	2090
5	newsfeed.fu-berlin.de	841	55	0	786
6	au2pb.net	593	548	0	45
7	nntp.pasdenom.info	187	153	0	34
8	newsfeeds.xmission.com	76	64	1	11
9	news.swapon.de	47	0	0	47
TOTAL: 9		15161	2583	4	12574

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	241
alt.politics.scorched-earth	197
lada.talk	171
mailing.freebsd.pkg-fallout	136
mailing.freebsd.ports-bugs	73
ut.test	70
it-alt.sport.calcio.juventus	67
pld.cvs.commit	61
nemo.test	59
alt.windows7.general	52
alt.politics	41
mailing.freebsd.svn-ports-all	38
mailing.freebsd.svn-ports-head	37
mailing.openbsd.source-changes	35
mailing.openbsd.tech	34
mailing.freebsd.svn-src-all	32
fa.openbsd.tech	32
mailing.freebsd.svn-src-head	30
fa.freebsd.cvs-all	30
alt.survival	30
TOTAL: 334	2583

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8601
EMP (phn path)	1528
EMP (phl)	1093
EMP (phn nph)	164
Too many newsgroups (low_xpost)	56
Too many newsgroups	44
HTML Multipart	35
HTML post	29
Binary Image: misplaced jpg	11
HTML Image Tags	8
Binary Payload	1
TOTAL: 11	11570

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	416
	feeder.eternal-september.org	39
	au2pb.net	32
	nntp.pasdenom.info	28
	news-out.mixmin.net	5
	news.swapon.de	3
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	2
	TOTAL: 8	528
Including strange strings
	newsfeed.fu-berlin.de	375
	feeder.eternal-september.org	33
	news.swapon.de	23
	news-out.mixmin.net	22
	newsfeed.neva.ru	8
	au2pb.net	7
	newsfeeds.xmission.com	5
	xanadu-bbs.net	2
	nntp.pasdenom.info	1
	TOTAL: 9	476
TOTAL: 2		1004

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	19935	279	18566	171	0	1%	23:59:56
2	usenet.pasdenom.info	26790	251	21996	768	195	0%	23:59:57
3	xanadu-bbs	21138	199	19365	83	9	0%	23:59:58
4	fu-berlin.de	21933	86	21794	53	0	0%	23:59:57
5	news.mixmin.net	21071	54	19886	0	0	0%	23:59:55
6	xmission	26907	24	21449	1	1	0%	23:59:54
7	neva.ru	22179	5	21133	74	0	0%	23:59:57
8	eternal-september	17766	5	16756	1	1	0%	23:59:42
9	news.swapon.de	2083	0	2060	9	0	0%	23:55:34
TOTAL: 9		179802	903	163005	1160	206	0%	215:54:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.5 MB	535.0 KB	2.0 MB	4.5 KB	23:59:56
2	xanadu-bbs	1.3 MB	168.9 KB	1.5 MB	5.3 KB	23:59:58
3	usenet.pasdenom.info	1.2 MB	1.6 MB	2.7 MB	2.8 KB	23:59:57
4	fu-berlin.de	456.5 KB	66.0 KB	522.5 KB	3.8 KB	23:59:57
5	news.mixmin.net	234.3 KB	0.0 KB	234.3 KB	4.3 KB	23:59:55
6	xmission	60.0 KB	2.0 KB	61.9 KB	2.5 KB	23:59:54
7	neva.ru	38.9 KB	392.9 KB	431.8 KB	5.5 KB	23:59:57
8	eternal-september	23.9 KB	1.4 KB	25.3 KB	4.2 KB	23:59:42
9	news.swapon.de	0.0 KB	18.5 KB	18.5 KB	2.1 KB	23:55:34
TOTAL: 9		4.7 MB	2.7 MB	7.4 MB	3.7 KB	215:54:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	67	209.7 KB	64	04:33:12
2	187.a2c-250-241.astra2connect.com	3	7	11.1 KB	2	01:02:20
TOTAL: 2		291	74	220.8 KB	66	35:22:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	417	67	209.7 KB	64	29:36:48
2	*.a2c-250-241.astra2connect.com	3	7	11.1 KB	2	01:02:20
3	*.chello.nl	4	0	0.0 KB	0	01:12:39
4	*.bbox.io	3	0	0.0 KB	0	00:30:14
5	*.ip-188-165-239.eu	1	0	0.0 KB	0	00:00:07
6	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	03:00:10
TOTAL: 6		434	74	220.8 KB	66	35:22:21

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.214	0.524	0.232	0.000	14:34:35
63.142.120.8	3.494	1.078	0.000	10:28:57
202.89.176.220	28.793	5.542	0.724	04:33:12
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.462	0.084	0.000	03:00:10
dhcp-089-099-203-254.chello.nl	0.330	0.062	0.000	01:12:39
187.a2c-250-241.astra2connect.com	0.389	0.090	0.000	01:02:20
tempe0.bbox.io	0.266	0.033	0.000	00:30:14
ns342002.ip-188-165-239.eu	0.100	0.008	0.000	00:00:07
69.160.37.54	1.950	0.348	0.000	00:00:02
TOTAL: 9	36.308	7.477	0.724	35:22:21

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	100
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
162.212.88.214	4
dhcp-089-099-203-254.chello.nl	4
tempe0.bbox.io	3
ns342002.ip-188-165-239.eu	1
TOTAL: 7	143

NNRP client timeouts [Top 20]:

System	Conn	Peer
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6	0
187.a2c-250-241.astra2connect.com	2	0
dhcp-089-099-203-254.chello.nl	2	0
tempe0.bbox.io	1	0
TOTAL: 4	11	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.pc.games.war-historical	19
2	comp.os.vms	19
3	comp.arch.embedded	7
4	comp.sys.ibm.ps2.hardware	7
5	comp.arch	6
6	comp.sys.ibm.pc.games.action	6
7	comp.sys.ibm.pc.hardware.chips	3
8	comp.ai.philosophy	2
9	comp.ai.fuzzy	1
10	comp.ai.neural-nets	1
11	comp.ai.shells	1
12	comp.ai.edu	1
13	comp.sys.ibm.pc.hardware.networking	1
TOTAL: 13		74

Daily Usenet report for csiph.com

Nov 1 04:15:00 -- Nov 2 04:15:00