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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	31819	40.2%	3.4 MB	27.9%
inn	30467	38.5%	6.5 MB	54.2%
innd	13627	17.2%	1.6 MB	13.5%
innfeed	3265	4.1%	538.9 KB	4.4%
nocem	51	0.1%	5.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	79230	100.0%	12.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	337047	55.1%
Negative hits	244777	40.0%
Do not exist	29555	4.8%
Cache misses	386	0.1%
TOTAL: 4	611765	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.702	0.0%	200	0.000	8.510	106.429
article cleanup	00:00:00.190	0.0%	29265	0.000	0.006	0.037
article logging	00:00:00.493	0.0%	29935	0.000	0.016	0.059
article parse	00:00:01.318	0.0%	80140	0.000	0.016	0.045
article write	00:01:12.817	0.1%	21076	0.267	3.455	12.443
artlog/artcncl	00:00:00.002	0.0%	174	0.000	0.011	0.250
artlog/artparse	00:00:00.005	0.0%	372	0.000	0.013	1.000
data move	00:00:00.180	0.0%	591661	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.024	0.0%	181	0.000	0.133	1.667
hishave/artcncl	00:00:00.011	0.0%	200	0.000	0.055	1.000
hishave/artparse	00:00:00.000	0.0%	365	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.692	0.0%	613043	0.006	0.014	0.079
history sync	00:00:01.279	0.0%	367	0.000	3.485	8.000
history write	00:00:22.848	0.0%	29257	0.240	0.781	3.376
hiswrite/artcncl	00:00:00.023	0.0%	19	0.000	1.211	1.278
hiswrite/artparse	00:00:00.314	0.0%	365	0.000	0.860	9.000
idle	23:55:25.930	99.6%	598695	76.568	143.856	395.322
nntp read	00:00:03.009	0.0%	602397	0.002	0.005	0.010
overview write	00:01:51.903	0.1%	21076	0.319	5.309	692.033
perl filter	00:00:53.565	0.1%	29068	1.156	1.843	3.075
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.367	0.0%	42196	0.000	0.009	0.023
TOTAL: 24:00:58.151	24:00:04.672	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.066	0.0%	21059	0.000	0.003	0.021
article prepare	00:00:00.005	0.0%	20732	0.000	0.000	0.010
article read	00:00:03.607	0.0%	2833	0.000	1.273	20.714
backlog stats	00:00:00.232	0.0%	387896	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.115	0.0%	301062	0.002	0.007	0.012
data write	00:00:01.759	0.0%	282935	0.002	0.006	0.013
idle	23:53:34.460	100.0%	387896	127.846	221.746	612.052
status file	00:00:00.509	0.0%	307	0.000	1.658	53.000
TOTAL: 23:53:51.164	23:53:42.753	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	17:57:11.562	99.7%	34834	70.655	1855.416	601613.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.261	0.0%	115901	0.000	0.037	0.167
readart	00:00:39.303	0.1%	3849	0.000	10.211	86.000
TOTAL: 17:59:53.573	17:57:55.126	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	93150	5531	84409	3210	5%	94:57:36
2	csiph-out.news.weretis.net	5	43771	5484	37735	552	12%	48:15:24
3	feeder.eternal-september.org	11	24584	2855	20531	1198	11%	23:59:58
4	news-out.aioe.org	3	21396	1889	19438	69	8%	24:08:59
5	newsfeed.fu-berlin.de	14	20802	1230	19246	326	5%	25:40:47
6	newsfeed.neva.ru	141	27207	1088	23766	2353	3%	72:07:51
7	news-out.mixmin.net	4	22350	851	21091	408	3%	24:11:31
8	news.redatomik.org	1	22339	829	21502	8	3%	26:55:33
9	news.fcku.it	2	8622	541	7863	218	6%	22:45:28
10	newsfeeds.xmission.com	3	15795	313	15481	1	1%	19:10:02
11	news.freedyn.net	3	22404	227	22070	107	1%	23:57:46
12	news.swapon.de	5	1844	58	1785	1	3%	24:30:21
13	nntp.pasdenom.info	3	19539	31	19499	9	0%	23:58:51
14	xanadu-bbs.net	13	761	9	747	5	1%	23:41:46
15	news.bbs.geek.nz	1	18330	3	18327	0	0%	23:59:46
16	localhost	33	0	0	0	0	0%	00:00:06
TOTAL: 16		1394	362894	20939	333490	8465	5%	502:21:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.2 MB	68.8 KB	14.4 MB	36.7 MB	60%	4.3 KB
2	csiph-out.news.weretis.net	15.6 MB	0.0 KB	13.1 MB	28.7 MB	54%	4.9 KB
3	feeder.eternal-september.org	7.3 MB	0.0 KB	4.4 MB	11.7 MB	62%	3.0 KB
4	news-out.aioe.org	4.4 MB	0.0 KB	121.1 KB	4.5 MB	97%	2.4 KB
5	news-out.mixmin.net	3.0 MB	0.0 KB	1.1 MB	4.2 MB	72%	3.4 KB
6	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	731.3 KB	3.5 MB	79%	2.3 KB
7	newsfeed.neva.ru	2.7 MB	0.0 KB	22.5 MB	25.1 MB	10%	7.5 KB
8	news.redatomik.org	2.2 MB	0.0 KB	41.4 KB	2.2 MB	98%	2.7 KB
9	news.fcku.it	1.3 MB	0.0 KB	964.3 KB	2.2 MB	58%	3.0 KB
10	newsfeeds.xmission.com	1.0 MB	0.0 KB	15.8 KB	1.0 MB	98%	3.3 KB
11	news.freedyn.net	786.1 KB	3.1 KB	502.9 KB	1.3 MB	60%	3.9 KB
12	news.swapon.de	129.0 KB	0.0 KB	6.5 KB	135.5 KB	95%	2.3 KB
13	nntp.pasdenom.info	75.6 KB	0.0 KB	15.8 KB	91.4 KB	82%	2.3 KB
14	xanadu-bbs.net	23.9 KB	0.0 KB	71.0 KB	94.9 KB	25%	6.8 KB
15	news.bbs.geek.nz	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.2 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		63.5 MB	71.9 KB	58.0 MB	121.5 MB	52%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:00 - 04:59:59	312	1.5%	0.12	846.8 KB	1.3%	0.31
Dec 18 05:00:00 - 05:59:59	560	2.7%	0.16	2.8 MB	4.4%	0.80
Dec 18 06:00:00 - 06:59:59	456	2.2%	0.13	1.5 MB	2.4%	0.43
Dec 18 07:00:00 - 07:59:59	709	3.4%	0.20	2.8 MB	4.4%	0.80
Dec 18 08:00:00 - 08:59:59	819	3.9%	0.23	2.6 MB	4.1%	0.74
Dec 18 09:00:00 - 09:59:59	1005	4.8%	0.28	2.5 MB	3.9%	0.70
Dec 18 10:00:00 - 10:59:59	913	4.3%	0.25	2.2 MB	3.5%	0.64
Dec 18 11:00:00 - 11:59:59	993	4.7%	0.28	2.6 MB	4.0%	0.73
Dec 18 12:00:00 - 12:59:59	902	4.3%	0.25	2.3 MB	3.5%	0.64
Dec 18 13:00:00 - 13:59:59	898	4.3%	0.25	2.2 MB	3.5%	0.64
Dec 18 14:00:00 - 14:59:59	1009	4.8%	0.28	2.6 MB	4.0%	0.73
Dec 18 15:00:00 - 15:59:59	1328	6.3%	0.37	5.1 MB	8.0%	1.46
Dec 18 16:00:00 - 16:59:59	1241	5.9%	0.34	3.6 MB	5.6%	1.01
Dec 18 17:00:00 - 17:59:59	1309	6.2%	0.36	4.0 MB	6.3%	1.15
Dec 18 18:00:00 - 18:59:59	1379	6.5%	0.38	4.3 MB	6.7%	1.21
Dec 18 19:00:00 - 19:59:59	1171	5.6%	0.33	3.6 MB	5.7%	1.03
Dec 18 20:00:00 - 20:59:59	1131	5.4%	0.31	3.3 MB	5.2%	0.94
Dec 18 21:00:00 - 21:59:59	1043	5.0%	0.29	3.1 MB	4.9%	0.88
Dec 18 22:00:00 - 22:59:59	983	4.7%	0.27	2.7 MB	4.3%	0.78
Dec 18 23:00:00 - 23:59:59	781	3.7%	0.22	2.6 MB	4.0%	0.73
Dec 19 00:00:00 - 00:59:59	569	2.7%	0.16	1.7 MB	2.7%	0.49
Dec 19 01:00:00 - 01:59:59	603	2.9%	0.17	1.7 MB	2.7%	0.49
Dec 19 02:00:00 - 02:59:59	406	1.9%	0.11	1.3 MB	2.0%	0.37
Dec 19 03:00:00 - 03:59:59	448	2.1%	0.12	1.6 MB	2.5%	0.46
Dec 19 04:00:00 - 04:15:00	98	0.5%	0.11	320.2 KB	0.5%	0.36
TOTAL: 24:00:00	21066	100.0%	0.24	63.9 MB	100.0%	0.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	3258	345	14	1	2898
2	newsfeed.neva.ru	2364	212	0	0	2152
3	feeder.eternal-september.org	1218	45	0	0	1173
4	newsfeed.fu-berlin.de	886	27	0	0	859
5	csiph-out.news.weretis.net	579	203	0	0	376
6	news-out.mixmin.net	429	0	0	0	429
7	news.fcku.it	219	0	0	0	219
8	news.freedyn.net	134	6	1	0	127
9	news-out.aioe.org	81	13	0	0	68
10	news.redatomik.org	26	0	0	0	26
11	news.swapon.de	18	0	0	0	18
12	nntp.pasdenom.info	9	8	0	0	1
13	xanadu-bbs.net	5	0	0	0	5
14	newsfeeds.xmission.com	1	1	0	0	0
TOTAL: 14		9227	860	15	1	8351

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	87
lada.talk	73
lada.auto	61
it.annunci.commerciali	39
it.annunci.varie	37
it.annunci.immobiliari	37
alt.survival	35
mpc.lists.freebsd.ports.bugs	30
mpc.lists.nanog	28
gmane.linux.alsa.devel	25
mozilla.dev.security.policy	21
mozilla.support.seamonkey	19
it-alt.sport.calcio.juventus	17
mozilla.general	17
alt.politics.usa	14
alt.windows7.general	14
gmane.linux.usb.general	12
alt.pl.nieruchomosci.gielda	12
alt.religion	12
mozilla.support.firefox	11
TOTAL: 119	860

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)	5549
Binary: misplaced rar	546
EMP (phn path)	507
EMP (phn nph)	308
Scoring filter (10)	43
HTML Multipart	33
Too many newsgroups	23
User-issued cancel	21
Binary Payload	17
Scoring filter (8)	10
Scoring filter (11)	10
Binary: misplaced par2	9
Too many newsgroups (meow)	7
Poison newsgroup	7
Scoring filter (12)	7
Too many newsgroups (low_xpost)	7
HTML post	7
Scoring filter (9)	5
User-issued spam cancel	4
Binary: misplaced 002	2
TOTAL: 25	7127

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	44	0	51	51
TOTAL: 1	44	0	51	51

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	166
	TOTAL: 1	166
Huge articles
	csiph-out.feeder.erje.net	176
	csiph-out.news.weretis.net	144
	feeder.eternal-september.org	24
	news.fcku.it	9
	news-out.mixmin.net	6
	news.freedyn.net	5
	newsfeed.fu-berlin.de	1
	TOTAL: 7	365
Including strange strings
	newsfeed.fu-berlin.de	532
	csiph-out.feeder.erje.net	53
	csiph-out.news.weretis.net	25
	news.redatomik.org	18
	news.swapon.de	17
	news-out.aioe.org	11
	news-out.mixmin.net	8
	newsfeed.neva.ru	3
	feeder.eternal-september.org	2
	news.fcku.it	1
	TOTAL: 10	670
No colon-space in header
	csiph-out.feeder.erje.net	7
	TOTAL: 1	7
TOTAL: 4		1208

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	38433	1411	18960	46	0	3%	23:59:55
2	news.freedyn.net	21252	764	19984	3	0	3%	23:59:54
3	xanadu-bbs	23486	682	20374	0	0	2%	23:59:56
4	fu-berlin.de	19575	606	18969	0	0	3%	23:59:56
5	usenet.pasdenom.info	22080	110	19630	1294	21	0%	23:59:56
6	aioe	18308	51	17713	12	0	0%	23:59:55
7	news.redatomik.org	20460	15	19688	14	0	0%	23:59:39
8	erje	15899	14	15382	5	2	0%	23:59:43
9	xmission	22066	6	19933	1	0	0%	23:59:50
10	neva.ru	19448	4	18975	141	0	0%	23:59:56
11	news.swapon.de	1903	4	1887	0	0	0%	23:55:07
12	weretis.net	15169	4	14247	0	0	0%	23:59:29
13	news.mixmin.net	19664	2	19237	0	0	0%	23:59:53
14	eternal-september	18584	1	17337	0	0	0%	23:59:56
15	bwh	0	0	0	0	20409	0%	23:58:42
16	news.fcku.it	2083	0	2076	0	0	0%	21:14:51
17	au2pb	0	0	0	0	20418	0%	23:58:42
TOTAL: 17		278410	3674	244392	1516	40850	1%	405:05:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.9 MB	41.1 KB	9.0 MB	0.1 KB/s	12.0 KB	23:59:54
2	xanadu-bbs	8.6 MB	0.0 KB	8.6 MB	0.1 KB/s	12.9 KB	23:59:56
3	fu-berlin.de	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	14.2 KB	23:59:56
4	news.bbs.geek.nz	4.0 MB	139.6 KB	4.1 MB	0.0 KB/s	2.9 KB	23:59:55
5	usenet.pasdenom.info	460.6 KB	9.5 MB	9.9 MB	0.1 KB/s	7.3 KB	23:59:56
6	aioe	149.8 KB	33.0 KB	182.8 KB	0.0 KB/s	2.9 KB	23:59:55
7	erje	46.1 KB	11.1 KB	57.2 KB	0.0 KB/s	3.0 KB	23:59:43
8	news.redatomik.org	44.2 KB	39.3 KB	83.5 KB	0.0 KB/s	2.9 KB	23:59:39
9	xmission	15.8 KB	2.5 KB	18.3 KB	0.0 KB/s	2.6 KB	23:59:50
10	neva.ru	11.5 KB	442.7 KB	454.3 KB	0.0 KB/s	3.1 KB	23:59:56
11	weretis.net	9.3 KB	0.0 KB	9.3 KB	0.0 KB/s	2.3 KB	23:59:29
12	news.swapon.de	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	2.0 KB	23:55:07
13	news.mixmin.net	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	1.3 KB	23:59:53
14	eternal-september	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:59:56
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:14:51
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		30.7 MB	10.2 MB	40.8 MB	0.0 KB/s	8.1 KB	405:05:20

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	141.168.117.108	46	109	406.3 KB	64	00:39:00
2	mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	1201	35	102.3 KB	32	01:24:24
3	tempe0.bbox.io	13	17	30.7 KB	4	04:23:51
4	194-73-239-109.cust.centrio.cz	3	3	9.6 KB	2	01:09:36
TOTAL: 4		1263	164	549.0 KB	102	17:59:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	46	109	406.3 KB	64	00:39:00
2	*.dsl.bell.ca	1201	35	102.3 KB	32	01:24:24
3	*.bbox.io	13	17	30.7 KB	4	04:23:51
4	*.cust.centrio.cz	3	3	9.6 KB	2	01:09:36
5	*.dhcp.mdsn.wi.charter.com	8	0	0.0 KB	0	03:08:27
6	*.nielsenmedia.com	5	0	0.0 KB	0	02:12:05
7	*.upc-a.chello.nl	31	0	0.0 KB	0	05:02:13
8	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	00:00:03
TOTAL: 8		1308	164	549.0 KB	102	17:59:43

NNRP auth users [Top 20]:

User	Conn
moussa	46
ctomczyk	3
TOTAL: 2	49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.290	0.466	0.000	05:02:13
tempe0.bbox.io	1.202	0.181	0.000	04:23:51
96-42-18-226.dhcp.mdsn.wi.charter.com	0.576	0.167	0.000	03:08:27
challenger.nielsenmedia.com	0.366	0.023	0.000	02:12:05
mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	98.606	14.391	0.000	01:24:24
194-73-239-109.cust.centrio.cz	0.322	0.064	0.011	01:09:36
141.168.117.108	5.357	1.175	0.286	00:39:00
p4ff8eaac.dip0.t-ipconnect.de	0.094	0.017	0.000	00:00:03
TOTAL: 8	108.813	16.484	0.297	17:59:43

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	31
96-42-18-226.dhcp.mdsn.wi.charter.com	8
challenger.nielsenmedia.com	5
p4ff8eaac.dip0.t-ipconnect.de	1
TOTAL: 4	45

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
96-42-18-226.dhcp.mdsn.wi.charter.com	6	0
challenger.nielsenmedia.com	4	0
194-73-239-109.cust.centrio.cz	2	0
TOTAL: 4	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	94	57.3%
2	alt	53	32.3%
3	uk	9	5.5%
4	rec	3	1.8%
5	soc	1	0.6%
6	qc	1	0.6%
7	sci	1	0.6%
8	talk	1	0.6%
9	fr	1	0.6%
TOTAL: 9		164	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	40
2	comp.ai.philosophy	30
3	comp.arch	13
4	comp.sys.m68k	11
5	comp.sys.ibm.pc.games.action	11
6	comp.os.vms	9
7	comp.sys.sun.misc	4
8	comp.os.msdos.misc	4
9	alt.home.repair	4
10	uk.rec.driving	3
11	comp.lang.python	3
12	comp.lang.javascript	3
13	comp.arch.fpga	2
14	alt.fan.rush-limbaugh	2
15	uk.tech.broadcast	2
16	alt.usage.english	2
17	uk.legal	2
18	rec.drugs.cannabis	2
19	sci.math	1
20	alt.comp.os.windows-10	1
21	comp.windows.x	1
22	alt.christnet.theology	1
23	rec.arts.tv.uk.coronation-st	1
24	talk.politics.misc	1
25	uk.tech.digital-tv	1
26	fr.soc.politique	1
27	qc.politique	1
28	alt.atheism	1
29	uk.media.tv.misc	1
30	comp.sys.ibm.pc.hardware.storage	1
31	comp.lang.lisp	1
32	alt.slack	1
33	comp.sys.sgi.marketplace	1
34	soc.culture.usa	1
35	alt.bible.prophecy	1
TOTAL: 35		164

Daily Usenet report for csiph.com

Dec 18 04:15:00 -- Dec 19 04:15:00