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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	56693	42.9%	8.6 MB	54.6%
innd	40472	30.6%	4.0 MB	25.5%
nnrpd	32341	24.5%	2.7 MB	17.4%
innfeed	2631	2.0%	400.3 KB	2.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	132138	100.0%	15.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	416362	56.9%
Negative hits	251558	34.4%
Do not exist	59683	8.2%
Cache misses	4604	0.6%
TOTAL: 4	732207	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.261	0.0%	265	0.000	0.985	28.000
article cleanup	00:00:00.255	0.0%	39669	0.000	0.006	0.027
article logging	00:00:00.509	0.0%	40087	0.000	0.013	0.189
article parse	00:00:41.721	0.0%	3508223	0.003	0.012	0.046
article write	00:00:33.218	0.0%	20565	0.214	1.615	12.159
artlog/artcncl	00:00:00.002	0.0%	196	0.000	0.010	0.167
artlog/artparse	00:00:00.220	0.0%	16443	0.000	0.013	0.250
data move	00:00:02.810	0.0%	3894567	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.101	0.0%	261	0.000	0.387	24.000
hishave/artcncl	00:00:00.002	0.0%	265	0.000	0.008	0.500
hishave/artparse	00:00:00.066	0.0%	15787	0.000	0.004	0.027
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.896	0.0%	717976	0.000	0.005	0.066
history sync	00:00:01.608	0.0%	321	0.000	5.009	85.500
history write	00:00:29.147	0.0%	40164	0.043	0.726	6.687
hiswrite/artcncl	00:00:00.004	0.0%	4	0.000	1.000	3.000
hiswrite/artparse	00:00:15.754	0.0%	15787	0.000	0.998	7.409
idle	23:54:38.187	99.6%	3954915	8.318	21.765	456.252
nntp read	00:00:12.246	0.0%	3964034	0.001	0.003	0.007
overview write	00:01:01.659	0.1%	20565	0.718	2.998	29.529
perl filter	00:01:29.728	0.1%	39447	1.019	2.275	4.678
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.340	0.0%	41130	0.000	0.008	0.040
TOTAL: 24:00:26.594	23:59:31.734	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	21763	0.000	0.003	0.031
article prepare	00:00:00.007	0.0%	42068	0.000	0.000	0.013
article read	00:00:01.133	0.0%	4718	0.000	0.240	21.350
backlog stats	00:00:00.154	0.0%	318129	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.364	0.0%	238573	0.001	0.006	0.011
data write	00:00:01.722	0.0%	223663	0.003	0.008	0.014
idle	23:53:49.290	100.0%	318129	101.047	270.423	887.907
status file	00:00:00.158	0.0%	296	0.000	0.534	1.000
TOTAL: 23:54:01.464	23:53:53.893	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.006	0.0%	448	0.000	0.013	0.049
idle	32:31:24.440	99.9%	32259	46.571	3629.512	600254.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.926	0.0%	65869	0.000	0.014	4.350
readart	00:00:02.708	0.0%	65860	0.000	0.041	4.000
TOTAL: 32:33:09.493	32:31:28.080	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	usenet.blueworldhosting.com	288	35733	5853	19496	10384	16%	23:44:55
2	csiph-out.feeder.erje.net	1156	155172	4521	144884	5767	2%	94:45:56
3	feeder.eternal-september.org	1	32367	3738	28196	433	11%	23:00:54
4	news-out.mixmin.net	3	22094	1701	19835	558	7%	24:08:51
5	newsfeed.neva.ru	149	66532	1361	62656	2515	2%	71:45:22
6	newsfeed.fu-berlin.de	24	21410	1084	20243	83	5%	28:06:33
7	au2pb.net	1	19296	811	18404	81	4%	24:12:50
8	news.fcku.it	1	5947	620	5327	0	10%	27:36:36
9	newsfeeds.xmission.com	1	22632	594	21994	44	2%	30:39:13
10	news.freedyn.net	53	14188	190	13971	27	1%	23:34:06
11	xanadu-bbs.net	52	49214	130	35397	13687	0%	69:10:32
12	nntp.pasdenom.info	2	17881	78	17786	17	0%	23:59:31
13	news.swapon.de	12	1741	54	1687	0	3%	23:18:36
14	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 14		1769	464207	20735	409876	33596	4%	488:03:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	16.9 MB	74.0 KB	116.9 MB	133.9 MB	12%	8.4 KB
2	csiph-out.feeder.erje.net	15.8 MB	974.7 KB	36.0 MB	52.8 MB	29%	5.3 KB
3	feeder.eternal-september.org	9.6 MB	2.3 KB	9.6 MB	19.2 MB	50%	4.7 KB
4	news-out.mixmin.net	4.9 MB	1.8 KB	1.3 MB	6.2 MB	79%	2.8 KB
5	newsfeed.neva.ru	4.4 MB	37.6 KB	27.1 MB	31.6 MB	14%	8.3 KB
6	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	174.7 KB	2.6 MB	93%	2.3 KB
7	newsfeeds.xmission.com	2.0 MB	0.0 KB	713.3 KB	2.7 MB	74%	4.3 KB
8	au2pb.net	1.7 MB	0.0 KB	529.5 KB	2.2 MB	76%	2.6 KB
9	news.fcku.it	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	1.8 KB
10	news.freedyn.net	444.8 KB	0.0 KB	47.2 KB	492.0 KB	90%	2.3 KB
11	xanadu-bbs.net	442.2 KB	12.6 KB	4.8 GB	4.8 GB	0%	366.2 KB
12	nntp.pasdenom.info	172.2 KB	0.0 KB	279.1 KB	451.2 KB	38%	4.7 KB
13	news.swapon.de	133.6 KB	0.0 KB	0.0 KB	133.6 KB	100%	2.5 KB
14	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 14		60.0 MB	1.1 MB	5.0 GB	5.1 GB	1%	97.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 26 04:15:00 - 04:59:59	267	1.3%	0.10	837.7 KB	1.4%	0.31
Dec 26 05:00:00 - 05:59:59	376	1.8%	0.10	1.4 MB	2.3%	0.39
Dec 26 06:00:00 - 06:59:59	409	2.0%	0.11	1.3 MB	2.3%	0.38
Dec 26 07:00:00 - 07:59:59	498	2.4%	0.14	1.5 MB	2.5%	0.42
Dec 26 08:00:00 - 08:59:59	697	3.4%	0.19	2.0 MB	3.4%	0.58
Dec 26 09:00:00 - 09:59:59	974	4.7%	0.27	3.6 MB	6.0%	1.02
Dec 26 10:00:00 - 10:59:59	986	4.8%	0.27	2.8 MB	4.7%	0.79
Dec 26 11:00:00 - 11:59:59	1031	5.0%	0.29	4.0 MB	6.8%	1.15
Dec 26 12:00:00 - 12:59:59	1232	6.0%	0.34	3.7 MB	6.2%	1.04
Dec 26 13:00:00 - 13:59:59	988	4.8%	0.27	2.9 MB	4.9%	0.83
Dec 26 14:00:00 - 14:59:59	981	4.8%	0.27	2.6 MB	4.4%	0.74
Dec 26 15:00:00 - 15:59:59	973	4.7%	0.27	2.7 MB	4.6%	0.78
Dec 26 16:00:00 - 16:59:59	1139	5.5%	0.32	3.5 MB	5.8%	0.98
Dec 26 17:00:00 - 17:59:59	1080	5.3%	0.30	2.9 MB	4.9%	0.82
Dec 26 18:00:00 - 18:59:59	1155	5.6%	0.32	3.2 MB	5.4%	0.90
Dec 26 19:00:00 - 19:59:59	1051	5.1%	0.29	3.0 MB	5.0%	0.85
Dec 26 20:00:00 - 20:59:59	1014	4.9%	0.28	2.8 MB	4.6%	0.78
Dec 26 21:00:00 - 21:59:59	962	4.7%	0.27	2.7 MB	4.5%	0.76
Dec 26 22:00:00 - 22:59:59	1010	4.9%	0.28	2.7 MB	4.6%	0.77
Dec 26 23:00:00 - 23:59:59	737	3.6%	0.20	2.1 MB	3.5%	0.59
Dec 27 00:00:00 - 00:59:59	1536	7.5%	0.43	2.8 MB	4.7%	0.79
Dec 27 01:00:00 - 01:59:59	532	2.6%	0.15	1.4 MB	2.4%	0.40
Dec 27 02:00:00 - 02:59:59	475	2.3%	0.13	1.5 MB	2.5%	0.41
Dec 27 03:00:00 - 03:59:59	362	1.8%	0.10	1.1 MB	1.9%	0.32
Dec 27 04:00:00 - 04:15:00	104	0.5%	0.12	403.4 KB	0.7%	0.45
TOTAL: 24:00:00	20569	100.0%	0.24	59.2 MB	100.0%	0.70

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	15629	11	1	15617
2	usenet.blueworldhosting.com	10362	788	6	9568
3	csiph-out.feeder.erje.net	5769	317	123	5329
4	newsfeed.neva.ru	2521	721	4	1796
5	news-out.mixmin.net	571	2	1	568
6	feeder.eternal-september.org	452	100	1	351
7	newsfeed.fu-berlin.de	436	40	0	396
8	au2pb.net	101	94	0	7
9	newsfeeds.xmission.com	32	24	0	8
10	news.freedyn.net	27	0	0	27
11	nntp.pasdenom.info	17	14	0	3
12	news.swapon.de	7	0	0	7
13	news.fcku.it	4	0	0	4
TOTAL: 13		35928	2111	136	33681

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	199
mailing.freebsd.pkg-fallout	192
fa.linux.kernel	151
mpc.lists.freebsd.ports.bugs	115
mailing.freebsd.ports-bugs	115
lada.talk	94
pld.cvs.commit	70
0	70
mailing.freebsd.svn-ports-all	50
mailing.freebsd.svn-ports-head	48
lada.auto	35
alt.survival	33
alt.biology	32
spline.eisfair	29
fa.freebsd.cvs-all	27
alt	27
mailing.freebsd.svn-src-all	27
mailing.openbsd.tech	26
fa.openbsd.tech	26
mpc.lists.nanog	25
TOTAL: 192	2111

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12334
EMP (phn path)	1899
EMP (phl)	833
PFM bot misc	686
Binary: misplaced rar	569
EMP (phn nph)	97
Binary: misplaced rev	90
Rogue cancel (newsgroups)	90
Too many newsgroups	40
HTML Multipart	21
Binary: misplaced par2	16
Binary Payload	9
HTML Image Tags	6
Binary: misplaced sfv	3
Binary: misplaced m3u	3
Binary: misplaced mp3	3
HTML post	3
User-issued spam cancel	2
UUencoded txt	2
Too many newsgroups (low_xpost)	1
TOTAL: 25	16712

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	78
	usenet.blueworldhosting.com	2
	TOTAL: 2	80
Bad command received
	xanadu-bbs.net	343
	TOTAL: 1	343
Huge articles
	xanadu-bbs.net	15531
	usenet.blueworldhosting.com	220
	feeder.eternal-september.org	59
	newsfeeds.xmission.com	6
	news-out.mixmin.net	6
	nntp.pasdenom.info	3
	au2pb.net	2
	newsfeed.fu-berlin.de	1
	TOTAL: 8	15828
Including strange strings
	newsfeed.fu-berlin.de	355
	feeder.eternal-september.org	24
	news-out.mixmin.net	13
	news.swapon.de	7
	au2pb.net	5
	newsfeed.neva.ru	4
	news.fcku.it	4
	usenet.blueworldhosting.com	3
	csiph-out.feeder.erje.net	3
	TOTAL: 9	418
No colon-space in header
	csiph-out.feeder.erje.net	526
	usenet.blueworldhosting.com	101
	TOTAL: 2	627
TOTAL: 5		17296

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	20873	3259	16523	598	0	1205	15%	23:59:54
2	xanadu-bbs	25507	1730	18419	343	2	20	6%	23:59:51
3	fu-berlin.de	19247	1581	17631	29	0	0	8%	23:59:44
4	usenet.pasdenom.info	24900	143	18109	2233	0	3	0%	23:59:54
5	au2pb	18371	66	17915	60	0	0	0%	23:59:47
6	neva.ru	20106	28	17223	175	0	0	0%	23:59:51
7	xmission	22512	16	18353	5	0	0	0%	23:59:02
8	news.fcku.it	1099	10	972	0	0	0	0%	22:07:46
9	news.mixmin.net	19528	8	17179	0	0	0	0%	23:59:54
10	eternal-september	17491	5	14815	1	0	0	0%	23:59:54
11	news.swapon.de	1783	2	1715	4	0	0	0%	23:27:51
12	erje	13963	1	13028	227	0	4	0%	23:59:54
13	bwh	13206	0	12922	0	0	0	0%	23:59:52
TOTAL: 13		218586	6849	184804	3675	2	1232	3%	309:33:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	12.8 MB	2.7 MB	15.5 MB	0.2 KB/s	4.1 KB	23:59:54
2	xanadu-bbs	6.8 MB	819.5 KB	7.6 MB	0.1 KB/s	3.8 KB	23:59:51
3	fu-berlin.de	6.1 MB	112.5 KB	6.2 MB	0.1 KB/s	3.9 KB	23:59:44
4	usenet.pasdenom.info	549.2 KB	7.9 MB	8.5 MB	0.1 KB/s	3.6 KB	23:59:54
5	au2pb	304.1 KB	166.9 KB	471.0 KB	0.0 KB/s	3.7 KB	23:59:47
6	neva.ru	170.2 KB	1.1 MB	1.2 MB	0.0 KB/s	6.3 KB	23:59:51
7	xmission	69.7 KB	14.4 KB	84.1 KB	0.0 KB/s	4.0 KB	23:59:02
8	eternal-september	55.4 KB	1.7 KB	57.2 KB	0.0 KB/s	9.5 KB	23:59:54
9	news.mixmin.net	35.6 KB	0.0 KB	35.6 KB	0.0 KB/s	4.5 KB	23:59:54
10	news.fcku.it	31.8 KB	0.0 KB	31.8 KB	0.0 KB/s	3.2 KB	22:07:46
11	news.swapon.de	7.1 KB	17.2 KB	24.3 KB	0.0 KB/s	4.1 KB	23:27:51
12	erje	4.1 KB	1.5 MB	1.5 MB	0.0 KB/s	6.8 KB	23:59:54
13	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 13		26.9 MB	14.3 MB	41.2 MB	0.0 KB/s	4.0 KB	309:33:14

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	287	66	167.6 KB	64	05:03:01
2	99-180-119-17.lightspeed.rcsntx.sbcglobal.net	68	12	25.0 KB	4	10:50:33
3	c-73-195-247-24.hsd1.nj.comcast.net	1	2	4.0 KB	1	00:31:52
4	challenger.nielsenmedia.com	2	2	4.0 KB	1	00:30:13
TOTAL: 4		358	82	200.7 KB	70	32:33:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	297	66	167.6 KB	64	05:05:04
2	*.lightspeed.rcsntx.sbcglobal.net	68	12	25.0 KB	4	10:50:33
3	*.nielsenmedia.com	2	2	4.0 KB	1	00:30:13
4	*.hsd1.nj.comcast.net	1	2	4.0 KB	1	00:31:52
5	*.chello.nl	34	0	0.0 KB	0	13:35:20
6	*.bbox.io	4	0	0.0 KB	0	02:00:01
TOTAL: 6		406	82	200.7 KB	70	32:33:05

NNRP auth users [Top 20]:

User	Conn
moussa	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.779	0.566	0.000	13:35:20
99-180-119-17.lightspeed.rcsntx.sbcglobal.net	5.675	1.152	0.000	10:50:33
202.89.176.220	27.743	6.147	0.729	05:03:01
tempe0.bbox.io	0.509	0.031	0.000	02:00:01
c-73-195-247-24.hsd1.nj.comcast.net	0.171	0.015	0.000	00:31:52
challenger.nielsenmedia.com	0.186	0.024	0.000	00:30:13
162.212.88.221	0.567	0.172	0.000	00:01:57
71.94.21.184	1.777	1.532	0.000	00:00:04
TOTAL: 8	39.407	9.639	0.729	32:33:05

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	34
162.212.88.221	8
tempe0.bbox.io	4
71.94.21.184	2
TOTAL: 4	48

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	24	0
tempe0.bbox.io	4	0
challenger.nielsenmedia.com	1	0
202.89.176.220	1	0
c-73-195-247-24.hsd1.nj.comcast.net	1	0
TOTAL: 5	31	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	81	98.8%
2	news	1	1.2%
TOTAL: 2		82	100.0%

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

Newsgroup		Count
1	comp.arch	24
2	comp.os.vms	11
3	comp.unix.solaris	10
4	comp.sys.ibm.pc.games.action	10
5	comp.arch.embedded	6
6	comp.ai.philosophy	5
7	comp.lang.awk	4
8	comp.arch.fpga	3
9	comp.sys.ibm.ps2.hardware	2
10	comp.os.os2.announce	2
11	comp.sys.ibm.pc.hardware.chips	1
12	comp.sys.ibm.as400.misc	1
13	comp.sys.ibm.pc.classic	1
14	comp.sys.ibm.pc.hardware.storage	1
15	news.admin.peering	1
TOTAL: 15		82

Daily Usenet report for csiph.com

Dec 26 04:15:00 -- Dec 27 04:15:00