Daily Usenet report for csiph.com: Dec 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	62138	48.8%	9.2 MB	60.5%
nnrpd	32889	25.8%	2.8 MB	18.4%
innd	30473	23.9%	2.9 MB	19.3%
innfeed	1829	1.4%	267.0 KB	1.7%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	127330	100.0%	15.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	266802	45.7%
Negative hits	246017	42.1%
Do not exist	66483	11.4%
Cache misses	4931	0.8%
TOTAL: 4	584233	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.483	0.0%	485	0.000	3.058	37.000
article cleanup	00:00:00.329	0.0%	46745	0.000	0.007	0.020
article logging	00:00:00.635	0.0%	47227	0.000	0.013	0.058
article parse	00:00:45.536	0.1%	2453571	0.003	0.019	0.091
article write	00:01:35.467	0.1%	28395	0.191	3.362	9.148
artlog/artcncl	00:00:00.004	0.0%	471	0.000	0.008	0.200
artlog/artparse	00:00:00.185	0.0%	14444	0.000	0.013	0.083
data move	00:00:02.297	0.0%	2934330	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.081	0.0%	479	0.000	0.169	15.000
hishave/artcncl	00:00:00.009	0.0%	485	0.000	0.019	0.030
hishave/artparse	00:00:00.046	0.0%	14434	0.000	0.003	0.045
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.781	0.0%	569392	0.002	0.007	0.068
history sync	00:00:02.053	0.0%	316	0.000	6.497	122.500
history write	00:01:01.089	0.1%	46739	0.067	1.307	16.530
hiswrite/artcncl	00:00:00.025	0.0%	6	0.000	4.167	25.000
hiswrite/artparse	00:00:26.229	0.0%	14432	0.000	1.817	18.135
idle	23:53:02.354	99.5%	2868394	9.733	29.976	324.972
nntp read	00:00:09.295	0.0%	2875088	0.002	0.003	0.005
overview write	00:01:24.311	0.1%	28395	0.488	2.969	23.035
perl filter	00:01:20.912	0.1%	46739	0.937	1.731	3.641
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.499	0.0%	56790	0.000	0.009	0.033
TOTAL: 24:00:27.444	23:59:56.620	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.077	0.0%	28414	0.000	0.003	0.019
article prepare	00:00:00.000	0.0%	6804	0.000	0.000	0.000
article read	00:00:00.214	0.0%	1430	0.000	0.150	22.500
backlog stats	00:00:00.110	0.0%	307305	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.307	0.0%	238137	0.001	0.005	0.010
data write	00:00:01.628	0.0%	216835	0.002	0.008	0.015
idle	23:54:34.748	100.0%	307305	154.322	280.096	831.545
status file	00:00:00.078	0.0%	179	0.000	0.436	2.000
TOTAL: 23:54:42.051	23:54:38.162	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.625	0.0%	103276	0.000	0.025	0.333
idle	49:34:32.578	99.9%	134980	0.000	1322.215	600141.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.642	0.0%	271017	0.000	0.006	0.167
readart	00:00:00.004	0.0%	98	0.000	0.041	1.000
TOTAL: 49:36:35.650	49:34:36.849	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	3	31971	9735	20474	1762	30%	24:54:26
2	feeder.eternal-september.org	1	43005	7266	32802	2937	16%	24:42:02
3	news-out.mixmin.net	3	33941	4206	26520	3215	12%	24:11:51
4	newsfeed.neva.ru	151	56155	2752	46663	6740	4%	71:42:21
5	newsfeeds.xmission.com	1	30758	1842	28825	91	5%	25:13:15
6	newsfeed.fu-berlin.de	65	31707	1225	30274	208	3%	33:14:32
7	xanadu-bbs.net	23	54499	1049	38157	15293	1%	56:37:41
8	nntp.pasdenom.info	3	29778	281	29338	159	0%	24:09:30
9	news.swapon.de	2	2031	162	1860	9	7%	22:41:51
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		278	313845	28518	254913	30414	9%	307:27:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	25.1 MB	21.1 KB	9.0 MB	34.1 MB	73%	3.0 KB
2	feeder.eternal-september.org	20.0 MB	17.8 KB	22.5 MB	42.6 MB	47%	4.3 KB
3	news-out.mixmin.net	15.3 MB	0.0 KB	6.9 MB	22.2 MB	69%	3.1 KB
4	newsfeed.neva.ru	8.8 MB	27.7 KB	57.1 MB	65.9 MB	13%	7.1 KB
5	newsfeeds.xmission.com	6.5 MB	0.0 KB	2.5 MB	8.9 MB	72%	4.7 KB
6	xanadu-bbs.net	3.0 MB	0.0 KB	3.2 GB	3.2 GB	0%	202.8 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	567.4 KB	3.5 MB	84%	2.5 KB
8	nntp.pasdenom.info	612.8 KB	0.0 KB	2.2 MB	2.8 MB	21%	6.5 KB
9	news.swapon.de	456.2 KB	0.0 KB	23.3 KB	479.6 KB	95%	2.8 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		82.6 MB	66.5 KB	3.3 GB	3.3 GB	2%	59.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 2 04:15:00 - 04:59:59	424	1.5%	0.16	1.9 MB	2.3%	0.73
Dec 2 05:00:00 - 05:59:59	792	2.8%	0.22	3.0 MB	3.7%	0.86
Dec 2 06:00:00 - 06:59:59	608	2.1%	0.17	1.8 MB	2.2%	0.52
Dec 2 07:00:00 - 07:59:59	785	2.8%	0.22	2.0 MB	2.5%	0.58
Dec 2 08:00:00 - 08:59:59	1117	3.9%	0.31	3.3 MB	4.0%	0.93
Dec 2 09:00:00 - 09:59:59	1298	4.6%	0.36	3.4 MB	4.2%	0.97
Dec 2 10:00:00 - 10:59:59	1418	5.0%	0.39	3.8 MB	4.6%	1.07
Dec 2 11:00:00 - 11:59:59	1361	4.8%	0.38	3.9 MB	4.8%	1.12
Dec 2 12:00:00 - 12:59:59	1474	5.2%	0.41	4.2 MB	5.1%	1.18
Dec 2 13:00:00 - 13:59:59	1507	5.3%	0.42	4.1 MB	4.9%	1.16
Dec 2 14:00:00 - 14:59:59	1507	5.3%	0.42	4.1 MB	5.0%	1.16
Dec 2 15:00:00 - 15:59:59	1663	5.9%	0.46	4.7 MB	5.8%	1.35
Dec 2 16:00:00 - 16:59:59	1721	6.1%	0.48	4.8 MB	5.9%	1.37
Dec 2 17:00:00 - 17:59:59	1866	6.6%	0.52	5.2 MB	6.4%	1.49
Dec 2 18:00:00 - 18:59:59	1709	6.0%	0.47	4.9 MB	5.9%	1.39
Dec 2 19:00:00 - 19:59:59	1552	5.5%	0.43	4.3 MB	5.2%	1.21
Dec 2 20:00:00 - 20:59:59	1603	5.6%	0.45	4.5 MB	5.4%	1.27
Dec 2 21:00:00 - 21:59:59	1301	4.6%	0.36	3.9 MB	4.7%	1.10
Dec 2 22:00:00 - 22:59:59	1101	3.9%	0.31	3.0 MB	3.6%	0.85
Dec 2 23:00:00 - 23:59:59	1017	3.6%	0.28	2.9 MB	3.5%	0.83
Dec 3 00:00:00 - 00:59:59	688	2.4%	0.19	2.1 MB	2.5%	0.59
Dec 3 01:00:00 - 01:59:59	601	2.1%	0.17	2.0 MB	2.4%	0.56
Dec 3 02:00:00 - 02:59:59	597	2.1%	0.17	2.3 MB	2.8%	0.65
Dec 3 03:00:00 - 03:59:59	526	1.9%	0.15	1.6 MB	2.0%	0.47
Dec 3 04:00:00 - 04:15:00	158	0.6%	0.18	565.5 KB	0.7%	0.63
TOTAL: 24:00:00	28394	100.0%	0.33	82.2 MB	100.0%	0.97

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	17533	234	0	17299
2	newsfeed.neva.ru	6814	1691	2	5121
3	news-out.mixmin.net	3258	8	0	3250
4	feeder.eternal-september.org	3024	310	3	2711
5	au2pb.net	1775	1654	1	120
6	newsfeed.fu-berlin.de	577	54	0	523
7	nntp.pasdenom.info	159	132	0	27
8	newsfeeds.xmission.com	93	65	0	28
9	news.swapon.de	40	0	0	40
TOTAL: 9		33273	4148	6	29119

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1158
mailing.freebsd.ports-bugs	174
lada.talk	161
mailing.freebsd.pkg-fallout	142
ut.test	133
alt.survival	105
alt.politics	95
mailing.freebsd.svn-ports-all	87
mailing.freebsd.svn-ports-head	81
mailing.openbsd.tech	80
fa.openbsd.tech	76
mailing.openbsd.misc	74
mailing.openbsd.source-changes	73
mailing.gcc.gcc-cvs	71
lada.auto	64
alt.politics.scorched-earth	60
mozilla.dev.tree-alerts	58
pld.cvs.commit	55
fa.openbsd.source-changes	54
mozilla.dev.platform	46
TOTAL: 238	4148

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9675
EMP (phn path)	2313
EMP (phl)	1081
Binary: misplaced rar	354
PFM bot misc	226
EMP (phn nph)	222
HTML Multipart	98
Too many newsgroups	84
Too many newsgroups (low_xpost)	34
HTML post	22
Binary: misplaced pdf	20
Binary: misplaced rev	20
Binary: misplaced par2	18
HTML Image Tags	16
Binary: non-binary distribution	3
EMP (fsl)	3
Binary Image: misplaced jpg	2
Binary: misplaced obj	1
Binary Payload	1
TOTAL: 19	14193

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	xanadu-bbs.net	89
	TOTAL: 1	89
Huge articles
	xanadu-bbs.net	14265
	feeder.eternal-september.org	78
	au2pb.net	35
	news-out.mixmin.net	25
	newsfeeds.xmission.com	25
	nntp.pasdenom.info	14
	newsfeed.fu-berlin.de	1
	TOTAL: 7	14443
Including strange strings
	newsfeed.fu-berlin.de	345
	feeder.eternal-september.org	50
	news.swapon.de	31
	au2pb.net	25
	news-out.mixmin.net	25
	xanadu-bbs.net	3
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	1
	TOTAL: 8	482
TOTAL: 3		15014

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	31761	199	27161	753	59	0%	23:59:58
2	au2pb	19209	142	18571	72	0	0%	23:59:45
3	xanadu-bbs	33103	111	26964	286	15	0%	23:59:58
4	fu-berlin.de	26733	25	26692	16	0	0%	23:59:43
5	xmission	32679	17	26377	3	0	0%	23:59:57
6	eternal-september	21166	15	20254	0	0	0%	23:59:57
7	news.mixmin.net	24986	9	24090	0	0	0%	23:59:57
8	neva.ru	26318	7	25263	41	0	0%	23:59:57
9	news.swapon.de	1951	0	1932	5	0	0%	23:59:04
TOTAL: 9		217906	525	197304	1176	74	0%	215:58:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.2 MB	1.9 MB	3.1 MB	3.3 KB	23:59:58
2	xanadu-bbs	809.1 KB	636.2 KB	1.4 MB	3.6 KB	23:59:58
3	au2pb	660.2 KB	533.0 KB	1.2 MB	5.6 KB	23:59:45
4	fu-berlin.de	170.5 KB	22.4 KB	193.0 KB	4.7 KB	23:59:43
5	eternal-september	67.6 KB	0.0 KB	67.6 KB	4.5 KB	23:59:57
6	neva.ru	55.5 KB	256.9 KB	312.4 KB	6.5 KB	23:59:57
7	xmission	52.9 KB	4.7 KB	57.7 KB	2.9 KB	23:59:57
8	news.mixmin.net	44.3 KB	0.0 KB	44.3 KB	4.9 KB	23:59:57
9	news.swapon.de	0.0 KB	16.6 KB	16.6 KB	3.3 KB	23:59:04
TOTAL: 9		3.0 MB	3.3 MB	6.3 MB	3.8 KB	215:58:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	98	302.8 KB	95	0	0	04:20:42
TOTAL: 1		288	98	302.8 KB	95	0	0	49:36:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	324	98	302.8 KB	95	27:57:23
2	*.149.22.98.dynamic.ip.windstream.net	4	0	0.0 KB	0	04:17:28
3	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	00:39:12
4	*.bbox.io	6	0	0.0 KB	0	02:30:12
5	*.ip-188-165-239.eu	226	0	0.0 KB	0	04:01:26
6	*.chello.nl	30	0	0.0 KB	0	10:10:45
TOTAL: 6		592	98	302.8 KB	95	49:36:29

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
216.246.166.29	0.831	0.399	0.000	22:23:38
dhcp-089-099-203-254.chello.nl	2.389	0.536	0.000	10:10:45
202.89.176.220	29.721	6.755	0.737	04:20:42
h188.149.22.98.dynamic.ip.windstream.net	0.381	0.159	0.000	04:17:28
ns342002.ip-188-165-239.eu	20.321	7.251	0.000	04:01:26
tempe0.bbox.io	0.702	0.138	0.000	02:30:12
138.108.60.60	0.283	0.056	0.000	01:13:00
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.193	0.031	0.000	00:39:12
69.160.37.54	1.951	0.448	0.000	00:00:02
TOTAL: 9	56.772	15.773	0.737	49:36:29

Curious NNRP server explorers [Top 20]:

System	Conn
ns342002.ip-188-165-239.eu	226
dhcp-089-099-203-254.chello.nl	30
69.160.37.54	25
216.246.166.29	8
tempe0.bbox.io	6
h188.149.22.98.dynamic.ip.windstream.net	4
138.108.60.60	3
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
TOTAL: 8	304

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	16	0
tempe0.bbox.io	5	0
h188.149.22.98.dynamic.ip.windstream.net	3	0
138.108.60.60	2	0
ns342002.ip-188-165-239.eu	1	1
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 6	28	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	27
2	comp.arch	17
3	comp.sys.ibm.pc.games.war-historical	15
4	comp.arch.fpga	10
5	comp.arch.embedded	9
6	comp.sys.ibm.pc.games.action	7
7	comp.os.os2.misc	4
8	comp.ai.philosophy	4
9	comp.sys.ibm.pc.games.rpg	2
10	comp.sys.ibm.pc.hardware.chips	1
11	comp.os.linux.networking	1
12	comp.os.os2.announce	1
TOTAL: 12		98

Daily Usenet report for csiph.com

Dec 2 04:15:00 -- Dec 3 04:15:00