Daily Usenet report for csiph.com: Jan 13 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53965	46.5%	10.4 MB	63.1%
nnrpd	33798	29.1%	2.9 MB	17.8%
innd	25038	21.6%	2.6 MB	16.0%
innfeed	3198	2.8%	513.2 KB	3.1%
nocem	101	0.1%	10.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	116101	100.0%	16.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	689178	57.3%
Negative hits	458881	38.2%
Do not exist	52756	4.4%
Cache misses	977	0.1%
TOTAL: 4	1201792	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.348	0.0%	265	0.000	1.313	22.000
article cleanup	00:00:00.314	0.0%	50848	0.000	0.006	0.016
article logging	00:00:00.756	0.0%	51527	0.000	0.015	0.069
article parse	00:00:37.488	0.0%	256627	0.003	0.146	0.772
article write	00:00:39.492	0.0%	30223	0.241	1.307	5.736
artlog/artcncl	00:00:00.003	0.0%	214	0.000	0.014	0.167
artlog/artparse	00:00:00.024	0.0%	2249	0.000	0.011	0.500
data move	00:00:00.383	0.0%	1182010	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.108	0.0%	264	0.000	0.409	12.000
hishave/artcncl	00:00:00.008	0.0%	265	0.000	0.030	1.400
hishave/artparse	00:00:00.006	0.0%	1285	0.000	0.005	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:53.353	0.1%	1204867	0.001	0.044	0.337
history sync	00:00:02.297	0.0%	293	0.000	7.840	43.500
history write	00:02:15.742	0.2%	51711	0.104	2.625	14.104
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:04.597	0.0%	1283	0.000	3.583	23.500
idle	23:50:07.633	99.3%	1197878	38.287	71.633	194.027
nntp read	00:00:03.741	0.0%	1211124	0.002	0.003	0.005
overview write	00:02:02.927	0.1%	30223	0.140	4.067	290.673
perl filter	00:02:09.730	0.2%	50691	1.331	2.559	4.382
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.534	0.0%	60541	0.000	0.009	0.020
TOTAL: 24:00:09.558	23:58:59.484	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.089	0.0%	30364	0.000	0.003	0.029
article prepare	00:00:00.003	0.0%	33772	0.000	0.000	0.013
article read	00:00:00.503	0.0%	3378	0.000	0.149	4.182
backlog stats	00:00:00.321	0.0%	712906	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.637	0.0%	519477	0.002	0.005	0.008
data write	00:00:03.422	0.0%	499511	0.003	0.007	0.012
idle	23:53:14.651	100.0%	712906	60.763	120.626	327.643
status file	00:00:00.203	0.0%	296	0.000	0.686	1.000
TOTAL: 23:53:35.323	23:53:21.829	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:10.391	0.0%	27031	0.007	0.384	1.157
idle	77:31:16.757	99.3%	69839	301.000	3996.002	600199.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:07.290	0.0%	163118	0.000	0.045	0.500
readart	00:03:23.074	0.1%	60945	0.000	3.332	24.667
TOTAL: 78:02:48.630	77:34:57.512	99.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	146
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	166

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	36848	6634	25969	4245	18%	23:45:02
2	csiph-out.feeder.erje.net	1152	184823	4566	175383	4874	2%	95:08:00
3	news-out.mixmin.net	2	34215	4243	28419	1553	12%	24:08:55
4	feeder.eternal-september.org	4	43972	3856	39506	610	8%	23:31:29
5	news-out.aioe.org	5	31610	2670	28861	79	8%	24:12:38
6	newsfeed.neva.ru	146	63877	2335	56304	5238	3%	71:24:31
7	csiph-out.news.weretis.net	4	69248	1810	67018	420	2%	50:30:09
8	newsfeed.fu-berlin.de	60	31554	1413	29840	301	4%	31:58:48
9	newsfeeds.xmission.com	1	30930	713	30181	36	2%	24:30:27
10	news.redatomik.org	5	34391	658	33714	19	1%	27:53:44
11	news.fcku.it	1	9671	489	9180	2	5%	25:17:29
12	xanadu-bbs.net	1	47757	422	42121	5214	0%	22:58:54
13	au2pb.net	3	61342	198	61067	77	0%	44:01:15
14	news.freedyn.net	6	35665	185	35440	40	0%	24:09:44
15	news.swapon.de	2	2799	142	2650	7	5%	25:10:34
16	nntp.pasdenom.info	3	30329	37	30268	24	0%	24:09:32
17	news.bbs.geek.nz	1	29625	4	29621	0	0%	23:59:48
18	localhost	35	2	2	0	0	100%	00:00:00
TOTAL: 18		1719	778658	30377	725542	22739	3%	586:50:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.6 MB	631.4 KB	20.8 MB	41.0 MB	47%	4.4 KB
2	usenet.blueworldhosting.com	18.6 MB	0.0 KB	152.8 MB	171.4 MB	10%	16.1 KB
3	news-out.mixmin.net	13.3 MB	5.4 KB	2.8 MB	16.1 MB	82%	2.8 KB
4	feeder.eternal-september.org	9.8 MB	7.5 KB	10.9 MB	20.7 MB	47%	4.7 KB
5	newsfeed.neva.ru	6.8 MB	260.0 KB	34.9 MB	41.9 MB	16%	5.7 KB
6	news-out.aioe.org	6.2 MB	0.0 KB	260.1 KB	6.5 MB	96%	2.4 KB
7	csiph-out.news.weretis.net	5.5 MB	0.0 KB	11.7 MB	17.2 MB	31%	7.9 KB
8	newsfeeds.xmission.com	3.2 MB	0.0 KB	337.1 KB	3.5 MB	90%	4.8 KB
9	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	687.0 KB	3.7 MB	81%	2.2 KB
10	news.redatomik.org	1.6 MB	0.0 KB	22.9 KB	1.6 MB	98%	2.4 KB
11	xanadu-bbs.net	1.1 MB	0.0 KB	51.8 MB	52.9 MB	2%	9.6 KB
12	news.fcku.it	869.4 KB	0.0 KB	3.7 KB	873.1 KB	99%	1.8 KB
13	news.freedyn.net	675.4 KB	0.0 KB	1.3 MB	2.0 MB	32%	9.1 KB
14	au2pb.net	603.7 KB	0.0 KB	474.5 KB	1.1 MB	55%	3.9 KB
15	news.swapon.de	337.8 KB	0.0 KB	13.8 KB	351.6 KB	96%	2.4 KB
16	nntp.pasdenom.info	80.8 KB	0.0 KB	230.1 KB	310.9 KB	25%	5.1 KB
17	localhost	7.1 KB	0.0 KB	0.0 KB	7.1 KB	100%	3.6 KB
18	news.bbs.geek.nz	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.6 KB
TOTAL: 18		91.1 MB	904.3 KB	289.0 MB	381.0 MB	23%	7.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 13 04:15:00 - 04:59:59	451	1.5%	0.17	1.5 MB	1.6%	0.55
Jan 13 05:00:00 - 05:59:59	630	2.1%	0.17	2.2 MB	2.4%	0.62
Jan 13 06:00:00 - 06:59:59	694	2.3%	0.19	2.5 MB	2.8%	0.72
Jan 13 07:00:00 - 07:59:59	878	2.9%	0.24	2.6 MB	2.9%	0.74
Jan 13 08:00:00 - 08:59:59	1178	3.9%	0.33	3.2 MB	3.6%	0.92
Jan 13 09:00:00 - 09:59:59	1550	5.1%	0.43	4.6 MB	5.1%	1.32
Jan 13 10:00:00 - 10:59:59	1564	5.2%	0.43	5.0 MB	5.5%	1.43
Jan 13 11:00:00 - 11:59:59	1323	4.4%	0.37	3.6 MB	3.9%	1.02
Jan 13 12:00:00 - 12:59:59	1424	4.7%	0.40	4.1 MB	4.5%	1.15
Jan 13 13:00:00 - 13:59:59	1503	5.0%	0.42	4.0 MB	4.5%	1.15
Jan 13 14:00:00 - 14:59:59	1501	5.0%	0.42	4.0 MB	4.4%	1.12
Jan 13 15:00:00 - 15:59:59	1571	5.2%	0.44	4.4 MB	4.8%	1.25
Jan 13 16:00:00 - 16:59:59	1774	5.9%	0.49	5.5 MB	6.1%	1.57
Jan 13 17:00:00 - 17:59:59	1780	5.9%	0.49	5.7 MB	6.3%	1.63
Jan 13 18:00:00 - 18:59:59	1796	5.9%	0.50	6.1 MB	6.8%	1.74
Jan 13 19:00:00 - 19:59:59	1664	5.5%	0.46	5.1 MB	5.6%	1.44
Jan 13 20:00:00 - 20:59:59	1642	5.4%	0.46	4.7 MB	5.2%	1.33
Jan 13 21:00:00 - 21:59:59	1700	5.6%	0.47	4.7 MB	5.1%	1.32
Jan 13 22:00:00 - 22:59:59	1508	5.0%	0.42	4.6 MB	5.1%	1.31
Jan 13 23:00:00 - 23:59:59	1069	3.5%	0.30	2.9 MB	3.2%	0.83
Jan 14 00:00:00 - 00:59:59	894	3.0%	0.25	2.8 MB	3.1%	0.81
Jan 14 01:00:00 - 01:59:59	818	2.7%	0.23	2.7 MB	2.9%	0.76
Jan 14 02:00:00 - 02:59:59	614	2.0%	0.17	1.9 MB	2.1%	0.54
Jan 14 03:00:00 - 03:59:59	567	1.9%	0.16	1.7 MB	1.9%	0.50
Jan 14 04:00:00 - 04:15:00	134	0.4%	0.15	514.7 KB	0.6%	0.57
TOTAL: 24:00:00	30227	100.0%	0.35	90.5 MB	100.0%	1.07

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	xanadu-bbs.net	5323	52	0	0	5271
2	newsfeed.neva.ru	5250	1546	27	1	3676
3	csiph-out.feeder.erje.net	4878	774	70	0	4034
4	usenet.blueworldhosting.com	4244	1306	0	0	2938
5	news-out.mixmin.net	1586	4	2	0	1580
6	newsfeed.fu-berlin.de	907	31	0	0	876
7	feeder.eternal-september.org	644	114	2	0	528
8	csiph-out.news.weretis.net	401	230	0	0	171
9	news-out.aioe.org	91	43	0	0	48
10	au2pb.net	45	37	0	0	8
11	news.freedyn.net	43	0	0	0	43
12	newsfeeds.xmission.com	36	34	0	0	2
13	news.swapon.de	34	0	0	0	34
14	nntp.pasdenom.info	24	13	0	0	11
15	news.redatomik.org	16	2	0	0	14
16	news.fcku.it	2	0	0	0	2
TOTAL: 16		23524	4186	101	1	19236

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	880
microsoft.public.windows.chromeffects	416
ut.test	194
lada.talk	180
mailing.freebsd.ports-bugs	158
lada.auto	131
mailing.freebsd.pkg-fallout	110
mailing.freebsd.svn-ports-all	95
mailing.freebsd.svn-ports-head	86
mailing.freebsd.svn-src-all	80
mailing.database.pgsql-general	78
fa.freebsd.cvs-all	76
alt.binaries.multimedia.tatu	69
mailing.openssl.cvs	63
mailing.freebsd.svn-src-head	61
alt.survival	59
mailing.gcc.gcc-cvs	48
mailing.openbsd.source-changes	47
mozilla.general	43
mailing.openbsd.tech	37
TOTAL: 268	4186

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11519
EMP (phn path)	2333
EMP (phl)	831
PFM bot misc	747
EMP (phn nph)	195
User-issued cancel	68
Scoring filter (26)	68
HTML Multipart	53
Scoring filter (19)	34
Scoring filter (18)	34
Too many newsgroups	30
Scoring filter (22)	26
Scoring filter (30)	24
Scoring filter (12)	23
Scoring filter (14)	22
Scoring filter (21)	22
Scoring filter (27)	21
Scoring filter (29)	19
Scoring filter (11)	18
Scoring filter (17)	16
TOTAL: 44	16255

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	94	0	98	98
TOTAL: 1	94	0	98	98

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	54
	usenet.blueworldhosting.com	2
	TOTAL: 2	56
Huge articles
	usenet.blueworldhosting.com	1023
	xanadu-bbs.net	105
	csiph-out.news.weretis.net	75
	feeder.eternal-september.org	59
	news-out.mixmin.net	8
	news.freedyn.net	8
	newsfeeds.xmission.com	2
	nntp.pasdenom.info	2
	au2pb.net	1
	TOTAL: 9	1283
Including strange strings
	newsfeed.fu-berlin.de	571
	news-out.mixmin.net	29
	news.swapon.de	27
	news-out.aioe.org	15
	csiph-out.feeder.erje.net	13
	feeder.eternal-september.org	12
	csiph-out.news.weretis.net	7
	newsfeed.neva.ru	2
	news.fcku.it	1
	xanadu-bbs.net	1
	TOTAL: 11	679
No colon-space in header
	csiph-out.feeder.erje.net	597
	TOTAL: 1	597
TOTAL: 4		2615

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	34040	1366	28680	5	13	4%	23:59:52
2	xanadu-bbs	32629	1327	28469	0	0	4%	23:59:51
3	fu-berlin.de	28302	1048	27254	0	0	3%	23:59:50
4	news.bbs.geek.nz	55004	746	28414	26	0	1%	23:59:52
5	aioe	28732	604	24189	33	0	2%	23:59:52
6	erje	30220	496	24957	65	37	1%	23:59:52
7	usenet.pasdenom.info	38250	424	28363	1404	8	1%	23:59:52
8	news.redatomik.org	35211	269	28281	14	39	0%	23:59:52
9	au2pb	31411	267	28796	33	78	0%	23:59:52
10	neva.ru	32527	26	26242	229	0	0%	23:59:47
11	xmission	32737	22	28427	1	0	0%	23:59:50
12	eternal-september	30911	17	24702	0	0	0%	23:59:52
13	weretis.net	26200	17	25413	1	0	0%	23:59:52
14	news.mixmin.net	29007	12	24536	0	0	0%	23:59:52
15	news.fcku.it	4169	5	3581	0	0	0%	21:56:08
16	bwh	23866	3	22450	0	0	0%	23:59:52
17	news.swapon.de	2833	1	2507	1	0	0%	23:54:59
TOTAL: 17		496049	6650	405261	1812	175	1%	405:48:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	10.9 MB	0.0 KB	10.9 MB	0.1 KB/s	8.4 KB	23:59:51
2	news.freedyn.net	10.7 MB	16.1 KB	10.7 MB	0.1 KB/s	8.0 KB	23:59:52
3	fu-berlin.de	9.4 MB	0.0 KB	9.4 MB	0.1 KB/s	9.2 KB	23:59:50
4	news.bbs.geek.nz	2.6 MB	101.4 KB	2.7 MB	0.0 KB/s	3.6 KB	23:59:52
5	usenet.pasdenom.info	2.0 MB	9.8 MB	11.8 MB	0.1 KB/s	6.6 KB	23:59:52
6	aioe	1.9 MB	370.5 KB	2.3 MB	0.0 KB/s	3.7 KB	23:59:52
7	erje	1.7 MB	501.8 KB	2.2 MB	0.0 KB/s	4.0 KB	23:59:52
8	au2pb	1.3 MB	490.1 KB	1.7 MB	0.0 KB/s	6.0 KB	23:59:52
9	news.redatomik.org	1.0 MB	193.6 KB	1.2 MB	0.0 KB/s	4.3 KB	23:59:52
10	neva.ru	93.4 KB	1.1 MB	1.2 MB	0.0 KB/s	4.7 KB	23:59:47
11	eternal-september	59.4 KB	0.0 KB	59.4 KB	0.0 KB/s	3.5 KB	23:59:52
12	xmission	48.2 KB	1.7 KB	49.9 KB	0.0 KB/s	2.2 KB	23:59:50
13	news.mixmin.net	38.0 KB	0.0 KB	38.0 KB	0.0 KB/s	3.2 KB	23:59:52
14	weretis.net	36.9 KB	26.5 KB	63.4 KB	0.0 KB/s	3.5 KB	23:59:52
15	bwh	9.9 KB	0.0 KB	9.9 KB	0.0 KB/s	3.3 KB	23:59:52
16	news.fcku.it	8.7 KB	0.0 KB	8.7 KB	0.0 KB/s	1.7 KB	21:56:08
17	news.swapon.de	1.4 KB	4.3 KB	5.7 KB	0.0 KB/s	2.8 KB	23:54:59
TOTAL: 17		41.8 MB	12.5 MB	54.3 MB	0.0 KB/s	6.6 KB	405:48:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	273	10664	29.4 MB	78	0	0	06:52:42
2	tempe0.bbox.io	77	16	33.7 KB	9	2	2	09:13:19
3	dhcp-089-099-203-254.chello.nl	23	1	0.9 KB	1	0	0	09:30:32
TOTAL: 3		373	10681	29.5 MB	88	2	2	78:02:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	640	10664	29.4 MB	78	0	0	59:18:47
2	*.bbox.io	77	16	33.7 KB	9	2	2	09:13:19
3	*.chello.nl	23	1	0.9 KB	1	0	0	09:30:32
4	*.138.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 4		741	10681	29.5 MB	88	2	2	78:02:38

NNRP auth users [Top 20]:

User	Conn
moussa	273
kev009	2
TOTAL: 2	275

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
63.142.120.8	21.162	7.266	0.000	31:36:42
162.212.88.221	1.317	0.853	0.000	18:46:58
dhcp-089-099-203-254.chello.nl	1.985	0.430	0.000	09:30:32
tempe0.bbox.io	6.467	1.241	0.006	09:13:19
202.89.176.220	28.242	8.922	0.765	06:52:42
138.108.60.60	0.313	0.080	0.000	02:00:01
196.52.17.13	1.672	4.164	0.000	00:02:22
TOTAL: 7	61.158	22.956	0.771	78:02:38

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	350
162.212.88.221	12
138.108.60.60	4
h39.138.22.98.dynamic.ip.windstream.net	1
196.52.17.13	1
TOTAL: 5	368

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	17	0
tempe0.bbox.io	12	0
138.108.60.60	4	0
TOTAL: 3	33	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	10678	100.0%
2	news	4	0.0%
TOTAL: 2		10682	100.0%

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

Newsgroup		Count
1	comp.os.cpm	5845
2	comp.os.msdos.djgpp	2700
3	comp.os.msdos.programmer	2016
4	comp.os.msdos.desqview	25
5	comp.os.vms	24
6	comp.arch	23
7	comp.sys.ibm.as400.misc	13
8	comp.sys.ibm.ps2.hardware	12
9	comp.arch.embedded	7
10	comp.os.msdos.programmer.turbovision	6
11	news.software.nntp	4
12	comp.sys.ibm.pc.games.action	4
13	comp.archives.ms-windows.discuss	2
14	comp.sys.ibm.pc.games.rpg	1
TOTAL: 14		10682

Daily Usenet report for csiph.com

Jan 13 04:15:00 -- Jan 14 04:15:00