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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47344	42.6%	7.5 MB	55.3%
nnrpd	33679	30.3%	2.9 MB	21.1%
innd	27527	24.8%	2.8 MB	20.7%
innfeed	2575	2.3%	403.9 KB	2.9%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 5	111126	100.0%	13.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	450564	58.0%
Negative hits	278727	35.9%
Do not exist	46122	5.9%
Cache misses	760	0.1%
TOTAL: 4	776173	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.682	0.0%	302	0.000	2.258	35.000
article cleanup	00:00:00.303	0.0%	40650	0.000	0.007	0.031
article logging	00:00:00.524	0.0%	41155	0.000	0.013	0.095
article parse	00:00:10.138	0.0%	1229470	0.001	0.008	0.029
article write	00:00:58.113	0.1%	22413	0.364	2.593	14.529
artlog/artcncl	00:00:00.005	0.0%	270	0.000	0.019	1.000
artlog/artparse	00:00:00.076	0.0%	5946	0.000	0.013	1.000
data move	00:00:00.811	0.0%	1719012	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.058	0.0%	292	0.000	0.199	15.000
hishave/artcncl	00:00:00.001	0.0%	302	0.000	0.003	0.053
hishave/artparse	00:00:00.016	0.0%	4679	0.000	0.003	0.154
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.118	0.0%	775028	0.001	0.004	0.045
history sync	00:00:01.201	0.0%	356	0.000	3.374	27.333
history write	00:00:28.634	0.0%	41704	0.078	0.687	12.751
hiswrite/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hiswrite/artparse	00:00:01.595	0.0%	4678	0.000	0.341	4.000
idle	23:55:43.195	99.7%	1769525	15.191	48.682	461.138
nntp read	00:00:05.317	0.0%	1775771	0.001	0.003	0.006
overview write	00:00:44.797	0.1%	22413	0.270	1.999	14.411
perl filter	00:01:27.535	0.1%	40317	0.656	2.171	4.577
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.391	0.0%	44826	0.000	0.009	0.037
TOTAL: 24:00:41.487	23:59:46.510	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.067	0.0%	22396	0.000	0.003	0.023
article prepare	00:00:00.005	0.0%	28352	0.000	0.000	0.007
article read	00:00:00.814	0.0%	3636	0.000	0.224	3.625
backlog stats	00:00:00.140	0.0%	346121	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.442	0.0%	265036	0.001	0.005	0.012
data write	00:00:01.907	0.0%	247837	0.003	0.008	0.014
idle	23:54:36.619	100.0%	346121	95.715	248.689	837.303
status file	00:00:00.147	0.0%	266	0.000	0.553	1.000
TOTAL: 23:54:50.388	23:54:41.141	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.373	0.0%	8855	0.000	0.155	0.559
idle	82:53:25.823	99.9%	41634	40.286	7167.359	600184.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.637	0.0%	84810	0.000	0.008	0.255
readart	00:00:02.756	0.0%	65988	0.000	0.042	39.000
TOTAL: 82:59:18.451	82:53:30.589	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	usenet.blueworldhosting.com	288	38314	7406	20839	10069	19%	23:44:38
2	csiph-out.feeder.erje.net	1152	162427	3963	153843	4621	2%	95:16:58
3	feeder.eternal-september.org	14	37059	3774	32473	812	10%	28:26:02
4	news-out.mixmin.net	3	25682	1950	22823	909	7%	24:11:50
5	newsfeed.neva.ru	151	68863	1330	64889	2644	1%	71:54:08
6	newsfeed.fu-berlin.de	28	23289	1140	22063	86	4%	28:14:26
7	au2pb.net	2	24115	872	23175	68	3%	26:58:36
8	news.freedyn.net	75	23012	734	21980	298	3%	24:08:43
9	news.fcku.it	1	5178	673	4505	0	12%	22:06:53
10	newsfeeds.xmission.com	1	15988	303	15667	18	1%	18:08:43
11	nntp.pasdenom.info	3	20664	104	20542	18	0%	23:59:09
12	xanadu-bbs.net	1	41633	58	35157	6418	0%	22:03:14
13	news.swapon.de	3	59	1	58	0	1%	01:40:25
14	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 14		1748	486283	22308	438014	25961	4%	410:53:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	20.5 MB	48.8 KB	102.5 MB	123.0 MB	16%	7.2 KB
2	csiph-out.feeder.erje.net	16.5 MB	1.3 MB	25.0 MB	42.8 MB	38%	5.1 KB
3	feeder.eternal-september.org	10.3 MB	342.8 KB	13.0 MB	23.6 MB	43%	5.3 KB
4	news-out.mixmin.net	6.1 MB	8.2 KB	1.5 MB	7.6 MB	80%	2.7 KB
5	newsfeed.neva.ru	4.4 MB	189.0 KB	17.0 MB	21.6 MB	20%	5.6 KB
6	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	220.4 KB	2.9 MB	92%	2.4 KB
7	au2pb.net	2.0 MB	0.0 KB	339.2 KB	2.3 MB	85%	2.5 KB
8	news.freedyn.net	1.7 MB	0.0 KB	1.7 MB	3.4 MB	50%	3.4 KB
9	news.fcku.it	1.2 MB	0.0 KB	0.0 KB	1.2 MB	100%	1.9 KB
10	newsfeeds.xmission.com	944.8 KB	0.0 KB	403.1 KB	1.3 MB	70%	4.2 KB
11	nntp.pasdenom.info	188.9 KB	0.0 KB	108.3 KB	297.2 KB	63%	2.4 KB
12	xanadu-bbs.net	166.0 KB	0.0 KB	2.3 GB	2.3 GB	0%	372.2 KB
13	news.swapon.de	2.1 KB	0.0 KB	0.0 KB	2.1 KB	100%	2.1 KB
14	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 14		66.7 MB	1.8 MB	2.5 GB	2.5 GB	2%	54.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 27 04:15:00 - 04:59:59	404	1.8%	0.15	1.2 MB	1.8%	0.47
Dec 27 05:00:00 - 05:59:59	461	2.1%	0.13	1.4 MB	2.0%	0.39
Dec 27 06:00:00 - 06:59:59	417	1.9%	0.12	1.2 MB	1.8%	0.35
Dec 27 07:00:00 - 07:59:59	549	2.5%	0.15	1.9 MB	2.8%	0.53
Dec 27 08:00:00 - 08:59:59	587	2.6%	0.16	1.5 MB	2.2%	0.42
Dec 27 09:00:00 - 09:59:59	883	3.9%	0.25	2.9 MB	4.4%	0.84
Dec 27 10:00:00 - 10:59:59	1056	4.7%	0.29	3.5 MB	5.2%	0.99
Dec 27 11:00:00 - 11:59:59	1117	5.0%	0.31	3.6 MB	5.4%	1.02
Dec 27 12:00:00 - 12:59:59	1504	6.7%	0.42	4.6 MB	6.9%	1.32
Dec 27 13:00:00 - 13:59:59	1155	5.2%	0.32	3.7 MB	5.5%	1.05
Dec 27 14:00:00 - 14:59:59	1093	4.9%	0.30	2.9 MB	4.3%	0.82
Dec 27 15:00:00 - 15:59:59	1031	4.6%	0.29	3.0 MB	4.5%	0.86
Dec 27 16:00:00 - 16:59:59	1275	5.7%	0.35	3.7 MB	5.6%	1.06
Dec 27 17:00:00 - 17:59:59	1111	5.0%	0.31	2.8 MB	4.1%	0.79
Dec 27 18:00:00 - 18:59:59	1263	5.6%	0.35	3.3 MB	5.0%	0.95
Dec 27 19:00:00 - 19:59:59	1213	5.4%	0.34	3.0 MB	4.4%	0.84
Dec 27 20:00:00 - 20:59:59	1289	5.8%	0.36	3.7 MB	5.5%	1.05
Dec 27 21:00:00 - 21:59:59	1210	5.4%	0.34	3.5 MB	5.2%	0.99
Dec 27 22:00:00 - 22:59:59	1001	4.5%	0.28	3.0 MB	4.5%	0.86
Dec 27 23:00:00 - 23:59:59	770	3.4%	0.21	2.1 MB	3.2%	0.60
Dec 28 00:00:00 - 00:59:59	726	3.2%	0.20	2.3 MB	3.4%	0.65
Dec 28 01:00:00 - 01:59:59	718	3.2%	0.20	2.2 MB	3.3%	0.62
Dec 28 02:00:00 - 02:59:59	1039	4.6%	0.29	4.3 MB	6.4%	1.23
Dec 28 03:00:00 - 03:59:59	443	2.0%	0.12	1.4 MB	2.2%	0.41
Dec 28 04:00:00 - 04:15:00	90	0.4%	0.10	227.3 KB	0.3%	0.25
TOTAL: 24:00:00	22405	100.0%	0.26	67.0 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	10080	664	4	9412
2	csiph-out.feeder.erje.net	4643	615	122	3906
3	xanadu-bbs.net	4518	21	0	4497
4	newsfeed.neva.ru	2652	988	20	1644
5	news-out.mixmin.net	920	5	2	913
6	feeder.eternal-september.org	911	127	64	720
7	newsfeed.fu-berlin.de	502	51	0	451
8	news.freedyn.net	318	0	0	318
9	au2pb.net	53	49	0	4
10	newsfeeds.xmission.com	27	18	1	8
11	news.fcku.it	18	0	0	18
12	nntp.pasdenom.info	18	16	0	2
13	news.swapon.de	9	0	0	9
TOTAL: 13		24669	2554	213	21902

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	410
fa.linux.kernel	179
ut.test	160
alt.binaries.multimedia.tatu	129
pld.cvs.commit	85
mailing.freebsd.svn-src-all	84
fa.freebsd.cvs-all	83
mailing.freebsd.svn-ports-all	74
mailing.freebsd.svn-ports-head	72
mailing.freebsd.ports-bugs	60
mpc.lists.freebsd.ports.bugs	60
mailing.freebsd.pkg-fallout	57
mailing.freebsd.svn-src-stable	48
mpc.lists.nanog	44
mailing.openbsd.misc	42
lada.auto	37
mailing.freebsd.svn-src-head	35
mailing.freebsd.svn-src-stable-10	34
mailing.openbsd.ports-changes	34
alt.politics.scorched-earth	32
TOTAL: 198	2554

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11692
EMP (phn path)	2087
EMP (phl)	1023
Binary: misplaced rar	259
Rogue cancel (newsgroups)	70
EMP (phn nph)	61
Too many newsgroups	48
Binary: misplaced rev	35
HTML Multipart	19
Binary Payload	18
Binary: misplaced par2	15
PFM bot misc	14
HTML post	3
HTML Image Tags	2
User-issued spam cancel	2
HTML file attachment	1
TOTAL: 16	15349

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	116
	usenet.blueworldhosting.com	4
	TOTAL: 2	120
Bad Message-ID's offered
	csiph-out.feeder.erje.net	8
	usenet.blueworldhosting.com	2
	TOTAL: 2	10
Huge articles
	xanadu-bbs.net	4439
	usenet.blueworldhosting.com	144
	feeder.eternal-september.org	55
	news.freedyn.net	12
	newsfeeds.xmission.com	6
	au2pb.net	2
	news-out.mixmin.net	2
	nntp.pasdenom.info	1
	TOTAL: 8	4661
Including strange strings
	newsfeed.fu-berlin.de	404
	csiph-out.feeder.erje.net	22
	news.freedyn.net	19
	news.fcku.it	18
	usenet.blueworldhosting.com	13
	feeder.eternal-september.org	11
	news-out.mixmin.net	10
	news.swapon.de	7
	newsfeed.neva.ru	1
	TOTAL: 9	505
No colon-space in header
	csiph-out.feeder.erje.net	1045
	usenet.blueworldhosting.com	221
	TOTAL: 2	1266
TOTAL: 5		6562

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	22313	2685	18780	207	10	12%	23:59:52
2	xanadu-bbs	24535	994	21222	110	0	4%	23:59:50
3	fu-berlin.de	21003	876	20123	4	0	4%	23:59:43
4	usenet.pasdenom.info	27823	187	20535	1585	12	0%	23:59:53
5	au2pb	21281	71	20580	48	0	0%	23:59:44
6	erje	15494	59	15227	116	0	0%	23:59:53
7	neva.ru	23420	12	19829	127	0	0%	23:59:52
8	xmission	25675	9	20918	2	0	0%	23:59:51
9	news.fcku.it	1384	3	1185	0	0	0%	22:18:14
10	news.mixmin.net	22819	3	19448	0	0	0%	23:59:53
11	eternal-september	20331	2	17053	2	0	0%	23:58:55
12	bwh	14294	0	13968	0	0	0%	23:59:36
13	news.swapon.de	1974	0	1936	0	0	0%	23:56:33
TOTAL: 13		242346	4901	210804	2201	22	2%	310:11:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	13.9 MB	1.0 MB	15.0 MB	0.2 KB/s	5.3 KB	23:59:52
2	xanadu-bbs	6.9 MB	227.6 KB	7.1 MB	0.1 KB/s	6.6 KB	23:59:50
3	fu-berlin.de	6.4 MB	5.7 KB	6.4 MB	0.1 KB/s	7.4 KB	23:59:43
4	usenet.pasdenom.info	935.8 KB	8.0 MB	8.9 MB	0.1 KB/s	5.1 KB	23:59:53
5	au2pb	376.7 KB	137.9 KB	514.5 KB	0.0 KB/s	4.3 KB	23:59:44
6	erje	183.6 KB	423.6 KB	607.3 KB	0.0 KB/s	3.5 KB	23:59:53
7	neva.ru	146.4 KB	822.9 KB	969.4 KB	0.0 KB/s	7.0 KB	23:59:52
8	xmission	25.4 KB	28.8 KB	54.2 KB	0.0 KB/s	4.9 KB	23:59:51
9	news.mixmin.net	18.8 KB	0.0 KB	18.8 KB	0.0 KB/s	6.3 KB	23:59:53
10	eternal-september	11.3 KB	2.7 KB	13.9 KB	0.0 KB/s	3.5 KB	23:58:55
11	news.fcku.it	5.9 KB	0.0 KB	5.9 KB	0.0 KB/s	2.0 KB	22:18:14
12	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:36
13	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:33
TOTAL: 13		28.8 MB	10.6 MB	39.5 MB	0.0 KB/s	5.7 KB	310:11:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	118	286.6 KB	100	04:45:13
2	99-180-119-17.lightspeed.rcsntx.sbcglobal.net	148	2	6.4 KB	1	24:17:04
TOTAL: 2		436	120	293.0 KB	101	82:59:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	352	118	286.6 KB	100	48:43:27
2	*.lightspeed.rcsntx.sbcglobal.net	148	2	6.4 KB	1	24:17:04
3	*.chello.nl	18	0	0.0 KB	0	07:34:32
4	*.bbox.io	2	0	0.0 KB	0	01:00:00
5	*.136.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	00:00:00
6	*.hsd1.nj.comcast.net	7	0	0.0 KB	0	01:24:05
TOTAL: 6		528	120	293.0 KB	101	82:59:12

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
63.142.120.8	5.219	1.407	0.000	43:48:38
99-180-119-17.lightspeed.rcsntx.sbcglobal.net	12.778	2.798	0.000	24:17:04
dhcp-089-099-203-254.chello.nl	1.582	0.283	0.000	07:34:32
202.89.176.220	30.392	6.333	0.735	04:45:13
c-73-195-247-24.hsd1.nj.comcast.net	0.555	0.068	0.000	01:24:05
tempe0.bbox.io	0.220	0.039	0.000	01:00:00
162.212.88.221	0.987	0.297	0.000	00:09:31
71.94.21.184	1.808	1.426	0.000	00:00:04
TOTAL: 8	53.541	12.651	0.735	82:59:12

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	50
dhcp-089-099-203-254.chello.nl	18
162.212.88.221	12
c-73-195-247-24.hsd1.nj.comcast.net	7
tempe0.bbox.io	2
71.94.21.184	2
h71.136.22.98.dynamic.ip.windstream.net	1
TOTAL: 7	92

NNRP client timeouts [Top 20]:

System	Conn	Peer
63.142.120.8	50	0
dhcp-089-099-203-254.chello.nl	14	0
tempe0.bbox.io	2	0
c-73-195-247-24.hsd1.nj.comcast.net	2	0
TOTAL: 4	68	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	119	99.2%
2	news	1	0.8%
TOTAL: 2		120	100.0%

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

Newsgroup		Count
1	comp.arch	51
2	comp.os.vms	42
3	comp.sys.ibm.pc.games.action	7
4	comp.arch.embedded	5
5	comp.arch.fpga	4
6	comp.sys.ibm.pc.hardware.storage	3
7	comp.sys.ibm.pc.games.strategic	2
8	comp.ai.neural-nets	2
9	comp.unix.programmer	2
10	comp.os.linux.networking	1
11	news.admin.peering	1
TOTAL: 11		120

Daily Usenet report for csiph.com

Dec 27 04:15:00 -- Dec 28 04:15:00