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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	30217	51.3%	6.7 MB	65.9%
nnrpd	13157	22.3%	1.4 MB	14.0%
innd	12337	20.9%	1.5 MB	14.8%
innfeed	3220	5.5%	543.2 KB	5.2%
nocem	24	0.0%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	58956	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	367567	57.0%
Negative hits	246671	38.3%
Do not exist	29269	4.5%
Cache misses	944	0.1%
TOTAL: 4	644451	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.877	0.0%	162	0.000	5.414	30.000
article cleanup	00:00:00.175	0.0%	29327	0.000	0.006	0.036
article logging	00:00:03.274	0.0%	29999	0.000	0.109	1.485
article parse	00:00:01.266	0.0%	74164	0.000	0.017	0.228
article write	00:02:07.346	0.1%	22459	0.182	5.670	41.265
artlog/artcncl	00:00:00.016	0.0%	155	0.000	0.103	1.000
artlog/artparse	00:00:00.002	0.0%	81	0.000	0.025	0.500
data move	00:00:00.175	0.0%	592813	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.199	0.0%	162	0.000	1.228	19.000
hishave/artcncl	00:00:00.020	0.0%	162	0.000	0.123	1.000
hishave/artparse	00:00:00.000	0.0%	80	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:35.357	0.1%	646659	0.002	0.147	0.469
history sync	00:00:04.213	0.0%	336	0.000	12.539	92.000
history write	00:02:31.381	0.2%	29313	0.230	5.164	21.832
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.386	0.0%	80	0.000	4.825	34.500
idle	23:46:56.385	99.1%	580870	85.669	147.393	440.164
nntp read	00:00:02.784	0.0%	604024	0.001	0.005	0.022
overview write	00:05:02.587	0.4%	22459	1.082	13.473	116.477
perl filter	00:00:59.072	0.1%	29312	1.487	2.015	4.505
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.391	0.0%	44939	0.000	0.009	0.036
TOTAL: 24:00:35.381	23:59:25.906	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	24792	0.000	0.003	0.020
article prepare	00:00:00.005	0.0%	36304	0.000	0.000	0.006
article read	00:00:00.995	0.0%	6268	0.000	0.159	2.600
backlog stats	00:00:00.268	0.0%	407818	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.099	0.0%	315230	0.002	0.007	0.012
data write	00:00:01.781	0.0%	298202	0.001	0.006	0.012
idle	23:53:18.684	100.0%	407818	115.293	210.875	592.835
status file	00:00:00.464	0.0%	291	0.000	1.595	28.500
TOTAL: 23:53:36.935	23:53:24.358	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.579	0.0%	16360	0.042	0.097	1.079
idle	46:49:39.932	99.5%	930663	79.321	181.140	600110.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:14.216	0.0%	2423973	0.000	0.006	0.143
readart	00:12:44.957	0.5%	833417	0.000	0.918	61.000
TOTAL: 47:04:09.099	47:02:40.684	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	103241	6249	92845	4147	6%	95:17:54
2	csiph-out.news.weretis.net	4	44467	5251	38968	248	11%	46:51:25
3	feeder.eternal-september.org	2	26490	3325	22732	433	12%	24:56:47
4	news-out.aioe.org	3	21597	1654	19929	14	7%	24:09:46
5	news-out.mixmin.net	3	24726	1628	22544	554	6%	24:08:49
6	newsfeed.fu-berlin.de	37	22515	1370	20988	157	6%	30:10:03
7	newsfeed.neva.ru	142	37244	1103	35024	1117	2%	71:24:49
8	newsfeeds.xmission.com	3	20978	618	20358	2	2%	23:12:57
9	news.redatomik.org	2	16902	576	16290	36	3%	18:04:27
10	news.freedyn.net	4	24679	243	24320	116	0%	24:09:49
11	news.swapon.de	2	2203	96	2106	1	4%	24:34:08
12	news.fcku.it	1	9793	88	9627	78	0%	23:59:56
13	nntp.pasdenom.info	3	21276	60	21216	0	0%	23:58:00
14	xanadu-bbs.net	8	977	13	910	54	1%	23:52:34
15	localhost	38	8	8	0	0	100%	00:00:01
16	news.bbs.geek.nz	3	25742	3	25739	0	0%	32:58:32
TOTAL: 16		1407	402838	22285	373596	6957	5%	511:49:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.0 MB	14.4 KB	12.0 MB	32.0 MB	62%	3.2 KB
2	csiph-out.news.weretis.net	16.2 MB	2.3 KB	4.7 MB	20.9 MB	77%	3.9 KB
3	feeder.eternal-september.org	8.9 MB	12.5 KB	6.7 MB	15.7 MB	57%	4.3 KB
4	news-out.mixmin.net	7.6 MB	0.0 KB	2.7 MB	10.3 MB	73%	4.8 KB
5	news-out.aioe.org	3.9 MB	2.5 KB	19.2 KB	3.9 MB	99%	2.4 KB
6	newsfeed.neva.ru	2.9 MB	5.1 KB	2.0 MB	4.9 MB	60%	2.3 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	422.3 KB	3.3 MB	87%	2.2 KB
8	newsfeeds.xmission.com	1.9 MB	0.0 KB	91.6 KB	2.0 MB	95%	3.3 KB
9	news.redatomik.org	1.5 MB	0.0 KB	101.8 KB	1.6 MB	93%	2.6 KB
10	news.freedyn.net	658.6 KB	0.0 KB	226.6 KB	885.2 KB	74%	2.5 KB
11	news.swapon.de	212.4 KB	0.0 KB	80.3 KB	292.7 KB	72%	3.0 KB
12	news.fcku.it	199.9 KB	2.5 KB	1.7 MB	1.9 MB	10%	11.7 KB
13	nntp.pasdenom.info	120.3 KB	0.0 KB	0.0 KB	120.3 KB	100%	2.0 KB
14	xanadu-bbs.net	39.3 KB	0.0 KB	123.3 KB	162.6 KB	24%	2.4 KB
15	localhost	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	1.3 KB
16	news.bbs.geek.nz	7.2 KB	0.0 KB	0.0 KB	7.2 KB	100%	2.4 KB
TOTAL: 16		67.0 MB	39.2 KB	30.8 MB	97.8 MB	68%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 28 04:15:00 - 04:59:59	538	2.4%	0.20	2.0 MB	3.0%	0.75
Jan 28 05:00:00 - 05:59:59	536	2.4%	0.15	1.7 MB	2.5%	0.47
Jan 28 06:00:00 - 06:59:59	558	2.5%	0.15	1.8 MB	2.8%	0.52
Jan 28 07:00:00 - 07:59:59	639	2.8%	0.18	1.9 MB	2.9%	0.54
Jan 28 08:00:00 - 08:59:59	878	3.9%	0.24	2.6 MB	3.8%	0.73
Jan 28 09:00:00 - 09:59:59	1066	4.7%	0.30	3.0 MB	4.5%	0.86
Jan 28 10:00:00 - 10:59:59	1012	4.5%	0.28	3.2 MB	4.9%	0.92
Jan 28 11:00:00 - 11:59:59	1083	4.8%	0.30	3.7 MB	5.6%	1.06
Jan 28 12:00:00 - 12:59:59	1077	4.8%	0.30	2.9 MB	4.3%	0.82
Jan 28 13:00:00 - 13:59:59	1067	4.8%	0.30	2.9 MB	4.3%	0.81
Jan 28 14:00:00 - 14:59:59	1121	5.0%	0.31	3.1 MB	4.7%	0.89
Jan 28 15:00:00 - 15:59:59	1326	5.9%	0.37	3.5 MB	5.3%	1.00
Jan 28 16:00:00 - 16:59:59	1446	6.4%	0.40	4.3 MB	6.5%	1.23
Jan 28 17:00:00 - 17:59:59	1343	6.0%	0.37	3.8 MB	5.6%	1.07
Jan 28 18:00:00 - 18:59:59	1304	5.8%	0.36	3.5 MB	5.2%	0.99
Jan 28 19:00:00 - 19:59:59	1094	4.9%	0.30	3.1 MB	4.7%	0.90
Jan 28 20:00:00 - 20:59:59	991	4.4%	0.28	2.8 MB	4.1%	0.79
Jan 28 21:00:00 - 21:59:59	1193	5.3%	0.33	3.6 MB	5.3%	1.01
Jan 28 22:00:00 - 22:59:59	1028	4.6%	0.29	3.4 MB	5.1%	0.97
Jan 28 23:00:00 - 23:59:59	832	3.7%	0.23	2.6 MB	3.9%	0.74
Jan 29 00:00:00 - 00:59:59	754	3.4%	0.21	2.5 MB	3.8%	0.72
Jan 29 01:00:00 - 01:59:59	613	2.7%	0.17	1.8 MB	2.7%	0.52
Jan 29 02:00:00 - 02:59:59	425	1.9%	0.12	1.2 MB	1.8%	0.35
Jan 29 03:00:00 - 03:59:59	426	1.9%	0.12	1.3 MB	2.0%	0.37
Jan 29 04:00:00 - 04:15:00	107	0.5%	0.12	320.1 KB	0.5%	0.36
TOTAL: 24:00:00	22457	100.0%	0.26	66.6 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	4199	239	5	3955
2	newsfeed.neva.ru	1129	315	2	812
3	newsfeed.fu-berlin.de	664	18	0	646
4	news-out.mixmin.net	578	1	0	577
5	feeder.eternal-september.org	423	45	5	373
6	csiph-out.news.weretis.net	269	115	1	153
7	news.freedyn.net	116	7	0	109
8	news.fcku.it	73	0	1	72
9	xanadu-bbs.net	54	0	0	54
10	news.redatomik.org	47	0	0	47
11	news.swapon.de	30	0	0	30
12	news-out.aioe.org	20	13	1	6
13	newsfeeds.xmission.com	3	0	0	3
TOTAL: 13		7605	753	15	6837

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	144
ut.test	91
lada.auto	76
lada.commerce	30
mozilla.support.firefox	23
it-alt.sport.calcio.juventus	21
lada.kitchen	19
alt.windows7.general	19
mozilla.support.seamonkey	18
mozilla.general	15
alt.survival	14
gmane.comp.gis.openstreetmap	12
eagle.userchat.eng	10
alt.politics.scorched-earth	9
eagle.support.eng	8
alt.computer.workshop	8
spline.eisfair	7
alt.2600	7
alt.philosophy	7
mozilla.support.thunderbird	7
TOTAL: 123	753

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4309
EMP (phn path)	764
EMP (phl)	498
EMP (phn nph)	227
Too many newsgroups	159
Binary Payload	66
User-issued cancel	17
HTML Multipart	13
Scoring filter (10)	7
HTML post	5
Scoring filter (8)	4
Scoring filter (17)	4
Binary Image: misplaced jpg	3
User-issued spam cancel	2
Scoring filter (9)	2
Scoring filter (13)	1
Scoring filter (16)	1
Too many newsgroups (low_xpost)	1
Binary: misplaced JPG	1
TOTAL: 19	6084

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.weretis.net	46
	news-out.mixmin.net	15
	feeder.eternal-september.org	11
	news.fcku.it	3
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	TOTAL: 7	80
Including strange strings
	newsfeed.fu-berlin.de	505
	csiph-out.feeder.erje.net	60
	news.swapon.de	29
	news-out.mixmin.net	28
	feeder.eternal-september.org	15
	newsfeed.neva.ru	12
	csiph-out.news.weretis.net	9
	news.redatomik.org	7
	news-out.aioe.org	6
	newsfeeds.xmission.com	1
	TOTAL: 10	672
TOTAL: 2		752

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	35826	4608	16962	132	2359	12%	23:55:06
2	news.freedyn.net	23342	896	21246	4	0	3%	23:50:05
3	xanadu-bbs	25088	769	21672	0	3	3%	23:59:51
4	fu-berlin.de	20786	682	20104	0	0	3%	23:59:51
5	usenet.pasdenom.info	25899	192	20738	1463	29	0%	23:59:51
6	news.redatomik.org	21490	48	20923	8	0	0%	23:50:05
7	aioe	19674	46	18809	33	0	0%	23:59:28
8	xmission	24322	28	20960	3	0	0%	23:59:51
9	weretis.net	15893	15	15332	3	0	0%	23:54:37
10	news.swapon.de	2233	10	2203	0	0	0%	23:55:31
11	news.mixmin.net	20000	9	19390	0	0	0%	23:59:51
12	neva.ru	20976	9	20297	22	0	0%	23:59:47
13	eternal-september	19456	8	18224	0	0	0%	23:59:51
14	erje	16219	4	15959	98	0	0%	23:52:46
15	news.fcku.it	1740	1	1718	0	0	0%	23:03:40
16	au2pb	0	0	0	0	21741	0%	23:58:41
17	bwh	0	0	0	0	21681	0%	23:50:07
TOTAL: 17		292944	7325	254537	1766	45813	2%	406:08:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	13.0 MB	593.7 KB	13.6 MB	0.2 KB/s	2.9 KB	23:55:06
2	news.freedyn.net	5.3 MB	3.5 KB	5.3 MB	0.1 KB/s	6.0 KB	23:50:05
3	xanadu-bbs	4.9 MB	0.0 KB	4.9 MB	0.1 KB/s	6.5 KB	23:59:51
4	fu-berlin.de	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	6.8 KB	23:59:51
5	usenet.pasdenom.info	619.7 KB	6.0 MB	6.6 MB	0.1 KB/s	4.1 KB	23:59:51
6	news.redatomik.org	138.5 KB	46.2 KB	184.8 KB	0.0 KB/s	3.3 KB	23:50:05
7	aioe	103.9 KB	129.8 KB	233.7 KB	0.0 KB/s	3.0 KB	23:59:28
8	xmission	77.8 KB	6.3 KB	84.2 KB	0.0 KB/s	2.7 KB	23:59:51
9	weretis.net	31.7 KB	33.6 KB	65.3 KB	0.0 KB/s	3.6 KB	23:54:37
10	erje	15.9 KB	401.4 KB	417.2 KB	0.0 KB/s	4.1 KB	23:52:46
11	news.swapon.de	13.5 KB	0.0 KB	13.5 KB	0.0 KB/s	1.3 KB	23:55:31
12	neva.ru	11.8 KB	66.2 KB	78.0 KB	0.0 KB/s	2.5 KB	23:59:47
13	news.mixmin.net	11.7 KB	0.0 KB	11.7 KB	0.0 KB/s	1.3 KB	23:59:51
14	eternal-september	10.0 KB	0.0 KB	10.0 KB	0.0 KB/s	1.3 KB	23:59:51
15	news.fcku.it	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	23:03:40
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
TOTAL: 17		28.7 MB	7.2 MB	35.9 MB	0.0 KB/s	4.0 KB	406:08:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	240	281256	985.7 MB	763	0	0	22:58:01
2	194-73-239-109.cust.centrio.cz	6	22	61.0 KB	4	0	0	02:01:30
3	68-185-181-64.dhcp.mdsn.wi.charter.com	24	9	12.6 KB	7	7	7	04:47:00
4	dhcp-089-099-203-254.chello.nl	34	8	11.5 KB	6	0	0	06:50:01
5	tempe0.bbox.io	16	3	4.9 KB	2	1	1	04:22:46
TOTAL: 5		320	281298	985.8 MB	782	8	8	47:04:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	240	281256	985.7 MB	763	0	0	22:58:01
2	*.cust.centrio.cz	6	22	61.0 KB	4	0	0	02:01:30
3	*.dhcp.mdsn.wi.charter.com	24	9	12.6 KB	7	7	7	04:47:00
4	*.chello.nl	34	8	11.5 KB	6	0	0	06:50:01
5	*.bbox.io	16	3	4.9 KB	2	1	1	04:22:46
6	unresolved	3	0	0.0 KB	0	0	0	01:47:02
7	*.251.91.75.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	03:47:41
8	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:01
TOTAL: 8		326	281298	985.8 MB	782	8	8	47:04:05

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
ctomczyk	4
kev009	1
TOTAL: 3	12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	72.011	87.706	0.000	22:58:01
dhcp-089-099-203-254.chello.nl	2.880	0.602	0.000	06:50:01
68-185-181-64.dhcp.mdsn.wi.charter.com	2.136	0.459	0.024	04:47:00
tempe0.bbox.io	1.429	0.245	0.006	04:22:46
h85.251.91.75.dynamic.ip.windstream.net	0.231	0.279	0.000	03:47:41
194-73-239-109.cust.centrio.cz	0.650	0.152	0.014	02:01:30
5.51.176.30	0.512	0.950	0.000	01:47:02
c-174-61-130-222.hsd1.wa.comcast.net	0.056	0.008	0.000	00:30:01
TOTAL: 8	79.905	90.401	0.044	47:04:05

Curious NNRP server explorers [Top 20]:

System	Conn
5.51.176.30	3
h85.251.91.75.dynamic.ip.windstream.net	2
c-174-61-130-222.hsd1.wa.comcast.net	1
TOTAL: 3	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
194-73-239-109.cust.centrio.cz	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
c-174-61-130-222.hsd1.wa.comcast.net	1	0
TOTAL: 4	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	talk	281057	99.9%
2	alt	131	0.0%
3	sci	47	0.0%
4	comp	42	0.0%
5	uk	8	0.0%
6	fr	7	0.0%
7	rec	4	0.0%
8	can	1	0.0%
9	aus	1	0.0%
TOTAL: 9		281298	100.0%

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

Newsgroup		Count
1	talk.politics.guns	281049
2	alt.free.newsservers	71
3	sci.physics	45
4	comp.lang.javascript	22
5	comp.sys.ibm.ps2.hardware	18
6	alt.politics.economics	14
7	alt.society.liberalism	12
8	alt.atheism	10
9	talk.politics.misc	8
10	fr.soc.politique	7
11	uk.politics.misc	5
12	alt.california	5
13	alt.global-warming	5
14	alt.christnet.christianlife	4
15	alt.russian.z1	2
16	comp.sys.hp.hpux	2
17	sci.electronics.design	2
18	alt.comp.anti-virus	2
19	rec.arts.tv	2
20	alt.usage.english	2
21	aus.politics	1
22	alt.life.sucks	1
23	rec.motorcycles	1
24	alt.comp.os.windows-10	1
25	alt.comp.hardware.pc-homebuilt	1
26	uk.rec.drugs.cannabis	1
27	uk.rec.driving	1
28	rec.sport.pro-wrestling	1
29	alt.home.repair	1
30	can.politics	1
31	uk.legal	1
TOTAL: 31		281298

Daily Usenet report for csiph.com

Jan 28 04:15:00 -- Jan 29 04:15:00