Daily Usenet report for csiph.com: Feb 23 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42446	60.7%	8.0 MB	72.1%
innd	23553	33.7%	2.5 MB	22.7%
innfeed	3199	4.6%	513.2 KB	4.5%
nnrpd	546	0.8%	61.7 KB	0.5%
nocem	126	0.2%	13.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	69871	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	430947	57.6%
Negative hits	275573	36.8%
Do not exist	41467	5.5%
Cache misses	540	0.1%
TOTAL: 4	748527	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.874	0.0%	373	0.000	2.343	23.000
article cleanup	00:00:00.279	0.0%	41420	0.000	0.007	0.020
article logging	00:00:02.722	0.0%	41946	0.000	0.065	0.742
article parse	00:00:02.651	0.0%	139118	0.002	0.019	0.272
article write	00:01:59.777	0.1%	23349	0.216	5.130	28.493
artlog/artcncl	00:00:00.018	0.0%	317	0.000	0.057	0.333
artlog/artparse	00:00:00.055	0.0%	204	0.000	0.270	22.000
data move	00:00:00.254	0.0%	704776	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.200	0.0%	370	0.000	0.541	15.000
hishave/artcncl	00:00:00.067	0.0%	373	0.000	0.180	2.400
hishave/artparse	00:00:00.001	0.0%	201	0.000	0.005	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:26.778	0.1%	750034	0.003	0.116	0.609
history sync	00:00:04.389	0.0%	360	0.000	12.192	94.667
history write	00:02:56.883	0.2%	41383	0.206	4.274	28.271
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:01.041	0.0%	201	0.000	5.179	23.000
idle	23:47:28.056	99.1%	691774	75.104	123.809	358.335
nntp read	00:00:03.308	0.0%	715237	0.002	0.005	0.008
overview write	00:03:51.498	0.3%	23349	0.805	9.915	174.151
perl filter	00:01:45.311	0.1%	41380	1.349	2.545	3.843
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.432	0.0%	46817	0.000	0.009	0.027
TOTAL: 24:00:55.745	23:59:44.594	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.075	0.0%	23445	0.000	0.003	0.018
article prepare	00:00:00.010	0.0%	54252	0.000	0.000	0.004
article read	00:00:01.724	0.0%	10173	0.032	0.169	1.871
backlog stats	00:00:00.282	0.0%	446570	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.280	0.0%	340298	0.002	0.007	0.012
data write	00:00:01.989	0.0%	322513	0.002	0.006	0.011
idle	23:52:52.267	100.0%	446570	112.703	192.517	546.029
status file	00:00:00.230	0.0%	281	0.000	0.819	14.000
TOTAL: 23:53:12.653	23:52:58.857	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.668	0.0%	978	2.728	2.728	2.728
idle	21:28:54.575	100.0%	1353	214.000	57157.853	600119.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	2786	0.000	0.003	0.100
readart	00:00:00.266	0.0%	26	0.000	10.231	56.000
TOTAL: 21:29:27.989	21:28:57.518	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	133902	7682	115690	10530	5%	94:58:14
2	csiph-out.news.weretis.net	6	53861	5379	46902	1580	9%	50:21:45
3	feeder.eternal-september.org	2	37362	3396	30170	3796	9%	24:07:48
4	news-out.aioe.org	2	22075	1734	20310	31	7%	24:09:56
5	newsfeed.neva.ru	146	58603	1117	55107	2379	1%	72:09:35
6	newsfeed.fu-berlin.de	47	22685	1052	20859	774	4%	29:48:35
7	newsfeeds.xmission.com	3	24599	993	23600	6	4%	29:03:05
8	news.redatomik.org	1	23243	907	22333	3	3%	26:06:15
9	news-out.mixmin.net	2	25544	662	24764	118	2%	23:59:47
10	news.freedyn.net	3	26325	180	25970	175	0%	24:09:50
11	news.fcku.it	2	11047	153	10810	84	1%	23:59:33
12	nntp.pasdenom.info	4	22003	104	21892	7	0%	23:56:05
13	news.swapon.de	3	2883	62	2764	57	2%	25:45:34
14	xanadu-bbs.net	15	1031	29	991	11	2%	26:08:51
15	news.bbs.geek.nz	2	16490	26	16463	1	0%	23:59:27
16	localhost	40	6	6	0	0	100%	00:00:02
TOTAL: 16		1430	481659	23482	438625	19552	4%	522:44:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.3 MB	190.7 KB	67.3 MB	91.8 MB	26%	5.2 KB
2	csiph-out.news.weretis.net	17.1 MB	13.2 KB	19.6 MB	36.6 MB	46%	5.4 KB
3	feeder.eternal-september.org	9.2 MB	0.0 KB	52.6 MB	61.8 MB	14%	8.8 KB
4	news-out.aioe.org	4.1 MB	0.0 KB	45.9 KB	4.2 MB	98%	2.4 KB
5	news-out.mixmin.net	3.3 MB	53.9 KB	700.9 KB	4.1 MB	81%	5.4 KB
6	newsfeeds.xmission.com	3.3 MB	0.0 KB	380.5 KB	3.7 MB	89%	3.8 KB
7	newsfeed.neva.ru	3.0 MB	17.9 KB	20.2 MB	23.3 MB	13%	6.8 KB
8	news.redatomik.org	2.7 MB	13.2 KB	68.3 KB	2.8 MB	97%	3.2 KB
9	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	2.1 MB	4.5 MB	52%	2.5 KB
10	news.freedyn.net	679.4 KB	0.0 KB	2.4 MB	3.1 MB	21%	8.8 KB
11	nntp.pasdenom.info	260.3 KB	0.0 KB	21.6 KB	281.8 KB	92%	2.5 KB
12	xanadu-bbs.net	235.0 KB	0.0 KB	50.8 KB	285.8 KB	82%	7.1 KB
13	news.fcku.it	226.1 KB	0.0 KB	238.2 KB	464.4 KB	48%	2.0 KB
14	news.swapon.de	160.4 KB	0.0 KB	180.6 KB	341.0 KB	47%	2.9 KB
15	news.bbs.geek.nz	63.9 KB	0.0 KB	1.8 KB	65.7 KB	97%	2.4 KB
16	localhost	10.6 KB	0.0 KB	0.0 KB	10.6 KB	100%	1.8 KB
TOTAL: 16		71.1 MB	288.9 KB	165.9 MB	237.3 MB	29%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 23 04:15:00 - 04:59:59	358	1.5%	0.13	1.0 MB	1.5%	0.39
Feb 23 05:00:00 - 05:59:59	698	3.0%	0.19	2.9 MB	4.0%	0.81
Feb 23 06:00:00 - 06:59:59	551	2.4%	0.15	1.8 MB	2.6%	0.52
Feb 23 07:00:00 - 07:59:59	688	2.9%	0.19	2.7 MB	3.9%	0.78
Feb 23 08:00:00 - 08:59:59	972	4.2%	0.27	2.5 MB	3.6%	0.72
Feb 23 09:00:00 - 09:59:59	1076	4.6%	0.30	2.8 MB	4.0%	0.80
Feb 23 10:00:00 - 10:59:59	1010	4.3%	0.28	3.2 MB	4.5%	0.90
Feb 23 11:00:00 - 11:59:59	1076	4.6%	0.30	2.9 MB	4.1%	0.82
Feb 23 12:00:00 - 12:59:59	1128	4.8%	0.31	3.1 MB	4.4%	0.89
Feb 23 13:00:00 - 13:59:59	1156	5.0%	0.32	3.2 MB	4.5%	0.90
Feb 23 14:00:00 - 14:59:59	1151	4.9%	0.32	2.9 MB	4.2%	0.84
Feb 23 15:00:00 - 15:59:59	1096	4.7%	0.30	3.1 MB	4.4%	0.88
Feb 23 16:00:00 - 16:59:59	1425	6.1%	0.40	5.0 MB	7.1%	1.42
Feb 23 17:00:00 - 17:59:59	1488	6.4%	0.41	4.7 MB	6.6%	1.34
Feb 23 18:00:00 - 18:59:59	1299	5.6%	0.36	3.9 MB	5.5%	1.11
Feb 23 19:00:00 - 19:59:59	1188	5.1%	0.33	3.3 MB	4.6%	0.93
Feb 23 20:00:00 - 20:59:59	1197	5.1%	0.33	3.5 MB	4.9%	0.99
Feb 23 21:00:00 - 21:59:59	1166	5.0%	0.32	3.2 MB	4.5%	0.91
Feb 23 22:00:00 - 22:59:59	1159	5.0%	0.32	3.5 MB	5.0%	1.00
Feb 23 23:00:00 - 23:59:59	906	3.9%	0.25	2.9 MB	4.0%	0.82
Feb 24 00:00:00 - 00:59:59	762	3.3%	0.21	2.2 MB	3.2%	0.64
Feb 24 01:00:00 - 01:59:59	551	2.4%	0.15	1.8 MB	2.5%	0.50
Feb 24 02:00:00 - 02:59:59	660	2.8%	0.18	2.8 MB	3.9%	0.79
Feb 24 03:00:00 - 03:59:59	467	2.0%	0.13	1.6 MB	2.2%	0.44
Feb 24 04:00:00 - 04:15:00	123	0.5%	0.14	402.1 KB	0.6%	0.45
TOTAL: 24:00:00	23351	100.0%	0.27	70.9 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9288	417	31	2	8838
2	feeder.eternal-september.org	3813	49	0	0	3764
3	newsfeed.neva.ru	2364	219	3	0	2142
4	csiph-out.news.weretis.net	1606	213	1	0	1392
5	newsfeed.fu-berlin.de	1184	25	0	0	1159
6	news.freedyn.net	176	14	0	0	162
7	news-out.mixmin.net	123	4	2	0	117
8	news.fcku.it	85	0	1	0	84
9	news.swapon.de	63	0	0	0	63
10	news-out.aioe.org	41	30	1	0	10
11	xanadu-bbs.net	11	0	0	0	11
12	newsfeeds.xmission.com	8	1	0	0	7
13	news.redatomik.org	8	0	1	0	7
14	nntp.pasdenom.info	7	5	0	0	2
15	news.bbs.geek.nz	1	0	0	0	1
TOTAL: 15		18778	977	40	2	17759

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	160
it-alt.sport.calcio.juventus	91
lada.talk	66
eagle.userchat.eng	49
alt.windows7.general	27
lada.auto	26
mozilla.dev.security.policy	25
mpc.lists.nanog	25
fido.enet.sysop	25
mozilla.dev.platform	22
mpc.lists.freebsd.ports.bugs	21
mozilla.support.seamonkey	19
mozilla.tools.taskcluster	14
mozilla.support.thunderbird	12
gmane.comp.gis.openstreetmap	12
gmane.linux.alsa.devel	10
fido.linux	10
gmane.comp.gis.openstreetmap.tagging	9
mozilla.dev.tech.crypto	9
gmane.comp.bacula.user	9
TOTAL: 149	977

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

Groups	Count
sci.space.news	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14024
EMP (phn path)	2226
EMP (phl)	481
EMP (phn nph)	119
Too many newsgroups	45
User-issued cancel	16
Scoring filter (12)	15
Binary Payload	15
HTML Multipart	15
Scoring filter (10)	15
Scoring filter (9)	14
Scoring filter (8)	9
EMP (fsl)	9
Binary Image: misplaced jpg	7
Scoring filter (11)	6
Scoring filter (14)	5
Scoring filter (16)	4
HTML Image Tags	3
Scoring filter (15)	2
HTML post	2
TOTAL: 22	17034

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	119	0	149	149
TOTAL: 1	119	0	149	149

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	110
	csiph-out.news.weretis.net	65
	news.freedyn.net	8
	news-out.mixmin.net	7
	newsfeeds.xmission.com	4
	news.fcku.it	2
	news.redatomik.org	1
	newsfeed.fu-berlin.de	1
	TOTAL: 8	198
Including strange strings
	newsfeed.fu-berlin.de	389
	csiph-out.feeder.erje.net	54
	csiph-out.news.weretis.net	29
	feeder.eternal-september.org	14
	news-out.aioe.org	9
	newsfeed.neva.ru	8
	news.swapon.de	7
	news.redatomik.org	5
	news-out.mixmin.net	4
	newsfeeds.xmission.com	3
	TOTAL: 12	524
No colon-space in header
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
TOTAL: 3		725

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	35810	8500	13797	231	2	0	23%	23:50:08
2	news.freedyn.net	24184	928	22144	4	0	0	3%	23:50:08
3	xanadu-bbs	26420	860	22454	4	0	3	3%	23:59:49
4	fu-berlin.de	21897	671	21220	0	0	0	3%	23:49:58
5	erje	16095	371	15056	46	0	0	2%	23:50:08
6	usenet.pasdenom.info	26259	240	21697	1309	0	88	0%	23:59:51
7	xmission	26613	143	21548	7	0	53	0%	23:59:51
8	aioe	21919	99	19706	12	0	0	0%	23:52:18
9	news.redatomik.org	22878	49	21617	8	0	0	0%	23:50:08
10	weretis.net	16680	18	15836	3	0	17	0%	23:58:31
11	eternal-september	21621	10	18918	0	0	3	0%	23:59:51
12	news.mixmin.net	22679	9	21406	0	0	0	0%	23:50:08
13	news.swapon.de	2211	5	2095	1	0	1	0%	23:47:40
14	news.fcku.it	3177	4	3035	0	0	0	0%	23:18:54
15	neva.ru	22710	2	21174	20	0	2	0%	23:59:44
16	bwh	0	0	0	0	0	22547	0%	23:50:14
17	au2pb	0	0	0	0	0	22561	0%	23:50:15
TOTAL: 17		311153	11909	261703	1645	2	45275	3%	405:37:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	22.5 MB	1.3 MB	23.8 MB	0.3 KB/s	2.8 KB	23:50:08
2	news.freedyn.net	6.3 MB	14.8 KB	6.3 MB	0.1 KB/s	6.9 KB	23:50:08
3	xanadu-bbs	5.8 MB	16.0 KB	5.8 MB	0.1 KB/s	6.9 KB	23:59:49
4	fu-berlin.de	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	8.1 KB	23:49:58
5	erje	1.7 MB	225.3 KB	1.9 MB	0.0 KB/s	4.7 KB	23:50:08
6	usenet.pasdenom.info	751.3 KB	6.6 MB	7.3 MB	0.1 KB/s	4.8 KB	23:59:51
7	xmission	345.5 KB	15.1 KB	360.6 KB	0.0 KB/s	2.4 KB	23:59:51
8	aioe	276.1 KB	53.9 KB	330.0 KB	0.0 KB/s	3.0 KB	23:52:18
9	news.redatomik.org	138.3 KB	59.2 KB	197.5 KB	0.0 KB/s	3.5 KB	23:50:08
10	weretis.net	51.1 KB	11.7 KB	62.9 KB	0.0 KB/s	3.0 KB	23:58:31
11	news.mixmin.net	22.0 KB	0.0 KB	22.0 KB	0.0 KB/s	2.4 KB	23:50:08
12	eternal-september	21.9 KB	0.0 KB	21.9 KB	0.0 KB/s	2.2 KB	23:59:51
13	news.swapon.de	10.9 KB	1.1 KB	11.9 KB	0.0 KB/s	2.0 KB	23:47:40
14	news.fcku.it	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	1.6 KB	23:18:54
15	neva.ru	4.6 KB	71.9 KB	76.5 KB	0.0 KB/s	3.5 KB	23:59:44
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:14
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:15
TOTAL: 17		43.2 MB	8.4 MB	51.6 MB	0.0 KB/s	3.9 KB	405:37:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	dhcp-089-099-203-254.chello.nl	32	8	14.0 KB	4	0	0	08:43:16
2	tempe0.bbox.io	13	7	12.3 KB	2	0	0	03:32:40
3	68-185-181-64.dhcp.mdsn.wi.charter.com	26	7	12.3 KB	7	6	5	06:05:49
4	194-73-239-109.cust.centrio.cz	11	2	2.8 KB	2	1	0	01:33:00
TOTAL: 4		82	26	45.5 KB	16	7	5	21:29:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.chello.nl	32	8	14.0 KB	4	0	0	08:43:16
2	*.bbox.io	13	7	12.3 KB	2	0	0	03:32:40
3	*.dhcp.mdsn.wi.charter.com	26	7	12.3 KB	7	6	5	06:05:49
4	*.cust.centrio.cz	11	2	2.8 KB	2	1	0	01:33:00
5	unresolved	2	2	4.2 KB	1	0	0	00:30:46
6	*.137.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	01:03:53
TOTAL: 6		85	26	45.5 KB	16	7	5	21:29:27

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
ctomczyk	3
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.639	0.541	0.000	08:43:16
68-185-181-64.dhcp.mdsn.wi.charter.com	2.444	0.515	0.021	06:05:49
tempe0.bbox.io	1.183	0.293	0.000	03:32:40
194-73-239-109.cust.centrio.cz	0.924	0.173	0.011	01:33:00
h201.137.22.98.dynamic.ip.windstream.net	0.054	0.135	0.000	01:03:53
1.126.48.100	0.143	0.030	0.004	00:30:38
106.75.75.176	0.093	0.008	0.000	00:00:04
71.6.146.185	0.083	0.015	0.000	00:00:03
TOTAL: 8	7.563	1.710	0.036	21:29:27

Curious NNRP server explorers [Top 20]:

System	Conn
106.75.75.176	1
71.6.146.185	1
h201.137.22.98.dynamic.ip.windstream.net	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
194-73-239-109.cust.centrio.cz	3	0
1.126.48.100	1	0
TOTAL: 4	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	24	92.3%
2	pl	2	7.7%
TOTAL: 2		26	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	24
2	pl.comp.lang.javascript	2
TOTAL: 2		26

Daily Usenet report for csiph.com

Feb 23 04:15:00 -- Feb 24 04:15:00