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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	44847	39.4%	4.0 MB	26.3%
inn	42416	37.2%	8.1 MB	53.6%
innd	23201	20.4%	2.5 MB	16.4%
innfeed	3323	2.9%	557.9 KB	3.6%
nocem	116	0.1%	12.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	113904	100.0%	15.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	482721	56.3%
Negative hits	331864	38.7%
Do not exist	42097	4.9%
Cache misses	713	0.1%
TOTAL: 4	857395	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.198	0.0%	274	0.000	0.723	11.000
article cleanup	00:00:00.276	0.0%	41274	0.000	0.007	0.033
article logging	00:00:00.612	0.0%	41840	0.000	0.015	0.213
article parse	00:00:02.190	0.0%	173905	0.003	0.013	0.071
article write	00:01:07.906	0.1%	23359	0.151	2.907	30.861
artlog/artcncl	00:00:00.003	0.0%	210	0.000	0.014	1.000
artlog/artparse	00:00:00.008	0.0%	390	0.000	0.021	1.000
data move	00:00:00.287	0.0%	840234	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.015	0.0%	272	0.000	0.055	10.000
hishave/artcncl	00:00:00.003	0.0%	274	0.000	0.011	0.158
hishave/artparse	00:00:00.000	0.0%	383	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:16.194	0.0%	859074	0.001	0.019	0.206
history sync	00:00:02.206	0.0%	350	0.000	6.303	106.500
history write	00:01:08.534	0.1%	41237	0.077	1.662	18.455
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.709	0.0%	380	0.000	1.866	24.333
idle	23:54:16.519	99.5%	867230	54.946	99.231	288.312
nntp read	00:00:02.887	0.0%	873112	0.001	0.003	0.006
overview write	00:01:21.477	0.1%	23359	0.386	3.488	39.703
perl filter	00:01:42.253	0.1%	41224	1.460	2.480	3.989
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.404	0.0%	46826	0.000	0.009	0.034
TOTAL: 24:00:56.666	24:00:02.681	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	24950	0.000	0.002	0.032
article prepare	00:00:00.008	0.0%	46216	0.000	0.000	0.011
article read	00:00:05.491	0.0%	6761	0.000	0.812	53.758
backlog stats	00:00:00.249	0.0%	558920	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.005	0.0%	395969	0.002	0.005	0.011
data write	00:00:02.797	0.0%	389938	0.004	0.007	0.012
idle	24:03:21.052	100.0%	558920	81.154	154.944	447.724
status file	00:00:00.263	0.0%	328	0.000	0.802	25.000
TOTAL: 24:03:40.355	24:03:31.927	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.796	0.0%	7449	0.000	0.107	1.000
idle	54:47:51.505	98.9%	64607	0.000	3053.408	600387.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.766	0.0%	130378	0.000	0.006	0.167
readart	00:00:18.845	0.0%	10815	0.000	1.742	72.000
TOTAL: 55:24:23.234	54:48:11.912	98.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	28858	5188	21083	2587	17%	23:44:54
2	feeder.eternal-september.org	8	36629	3675	30486	2468	10%	25:12:59
3	csiph-out.feeder.erje.net	1152	95992	3607	87519	4866	3%	95:02:57
4	csiph-out.news.weretis.net	3	52739	2806	48886	1047	5%	43:40:50
5	news-out.aioe.org	2	22930	1870	21042	18	8%	24:09:02
6	newsfeed.fu-berlin.de	26	28054	1320	26009	725	4%	28:29:22
7	newsfeed.neva.ru	143	45675	1282	37944	6449	2%	72:31:26
8	news-out.mixmin.net	2	28601	1068	25843	1690	3%	24:08:56
9	newsfeeds.xmission.com	1	22198	867	21320	11	3%	22:37:10
10	news.freedyn.net	3	28356	663	27343	350	2%	24:08:45
11	news.fcku.it	1	7302	328	6970	4	4%	21:00:36
12	au2pb.net	1	31567	217	31282	68	0%	26:13:07
13	news.swapon.de	7	2253	123	2128	2	5%	23:40:59
14	xanadu-bbs.net	1	38078	76	37223	779	0%	24:34:26
15	news.redatomik.org	1	21504	71	21430	3	0%	22:40:05
16	nntp.pasdenom.info	14	23925	45	23867	13	0%	35:07:46
17	news.bbs.geek.nz	2	27007	15	26989	3	0%	23:59:45
18	localhost	49	14	14	0	0	100%	00:00:01
TOTAL: 18		1704	541682	23235	497364	21083	4%	561:03:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	14.9 MB	0.0 KB	23.2 MB	38.1 MB	39%	5.0 KB
2	csiph-out.feeder.erje.net	14.4 MB	48.4 KB	8.1 MB	22.5 MB	63%	2.7 KB
3	feeder.eternal-september.org	9.4 MB	21.4 KB	24.5 MB	33.9 MB	27%	5.7 KB
4	csiph-out.news.weretis.net	8.0 MB	0.0 KB	12.5 MB	20.5 MB	38%	5.4 KB
5	news-out.aioe.org	4.5 MB	7.2 KB	54.5 KB	4.5 MB	98%	2.5 KB
6	news-out.mixmin.net	4.4 MB	4.6 KB	8.0 MB	12.4 MB	35%	4.6 KB
7	newsfeed.neva.ru	3.6 MB	115.9 KB	64.7 MB	68.4 MB	5%	9.1 KB
8	newsfeed.fu-berlin.de	3.0 MB	7.4 KB	1.8 MB	4.7 MB	62%	2.4 KB
9	news.freedyn.net	2.1 MB	3.7 KB	2.7 MB	4.8 MB	44%	4.9 KB
10	newsfeeds.xmission.com	2.0 MB	0.0 KB	624.1 KB	2.6 MB	76%	3.0 KB
11	news.fcku.it	636.8 KB	0.0 KB	59.3 KB	696.1 KB	91%	2.1 KB
12	au2pb.net	578.7 KB	0.0 KB	407.7 KB	986.4 KB	58%	3.5 KB
13	news.swapon.de	357.7 KB	0.0 KB	3.6 KB	361.3 KB	99%	2.9 KB
14	news.redatomik.org	180.9 KB	0.0 KB	89.0 KB	269.8 KB	67%	3.6 KB
15	xanadu-bbs.net	166.7 KB	37.4 KB	8.5 MB	8.7 MB	1%	10.4 KB
16	nntp.pasdenom.info	119.9 KB	12.0 KB	129.5 KB	261.4 KB	45%	4.5 KB
17	news.bbs.geek.nz	46.9 KB	1.3 KB	4.4 KB	52.6 KB	89%	2.9 KB
18	localhost	17.8 KB	0.0 KB	0.0 KB	17.8 KB	100%	1.3 KB
TOTAL: 18		68.2 MB	259.3 KB	155.3 MB	223.7 MB	30%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 13 04:15:00 - 04:59:59	338	1.4%	0.13	1.1 MB	1.6%	0.42
Mar 13 05:00:00 - 05:59:59	376	1.6%	0.10	1.2 MB	1.7%	0.34
Mar 13 06:00:00 - 06:59:59	466	2.0%	0.13	1.5 MB	2.1%	0.42
Mar 13 07:00:00 - 07:59:59	750	3.2%	0.21	2.1 MB	3.1%	0.61
Mar 13 08:00:00 - 08:59:59	852	3.6%	0.24	2.5 MB	3.7%	0.72
Mar 13 09:00:00 - 09:59:59	946	4.1%	0.26	3.1 MB	4.6%	0.89
Mar 13 10:00:00 - 10:59:59	1090	4.7%	0.30	2.9 MB	4.2%	0.83
Mar 13 11:00:00 - 11:59:59	1091	4.7%	0.30	3.1 MB	4.5%	0.89
Mar 13 12:00:00 - 12:59:59	1120	4.8%	0.31	3.3 MB	4.9%	0.94
Mar 13 13:00:00 - 13:59:59	1083	4.6%	0.30	3.4 MB	5.0%	0.97
Mar 13 14:00:00 - 14:59:59	1072	4.6%	0.30	2.9 MB	4.2%	0.83
Mar 13 15:00:00 - 15:59:59	1322	5.7%	0.37	4.1 MB	6.0%	1.16
Mar 13 16:00:00 - 16:59:59	1338	5.7%	0.37	4.2 MB	6.1%	1.18
Mar 13 17:00:00 - 17:59:59	1466	6.3%	0.41	4.5 MB	6.6%	1.29
Mar 13 18:00:00 - 18:59:59	1466	6.3%	0.41	4.1 MB	5.9%	1.16
Mar 13 19:00:00 - 19:59:59	1380	5.9%	0.38	3.6 MB	5.2%	1.01
Mar 13 20:00:00 - 20:59:59	1431	6.1%	0.40	3.5 MB	5.1%	1.00
Mar 13 21:00:00 - 21:59:59	1387	5.9%	0.39	4.1 MB	6.0%	1.17
Mar 13 22:00:00 - 22:59:59	1098	4.7%	0.30	3.2 MB	4.7%	0.91
Mar 13 23:00:00 - 23:59:59	918	3.9%	0.26	2.5 MB	3.7%	0.71
Mar 14 00:00:00 - 00:59:59	635	2.7%	0.18	2.0 MB	2.9%	0.57
Mar 14 01:00:00 - 01:59:59	610	2.6%	0.17	1.8 MB	2.6%	0.51
Mar 14 02:00:00 - 02:59:59	549	2.4%	0.15	1.8 MB	2.6%	0.50
Mar 14 03:00:00 - 03:59:59	481	2.1%	0.13	1.5 MB	2.2%	0.43
Mar 14 04:00:00 - 04:15:00	83	0.4%	0.09	375.2 KB	0.5%	0.42
TOTAL: 24:00:00	23348	100.0%	0.27	68.4 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	6414	757	11	5646
2	usenet.blueworldhosting.com	2585	968	0	1617
3	feeder.eternal-september.org	2445	160	8	2277
4	csiph-out.feeder.erje.net	2293	372	10	1911
5	news-out.mixmin.net	1635	1	2	1632
6	newsfeed.fu-berlin.de	1131	32	0	1099
7	csiph-out.news.weretis.net	1048	170	0	878
8	xanadu-bbs.net	797	6	6	785
9	news.freedyn.net	345	1	1	343
10	au2pb.net	65	48	0	17
11	news.swapon.de	31	0	0	31
12	news-out.aioe.org	28	10	2	16
13	newsfeeds.xmission.com	19	3	0	16
14	nntp.pasdenom.info	13	4	3	6
15	news.fcku.it	6	0	1	5
16	news.redatomik.org	3	0	0	3
TOTAL: 16		18858	2532	44	16282

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	251
fa.linux.kernel	249
mailing.freebsd.ports-bugs	150
mailing.freebsd.pkg-fallout	130
mailing.freebsd.svn-ports-all	103
mailing.freebsd.svn-ports-head	99
lada.auto	88
mailing.openssl.dev	80
lada.photo	53
lada.talk	49
alt.politics.scorched-earth	45
alt.survival	42
fa.freebsd.cvs-all	33
mailing.freebsd.svn-src-all	33
it-alt.sport.calcio.juventus	31
lada.commerce	29
alt.windows7.general	29
lada.kitchen	28
mailing.database.pgsql-general	27
fa.freebsd.current	27
TOTAL: 233	2532

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9385
EMP (phn path)	2817
EMP (phl)	1191
PFM bot misc	807
Binary: misplaced rar	460
EMP (phn nph)	244
Too many newsgroups	55
Scoring filter (9)	47
Scoring filter (17)	31
Scoring filter (8)	27
Binary: misplaced par2	27
Scoring filter (12)	23
Scoring filter (10)	21
HTML Multipart	20
Scoring filter (16)	19
Binary Payload	18
User-issued cancel	16
Scoring filter (14)	16
Scoring filter (15)	14
Scoring filter (20)	13
TOTAL: 35	15321

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	108	0	110	110
TOTAL: 1	108	0	110	110

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	5
	usenet.blueworldhosting.com	1
	TOTAL: 2	6
Huge articles
	news-out.mixmin.net	117
	csiph-out.news.weretis.net	112
	usenet.blueworldhosting.com	61
	feeder.eternal-september.org	42
	news.freedyn.net	22
	xanadu-bbs.net	10
	newsfeeds.xmission.com	8
	au2pb.net	4
	newsfeed.fu-berlin.de	3
	nntp.pasdenom.info	2
	TOTAL: 12	383
Including strange strings
	newsfeed.fu-berlin.de	446
	news.swapon.de	29
	feeder.eternal-september.org	24
	csiph-out.feeder.erje.net	22
	csiph-out.news.weretis.net	14
	news-out.aioe.org	12
	newsfeeds.xmission.com	8
	newsfeed.neva.ru	4
	news-out.mixmin.net	3
	news.fcku.it	1
	TOTAL: 12	565
No colon-space in header
	usenet.blueworldhosting.com	6
	TOTAL: 1	6
TOTAL: 4		960

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	38658	3344	19064	852	6	13	8%	23:59:51
2	news.bbs.geek.nz	42338	1718	20633	11	0	0	4%	23:59:54
3	news.freedyn.net	25806	1281	21394	12	0	0	4%	23:59:51
4	fu-berlin.de	21719	975	20744	0	0	0	4%	23:59:56
5	aioe	21712	682	18651	7	0	0	3%	23:59:55
6	xmission	28992	207	21231	11	0	0	0%	23:59:51
7	au2pb	23100	184	21988	6	0	0	0%	23:59:56
8	usenet.pasdenom.info	28587	164	21785	1360	0	1556	0%	23:58:43
9	news.redatomik.org	26853	155	21872	5	0	0	0%	23:59:56
10	erje	20118	152	19319	50	0	0	0%	23:59:56
11	news.mixmin.net	24356	32	20845	1	0	0	0%	23:59:56
12	neva.ru	25691	25	20614	213	0	0	0%	23:59:54
13	weretis.net	19423	25	18761	2	0	0	0%	23:59:42
14	eternal-september	23172	20	18307	0	0	0	0%	23:59:51
15	bwh	17951	14	17026	0	0	0	0%	23:59:46
16	news.swapon.de	2479	12	2160	5	0	0	0%	23:49:43
17	news.fcku.it	2231	10	1849	0	0	0	0%	22:41:59
TOTAL: 17		393186	9000	306243	2535	6	1569	2%	406:28:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	14.3 MB	2.5 MB	16.8 MB	0.2 KB/s	4.1 KB	23:59:51
2	news.freedyn.net	8.9 MB	51.3 KB	9.0 MB	0.1 KB/s	7.1 KB	23:59:51
3	fu-berlin.de	8.0 MB	0.0 KB	8.0 MB	0.1 KB/s	8.4 KB	23:59:56
4	news.bbs.geek.nz	4.3 MB	78.5 KB	4.3 MB	0.1 KB/s	2.6 KB	23:59:54
5	aioe	1.5 MB	26.7 KB	1.5 MB	0.0 KB/s	2.3 KB	23:59:55
6	usenet.pasdenom.info	749.4 KB	8.8 MB	9.6 MB	0.1 KB/s	6.4 KB	23:58:43
7	erje	541.9 KB	234.8 KB	776.7 KB	0.0 KB/s	3.8 KB	23:59:56
8	xmission	511.8 KB	20.2 KB	531.9 KB	0.0 KB/s	2.4 KB	23:59:51
9	au2pb	432.4 KB	32.5 KB	464.9 KB	0.0 KB/s	2.4 KB	23:59:56
10	news.redatomik.org	344.6 KB	22.9 KB	367.5 KB	0.0 KB/s	2.3 KB	23:59:56
11	news.mixmin.net	65.6 KB	1.8 KB	67.4 KB	0.0 KB/s	2.0 KB	23:59:56
12	neva.ru	60.2 KB	817.6 KB	877.8 KB	0.0 KB/s	3.7 KB	23:59:54
13	weretis.net	39.0 KB	44.0 KB	83.0 KB	0.0 KB/s	3.1 KB	23:59:42
14	eternal-september	30.4 KB	0.0 KB	30.4 KB	0.0 KB/s	1.5 KB	23:59:51
15	news.fcku.it	26.4 KB	0.0 KB	26.4 KB	0.0 KB/s	2.6 KB	22:41:59
16	bwh	17.9 KB	0.0 KB	17.9 KB	0.0 KB/s	1.3 KB	23:59:46
17	news.swapon.de	16.8 KB	9.0 KB	25.8 KB	0.0 KB/s	1.5 KB	23:49:43
TOTAL: 17		39.8 MB	12.7 MB	52.5 MB	0.0 KB/s	4.7 KB	406:28:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	68	175.0 KB	60	1	0	04:41:49
2	lortim.demon.co.uk	4	41	76.3 KB	3	0	0	00:44:48
3	68-185-181-64.dhcp.mdsn.wi.charter.com	25	18	27.8 KB	15	13	10	07:36:01
4	mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	50	16	19.1 KB	9	0	0	00:35:03
5	dhcp-089-099-203-254.chello.nl	36	2	2.1 KB	2	0	0	13:02:33
6	94.163.252.42	3	1	1.1 KB	1	0	0	00:00:31
TOTAL: 6		406	146	301.3 KB	90	14	10	55:24:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	454	69	176.0 KB	61	1	0	28:09:48
2	*.demon.co.uk	4	41	76.3 KB	3	0	0	00:44:48
3	*.dhcp.mdsn.wi.charter.com	25	18	27.8 KB	15	13	10	07:36:01
4	*.dsl.bell.ca	50	16	19.1 KB	9	0	0	00:35:03
5	*.chello.nl	36	2	2.1 KB	2	0	0	13:02:33
6	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	0	0	01:45:22
7	*.bbox.io	12	0	0.0 KB	0	0	0	03:09:08
8	*.ip-188-165-239.eu	6	0	0.0 KB	0	0	0	00:03:07
9	*.148.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	00:18:20
10	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:03
TOTAL: 10		594	146	301.3 KB	90	14	10	55:24:17

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	10
TOTAL: 2	298

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
63.142.120.8	11.392	3.618	0.000	17:12:47
dhcp-089-099-203-254.chello.nl	3.280	0.583	0.000	13:02:33
68-185-181-64.dhcp.mdsn.wi.charter.com	2.674	0.549	0.030	07:36:01
162.212.88.153	0.430	0.136	0.000	06:14:37
202.89.176.220	28.151	6.972	0.752	04:41:49
tempe0.bbox.io	1.079	0.213	0.000	03:09:08
pool-71-98-160-36.tampfl.dsl-w.verizon.net	0.359	0.059	0.000	01:45:22
lortim.demon.co.uk	0.469	0.079	0.000	00:44:48
mtrlpq2314w-lp140-01-845526598.dsl.bell.ca	6.878	2.937	0.000	00:35:03
h19.148.22.98.dynamic.ip.windstream.net	0.099	0.023	0.000	00:18:20
ns342002.ip-188-165-239.eu	0.519	0.158	0.000	00:03:07
94.163.252.42	0.249	0.070	0.000	00:00:31
census3.shodan.io	0.073	0.008	0.000	00:00:03
94.167.155.114	0.103	0.008	0.000	00:00:01
TOTAL: 14	55.755	15.413	0.782	55:24:17

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	154
tempe0.bbox.io	12
162.212.88.153	8
ns342002.ip-188-165-239.eu	6
pool-71-98-160-36.tampfl.dsl-w.verizon.net	4
h19.148.22.98.dynamic.ip.windstream.net	2
94.167.155.114	1
census3.shodan.io	1
TOTAL: 8	188

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	22	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
tempe0.bbox.io	6	0
pool-71-98-160-36.tampfl.dsl-w.verizon.net	3	0
lortim.demon.co.uk	1	0
TOTAL: 5	39	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	129	88.4%
2	alt	10	6.8%
3	sci	7	4.8%
TOTAL: 3		146	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	39
2	comp.lang.pl1	39
3	comp.os.vms	13
4	comp.sys.ibm.pc.games.action	13
5	comp.sys.ibm.pc.hardware.storage	13
6	sci.physics	7
7	comp.ai.neural-nets	5
8	alt.drugs.heroin	5
9	comp.os.msdos.programmer	3
10	alt.os.linux.mint	1
11	comp.sys.ibm.pc.games.war-historical	1
12	comp.sys.ibm.pc.hardware.chips	1
13	alt.support.diabetes	1
14	alt.comp.freeware	1
15	comp.os.os2.misc	1
16	alt.de.test	1
17	alt.happy.valley	1
18	comp.os.cpm	1
TOTAL: 18		146

Daily Usenet report for csiph.com

Mar 13 04:15:00 -- Mar 14 04:15:00