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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40188	40.6%	6.3 MB	53.1%
nnrpd	31927	32.3%	2.6 MB	22.2%
innd	23613	23.9%	2.4 MB	20.4%
innfeed	3157	3.2%	513.1 KB	4.2%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 5	98887	100.0%	11.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	388737	59.8%
Negative hits	221886	34.1%
Do not exist	39334	6.0%
Cache misses	633	0.1%
TOTAL: 4	650590	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.420	0.0%	252	0.000	1.667	59.000
article cleanup	00:00:00.240	0.0%	37739	0.000	0.006	0.039
article logging	00:00:00.493	0.0%	38159	0.000	0.013	0.055
article parse	00:00:02.060	0.0%	208948	0.003	0.010	0.041
article write	00:00:52.914	0.1%	19756	0.148	2.678	11.952
artlog/artcncl	00:00:00.001	0.0%	171	0.000	0.006	0.500
artlog/artparse	00:00:00.024	0.0%	1837	0.000	0.013	0.071
data move	00:00:00.231	0.0%	622958	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.042	0.0%	244	0.000	0.172	14.000
hishave/artcncl	00:00:00.002	0.0%	252	0.000	0.008	0.200
hishave/artparse	00:00:00.005	0.0%	566	0.000	0.009	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.549	0.0%	653058	0.001	0.005	0.078
history sync	00:00:01.381	0.0%	362	0.000	3.815	71.000
history write	00:00:30.008	0.0%	38930	0.037	0.771	18.393
hiswrite/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hiswrite/artparse	00:00:00.239	0.0%	564	0.000	0.424	20.000
idle	23:56:16.317	99.7%	662951	37.210	129.989	439.499
nntp read	00:00:02.075	0.0%	668596	0.001	0.003	0.007
overview write	00:00:53.591	0.1%	19756	0.481	2.713	27.762
perl filter	00:01:31.569	0.1%	37659	0.838	2.432	4.602
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.313	0.0%	39512	0.000	0.008	0.030
TOTAL: 24:00:54.490	24:00:15.474	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	20608	0.000	0.003	0.027
article prepare	00:00:00.012	0.0%	38748	0.000	0.000	0.015
article read	00:00:00.812	0.0%	5271	0.000	0.154	5.750
backlog stats	00:00:00.146	0.0%	309457	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.271	0.0%	233288	0.001	0.005	0.011
data write	00:00:01.693	0.0%	217324	0.002	0.008	0.018
idle	23:54:21.043	100.0%	309457	151.437	278.103	769.366
status file	00:00:00.285	0.0%	497	0.000	0.573	1.000
TOTAL: 23:54:30.773	23:54:25.321	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	17:11:32.543	99.9%	31439	0.000	1968.655	600178.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.409	0.0%	63974	0.000	0.006	0.222
readart	00:00:02.085	0.0%	32975	0.000	0.063	63.000
TOTAL: 17:12:53.238	17:11:35.037	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
shutdown	3
TOTAL: 11	169

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	289	35082	7598	15048	12436	21%	23:42:17
2	csiph-out.feeder.erje.net	1156	148946	3461	141384	4101	2%	94:48:45
3	feeder.eternal-september.org	5	35075	2442	32270	363	6%	26:48:13
4	news-out.mixmin.net	2	21452	1655	19364	433	7%	23:59:11
5	newsfeed.neva.ru	148	70728	1655	67406	1667	2%	71:34:24
6	newsfeed.fu-berlin.de	25	18926	887	17990	49	4%	25:57:46
7	au2pb.net	1	17384	621	16676	87	3%	20:53:04
8	xanadu-bbs.net	1	34793	372	33828	593	1%	22:56:48
9	newsfeeds.xmission.com	2	18245	361	17864	20	1%	24:24:10
10	news.fcku.it	2	4623	354	4269	0	7%	23:02:30
11	news.freedyn.net	4	5512	73	5435	4	1%	17:46:31
12	news.swapon.de	7	1390	56	1333	1	4%	23:28:16
13	nntp.pasdenom.info	4	17107	55	17027	25	0%	24:07:53
14	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 14		1672	429263	19590	389894	19779	4%	423:29:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	21.5 MB	0.0 KB	136.4 MB	157.9 MB	13%	8.1 KB
2	csiph-out.feeder.erje.net	15.9 MB	847.5 KB	15.5 MB	32.2 MB	49%	4.4 KB
3	feeder.eternal-september.org	6.6 MB	2.1 KB	10.3 MB	16.9 MB	38%	6.2 KB
4	news-out.mixmin.net	4.8 MB	3.0 KB	755.2 KB	5.5 MB	86%	2.7 KB
5	newsfeed.neva.ru	4.2 MB	16.5 KB	6.0 MB	10.2 MB	41%	3.1 KB
6	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	197.8 KB	2.0 MB	90%	2.2 KB
7	au2pb.net	1.3 MB	0.0 KB	733.5 KB	2.0 MB	64%	2.9 KB
8	newsfeeds.xmission.com	1.2 MB	0.0 KB	166.6 KB	1.4 MB	88%	3.7 KB
9	xanadu-bbs.net	1.0 MB	0.0 KB	63.1 MB	64.1 MB	1%	68.0 KB
10	news.fcku.it	633.3 KB	0.0 KB	0.0 KB	633.3 KB	100%	1.8 KB
11	news.freedyn.net	200.0 KB	0.0 KB	51.3 KB	251.3 KB	79%	3.3 KB
12	news.swapon.de	138.5 KB	0.0 KB	217.0 KB	355.6 KB	38%	6.2 KB
13	nntp.pasdenom.info	126.1 KB	0.0 KB	139.1 KB	265.2 KB	47%	3.3 KB
14	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 14		59.4 MB	869.2 KB	233.5 MB	293.7 MB	20%	7.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 25 04:15:00 - 04:59:59	309	1.6%	0.11	996.9 KB	1.6%	0.37
Dec 25 05:00:00 - 05:59:59	443	2.2%	0.12	1.1 MB	1.8%	0.31
Dec 25 06:00:00 - 06:59:59	474	2.4%	0.13	1.3 MB	2.1%	0.36
Dec 25 07:00:00 - 07:59:59	489	2.5%	0.14	1.3 MB	2.1%	0.37
Dec 25 08:00:00 - 08:59:59	827	4.2%	0.23	2.2 MB	3.7%	0.64
Dec 25 09:00:00 - 09:59:59	1250	6.3%	0.35	6.1 MB	10.2%	1.74
Dec 25 10:00:00 - 10:59:59	1100	5.6%	0.31	3.4 MB	5.7%	0.98
Dec 25 11:00:00 - 11:59:59	1199	6.1%	0.33	4.0 MB	6.6%	1.13
Dec 25 12:00:00 - 12:59:59	1119	5.7%	0.31	3.5 MB	5.8%	0.99
Dec 25 13:00:00 - 13:59:59	1111	5.6%	0.31	3.4 MB	5.7%	0.96
Dec 25 14:00:00 - 14:59:59	923	4.7%	0.26	2.9 MB	4.9%	0.84
Dec 25 15:00:00 - 15:59:59	824	4.2%	0.23	2.1 MB	3.4%	0.59
Dec 25 16:00:00 - 16:59:59	997	5.1%	0.28	3.0 MB	5.1%	0.86
Dec 25 17:00:00 - 17:59:59	919	4.7%	0.26	2.5 MB	4.1%	0.70
Dec 25 18:00:00 - 18:59:59	1006	5.1%	0.28	2.7 MB	4.5%	0.76
Dec 25 19:00:00 - 19:59:59	967	4.9%	0.27	2.7 MB	4.5%	0.77
Dec 25 20:00:00 - 20:59:59	860	4.4%	0.24	2.3 MB	3.9%	0.66
Dec 25 21:00:00 - 21:59:59	1091	5.5%	0.30	3.1 MB	5.3%	0.90
Dec 25 22:00:00 - 22:59:59	971	4.9%	0.27	2.9 MB	4.8%	0.81
Dec 25 23:00:00 - 23:59:59	819	4.2%	0.23	2.1 MB	3.6%	0.61
Dec 26 00:00:00 - 00:59:59	528	2.7%	0.15	1.5 MB	2.5%	0.43
Dec 26 01:00:00 - 01:59:59	543	2.8%	0.15	1.8 MB	3.1%	0.52
Dec 26 02:00:00 - 02:59:59	412	2.1%	0.11	1.2 MB	2.1%	0.35
Dec 26 03:00:00 - 03:59:59	425	2.2%	0.12	1.4 MB	2.3%	0.40
Dec 26 04:00:00 - 04:15:00	111	0.6%	0.12	411.0 KB	0.7%	0.46
TOTAL: 24:00:00	19717	100.0%	0.23	60.0 MB	100.0%	0.71

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	12482	740	0	11742
2	csiph-out.feeder.erje.net	4119	280	72	3767
3	newsfeed.neva.ru	1671	809	6	856
4	xanadu-bbs.net	639	60	0	579
5	news-out.mixmin.net	453	3	1	449
6	feeder.eternal-september.org	398	47	1	350
7	newsfeed.fu-berlin.de	378	34	0	344
8	au2pb.net	90	85	0	5
9	newsfeeds.xmission.com	30	27	0	3
10	nntp.pasdenom.info	25	23	0	2
11	news.fcku.it	9	0	0	9
12	news.freedyn.net	4	2	0	2
13	news.swapon.de	2	0	0	2
TOTAL: 13		20300	2110	80	18110

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	177
lada.talk	164
pld.cvs.commit	147
mailing.freebsd.pkg-fallout	114
mailing.freebsd.svn-ports-all	96
mailing.freebsd.svn-ports-head	95
lada.auto	92
fa.linux.kernel	78
mpc.lists.freebsd.ports.bugs	73
mailing.freebsd.ports-bugs	73
alt.binaries.multimedia.tatu	61
lada.commerce	60
mailing.freebsd.usb	30
lada.movies	29
mpc.lists.nanog	28
spline.eisfair	28
mailing.openbsd.source-changes	27
fa.openbsd.source-changes	27
fa.freebsd.cvs-all	26
mailing.freebsd.svn-src-all	26
TOTAL: 196	2110

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10034
EMP (phn path)	4005
EMP (phl)	834
PFM bot misc	747
Rogue cancel (newsgroups)	160
Too many newsgroups	37
Binary Payload	16
HTML Multipart	11
User-issued spam cancel	4
Too many newsgroups (low_xpost)	3
HTML Image Tags	3
Binary Image: misplaced gif	1
TOTAL: 12	15855

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	292
	usenet.blueworldhosting.com	186
	feeder.eternal-september.org	75
	au2pb.net	4
	news-out.mixmin.net	3
	nntp.pasdenom.info	1
	newsfeed.fu-berlin.de	1
	news.freedyn.net	1
	news.swapon.de	1
	newsfeeds.xmission.com	1
	TOTAL: 10	565
Including strange strings
	newsfeed.fu-berlin.de	328
	feeder.eternal-september.org	27
	news-out.mixmin.net	18
	usenet.blueworldhosting.com	17
	csiph-out.feeder.erje.net	16
	news.fcku.it	9
	newsfeed.neva.ru	2
	au2pb.net	1
	news.swapon.de	1
	TOTAL: 9	419
No colon-space in header
	csiph-out.feeder.erje.net	1153
	usenet.blueworldhosting.com	114
	TOTAL: 2	1267
TOTAL: 3		2251

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	13800	2252	9981	386	0	16%	23:59:39
2	news.freedyn.net	17822	2181	14034	1040	909	12%	19:27:37
3	xanadu-bbs	20014	877	18365	47	0	4%	23:59:40
4	fu-berlin.de	18534	804	17723	7	0	4%	23:59:39
5	usenet.pasdenom.info	24686	130	18019	1511	11	0%	23:59:40
6	au2pb	18502	70	18029	86	0	0%	23:59:40
7	xmission	22645	59	18188	6	0	0%	23:59:40
8	neva.ru	19846	9	16786	204	0	0%	23:59:40
9	news.mixmin.net	20115	9	17127	1	0	0%	23:59:40
10	eternal-september	19179	9	16153	2	0	0%	23:59:40
11	news.fcku.it	922	5	790	0	0	0%	22:01:45
12	news.swapon.de	1449	1	1392	3	0	0%	23:33:10
13	bwh	11298	0	11083	0	0	0%	23:59:40
TOTAL: 13		208812	6406	177670	3293	920	3%	304:59:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	11.5 MB	5.3 MB	16.8 MB	0.2 KB/s	5.3 KB	19:27:37
2	erje	9.1 MB	1.4 MB	10.5 MB	0.1 KB/s	4.1 KB	23:59:39
3	xanadu-bbs	6.7 MB	91.7 KB	6.8 MB	0.1 KB/s	7.6 KB	23:59:40
4	fu-berlin.de	6.5 MB	9.1 KB	6.5 MB	0.1 KB/s	8.2 KB	23:59:39
5	usenet.pasdenom.info	528.8 KB	9.3 MB	9.8 MB	0.1 KB/s	6.1 KB	23:59:40
6	au2pb	360.5 KB	422.0 KB	782.5 KB	0.0 KB/s	5.0 KB	23:59:40
7	xmission	129.9 KB	15.6 KB	145.5 KB	0.0 KB/s	2.2 KB	23:59:40
8	neva.ru	91.9 KB	1.6 MB	1.7 MB	0.0 KB/s	8.4 KB	23:59:40
9	news.mixmin.net	55.4 KB	12.9 KB	68.3 KB	0.0 KB/s	6.8 KB	23:59:40
10	eternal-september	49.0 KB	3.2 KB	52.2 KB	0.0 KB/s	4.7 KB	23:59:40
11	news.fcku.it	14.0 KB	0.0 KB	14.0 KB	0.0 KB/s	2.8 KB	22:01:45
12	news.swapon.de	1.5 KB	8.5 KB	10.0 KB	0.0 KB/s	2.5 KB	23:33:10
13	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:40
TOTAL: 13		35.0 MB	18.1 MB	53.1 MB	0.0 KB/s	5.6 KB	304:59:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	60	150.4 KB	57	04:29:40
2	challenger.nielsenmedia.com	3	12	26.4 KB	2	01:00:22
3	c-73-195-247-24.hsd1.nj.comcast.net	7	12	26.4 KB	4	03:26:57
4	tempe0.bbox.io	50	2	4.2 KB	2	07:01:13
TOTAL: 4		348	86	207.4 KB	65	17:12:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	297	60	150.4 KB	57	04:29:43
2	*.hsd1.nj.comcast.net	7	12	26.4 KB	4	03:26:57
3	*.nielsenmedia.com	3	12	26.4 KB	2	01:00:22
4	*.bbox.io	50	2	4.2 KB	2	07:01:13
5	*.chello.nl	4	0	0.0 KB	0	01:14:33
TOTAL: 5		361	86	207.4 KB	65	17:12:49

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
tempe0.bbox.io	4.224	0.599	0.000	07:01:13
202.89.176.220	28.256	5.689	0.723	04:29:40
c-73-195-247-24.hsd1.nj.comcast.net	1.004	0.154	0.000	03:26:57
dhcp-089-099-203-254.chello.nl	0.291	0.046	0.000	01:14:33
challenger.nielsenmedia.com	0.301	0.058	0.000	01:00:22
71.94.21.184	0.889	0.850	0.000	00:00:02
69.160.37.54	0.597	0.134	0.000	00:00:00
TOTAL: 7	35.562	7.530	0.723	17:12:49

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	8
dhcp-089-099-203-254.chello.nl	4
71.94.21.184	1
TOTAL: 3	13

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	11	0
c-73-195-247-24.hsd1.nj.comcast.net	6	0
challenger.nielsenmedia.com	2	0
dhcp-089-099-203-254.chello.nl	2	0
TOTAL: 4	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	83	96.5%
2	news	3	3.5%
TOTAL: 2		86	100.0%

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

Newsgroup		Count
1	comp.lang.awk	24
2	comp.sys.ibm.pc.hardware.storage	15
3	comp.arch.embedded	12
4	comp.arch	10
5	comp.os.vms	8
6	comp.sys.ibm.pc.games.action	5
7	news.admin.peering	3
8	comp.os.os2.misc	3
9	comp.arch.fpga	2
10	comp.ai.neural-nets	2
11	comp.ai.philosophy	1
12	comp.sys.ibm.pc.games.war-historical	1
TOTAL: 12		86

Daily Usenet report for csiph.com

Dec 25 04:15:00 -- Dec 26 04:15:00