Daily Usenet report for csiph.com: Apr 11 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	59894	45.7%	5.7 MB	34.1%
inn	43075	32.9%	8.0 MB	47.4%
innd	24786	18.9%	2.6 MB	15.4%
innfeed	3215	2.5%	524.4 KB	3.0%
nocem	64	0.0%	6.7 KB	0.0%
controlchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	131036	100.0%	16.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	422303	55.8%
Negative hits	283463	37.4%
Do not exist	42171	5.6%
Cache misses	9014	1.2%
TOTAL: 4	756951	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.966	0.0%	231	0.000	8.511	71.889
article cleanup	00:00:00.310	0.0%	42286	0.000	0.007	0.021
article logging	00:00:06.038	0.0%	42850	0.000	0.141	1.923
article parse	00:00:01.878	0.0%	135707	0.003	0.014	0.100
article write	00:02:57.871	0.2%	22670	0.188	7.846	35.937
artlog/artcncl	00:00:00.051	0.0%	196	0.000	0.260	1.714
artlog/artparse	00:00:00.021	0.0%	71	0.000	0.296	8.500
data move	00:00:00.383	0.0%	687407	0.000	0.001	0.010
hisgrep/artcncl	00:00:00.783	0.0%	230	0.000	3.404	28.556
hishave/artcncl	00:00:00.365	0.0%	231	0.000	1.580	25.667
hishave/artparse	00:00:00.000	0.0%	71	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:04.219	0.1%	759303	0.002	0.164	1.281
history sync	00:00:06.132	0.0%	364	0.000	16.846	76.333
history write	00:05:03.821	0.4%	42263	0.093	7.189	36.500
hiswrite/artcncl	00:00:00.007	0.0%	1	7.000	7.000	7.000
hiswrite/artparse	00:00:00.306	0.0%	71	0.000	4.310	50.000
idle	23:41:01.115	98.6%	673053	63.353	126.678	366.785
nntp read	00:00:03.338	0.0%	702137	0.002	0.005	0.011
overview write	00:05:44.813	0.4%	22670	0.733	15.210	124.306
perl filter	00:01:50.287	0.1%	42263	1.672	2.610	5.045
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.439	0.0%	45397	0.000	0.010	0.042
TOTAL: 24:00:56.668	23:59:04.143	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.090	0.0%	32914	0.000	0.003	0.026
article prepare	00:00:00.006	0.0%	27328	0.000	0.000	0.009
article read	00:00:01.735	0.0%	4683	0.000	0.370	14.000
backlog stats	00:00:00.249	0.0%	408398	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.205	0.0%	318656	0.004	0.007	0.011
data write	00:00:01.870	0.0%	302648	0.001	0.006	0.012
idle	23:53:26.531	100.0%	408398	82.370	210.595	555.721
status file	00:00:00.404	0.0%	296	0.000	1.365	26.000
TOTAL: 23:53:44.598	23:53:33.090	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:10.118	0.0%	6709	0.068	1.508	3.435
idle	52:49:39.026	99.7%	105197	81.000	1807.837	600131.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.182	0.0%	211655	0.000	0.006	0.250
readart	00:02:14.864	0.1%	10742	0.000	12.555	203.000
TOTAL: 52:58:53.892	52:52:05.190	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	46262	6850	38434	978	14%	45:22:46
2	csiph-out.feeder.erje.net	1152	133481	5189	115273	13019	3%	94:58:41
3	feeder.eternal-september.org	1	37813	3011	30790	4012	7%	23:59:44
4	news-out.aioe.org	4	22629	1694	20898	37	7%	24:09:50
5	news-out.mixmin.net	3	24316	1362	22613	341	5%	24:08:39
6	newsfeed.neva.ru	142	57835	1268	54384	2183	2%	71:57:19
7	newsfeed.fu-berlin.de	41	21091	943	19809	339	4%	26:58:51
8	newsfeeds.xmission.com	3	27385	878	26349	158	3%	16:59:09
9	news.redatomik.org	1	20988	801	20184	3	3%	23:40:14
10	news.fcku.it	1	10044	219	9809	16	2%	23:59:56
11	news.freedyn.net	3	25568	88	25404	76	0%	24:09:46
12	nntp.pasdenom.info	2	21589	63	21517	9	0%	23:58:33
13	news.swapon.de	1	1906	42	1861	3	2%	24:54:51
14	news.bbs.geek.nz	1	20861	29	20830	2	0%	23:59:49
15	xanadu-bbs.net	9	186	15	169	2	8%	08:26:32
16	localhost	43	10	10	0	0	100%	00:00:09
TOTAL: 16		1410	471964	22462	428324	21178	4%	481:44:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	20.1 MB	5.9 KB	11.5 MB	31.5 MB	63%	4.1 KB
2	csiph-out.feeder.erje.net	17.1 MB	180.3 KB	78.8 MB	96.0 MB	17%	5.4 KB
3	feeder.eternal-september.org	7.4 MB	15.8 KB	51.7 MB	59.1 MB	12%	8.6 KB
4	news-out.mixmin.net	5.3 MB	5.9 KB	1.0 MB	6.3 MB	83%	3.8 KB
5	news-out.aioe.org	4.3 MB	5.9 KB	43.1 KB	4.3 MB	98%	2.5 KB
6	newsfeed.neva.ru	2.9 MB	12.6 KB	20.6 MB	23.5 MB	12%	7.0 KB
7	newsfeeds.xmission.com	2.6 MB	0.0 KB	775.2 KB	3.4 MB	77%	3.3 KB
8	news.redatomik.org	2.4 MB	5.9 KB	7.1 KB	2.4 MB	99%	3.0 KB
9	newsfeed.fu-berlin.de	2.1 MB	5.8 KB	622.6 KB	2.7 MB	76%	2.1 KB
10	news.freedyn.net	380.4 KB	9.9 KB	652.5 KB	1.0 MB	36%	6.4 KB
11	news.fcku.it	280.4 KB	2.1 KB	422.5 KB	705.0 KB	39%	3.0 KB
12	nntp.pasdenom.info	182.0 KB	6.0 KB	24.1 KB	212.1 KB	85%	2.9 KB
13	news.swapon.de	106.0 KB	2.0 KB	52.8 KB	160.8 KB	65%	3.6 KB
14	news.bbs.geek.nz	56.9 KB	5.9 KB	5.9 KB	68.8 KB	82%	2.2 KB
15	xanadu-bbs.net	27.4 KB	0.0 KB	6.0 KB	33.4 KB	82%	2.0 KB
16	localhost	12.8 KB	0.0 KB	0.0 KB	12.8 KB	100%	1.3 KB
TOTAL: 16		65.0 MB	264.0 KB	166.0 MB	231.3 MB	28%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 11 04:15:00 - 04:59:59	367	1.6%	0.14	1.4 MB	2.1%	0.52
Apr 11 05:00:00 - 05:59:59	491	2.2%	0.14	1.4 MB	2.2%	0.41
Apr 11 06:00:00 - 06:59:59	640	2.8%	0.18	1.7 MB	2.5%	0.47
Apr 11 07:00:00 - 07:59:59	941	4.2%	0.26	3.2 MB	4.8%	0.90
Apr 11 08:00:00 - 08:59:59	1013	4.5%	0.28	3.5 MB	5.4%	1.01
Apr 11 09:00:00 - 09:59:59	1077	4.8%	0.30	2.9 MB	4.4%	0.82
Apr 11 10:00:00 - 10:59:59	1032	4.6%	0.29	2.7 MB	4.2%	0.78
Apr 11 11:00:00 - 11:59:59	1172	5.2%	0.33	3.0 MB	4.6%	0.85
Apr 11 12:00:00 - 12:59:59	1130	5.0%	0.31	2.8 MB	4.3%	0.80
Apr 11 13:00:00 - 13:59:59	1170	5.2%	0.33	3.0 MB	4.5%	0.84
Apr 11 14:00:00 - 14:59:59	1447	6.4%	0.40	4.2 MB	6.4%	1.19
Apr 11 15:00:00 - 15:59:59	1316	5.8%	0.37	3.7 MB	5.7%	1.07
Apr 11 16:00:00 - 16:59:59	1220	5.4%	0.34	3.5 MB	5.3%	0.99
Apr 11 17:00:00 - 17:59:59	1199	5.3%	0.33	3.5 MB	5.3%	0.99
Apr 11 18:00:00 - 18:59:59	1204	5.3%	0.33	3.4 MB	5.2%	0.97
Apr 11 19:00:00 - 19:59:59	1221	5.4%	0.34	3.8 MB	5.7%	1.07
Apr 11 20:00:00 - 20:59:59	1111	4.9%	0.31	3.1 MB	4.7%	0.88
Apr 11 21:00:00 - 21:59:59	1092	4.8%	0.30	3.1 MB	4.8%	0.89
Apr 11 22:00:00 - 22:59:59	870	3.8%	0.24	2.6 MB	4.0%	0.75
Apr 11 23:00:00 - 23:59:59	783	3.5%	0.22	2.4 MB	3.6%	0.67
Apr 12 00:00:00 - 00:59:59	613	2.7%	0.17	1.7 MB	2.6%	0.49
Apr 12 01:00:00 - 01:59:59	512	2.3%	0.14	1.8 MB	2.7%	0.50
Apr 12 02:00:00 - 02:59:59	471	2.1%	0.13	1.5 MB	2.3%	0.43
Apr 12 03:00:00 - 03:59:59	431	1.9%	0.12	1.3 MB	1.9%	0.36
Apr 12 04:00:00 - 04:15:00	141	0.6%	0.16	543.0 KB	0.8%	0.60
TOTAL: 24:00:00	22664	100.0%	0.26	65.6 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	11526	354	20	2	11150
2	feeder.eternal-september.org	3999	91	1	0	3907
3	newsfeed.neva.ru	2172	279	1	0	1892
4	csiph-out.news.weretis.net	1074	266	0	0	808
5	newsfeed.fu-berlin.de	789	42	0	0	747
6	news-out.mixmin.net	340	2	0	0	338
7	newsfeeds.xmission.com	165	5	0	0	160
8	news.freedyn.net	71	4	1	0	66
9	news-out.aioe.org	45	35	0	0	10
10	news.fcku.it	14	3	0	0	11
11	nntp.pasdenom.info	7	6	0	0	1
12	xanadu-bbs.net	4	0	0	0	4
13	news.redatomik.org	4	0	0	0	4
14	news.swapon.de	4	0	0	0	4
15	news.bbs.geek.nz	2	0	0	0	2
TOTAL: 15		20216	1087	23	2	19104

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	124
lada.talk	122
ut.test	115
lada.auto	76
it.annunci.immobiliari	39
it.annunci.varie	38
it.annunci.commerciali	37
mozilla.dev.security.policy	35
alt.windows7.general	33
cakewalk.audio	32
alt.survival	31
mpc.lists.freebsd.x11	23
gmane.linux.alsa.devel	23
spline.eisfair	19
mozilla.support.seamonkey	16
mozilla.support.thunderbird	16
lada.photo	11
mpc.lists.freebsd.gecko	9
gmane.comp.gis.openstreetmap	9
gmane.comp.gis.openstreetmap.tagging	9
TOTAL: 136	1087

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

Groups	Count
nz.politics.announce	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16778
EMP (phn path)	1297
EMP (phn nph)	120
Too many newsgroups	73
Scoring filter (8)	35
User-issued cancel	34
Binary Payload	29
EMP (phl)	23
HTML Multipart	22
Scoring filter (9)	17
Scoring filter (13)	10
Scoring filter (10)	9
HTML post	8
Too many newsgroups (low_xpost)	4
Scoring filter (11)	4
Too many newsgroups (meow)	1
Scoring filter (22)	1
Scoring filter (21)	1
Scoring filter (26)	1
Scoring filter (16)	1
TOTAL: 21	18469

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	56	0	61	61
TOTAL: 1	56	1	61	61

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	16
	TOTAL: 1	16
Huge articles
	csiph-out.news.weretis.net	41
	feeder.eternal-september.org	15
	news-out.mixmin.net	8
	newsfeeds.xmission.com	3
	news.fcku.it	2
	news.freedyn.net	1
	news.swapon.de	1
	TOTAL: 7	71
Including strange strings
	newsfeed.fu-berlin.de	412
	csiph-out.news.weretis.net	78
	csiph-out.feeder.erje.net	42
	news-out.mixmin.net	9
	news-out.aioe.org	9
	newsfeed.neva.ru	4
	news.redatomik.org	3
	feeder.eternal-september.org	2
	news.bbs.geek.nz	2
	news.swapon.de	2
	TOTAL: 11	564
TOTAL: 3		651

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40568	3003	19014	94	0	7%	23:59:53
2	news.freedyn.net	23564	707	21856	9	0	3%	23:59:34
3	xanadu-bbs	24568	676	21847	0	0	2%	23:59:53
4	fu-berlin.de	21430	519	20911	0	0	2%	23:59:20
5	usenet.pasdenom.info	24406	161	21044	1395	5	0%	23:59:53
6	xmission	29620	81	27577	16	3673	0%	23:59:53
7	news.redatomik.org	21904	24	21363	1	0	0%	23:59:53
8	aioe	20265	24	19395	1	0	0%	23:59:27
9	weretis.net	14516	15	14067	3	0	0%	23:59:53
10	eternal-september	20608	15	18820	0	0	0%	23:59:52
11	news.mixmin.net	21140	12	20411	0	1	0%	23:59:53
12	news.swapon.de	2116	10	2060	0	0	0%	23:58:06
13	neva.ru	20953	5	20513	10	0	0%	23:59:51
14	erje	17522	2	17317	48	5	0%	23:59:53
15	news.fcku.it	2924	2	2850	0	0	0%	22:41:50
16	bwh	0	0	0	0	22122	0%	23:58:40
17	au2pb	0	0	0	0	22131	0%	23:58:41
TOTAL: 17		306104	5256	269045	1577	47937	1%	406:34:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	7.6 MB	337.7 KB	7.9 MB	0.1 KB/s	2.6 KB	23:59:53
2	news.freedyn.net	5.0 MB	22.3 KB	5.0 MB	0.1 KB/s	7.2 KB	23:59:34
3	xanadu-bbs	4.8 MB	0.0 KB	4.8 MB	0.1 KB/s	7.3 KB	23:59:53
4	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	8.6 KB	23:59:20
5	usenet.pasdenom.info	587.2 KB	5.9 MB	6.4 MB	0.1 KB/s	4.2 KB	23:59:53
6	xmission	195.1 KB	27.9 KB	223.0 KB	0.0 KB/s	2.3 KB	23:59:53
7	news.redatomik.org	54.3 KB	30.1 KB	84.5 KB	0.0 KB/s	3.4 KB	23:59:53
8	aioe	42.4 KB	2.2 KB	44.6 KB	0.0 KB/s	1.8 KB	23:59:27
9	weretis.net	30.8 KB	58.8 KB	89.7 KB	0.0 KB/s	5.0 KB	23:59:53
10	eternal-september	26.1 KB	0.0 KB	26.1 KB	0.0 KB/s	1.7 KB	23:59:52
11	news.mixmin.net	16.1 KB	0.0 KB	16.1 KB	0.0 KB/s	1.3 KB	23:59:53
12	news.swapon.de	12.8 KB	0.0 KB	12.8 KB	0.0 KB/s	1.3 KB	23:58:06
13	neva.ru	6.5 KB	23.2 KB	29.7 KB	0.0 KB/s	2.0 KB	23:59:51
14	news.fcku.it	3.2 KB	0.0 KB	3.2 KB	0.0 KB/s	1.6 KB	22:41:50
15	erje	2.2 KB	444.9 KB	447.1 KB	0.0 KB/s	8.9 KB	23:59:53
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:40
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
TOTAL: 17		22.7 MB	6.8 MB	29.5 MB	0.0 KB/s	4.4 KB	406:34:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	144.138.90.254	286	95	204.3 KB	87	0	0	03:30:27
2	mtrlpq2314w-lp130-02-70-24-222-72.dsl.bell.ca	2388	74	192.8 KB	9	0	0	03:08:31
3	dhcp-089-099-203-254.chello.nl	84	14	21.8 KB	8	0	0	25:05:34
4	68-185-181-64.dhcp.mdsn.wi.charter.com	41	12	17.7 KB	11	10	9	09:04:31
5	204-73-239-109.cust.centrio.cz	12	9	23.8 KB	4	0	0	02:11:27
TOTAL: 5		2811	204	460.5 KB	119	10	9	52:58:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	288	95	204.3 KB	87	0	0	04:11:53
2	*.dsl.bell.ca	2388	74	192.8 KB	9	0	0	03:08:31
3	*.chello.nl	84	14	21.8 KB	8	0	0	25:05:34
4	*.dhcp.mdsn.wi.charter.com	41	12	17.7 KB	11	10	9	09:04:31
5	*.cust.centrio.cz	12	9	23.8 KB	4	0	0	02:11:27
6	*.mega.tmns.net.au	2	0	0.0 KB	0	0	0	00:01:26
7	*.141.22.98.dynamic.ip.windstream.net	3	0	0.0 KB	0	0	0	04:38:47
8	*.bbox.io	13	0	0.0 KB	0	0	0	04:36:17
TOTAL: 8		2831	204	460.5 KB	119	10	9	52:58:30

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	9
ctomczyk	6
TOTAL: 3	303

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	7.099	1.529	0.000	25:05:34
68-185-181-64.dhcp.mdsn.wi.charter.com	3.620	0.755	0.033	09:04:31
h69.141.22.98.dynamic.ip.windstream.net	0.290	0.369	0.000	04:38:47
tempe0.bbox.io	1.018	0.239	0.000	04:36:17
144.138.90.254	25.779	6.903	1.034	03:30:27
mtrlpq2314w-lp130-02-70-24-222-72.dsl.bell.ca	189.163	42.923	0.000	03:08:31
204-73-239-109.cust.centrio.cz	1.067	0.228	0.022	02:11:27
71.94.21.184	0.164	0.200	0.000	00:41:26
ess-p-144-138-90-254.mega.tmns.net.au	0.113	0.043	0.007	00:01:26
TOTAL: 9	228.313	53.189	1.096	52:58:30

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	13
h69.141.22.98.dynamic.ip.windstream.net	3
71.94.21.184	2
ess-p-144-138-90-254.mega.tmns.net.au	2
TOTAL: 4	20

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	8	0
tempe0.bbox.io	7	0
204-73-239-109.cust.centrio.cz	3	0
h69.141.22.98.dynamic.ip.windstream.net	3	0
TOTAL: 4	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	129	63.2%
2	alt	67	32.8%
3	uk	6	2.9%
4	pl	2	1.0%
TOTAL: 4		204	100.0%

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

Newsgroup		Count
1	alt.atheism	59
2	comp.sys.ibm.ps2.hardware	37
3	comp.os.vms	34
4	comp.arch.fpga	22
5	comp.arch	10
6	comp.lang.javascript	7
7	comp.os.msdos.djgpp	6
8	uk.legal.moderated	5
9	alt.comp.freeware	5
10	comp.sys.ibm.pc.games.war-historical	4
11	comp.os.msdos.misc	2
12	comp.os.msdos.programmer	2
13	comp.sys.ibm.pc.games.action	2
14	pl.comp.lang.javascript	2
15	alt.comp.os.windows-10	2
16	alt.os.linux.mageia	1
17	uk.radio.amateur	1
18	comp.ai.neural-nets	1
19	comp.mobile.android	1
20	comp.os.cpm	1
TOTAL: 20		204

Daily Usenet report for csiph.com

Apr 11 04:15:00 -- Apr 12 04:15:00