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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40622	45.5%	6.1 MB	57.9%
nnrpd	32298	36.2%	2.7 MB	25.4%
innd	14562	16.3%	1.5 MB	14.0%
innfeed	1851	2.1%	291.3 KB	2.7%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	89334	100.0%	10.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	217631	47.8%
Negative hits	197896	43.4%
Do not exist	39414	8.7%
Cache misses	568	0.1%
TOTAL: 4	455509	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.571	0.0%	664	0.000	8.390	38.000
article cleanup	00:00:00.236	0.0%	38557	0.000	0.006	0.024
article logging	00:00:00.556	0.0%	39062	0.000	0.014	0.068
article parse	00:00:02.734	0.0%	208056	0.000	0.013	0.208
article write	00:01:02.025	0.1%	23879	0.181	2.597	8.803
artlog/artcncl	00:00:00.003	0.0%	591	0.000	0.005	0.500
artlog/artparse	00:00:00.011	0.0%	1011	0.000	0.011	1.000
data move	00:00:00.265	0.0%	607581	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.637	0.0%	664	0.000	0.959	16.000
hishave/artcncl	00:00:00.305	0.0%	664	0.000	0.459	0.689
hishave/artparse	00:00:00.004	0.0%	1011	0.000	0.004	0.028
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.673	0.0%	455425	0.000	0.010	0.070
history sync	00:00:01.932	0.0%	351	0.000	5.504	42.000
history write	00:00:47.367	0.1%	38554	0.053	1.229	10.438
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.039	0.0%	1011	0.000	1.028	64.000
idle	23:56:00.915	99.7%	588245	20.681	146.471	445.711
nntp read	00:00:01.896	0.0%	590369	0.001	0.003	0.007
overview write	00:01:10.331	0.1%	23879	0.646	2.945	46.762
perl filter	00:01:06.160	0.1%	38554	0.942	1.716	4.600
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.403	0.0%	47758	0.000	0.008	0.034
TOTAL: 24:01:00.495	24:00:27.063	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	23830	0.000	0.003	0.017
article prepare	00:00:00.002	0.0%	5244	0.000	0.000	0.028
article read	00:00:00.341	0.0%	1080	0.000	0.316	15.333
backlog stats	00:00:00.106	0.0%	255936	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.132	0.0%	200663	0.000	0.006	0.014
data write	00:00:01.435	0.0%	180494	0.003	0.008	0.016
idle	23:54:45.834	100.0%	255936	154.527	336.357	860.887
status file	00:00:00.097	0.0%	179	0.000	0.542	3.000
TOTAL: 23:54:52.930	23:54:49.018	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:07.155	0.0%	84090	0.000	0.085	1.531
idle	124:55:11.033	99.9%	115571	0.000	3891.210	600233.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.175	0.0%	232248	0.000	0.005	0.500
readart	00:00:00.032	0.0%	102	0.000	0.314	12.000
TOTAL: 125:03:43.512	124:55:19.395	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	1	25769	6385	18685	699	24%	24:53:34
2	feeder.eternal-september.org	8	36156	5335	29762	1059	14%	26:24:16
3	news-out.mixmin.net	5	28847	3962	22109	2776	13%	24:20:57
4	newsfeeds.xmission.com	1	24564	2394	21833	337	9%	25:48:42
5	xanadu-bbs.net	1	39118	2377	29531	7210	6%	23:47:26
6	newsfeed.neva.ru	144	51745	2209	46257	3279	4%	71:27:14
7	newsfeed.fu-berlin.de	26	28608	1340	26958	310	4%	30:22:27
8	news.swapon.de	2	4588	303	4259	26	6%	45:29:39
9	nntp.pasdenom.info	4	23158	163	22971	24	0%	24:11:37
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		218	262553	24468	222365	15720	9%	296:45:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	17.0 MB	0.0 KB	4.1 MB	21.1 MB	80%	3.1 KB
2	feeder.eternal-september.org	13.7 MB	1.9 KB	12.9 MB	26.6 MB	51%	4.3 KB
3	news-out.mixmin.net	11.7 MB	0.0 KB	5.8 MB	17.4 MB	66%	2.7 KB
4	newsfeeds.xmission.com	7.3 MB	0.0 KB	2.9 MB	10.3 MB	71%	3.9 KB
5	xanadu-bbs.net	6.9 MB	2.0 KB	166.8 MB	173.7 MB	3%	18.6 KB
6	newsfeed.neva.ru	6.2 MB	2.0 KB	11.8 MB	18.0 MB	34%	3.4 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	997.7 KB	3.8 MB	74%	2.4 KB
8	news.swapon.de	809.0 KB	0.0 KB	84.8 KB	893.9 KB	90%	2.7 KB
9	nntp.pasdenom.info	330.4 KB	0.0 KB	316.9 KB	647.3 KB	51%	3.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		66.8 MB	5.8 KB	205.7 MB	272.5 MB	24%	6.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 12 04:15:00 - 04:59:59	340	1.4%	0.13	1005.4 KB	1.5%	0.37
Dec 12 05:00:00 - 05:59:59	458	1.9%	0.13	1.3 MB	2.0%	0.37
Dec 12 06:00:00 - 06:59:59	523	2.2%	0.15	1.6 MB	2.5%	0.46
Dec 12 07:00:00 - 07:59:59	638	2.7%	0.18	1.8 MB	2.8%	0.52
Dec 12 08:00:00 - 08:59:59	864	3.6%	0.24	2.5 MB	3.8%	0.70
Dec 12 09:00:00 - 09:59:59	1136	4.8%	0.32	2.8 MB	4.4%	0.81
Dec 12 10:00:00 - 10:59:59	1068	4.5%	0.30	2.9 MB	4.4%	0.81
Dec 12 11:00:00 - 11:59:59	1055	4.4%	0.29	2.8 MB	4.2%	0.78
Dec 12 12:00:00 - 12:59:59	1177	4.9%	0.33	3.0 MB	4.7%	0.86
Dec 12 13:00:00 - 13:59:59	1123	4.7%	0.31	2.9 MB	4.4%	0.81
Dec 12 14:00:00 - 14:59:59	1112	4.7%	0.31	3.2 MB	5.0%	0.92
Dec 12 15:00:00 - 15:59:59	1362	5.7%	0.38	3.4 MB	5.3%	0.97
Dec 12 16:00:00 - 16:59:59	1274	5.3%	0.35	3.4 MB	5.3%	0.98
Dec 12 17:00:00 - 17:59:59	1332	5.6%	0.37	3.4 MB	5.3%	0.97
Dec 12 18:00:00 - 18:59:59	1180	4.9%	0.33	3.0 MB	4.6%	0.86
Dec 12 19:00:00 - 19:59:59	1319	5.5%	0.37	3.6 MB	5.5%	1.01
Dec 12 20:00:00 - 20:59:59	1337	5.6%	0.37	3.6 MB	5.5%	1.02
Dec 12 21:00:00 - 21:59:59	1347	5.6%	0.37	3.5 MB	5.4%	1.00
Dec 12 22:00:00 - 22:59:59	1087	4.6%	0.30	2.9 MB	4.4%	0.82
Dec 12 23:00:00 - 23:59:59	943	4.0%	0.26	2.5 MB	3.8%	0.71
Dec 13 00:00:00 - 00:59:59	814	3.4%	0.23	2.3 MB	3.6%	0.66
Dec 13 01:00:00 - 01:59:59	763	3.2%	0.21	2.7 MB	4.1%	0.76
Dec 13 02:00:00 - 02:59:59	552	2.3%	0.15	1.8 MB	2.8%	0.52
Dec 13 03:00:00 - 03:59:59	949	4.0%	0.26	2.5 MB	3.9%	0.72
Dec 13 04:00:00 - 04:15:00	104	0.4%	0.12	372.1 KB	0.6%	0.41
TOTAL: 24:00:00	23857	100.0%	0.28	64.9 MB	100.0%	0.77

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	7261	416	1	6844
2	newsfeed.neva.ru	3286	1015	1	2270
3	news-out.mixmin.net	2749	2	0	2747
4	feeder.eternal-september.org	1089	140	1	948
5	newsfeed.fu-berlin.de	707	38	0	669
6	au2pb.net	706	654	0	52
7	newsfeeds.xmission.com	317	312	0	5
8	news.swapon.de	33	0	0	33
9	nntp.pasdenom.info	26	19	0	7
TOTAL: 9		16174	2596	3	13575

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	436
pld.cvs.commit	251
mailing.freebsd.pkg-fallout	234
alt.politics	138
lada.talk	95
ut.test	89
mailing.openbsd.source-changes	79
mailing.freebsd.svn-ports-all	72
mailing.freebsd.svn-ports-head	62
mailing.freebsd.ports-bugs	52
lada.auto	48
alt.survival	45
fa.openbsd.source-changes	39
gmane.linux.alsa.devel	37
mailing.unix.spamassassin-talk	32
mozilla.support.firefox	30
mailing.freebsd.svn-src-all	29
fa.freebsd.cvs-all	29
mozilla.general	27
mailing.openbsd.tech	25
TOTAL: 207	2596

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8410
EMP (phn path)	2105
EMP (phl)	970
PFM bot misc	261
EMP (phn nph)	145
Too many newsgroups	61
HTML Multipart	40
Too many newsgroups (low_xpost)	16
HTML post	11
Binary Image: misplaced jpg	10
HTML Image Tags	6
Binary: misplaced sfv	6
Binary: misplaced m3u	6
Poison newsgroup	5
Binary: misplaced nfo	3
User-issued spam cancel	3
Binary: non-binary distribution	3
Binary: misplaced nzb	1
Binary Payload	1
Binary: misplaced mp3	1
TOTAL: 21	12065

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	898
	feeder.eternal-september.org	76
	au2pb.net	14
	news-out.mixmin.net	7
	newsfeed.fu-berlin.de	4
	newsfeeds.xmission.com	4
	nntp.pasdenom.info	3
	TOTAL: 7	1006
Including strange strings
	newsfeed.fu-berlin.de	408
	feeder.eternal-september.org	51
	news.swapon.de	20
	au2pb.net	14
	news-out.mixmin.net	7
	newsfeed.neva.ru	3
	xanadu-bbs.net	1
	TOTAL: 7	504
TOTAL: 2		1510

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	27983	143	22757	795	2	0%	23:59:57
2	xanadu-bbs	22506	79	21344	41	14	0%	23:59:57
3	au2pb	17931	71	17424	53	0	0%	23:59:53
4	fu-berlin.de	22230	40	22171	19	0	0%	23:59:54
5	xmission	24400	12	21372	0	0	0%	23:59:55
6	news.mixmin.net	20943	8	19795	1	0	0%	23:59:57
7	neva.ru	22952	3	21418	45	0	0%	23:59:57
8	eternal-september	19160	1	17742	1	0	0%	23:59:52
9	news.swapon.de	2006	0	1974	1	0	0%	23:45:04
TOTAL: 9		180111	357	165997	956	16	0%	215:44:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	536.6 KB	2.1 MB	2.6 MB	2.8 KB	23:59:57
2	xanadu-bbs	423.6 KB	82.5 KB	506.2 KB	4.2 KB	23:59:57
3	au2pb	354.4 KB	171.9 KB	526.3 KB	4.2 KB	23:59:53
4	fu-berlin.de	191.5 KB	26.6 KB	218.1 KB	3.7 KB	23:59:54
5	news.mixmin.net	90.3 KB	2.0 KB	92.2 KB	10.2 KB	23:59:57
6	neva.ru	33.6 KB	294.6 KB	328.2 KB	6.8 KB	23:59:57
7	xmission	32.4 KB	0.0 KB	32.4 KB	2.7 KB	23:59:55
8	eternal-september	13.9 KB	1.1 KB	15.1 KB	7.5 KB	23:59:52
9	news.swapon.de	0.0 KB	2.3 KB	2.3 KB	2.3 KB	23:45:04
TOTAL: 9		1.6 MB	2.6 MB	4.3 MB	3.3 KB	215:44:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	100	315.3 KB	96	04:23:13
2	tempe0.bbox.io	17	2	3.4 KB	0	05:11:06
TOTAL: 2		305	102	318.7 KB	96	125:03:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	399	100	315.3 KB	96	97:47:16
2	*.bbox.io	17	2	3.4 KB	0	05:11:06
3	*.nielsenmedia.com	2	0	0.0 KB	0	00:38:58
4	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	00:00:02
5	*.chello.nl	10	0	0.0 KB	0	04:19:40
6	*.tampfl.dsl-w.verizon.net	7	0	0.0 KB	0	02:44:20
7	*.248.91.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	14:22:15
TOTAL: 7		438	102	318.7 KB	96	125:03:38

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.907	1.174	0.000	77:27:31
63.142.120.8	6.696	2.093	0.000	15:56:28
h130.248.91.75.dynamic.ip.windstream.net	1.445	2.267	0.000	14:22:15
tempe0.bbox.io	1.589	0.323	0.000	05:11:06
202.89.176.220	30.522	6.153	0.734	04:23:13
dhcp-089-099-203-254.chello.nl	0.775	0.111	0.000	04:19:40
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.614	0.054	0.000	02:44:20
challenger.nielsenmedia.com	0.181	0.024	0.000	00:38:58
ip230.208-100-26.static.steadfastdns.net	0.200	0.024	0.000	00:00:02
69.160.37.54	1.857	0.426	0.000	00:00:02
TOTAL: 10	44.786	12.649	0.734	125:03:38

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	82
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	10
pool-71-98-160-84.tampfl.dsl-w.verizon.net	7
216.246.166.29	4
challenger.nielsenmedia.com	2
ip230.208-100-26.static.steadfastdns.net	2
h130.248.91.75.dynamic.ip.windstream.net	1
TOTAL: 8	133

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	9	0
dhcp-089-099-203-254.chello.nl	8	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
202.89.176.220	2	0
h130.248.91.75.dynamic.ip.windstream.net	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 6	26	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	36
2	comp.arch	30
3	comp.sys.ibm.pc.games.action	12
4	comp.arch.embedded	9
5	comp.ai.philosophy	5
6	comp.sys.ibm.as400.misc	2
7	comp.os.os2.misc	2
8	comp.ai.neural-nets	2
9	comp.sys.ibm.pc.games.war-historical	2
TOTAL: 9		100

Daily Usenet report for csiph.com

Dec 12 04:15:00 -- Dec 13 04:15:00