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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	29393	57.2%	6.4 MB	71.5%
innd	12672	24.7%	1.6 MB	17.3%
nnrpd	5986	11.7%	512.6 KB	5.6%
innfeed	3221	6.3%	512.3 KB	5.5%
nocem	96	0.2%	10.9 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	51369	100.0%	9.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	332806	55.0%
Negative hits	242925	40.2%
Do not exist	28316	4.7%
Cache misses	519	0.1%
TOTAL: 4	604566	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.626	0.0%	292	0.000	8.993	57.000
article cleanup	00:00:00.239	0.0%	28277	0.000	0.008	0.025
article logging	00:00:05.409	0.0%	28869	0.000	0.187	4.070
article parse	00:00:04.318	0.0%	77242	0.003	0.056	1.052
article write	00:04:15.762	0.3%	21125	0.144	12.107	71.522
artlog/artcncl	00:00:00.140	0.0%	277	0.000	0.505	4.333
artlog/artparse	00:00:00.128	0.0%	376	0.000	0.340	3.529
data move	00:00:00.190	0.0%	579100	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.487	0.0%	290	0.000	1.679	37.000
hishave/artcncl	00:00:00.150	0.0%	292	0.000	0.514	6.250
hishave/artparse	00:00:00.002	0.0%	375	0.000	0.005	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:00.277	0.1%	609340	0.001	0.197	1.091
history sync	00:00:02.995	0.0%	363	0.000	8.251	87.000
history write	00:04:08.845	0.3%	28253	0.115	8.808	59.264
hiswrite/artcncl	00:00:00.019	0.0%	2	3.000	9.500	16.000
hiswrite/artparse	00:00:03.157	0.0%	375	0.000	8.419	63.333
idle	23:38:22.052	98.4%	560577	76.102	151.812	446.326
nntp read	00:00:02.828	0.0%	590152	0.001	0.005	0.028
overview write	00:09:15.433	0.6%	21125	0.263	26.293	307.132
perl filter	00:01:05.321	0.1%	28075	1.467	2.327	7.806
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.386	0.0%	42339	0.000	0.009	0.025
TOTAL: 24:00:59.087	23:59:30.764	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.056	0.0%	21257	0.000	0.003	0.016
article prepare	00:00:00.003	0.0%	21404	0.000	0.000	0.016
article read	00:00:02.221	0.0%	2793	0.000	0.795	23.727
backlog stats	00:00:00.259	0.0%	394243	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.093	0.0%	302832	0.002	0.007	0.011
data write	00:00:01.810	0.0%	286331	0.001	0.006	0.011
idle	23:54:25.201	100.0%	394243	121.884	218.305	529.938
status file	00:00:00.693	0.0%	292	0.000	2.373	103.000
TOTAL: 23:54:40.965	23:54:32.336	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.749	0.0%	4116	0.045	1.397	2.175
idle	94:31:12.740	100.0%	10003	414.603	34017.069	600148.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.136	0.0%	20527	0.000	0.007	0.167
readart	00:00:00.341	0.0%	105	0.000	3.248	59.000
TOTAL: 94:32:12.645	94:31:18.966	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1157	92108	6971	82427	2710	7%	96:28:24
2	csiph-out.news.weretis.net	7	43589	3870	39144	575	8%	49:41:19
3	feeder.eternal-september.org	2	25040	2687	21410	943	10%	23:59:05
4	news-out.aioe.org	4	20776	1880	18865	31	9%	24:04:37
5	newsfeed.fu-berlin.de	24	22097	1251	20609	237	5%	27:58:03
6	newsfeed.neva.ru	150	24092	1195	21112	1785	4%	71:41:08
7	newsfeeds.xmission.com	3	18848	1045	17798	5	5%	20:48:47
8	news-out.mixmin.net	2	22690	783	20979	928	3%	23:59:20
9	news.redatomik.org	2	24168	736	23350	82	3%	25:45:03
10	news.fcku.it	2	9561	668	8544	349	6%	23:59:54
11	news.swapon.de	3	1835	72	1762	1	3%	25:05:50
12	nntp.pasdenom.info	2	19738	34	19702	2	0%	23:58:46
13	news.freedyn.net	2	23427	8	23398	21	0%	23:59:47
14	news.bbs.geek.nz	1	22155	1	22154	0	0%	23:59:50
15	localhost	34	0	0	0	0	0%	00:00:07
16	xanadu-bbs.net	15	763	0	763	0	0%	21:45:44
TOTAL: 16		1410	370887	21201	342017	7669	5%	507:15:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	25.2 MB	159.3 KB	15.1 MB	40.4 MB	62%	4.3 KB
2	csiph-out.news.weretis.net	12.1 MB	10.1 KB	15.0 MB	27.1 MB	44%	6.2 KB
3	feeder.eternal-september.org	7.1 MB	5.5 KB	5.2 MB	12.3 MB	57%	3.5 KB
4	news-out.aioe.org	4.6 MB	1.8 KB	97.2 KB	4.7 MB	97%	2.5 KB
5	newsfeeds.xmission.com	3.4 MB	4.4 KB	239.9 KB	3.7 MB	93%	3.6 KB
6	newsfeed.neva.ru	3.3 MB	0.0 KB	7.5 MB	10.8 MB	30%	3.7 KB
7	news-out.mixmin.net	3.0 MB	3.4 KB	1.7 MB	4.7 MB	63%	2.8 KB
8	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	611.1 KB	3.5 MB	83%	2.4 KB
9	news.redatomik.org	2.1 MB	2.7 KB	263.7 KB	2.4 MB	88%	3.0 KB
10	news.fcku.it	1.6 MB	1.7 KB	5.9 MB	7.5 MB	21%	7.6 KB
11	news.swapon.de	179.5 KB	0.0 KB	19.5 KB	199.0 KB	90%	2.7 KB
12	nntp.pasdenom.info	90.3 KB	0.0 KB	1.5 KB	91.8 KB	98%	2.6 KB
13	news.freedyn.net	28.6 KB	0.0 KB	683.0 KB	711.5 KB	4%	24.5 KB
14	news.bbs.geek.nz	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	9.1 KB
15	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		65.6 MB	188.9 KB	52.3 MB	118.1 MB	55%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:00 - 04:59:59	404	1.9%	0.15	1.9 MB	2.9%	0.71
Oct 13 05:00:00 - 05:59:59	623	3.0%	0.17	3.4 MB	5.3%	0.97
Oct 13 06:00:00 - 06:59:59	606	2.9%	0.17	1.8 MB	2.8%	0.51
Oct 13 07:00:00 - 07:59:59	912	4.3%	0.25	2.4 MB	3.7%	0.68
Oct 13 08:00:00 - 08:59:59	837	4.0%	0.23	2.1 MB	3.2%	0.59
Oct 13 09:00:00 - 09:59:59	1074	5.1%	0.30	2.9 MB	4.5%	0.83
Oct 13 10:00:00 - 10:59:59	1089	5.2%	0.30	2.9 MB	4.5%	0.84
Oct 13 11:00:00 - 11:59:59	963	4.6%	0.27	2.7 MB	4.1%	0.76
Oct 13 12:00:00 - 12:59:59	1038	4.9%	0.29	2.7 MB	4.2%	0.78
Oct 13 13:00:00 - 13:59:59	1159	5.5%	0.32	3.9 MB	6.0%	1.10
Oct 13 14:00:00 - 14:59:59	1150	5.5%	0.32	3.4 MB	5.2%	0.96
Oct 13 15:00:00 - 15:59:59	1315	6.2%	0.37	4.2 MB	6.4%	1.18
Oct 13 16:00:00 - 16:59:59	1277	6.1%	0.35	4.2 MB	6.5%	1.19
Oct 13 17:00:00 - 17:59:59	1092	5.2%	0.30	3.6 MB	5.5%	1.01
Oct 13 18:00:00 - 18:59:59	1134	5.4%	0.32	3.5 MB	5.4%	1.00
Oct 13 19:00:00 - 19:59:59	1001	4.8%	0.28	2.7 MB	4.1%	0.76
Oct 13 20:00:00 - 20:59:59	958	4.6%	0.27	3.2 MB	4.9%	0.91
Oct 13 21:00:00 - 21:59:59	868	4.1%	0.24	2.4 MB	3.7%	0.68
Oct 13 22:00:00 - 22:59:59	893	4.2%	0.25	2.8 MB	4.3%	0.80
Oct 13 23:00:00 - 23:59:59	661	3.1%	0.18	2.0 MB	3.0%	0.56
Oct 14 00:00:00 - 00:59:59	522	2.5%	0.14	1.8 MB	2.8%	0.51
Oct 14 01:00:00 - 01:59:59	516	2.5%	0.14	1.6 MB	2.5%	0.46
Oct 14 02:00:00 - 02:59:59	430	2.0%	0.12	1.5 MB	2.3%	0.43
Oct 14 03:00:00 - 03:59:59	413	2.0%	0.11	1.2 MB	1.8%	0.33
Oct 14 04:00:00 - 04:15:00	105	0.5%	0.12	320.2 KB	0.5%	0.36
TOTAL: 24:00:00	21040	100.0%	0.24	64.9 MB	100.0%	0.77

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	2726	291	20	3	2412
2	newsfeed.neva.ru	1782	242	0	1	1539
3	feeder.eternal-september.org	951	26	2	0	923
4	news-out.mixmin.net	857	0	1	0	856
5	newsfeed.fu-berlin.de	704	27	0	0	677
6	csiph-out.news.weretis.net	565	216	1	0	348
7	news.fcku.it	308	1	1	0	306
8	news.redatomik.org	103	0	0	0	103
9	news-out.aioe.org	37	12	0	0	25
10	news.freedyn.net	21	0	0	0	21
11	news.swapon.de	21	0	0	0	21
12	newsfeeds.xmission.com	2	0	0	0	2
13	nntp.pasdenom.info	2	0	0	0	2
TOTAL: 13		8079	815	25	4	7235

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	137
ut.test	60
alt.survival	46
lada.auto	39
it.annunci.immobiliari	37
mpc.lists.nanog	36
it.annunci.commerciali	35
it.annunci.varie	34
gmane.linux.alsa.devel	31
mpc.lists.freebsd.ports.bugs	28
lada.commerce	22
free.it.religioni.scientology	18
alt.computer.workshop	15
mozilla.support.firefox	15
it-alt.sport.calcio.juventus	12
spline.fli4l.dev	10
gmane.comp.gis.openstreetmap.tagging	10
mozilla.tools.marionette	10
mozilla.support.seamonkey	10
ab.test	9
TOTAL: 109	815

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

Groups	Count
uk.legal.moderated	1
alt.culture.china	1
nz.politics.announce	1
it.scienza.astronomia	1
TOTAL: 4	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5194
EMP (phn path)	462
EMP (phn nph)	229
Scoring filter (10)	47
HTML Multipart	25
Too many newsgroups	24
User-issued cancel	20
Scoring filter (8)	20
Binary Payload	17
Scoring filter (12)	12
HTML post	7
Scoring filter (11)	7
Binary: misplaced sfv	6
Scoring filter (15)	3
Scoring filter (9)	3
EMP (phl)	3
Poison newsgroup	2
Binary Image: misplaced jpg	2
Angle-bracket bot	2
Too many newsgroups (low_xpost)	2
TOTAL: 27	6095

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	177
	TOTAL: 1	177
Huge articles
	csiph-out.feeder.erje.net	166
	csiph-out.news.weretis.net	109
	news.fcku.it	57
	feeder.eternal-september.org	25
	news.freedyn.net	7
	news-out.mixmin.net	4
	news.redatomik.org	1
	newsfeeds.xmission.com	1
	TOTAL: 8	370
Including strange strings
	newsfeed.fu-berlin.de	453
	csiph-out.news.weretis.net	49
	csiph-out.feeder.erje.net	34
	news.swapon.de	20
	news.redatomik.org	13
	news-out.mixmin.net	7
	news-out.aioe.org	6
	feeder.eternal-september.org	6
	newsfeed.neva.ru	3
	newsfeeds.xmission.com	1
	TOTAL: 10	592
TOTAL: 3		1139

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	22535	975	20052	6	0	4%	23:59:55
2	xanadu-bbs	24833	841	20194	1	108	3%	23:59:55
3	news.bbs.geek.nz	37266	717	19648	65	0	1%	23:59:55
4	fu-berlin.de	19624	591	19027	0	0	3%	23:59:50
5	usenet.pasdenom.info	21845	130	19232	1644	11	0%	23:59:55
6	aioe	19647	111	17712	13	0	0%	23:59:52
7	erje	14551	46	13978	4	103	0%	23:59:55
8	news.redatomik.org	21333	36	19661	18	11	0%	23:59:55
9	eternal-september	19158	17	17510	1	0	0%	23:59:55
10	weretis.net	18050	4	16196	0	0	0%	23:59:55
11	neva.ru	19136	4	18848	119	0	0%	23:59:55
12	xmission	20764	3	19182	1	0	0%	23:59:55
13	news.swapon.de	1904	2	1860	0	0	0%	23:59:00
14	news.mixmin.net	19623	1	19087	0	0	0%	23:59:55
15	news.fcku.it	2049	0	2037	0	0	0%	21:47:15
16	bwh	0	0	0	0	20414	0%	23:58:42
17	au2pb	0	0	0	0	20429	0%	23:58:42
TOTAL: 17		282318	3478	244224	1872	41076	1%	405:42:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.4 MB	25.3 KB	9.4 MB	0.1 KB/s	9.8 KB	23:59:55
2	xanadu-bbs	9.0 MB	1.8 KB	9.0 MB	0.1 KB/s	10.9 KB	23:59:55
3	fu-berlin.de	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	14.3 KB	23:59:50
4	news.bbs.geek.nz	1.9 MB	223.8 KB	2.1 MB	0.0 KB/s	2.7 KB	23:59:55
5	usenet.pasdenom.info	394.5 KB	10.0 MB	10.4 MB	0.1 KB/s	6.0 KB	23:59:55
6	aioe	295.7 KB	29.2 KB	324.8 KB	0.0 KB/s	2.6 KB	23:59:52
7	erje	165.4 KB	15.1 KB	180.4 KB	0.0 KB/s	3.6 KB	23:59:55
8	news.redatomik.org	128.3 KB	52.2 KB	180.5 KB	0.0 KB/s	3.3 KB	23:59:55
9	eternal-september	39.7 KB	1.5 KB	41.2 KB	0.0 KB/s	2.3 KB	23:59:55
10	weretis.net	14.9 KB	0.0 KB	14.9 KB	0.0 KB/s	3.7 KB	23:59:55
11	news.swapon.de	12.7 KB	0.0 KB	12.7 KB	0.0 KB/s	6.3 KB	23:59:00
12	neva.ru	12.3 KB	306.4 KB	318.7 KB	0.0 KB/s	2.6 KB	23:59:55
13	xmission	5.5 KB	2.4 KB	8.0 KB	0.0 KB/s	2.0 KB	23:59:55
14	news.mixmin.net	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	4.4 KB	23:59:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:47:15
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		29.5 MB	10.7 MB	40.2 MB	0.0 KB/s	7.7 KB	405:42:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	141.168.117.108	48	104	278.6 KB	68	00:41:44
2	a131033.upc-a.chello.nl	37	1	0.9 KB	1	06:57:47
TOTAL: 2		85	105	279.5 KB	69	94:32:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	56	104	278.6 KB	68	78:21:17
2	*.upc-a.chello.nl	37	1	0.9 KB	1	06:57:47
3	*.cust.centrio.cz	7	0	0.0 KB	0	02:04:08
4	*.19-1.cable.virginm.net	1	0	0.0 KB	0	00:30:12
5	*.dhcp.mdsn.wi.charter.com	12	0	0.0 KB	0	02:57:40
6	*.nwrknj.fios.verizon.net	2	0	0.0 KB	0	01:00:18
7	*.bbox.io	6	0	0.0 KB	0	02:40:46
TOTAL: 7		121	105	279.5 KB	69	94:32:11

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	7
TOTAL: 2	55

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.182	0.719	0.583	0.000	77:39:33
a131033.upc-a.chello.nl	2.927	0.424	0.000	06:57:47
96-42-18-93.dhcp.mdsn.wi.charter.com	0.914	0.166	0.000	02:57:40
tempe0.bbox.io	0.610	0.101	0.000	02:40:46
194-73-239-109.cust.centrio.cz	0.492	0.112	0.024	02:04:08
pool-74-102-9-20.nwrknj.fios.verizon.net	0.135	0.025	0.000	01:00:18
141.168.117.108	5.408	1.183	0.207	00:41:44
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.114	0.008	0.000	00:30:12
TOTAL: 8	11.319	2.602	0.231	94:32:11

Curious NNRP server explorers [Top 20]:

System	Conn
96-42-18-93.dhcp.mdsn.wi.charter.com	12
216.239.174.182	8
194-73-239-109.cust.centrio.cz	7
tempe0.bbox.io	6
pool-74-102-9-20.nwrknj.fios.verizon.net	2
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1
TOTAL: 6	36

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	5	0
216.239.174.182	4	0
194-73-239-109.cust.centrio.cz	4	0
96-42-18-93.dhcp.mdsn.wi.charter.com	3	0
pool-74-102-9-20.nwrknj.fios.verizon.net	2	0
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1	0
TOTAL: 6	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	104	99.0%
2	news	1	1.0%
TOTAL: 2		105	100.0%

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

Newsgroup		Count
1	comp.os.vms	41
2	comp.arch	25
3	comp.sys.ibm.pc.hardware.storage	9
4	comp.sys.ibm.pc.games.action	7
5	comp.os.cpm	6
6	comp.arch.embedded	5
7	comp.ai.philosophy	4
8	comp.os.os2.announce	3
9	comp.sys.ibm.as400.misc	1
10	comp.sys.ibm.ps2.hardware	1
11	news.admin.peering	1
12	comp.arch.fpga	1
13	comp.os.msdos.misc	1
TOTAL: 13		105

Daily Usenet report for csiph.com

Oct 13 04:15:00 -- Oct 14 04:15:00