Daily Usenet report for csiph.com: Jul 29 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45044	52.8%	8.4 MB	65.2%
innd	24923	29.2%	2.7 MB	20.8%
nnrpd	12145	14.2%	1.3 MB	9.8%
innfeed	3151	3.7%	552.9 KB	4.2%
nocem	66	0.1%	7.1 KB	0.1%
TOTAL: 5	85329	100.0%	12.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	510151	57.6%
Negative hits	330235	37.3%
Do not exist	43897	5.0%
Cache misses	674	0.1%
TOTAL: 4	884957	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.320	0.0%	219	0.000	6.027	32.333
article cleanup	00:00:00.303	0.0%	43944	0.000	0.007	0.036
article logging	00:00:00.973	0.0%	44548	0.000	0.022	0.544
article parse	00:00:02.294	0.0%	170193	0.004	0.013	0.106
article write	00:06:01.655	0.4%	23717	0.565	15.249	54.522
artlog/artcncl	00:00:00.003	0.0%	197	0.000	0.015	0.167
artlog/artparse	00:00:00.001	0.0%	332	0.000	0.003	1.000
data move	00:00:00.325	0.0%	825521	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.116	0.0%	218	0.000	0.532	7.333
hishave/artcncl	00:00:00.001	0.0%	219	0.000	0.005	0.056
hishave/artparse	00:00:00.001	0.0%	326	0.000	0.003	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:28.483	0.0%	888474	0.002	0.032	0.223
history sync	00:00:03.741	0.0%	354	0.000	10.568	96.000
history write	00:02:10.863	0.2%	43777	0.213	2.989	24.605
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.736	0.0%	326	0.000	2.258	51.500
idle	23:46:40.605	99.0%	843265	64.691	101.511	272.083
nntp read	00:00:02.899	0.0%	864375	0.002	0.003	0.006
overview write	00:02:18.066	0.2%	23717	0.907	5.821	77.228
perl filter	00:01:59.262	0.1%	43731	1.447	2.727	5.053
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.422	0.0%	47492	0.000	0.009	0.026
TOTAL: 24:00:47.997	23:59:52.069	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.076	0.0%	23688	0.000	0.003	0.026
article prepare	00:00:00.002	0.0%	24472	0.000	0.000	0.006
article read	00:00:04.979	0.0%	2910	0.000	1.711	44.500
backlog stats	00:00:00.264	0.0%	539473	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.255	0.0%	400251	0.003	0.006	0.010
data write	00:00:02.662	0.0%	383357	0.003	0.007	0.023
idle	23:53:22.843	100.0%	539473	92.126	159.420	440.476
status file	00:00:00.734	0.0%	271	0.000	2.708	131.000
TOTAL: 23:53:42.253	23:53:33.815	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	25:42:28.794	99.9%	25019	78.395	3699.140	600063.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.316	0.0%	50285	0.000	0.006	0.500
readart	00:01:27.157	0.1%	10376	0.000	8.400	68.833
TOTAL: 25:44:42.768	25:43:56.267	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	28279	5566	20137	2576	19%	23:44:49
2	csiph-out.feeder.erje.net	1152	138974	5320	124835	8819	3%	94:58:38
3	feeder.eternal-september.org	5	37441	3376	32510	1555	9%	23:50:28
4	news-out.aioe.org	2	22918	2163	20718	37	9%	24:09:57
5	csiph-out.news.weretis.net	5	64809	1682	62035	1092	2%	54:34:29
6	news-out.mixmin.net	2	26159	1654	24098	407	6%	24:08:52
7	newsfeed.neva.ru	144	60733	1446	53597	5690	2%	71:46:25
8	newsfeed.fu-berlin.de	39	24971	1073	23513	385	4%	29:08:32
9	newsfeeds.xmission.com	1	24674	545	24085	44	2%	25:10:13
10	news.fcku.it	1	10140	358	9725	57	3%	24:50:23
11	au2pb.net	1	19971	212	19716	43	1%	17:11:39
12	news.freedyn.net	3	26729	81	26599	49	0%	24:09:30
13	news.redatomik.org	2	9262	69	9177	16	0%	12:11:25
14	news.swapon.de	9	1788	63	1724	1	3%	24:15:24
15	nntp.pasdenom.info	4	24246	52	24175	19	0%	24:07:54
16	news.bbs.geek.nz	2	24378	12	24366	0	0%	23:59:41
17	localhost	41	6	6	0	0	100%	00:00:00
18	xanadu-bbs.net	18	576	3	566	7	0%	22:22:12
TOTAL: 18		1719	546054	23681	501576	20797	4%	544:40:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.4 MB	820.4 KB	44.0 MB	65.2 MB	31%	4.7 KB
2	usenet.blueworldhosting.com	16.4 MB	3.3 KB	27.6 MB	44.0 MB	37%	5.5 KB
3	feeder.eternal-september.org	8.6 MB	405.4 KB	22.3 MB	31.3 MB	27%	6.5 KB
4	news-out.mixmin.net	6.3 MB	26.8 KB	1.6 MB	7.9 MB	79%	3.9 KB
5	news-out.aioe.org	5.6 MB	2.1 KB	100.4 KB	5.7 MB	98%	2.6 KB
6	csiph-out.news.weretis.net	5.0 MB	10.6 KB	15.7 MB	20.6 MB	24%	7.6 KB
7	newsfeed.neva.ru	3.9 MB	190.3 KB	53.5 MB	57.6 MB	6%	8.3 KB
8	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	766.1 KB	3.1 MB	76%	2.2 KB
9	newsfeeds.xmission.com	1.8 MB	0.0 KB	792.1 KB	2.6 MB	69%	4.5 KB
10	news.fcku.it	683.1 KB	1.4 KB	2.9 MB	3.6 MB	18%	8.8 KB
11	au2pb.net	463.4 KB	0.0 KB	213.3 KB	676.7 KB	68%	2.7 KB
12	news.freedyn.net	296.1 KB	5.7 KB	2.1 MB	2.4 MB	12%	18.9 KB
13	news.redatomik.org	155.8 KB	2.2 KB	453.8 KB	611.8 KB	25%	7.2 KB
14	news.swapon.de	153.8 KB	0.0 KB	52.6 KB	206.4 KB	74%	3.2 KB
15	nntp.pasdenom.info	95.1 KB	22.1 KB	41.2 KB	158.4 KB	60%	2.2 KB
16	news.bbs.geek.nz	20.1 KB	0.0 KB	0.0 KB	20.1 KB	100%	1.7 KB
17	xanadu-bbs.net	10.7 KB	0.0 KB	56.4 KB	67.0 KB	15%	6.7 KB
18	localhost	8.0 KB	0.0 KB	0.0 KB	8.0 KB	100%	1.3 KB
TOTAL: 18		72.2 MB	1.5 MB	172.0 MB	245.6 MB	29%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 29 04:15:00 - 04:59:59	534	2.3%	0.20	2.5 MB	3.4%	0.94
Jul 29 05:00:00 - 05:59:59	575	2.4%	0.16	1.8 MB	2.5%	0.52
Jul 29 06:00:00 - 06:59:59	738	3.1%	0.20	2.2 MB	3.0%	0.62
Jul 29 07:00:00 - 07:59:59	1091	4.6%	0.30	4.9 MB	6.8%	1.39
Jul 29 08:00:00 - 08:59:59	1119	4.7%	0.31	3.1 MB	4.3%	0.87
Jul 29 09:00:00 - 09:59:59	1200	5.1%	0.33	3.0 MB	4.2%	0.85
Jul 29 10:00:00 - 10:59:59	1269	5.4%	0.35	3.7 MB	5.2%	1.06
Jul 29 11:00:00 - 11:59:59	1126	4.8%	0.31	2.6 MB	3.6%	0.75
Jul 29 12:00:00 - 12:59:59	1198	5.1%	0.33	3.4 MB	4.7%	0.97
Jul 29 13:00:00 - 13:59:59	1252	5.3%	0.35	3.3 MB	4.6%	0.95
Jul 29 14:00:00 - 14:59:59	1418	6.0%	0.39	4.6 MB	6.4%	1.32
Jul 29 15:00:00 - 15:59:59	1321	5.6%	0.37	3.9 MB	5.3%	1.10
Jul 29 16:00:00 - 16:59:59	1338	5.6%	0.37	4.0 MB	5.6%	1.15
Jul 29 17:00:00 - 17:59:59	1151	4.9%	0.32	3.3 MB	4.5%	0.93
Jul 29 18:00:00 - 18:59:59	1286	5.4%	0.36	3.9 MB	5.4%	1.11
Jul 29 19:00:00 - 19:59:59	1210	5.1%	0.34	3.4 MB	4.7%	0.96
Jul 29 20:00:00 - 20:59:59	1108	4.7%	0.31	3.2 MB	4.4%	0.90
Jul 29 21:00:00 - 21:59:59	1077	4.5%	0.30	3.4 MB	4.7%	0.96
Jul 29 22:00:00 - 22:59:59	735	3.1%	0.20	2.5 MB	3.4%	0.70
Jul 29 23:00:00 - 23:59:59	833	3.5%	0.23	2.4 MB	3.3%	0.68
Jul 30 00:00:00 - 00:59:59	596	2.5%	0.17	2.1 MB	2.9%	0.59
Jul 30 01:00:00 - 01:59:59	555	2.3%	0.15	1.7 MB	2.4%	0.50
Jul 30 02:00:00 - 02:59:59	417	1.8%	0.12	1.3 MB	1.9%	0.38
Jul 30 03:00:00 - 03:59:59	452	1.9%	0.13	1.6 MB	2.2%	0.45
Jul 30 04:00:00 - 04:15:00	104	0.4%	0.12	438.5 KB	0.6%	0.49
TOTAL: 24:00:00	23703	100.0%	0.27	72.2 MB	100.0%	0.86

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	8650	585	82	7983
2	newsfeed.neva.ru	5752	751	20	4981
3	usenet.blueworldhosting.com	2581	1010	2	1569
4	feeder.eternal-september.org	1635	125	41	1469
5	csiph-out.news.weretis.net	990	222	2	766
6	newsfeed.fu-berlin.de	800	30	0	770
7	news-out.mixmin.net	426	6	13	407
8	news.fcku.it	62	0	1	61
9	news-out.aioe.org	52	32	0	20
10	au2pb.net	52	46	0	6
11	newsfeeds.xmission.com	48	35	0	13
12	news.freedyn.net	46	0	1	45
13	nntp.pasdenom.info	16	1	9	6
14	news.redatomik.org	15	0	2	13
15	news.swapon.de	13	0	0	13
16	xanadu-bbs.net	7	0	0	7
TOTAL: 16		21145	2843	173	18129

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	400
mailing.freebsd.pkg-fallout	195
lada.talk	162
lada.auto	139
ut.test	97
it-alt.sport.calcio.juventus	85
fa.freebsd.cvs-all	79
mailing.freebsd.svn-src-head	79
mailing.freebsd.svn-src-all	79
mailing.freebsd.svn-ports-all	74
mailing.freebsd.svn-ports-head	72
mailing.freebsd.ports-bugs	70
mailing.gcc.gcc-cvs	57
mailing.database.pgsql-general	55
alt.windows7.general	47
mozilla.general	37
alt.politics.scorched-earth	35
mpc.lists.freebsd.ports.bugs	35
mailing.unix.spamassassin-talk	32
it.annunci.commerciali	29
TOTAL: 215	2843

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12952
EMP (phn path)	2555
PFM bot misc	672
EMP (phl)	643
EMP (phn nph)	113
Too many newsgroups	43
HTML Multipart	31
User-issued spam cancel	15
User-issued cancel	12
Binary Payload	12
Scoring filter (19)	11
Scoring filter (10)	9
Scoring filter (11)	9
Scoring filter (8)	7
Scoring filter (21)	6
Scoring filter (17)	6
Scoring filter (9)	5
Scoring filter (18)	5
Scoring filter (15)	5
Scoring filter (20)	5
TOTAL: 37	17153

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	39
	usenet.blueworldhosting.com	1
	TOTAL: 2	40
Huge articles
	csiph-out.news.weretis.net	89
	usenet.blueworldhosting.com	82
	feeder.eternal-september.org	71
	news.freedyn.net	25
	news.fcku.it	21
	news-out.mixmin.net	17
	newsfeeds.xmission.com	11
	news.redatomik.org	6
	au2pb.net	2
	news.swapon.de	1
	TOTAL: 11	326
Including strange strings
	newsfeed.fu-berlin.de	413
	csiph-out.feeder.erje.net	55
	feeder.eternal-september.org	34
	news-out.mixmin.net	25
	csiph-out.news.weretis.net	24
	newsfeed.neva.ru	22
	news-out.aioe.org	15
	news.swapon.de	12
	usenet.blueworldhosting.com	3
	news.fcku.it	1
	TOTAL: 10	604
No colon-space in header
	usenet.blueworldhosting.com	6
	TOTAL: 1	6
TOTAL: 4		976

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	43650	1205	21538	38	1	2%	23:59:56
2	news.freedyn.net	26517	1083	22524	5	0	4%	23:59:56
3	xanadu-bbs	26363	1042	22636	6	0	3%	23:59:56
4	fu-berlin.de	22334	811	21523	0	0	3%	23:59:56
5	erje	21084	127	18032	20	0	0%	23:59:56
6	weretis.net	22226	115	20756	24	0	0%	23:59:55
7	usenet.pasdenom.info	30568	109	22303	1237	20	0%	23:59:56
8	aioe	22023	71	19604	4	0	0%	23:59:19
9	xmission	26172	69	22255	2	0	0%	23:59:56
10	au2pb	24332	47	22499	0	0	0%	23:59:56
11	news.redatomik.org	23517	46	22303	1	6	0%	23:59:37
12	eternal-september	22133	17	18986	0	0	0%	23:59:56
13	news.mixmin.net	23217	12	20800	0	0	0%	23:59:43
14	bwh	18932	6	16903	0	0	0%	23:59:56
15	neva.ru	23440	3	20845	25	0	0%	23:59:47
16	news.swapon.de	1935	1	1791	5	1	0%	23:41:01
17	news.fcku.it	2819	0	2508	0	0	0%	22:33:34
TOTAL: 17		381262	4764	317806	1367	28	1%	406:12:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.3 MB	21.8 KB	9.4 MB	0.1 KB/s	8.8 KB	23:59:56
2	xanadu-bbs	9.0 MB	15.7 KB	9.0 MB	0.1 KB/s	8.8 KB	23:59:56
3	fu-berlin.de	8.5 MB	0.0 KB	8.5 MB	0.1 KB/s	10.8 KB	23:59:56
4	news.bbs.geek.nz	3.2 MB	113.3 KB	3.4 MB	0.0 KB/s	2.8 KB	23:59:56
5	erje	605.4 KB	74.8 KB	680.3 KB	0.0 KB/s	4.6 KB	23:59:56
6	usenet.pasdenom.info	472.3 KB	9.3 MB	9.8 MB	0.1 KB/s	7.5 KB	23:59:56
7	weretis.net	295.7 KB	203.9 KB	499.6 KB	0.0 KB/s	3.6 KB	23:59:55
8	aioe	224.3 KB	10.5 KB	234.8 KB	0.0 KB/s	3.1 KB	23:59:19
9	xmission	201.2 KB	6.8 KB	208.0 KB	0.0 KB/s	2.9 KB	23:59:56
10	news.redatomik.org	151.0 KB	4.5 KB	155.5 KB	0.0 KB/s	3.3 KB	23:59:37
11	au2pb	138.1 KB	0.0 KB	138.1 KB	0.0 KB/s	2.9 KB	23:59:56
12	eternal-september	47.9 KB	0.0 KB	47.9 KB	0.0 KB/s	2.8 KB	23:59:56
13	news.mixmin.net	18.0 KB	0.0 KB	18.0 KB	0.0 KB/s	1.5 KB	23:59:43
14	neva.ru	8.6 KB	143.9 KB	152.5 KB	0.0 KB/s	5.4 KB	23:59:47
15	bwh	8.0 KB	0.0 KB	8.0 KB	0.0 KB/s	1.3 KB	23:59:56
16	news.swapon.de	1.7 KB	6.3 KB	8.0 KB	0.0 KB/s	1.3 KB	23:41:01
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:33:34
TOTAL: 17		32.2 MB	9.9 MB	42.2 MB	0.0 KB/s	7.0 KB	406:12:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-1178028573.dsl.bell.ca	81	73	304.6 KB	55	0	0	00:37:06
2	tempe0.bbox.io	15	10	12.6 KB	3	0	0	04:08:28
3	dhcp-089-099-203-254.chello.nl	53	7	8.3 KB	5	0	0	13:55:05
4	68-185-181-64.dhcp.mdsn.wi.charter.com	16	7	8.9 KB	6	6	6	04:10:03
TOTAL: 4		165	97	334.4 KB	69	6	6	25:44:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	81	73	304.6 KB	55	0	0	00:37:06
2	*.bbox.io	15	10	12.6 KB	3	0	0	04:08:28
3	*.dhcp.mdsn.wi.charter.com	16	7	8.9 KB	6	6	6	04:10:03
4	*.chello.nl	53	7	8.3 KB	5	0	0	13:55:05
5	*.hsd1.nj.comcast.net	8	0	0.0 KB	0	0	0	02:53:52
6	unresolved	1	0	0.0 KB	0	0	0	00:00:03
TOTAL: 6		174	97	334.4 KB	69	6	6	25:44:41

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	4.765	1.123	0.000	13:55:05
68-185-181-64.dhcp.mdsn.wi.charter.com	1.559	0.354	0.017	04:10:03
tempe0.bbox.io	1.509	0.361	0.000	04:08:28
c-73-195-247-24.hsd1.nj.comcast.net	0.573	0.108	0.000	02:53:52
mtrlpq2314w-lp140-06-1178028573.dsl.bell.ca	11.135	4.073	0.000	00:37:06
89.248.167.131	0.072	0.024	0.000	00:00:03
TOTAL: 6	19.613	6.043	0.017	25:44:41

Curious NNRP server explorers [Top 20]:

System	Conn
c-73-195-247-24.hsd1.nj.comcast.net	8
89.248.167.131	1
TOTAL: 2	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	8	0
c-73-195-247-24.hsd1.nj.comcast.net	5	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
TOTAL: 3	17	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	39	40.2%
2	comp	30	30.9%
3	uk	12	12.4%
4	rec	8	8.2%
5	misc	3	3.1%
6	can	1	1.0%
7	soc	1	1.0%
8	talk	1	1.0%
9	linux	1	1.0%
10	fr	1	1.0%
TOTAL: 10		97	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	24
2	alt.comp.freeware	11
3	uk.legal	7
4	alt.california	6
5	alt.christnet.christianlife	6
6	alt.atheism	3
7	misc.survivalism	3
8	uk.rec.sheds	3
9	comp.sys.mac.system	2
10	alt.fan.rush-limbaugh	2
11	alt.home.repair	2
12	rec.boats	2
13	alt.happy.valley	2
14	rec.autos.sport.f1	2
15	comp.lang.python	2
16	rec.arts.tv.uk.coronation-st	2
17	alt.usage.english	2
18	rec.sport.pro-wrestling	1
19	rec.arts.tv	1
20	fr.soc.politique	1
21	talk.politics.misc	1
22	linux.kernel	1
23	uk.media.radio.archers	1
24	comp.mobile.android	1
25	alt.conspiracy	1
26	comp.databases.oracle.server	1
27	alt.books.louis-lamour	1
28	soc.culture.usa	1
29	alt.life.sucks	1
30	alt.os.linux.ubuntu	1
31	can.politics	1
32	alt.os.linux	1
33	uk.politics.misc	1
TOTAL: 33		97

Daily Usenet report for csiph.com

Jul 29 04:15:00 -- Jul 30 04:15:00