Daily Usenet report for csiph.com: Oct 12 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	32023	57.8%	6.9 MB	71.6%
innd	14230	25.7%	1.7 MB	17.7%
nnrpd	5849	10.5%	501.9 KB	5.1%
innfeed	3250	5.9%	537.6 KB	5.5%
nocem	95	0.2%	10.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	55448	100.0%	9.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	351776	55.1%
Negative hits	255066	40.0%
Do not exist	30950	4.8%
Cache misses	463	0.1%
TOTAL: 4	638255	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.497	0.0%	334	0.000	7.476	77.333
article cleanup	00:00:00.246	0.0%	30686	0.000	0.008	0.034
article logging	00:00:06.615	0.0%	31304	0.000	0.211	2.673
article parse	00:00:06.278	0.0%	86922	0.003	0.072	1.585
article write	00:04:32.973	0.3%	22130	0.263	12.335	70.308
artlog/artcncl	00:00:00.100	0.0%	209	0.000	0.478	2.421
artlog/artparse	00:00:00.214	0.0%	451	0.000	0.475	8.571
data move	00:00:00.281	0.0%	608348	0.000	0.000	0.013
hisgrep/artcncl	00:00:00.817	0.0%	334	0.000	2.446	30.533
hishave/artcncl	00:00:00.234	0.0%	334	0.000	0.701	6.833
hishave/artparse	00:00:00.000	0.0%	451	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:30.527	0.2%	640631	0.003	0.235	1.213
history sync	00:00:02.717	0.0%	359	0.000	7.568	122.000
history write	00:04:04.549	0.3%	30651	0.958	7.978	51.984
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:04.820	0.0%	451	0.000	10.687	134.500
idle	23:36:32.656	98.3%	582194	75.590	145.987	374.749
nntp read	00:00:02.861	0.0%	617196	0.002	0.005	0.018
overview write	00:10:13.215	0.7%	22130	0.860	27.710	866.409
perl filter	00:01:13.632	0.1%	30651	1.409	2.402	17.973
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.460	0.0%	44346	0.000	0.010	0.041
TOTAL: 24:00:56.886	23:59:35.692	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.063	0.0%	22112	0.000	0.003	0.029
article prepare	00:00:00.001	0.0%	20410	0.000	0.000	0.012
article read	00:00:01.984	0.0%	3112	0.000	0.638	30.909
backlog stats	00:00:00.236	0.0%	416128	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.238	0.0%	317652	0.002	0.007	0.014
data write	00:00:01.759	0.0%	302923	0.002	0.006	0.011
idle	23:54:41.230	100.0%	416128	103.186	206.862	552.254
status file	00:00:00.706	0.0%	320	0.000	2.206	62.000
TOTAL: 23:54:58.066	23:54:48.217	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:29.070	0.0%	4439	0.521	6.549	21.088
idle	76:58:37.149	100.0%	10164	349.500	27264.576	600116.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.192	0.0%	20741	0.000	0.009	0.167
readart	00:00:00.294	0.0%	87	0.000	3.379	61.000
TOTAL: 76:59:46.599	76:59:06.705	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	5	21157	3681	17462	14	17%	23:39:25
2	csiph-out.feeder.erje.net	1152	95590	3645	88378	3567	3%	95:00:29
3	csiph-out.news.weretis.net	5	44796	3640	40446	710	8%	46:54:24
4	feeder.eternal-september.org	8	26216	2811	22588	817	10%	25:16:50
5	news-out.mixmin.net	3	23425	2446	20078	901	10%	24:08:48
6	news-out.aioe.org	5	21812	1807	19989	16	8%	24:24:01
7	newsfeed.neva.ru	143	26203	1359	22496	2348	5%	72:09:51
8	newsfeed.fu-berlin.de	27	19369	1127	18031	211	5%	25:08:40
9	news.fcku.it	2	9804	736	8803	265	7%	23:59:57
10	news.redatomik.org	2	19754	554	19136	64	2%	23:34:00
11	news.swapon.de	2	3634	120	3506	8	3%	44:35:09
12	nntp.pasdenom.info	2	21280	27	21251	2	0%	23:58:36
13	xanadu-bbs.net	15	730	11	710	9	1%	23:28:16
14	news.bbs.geek.nz	1	19880	6	19874	0	0%	23:59:46
15	news.freedyn.net	2	24402	3	24392	7	0%	23:59:44
16	localhost	36	1	1	0	0	100%	00:00:01
TOTAL: 16		1410	378053	21974	347140	8939	5%	524:17:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	14.9 MB	42.3 KB	18.3 MB	33.2 MB	44%	4.7 KB
2	csiph-out.news.weretis.net	11.5 MB	3.8 KB	15.9 MB	27.4 MB	41%	6.4 KB
3	newsfeeds.xmission.com	10.1 MB	0.0 KB	654.4 KB	10.8 MB	94%	3.0 KB
4	feeder.eternal-september.org	7.9 MB	8.4 KB	7.2 MB	15.1 MB	52%	4.3 KB
5	news-out.mixmin.net	7.9 MB	2.7 KB	1.9 MB	9.8 MB	80%	3.0 KB
6	news-out.aioe.org	3.9 MB	2.7 KB	20.1 KB	3.9 MB	99%	2.2 KB
7	newsfeed.neva.ru	3.6 MB	8.0 KB	10.0 MB	13.6 MB	26%	3.8 KB
8	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	490.6 KB	3.1 MB	84%	2.4 KB
9	news.fcku.it	1.8 MB	0.0 KB	5.2 MB	6.9 MB	25%	7.1 KB
10	news.redatomik.org	1.3 MB	0.0 KB	207.7 KB	1.5 MB	86%	2.4 KB
11	news.swapon.de	309.8 KB	0.0 KB	4.4 MB	4.7 MB	6%	37.8 KB
12	nntp.pasdenom.info	56.1 KB	0.0 KB	6.4 KB	62.5 KB	89%	2.2 KB
13	xanadu-bbs.net	20.9 KB	0.0 KB	17.6 KB	38.6 KB	54%	1.9 KB
14	news.bbs.geek.nz	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	1.5 KB
15	news.freedyn.net	8.5 KB	0.0 KB	365.7 KB	374.1 KB	2%	37.4 KB
16	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 16		65.8 MB	68.0 KB	64.7 MB	130.6 MB	50%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 12 04:15:00 - 04:59:59	462	2.1%	0.17	2.4 MB	3.5%	0.90
Oct 12 05:00:00 - 05:59:59	501	2.3%	0.14	1.9 MB	2.8%	0.53
Oct 12 06:00:00 - 06:59:59	599	2.7%	0.17	1.9 MB	2.8%	0.54
Oct 12 07:00:00 - 07:59:59	876	3.9%	0.24	3.1 MB	4.6%	0.88
Oct 12 08:00:00 - 08:59:59	862	3.9%	0.24	2.2 MB	3.3%	0.63
Oct 12 09:00:00 - 09:59:59	885	4.0%	0.25	2.1 MB	3.2%	0.61
Oct 12 10:00:00 - 10:59:59	873	3.9%	0.24	2.3 MB	3.5%	0.66
Oct 12 11:00:00 - 11:59:59	908	4.1%	0.25	2.1 MB	3.2%	0.60
Oct 12 12:00:00 - 12:59:59	926	4.2%	0.26	2.2 MB	3.3%	0.63
Oct 12 13:00:00 - 13:59:59	1035	4.7%	0.29	2.5 MB	3.7%	0.71
Oct 12 14:00:00 - 14:59:59	1163	5.2%	0.32	3.3 MB	4.9%	0.93
Oct 12 15:00:00 - 15:59:59	1151	5.2%	0.32	3.7 MB	5.5%	1.05
Oct 12 16:00:00 - 16:59:59	1230	5.5%	0.34	3.4 MB	5.0%	0.96
Oct 12 17:00:00 - 17:59:59	1370	6.2%	0.38	3.8 MB	5.7%	1.09
Oct 12 18:00:00 - 18:59:59	1134	5.1%	0.32	3.2 MB	4.8%	0.92
Oct 12 19:00:00 - 19:59:59	1107	5.0%	0.31	2.8 MB	4.2%	0.79
Oct 12 20:00:00 - 20:59:59	1022	4.6%	0.28	3.2 MB	4.7%	0.90
Oct 12 21:00:00 - 21:59:59	930	4.2%	0.26	2.6 MB	4.0%	0.75
Oct 12 22:00:00 - 22:59:59	1671	7.5%	0.46	6.6 MB	9.9%	1.88
Oct 12 23:00:00 - 23:59:59	1188	5.4%	0.33	4.5 MB	6.7%	1.27
Oct 13 00:00:00 - 00:59:59	624	2.8%	0.17	1.8 MB	2.7%	0.52
Oct 13 01:00:00 - 01:59:59	509	2.3%	0.14	1.4 MB	2.1%	0.41
Oct 13 02:00:00 - 02:59:59	495	2.2%	0.14	1.6 MB	2.4%	0.45
Oct 13 03:00:00 - 03:59:59	464	2.1%	0.13	1.2 MB	1.8%	0.35
Oct 13 04:00:00 - 04:15:00	204	0.9%	0.23	1.0 MB	1.5%	1.16
TOTAL: 24:00:00	22189	100.0%	0.26	66.9 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	3587	229	14	3	3341
2	newsfeed.neva.ru	2349	272	6	0	2071
3	news-out.mixmin.net	976	3	2	0	971
4	feeder.eternal-september.org	791	69	3	0	719
5	csiph-out.news.weretis.net	776	350	5	0	421
6	newsfeed.fu-berlin.de	720	22	0	0	698
7	news.fcku.it	298	0	0	0	298
8	news.redatomik.org	45	0	1	0	44
9	newsfeeds.xmission.com	21	5	2	0	14
10	news-out.aioe.org	21	14	2	0	5
11	news.swapon.de	20	0	0	0	20
12	xanadu-bbs.net	9	0	0	0	9
13	news.freedyn.net	7	3	0	0	4
14	nntp.pasdenom.info	2	1	0	0	1
TOTAL: 14		9622	968	35	3	8616

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	191
alt.survival	122
ut.test	71
mpc.lists.freebsd.ports.bugs	43
it.annunci.immobiliari	41
it.annunci.commerciali	40
it.annunci.varie	40
lada.auto	32
gmane.linux.alsa.devel	27
mpc.lists.nanog	26
alt.computer.workshop	25
mozilla.support.seamonkey	16
lada.commerce	13
free.it.religioni.scientology	12
mpc.lists.freebsd.x11	12
ab.test	11
gmane.comp.gis.openstreetmap.tagging	11
it-alt.sport.calcio.juventus	10
oesterreich.tratsch	8
alt.politics	8
TOTAL: 105	968

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

Groups	Count
nz.politics.announce	2
it.scienza.astronomia	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6624
EMP (phn path)	393
EMP (phn nph)	200
Scoring filter (10)	47
HTML Multipart	46
Too many newsgroups	37
Scoring filter (8)	31
Scoring filter (9)	26
Scoring filter (12)	18
Scoring filter (15)	16
Too many newsgroups (low_xpost)	15
User-issued cancel	15
Scoring filter (13)	15
Binary Payload	13
HTML Image Tags	8
Scoring filter (11)	8
Scoring filter (14)	7
Scoring filter (17)	6
Binary: misplaced sfv	5
Scoring filter (16)	5
TOTAL: 26	7543

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	5
	TOTAL: 1	5
Huge articles
	csiph-out.news.weretis.net	184
	csiph-out.feeder.erje.net	166
	news.fcku.it	40
	feeder.eternal-september.org	35
	newsfeeds.xmission.com	11
	news.swapon.de	8
	news.freedyn.net	4
	news-out.mixmin.net	4
	newsfeed.fu-berlin.de	1
	TOTAL: 9	453
Including strange strings
	newsfeed.fu-berlin.de	514
	news-out.mixmin.net	34
	csiph-out.news.weretis.net	28
	csiph-out.feeder.erje.net	15
	news.swapon.de	12
	news.redatomik.org	7
	news-out.aioe.org	5
	newsfeeds.xmission.com	3
	newsfeed.neva.ru	2
	TOTAL: 9	620
TOTAL: 3		1078

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	erje	23805	904	17419	21	0	0	3%	23:52:04
2	news.freedyn.net	23774	838	21187	14	0	0	3%	23:50:07
3	xanadu-bbs	25185	788	21386	0	0	6	3%	23:59:55
4	news.bbs.geek.nz	39668	508	21058	59	0	0	1%	23:54:42
5	fu-berlin.de	20658	468	20189	0	0	1	2%	23:59:50
6	aioe	20492	72	18440	1	0	0	0%	23:50:06
7	usenet.pasdenom.info	22764	57	20827	1280	0	14	0%	23:59:54
8	eternal-september	22926	30	18616	9	1	2	0%	23:59:55
9	news.redatomik.org	22664	14	20967	3	0	0	0%	23:50:07
10	neva.ru	20057	2	19843	92	0	0	0%	23:59:52
11	xmission	19593	2	17877	0	0	1	0%	23:59:38
12	weretis.net	17814	2	17463	0	0	0	0%	23:50:07
13	news.swapon.de	1926	1	1883	0	0	0	0%	23:59:55
14	news.mixmin.net	19353	1	18823	0	0	1	0%	23:59:51
15	news.fcku.it	2039	0	2029	0	0	0	0%	22:49:48
16	au2pb	0	0	0	0	0	21701	0%	23:58:42
17	bwh	0	0	0	0	0	21567	0%	23:50:14
TOTAL: 17		302718	3687	258007	1479	1	43293	1%	405:44:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.4 MB	82.4 KB	7.5 MB	0.1 KB/s	9.0 KB	23:50:07
2	xanadu-bbs	7.1 MB	0.0 KB	7.1 MB	0.1 KB/s	9.2 KB	23:59:55
3	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	13.5 KB	23:59:50
4	erje	2.5 MB	104.3 KB	2.6 MB	0.0 KB/s	2.9 KB	23:52:04
5	news.bbs.geek.nz	1.4 MB	235.3 KB	1.6 MB	0.0 KB/s	2.9 KB	23:54:42
6	usenet.pasdenom.info	220.6 KB	7.5 MB	7.7 MB	0.1 KB/s	5.9 KB	23:59:54
7	aioe	166.3 KB	3.0 KB	169.3 KB	0.0 KB/s	2.3 KB	23:50:06
8	eternal-september	77.8 KB	20.0 KB	97.8 KB	0.0 KB/s	2.5 KB	23:59:55
9	news.redatomik.org	36.2 KB	22.1 KB	58.3 KB	0.0 KB/s	3.4 KB	23:50:07
10	neva.ru	2.6 KB	229.8 KB	232.4 KB	0.0 KB/s	2.5 KB	23:59:52
11	weretis.net	2.5 KB	0.0 KB	2.5 KB	0.0 KB/s	1.2 KB	23:50:07
12	xmission	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	1.0 KB	23:59:38
13	news.swapon.de	0.9 KB	0.0 KB	0.9 KB	0.0 KB/s	0.9 KB	23:59:55
14	news.mixmin.net	0.9 KB	0.0 KB	0.9 KB	0.0 KB/s	0.9 KB	23:59:51
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:49:48
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:14
TOTAL: 17		25.0 MB	8.2 MB	33.2 MB	0.0 KB/s	6.6 KB	405:44:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	48	85	246.9 KB	52	0	0	00:54:43
2	tempe0.bbox.io	7	1	0.9 KB	1	0	0	04:03:18
3	96-42-18-93.dhcp.mdsn.wi.charter.com	15	1	0.9 KB	1	1	1	06:13:49
TOTAL: 3		70	87	248.7 KB	54	1	1	76:59:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	59	85	246.9 KB	52	0	0	62:25:44
2	*.bbox.io	7	1	0.9 KB	1	0	0	04:03:18
3	*.dhcp.mdsn.wi.charter.com	15	1	0.9 KB	1	1	1	06:13:49
4	*.nwrknj.fios.verizon.net	2	0	0.0 KB	0	0	0	01:00:14
5	*.151.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	00:42:57
6	*.cust.centrio.cz	1	0	0.0 KB	0	0	0	00:05:35
7	*.upc-a.chello.nl	15	0	0.0 KB	0	0	0	02:28:04
TOTAL: 7		101	87	248.7 KB	54	1	1	76:59:45

NNRP auth users [Top 20]:

User	Conn
moussa	48
ohlandl	1
ctomczyk	1
TOTAL: 3	50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.182	0.823	0.401	0.000	61:30:51
96-42-18-93.dhcp.mdsn.wi.charter.com	1.234	0.205	0.004	06:13:49
tempe0.bbox.io	0.845	0.165	0.000	04:03:18
a131033.upc-a.chello.nl	1.172	0.182	0.000	02:28:04
pool-74-102-9-20.nwrknj.fios.verizon.net	0.110	0.016	0.000	01:00:14
141.168.117.108	5.393	0.895	0.211	00:54:43
h87.151.22.98.dynamic.ip.windstream.net	0.218	0.231	0.000	00:42:57
194-73-239-109.cust.centrio.cz	0.085	0.017	0.004	00:05:35
45.55.12.155	0.075	0.023	0.000	00:00:09
TOTAL: 9	9.955	2.135	0.219	76:59:45

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	15
216.239.174.182	10
pool-74-102-9-20.nwrknj.fios.verizon.net	2
h87.151.22.98.dynamic.ip.windstream.net	2
45.55.12.155	1
194-73-239-109.cust.centrio.cz	1
TOTAL: 6	31

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.182	8	4
tempe0.bbox.io	8	0
96-42-18-93.dhcp.mdsn.wi.charter.com	7	0
pool-74-102-9-20.nwrknj.fios.verizon.net	2	0
h87.151.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 5	26	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	87	100.0%
TOTAL: 1		87	100.0%

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

Newsgroup		Count
1	comp.os.vms	50
2	comp.arch.embedded	15
3	comp.os.cpm	5
4	comp.sys.ibm.pc.games.action	5
5	comp.sys.ibm.pc.hardware.storage	4
6	comp.sys.ibm.ps2.hardware	3
7	comp.sys.ibm.pc.hardware.chips	2
8	comp.arch.fpga	1
9	comp.arch	1
10	comp.ai.neural-nets	1
TOTAL: 10		87

Daily Usenet report for csiph.com

Oct 12 04:15:00 -- Oct 13 04:15:00