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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40920	58.4%	7.7 MB	70.0%
innd	22458	32.1%	2.4 MB	21.6%
nnrpd	3415	4.9%	416.1 KB	3.7%
innfeed	3162	4.5%	524.5 KB	4.6%
nocem	69	0.1%	7.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	70025	100.0%	11.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	410590	54.6%
Negative hits	300372	40.0%
Do not exist	39718	5.3%
Cache misses	902	0.1%
TOTAL: 4	751582	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.324	0.0%	257	0.000	5.152	34.000
article cleanup	00:00:00.249	0.0%	39960	0.000	0.006	0.032
article logging	00:00:05.507	0.0%	40603	0.000	0.136	1.359
article parse	00:00:04.690	0.0%	137290	0.003	0.034	0.632
article write	00:03:18.501	0.2%	23007	0.163	8.628	44.676
artlog/artcncl	00:00:00.006	0.0%	189	0.000	0.032	0.357
artlog/artparse	00:00:00.088	0.0%	214	0.000	0.411	4.571
data move	00:00:00.274	0.0%	687842	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.550	0.0%	256	0.000	2.148	18.000
hishave/artcncl	00:00:00.175	0.0%	257	0.000	0.681	7.875
hishave/artparse	00:00:00.001	0.0%	214	0.000	0.005	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:05.026	0.1%	755051	0.002	0.166	1.059
history sync	00:00:05.922	0.0%	339	0.000	17.469	176.000
history write	00:03:59.020	0.3%	39713	0.300	6.019	43.083
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:03.129	0.0%	214	0.000	14.621	363.000
idle	23:42:26.250	98.7%	668375	71.136	127.692	462.609
nntp read	00:00:03.170	0.0%	700600	0.002	0.005	0.010
overview write	00:05:30.163	0.4%	23007	0.627	14.351	133.092
perl filter	00:01:37.677	0.1%	39713	1.432	2.460	3.957
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.467	0.0%	46075	0.000	0.010	0.034
TOTAL: 24:00:46.692	23:59:22.189	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.066	0.0%	22967	0.000	0.003	0.019
article prepare	00:00:00.003	0.0%	28824	0.000	0.000	0.006
article read	00:00:01.270	0.0%	4797	0.000	0.265	16.233
backlog stats	00:00:00.240	0.0%	433807	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.145	0.0%	329640	0.002	0.007	0.013
data write	00:00:01.758	0.0%	312063	0.002	0.006	0.012
idle	23:53:16.220	100.0%	433807	113.716	198.236	591.915
status file	00:00:00.389	0.0%	274	0.000	1.420	27.000
TOTAL: 23:53:30.317	23:53:22.091	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.119	0.0%	8	1.000	14.875	45.000
idle	20:04:21.993	95.7%	13564	78.500	5327.484	600061.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:38:39.245	3.1%	4916174	0.000	0.472	1842.596
readart	00:00:55.883	0.1%	8753	0.000	6.384	63.000
TOTAL: 20:57:59.335	20:43:57.240	98.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	128930	6749	112935	9246	5%	95:02:58
2	csiph-out.news.weretis.net	2	45751	5114	39864	773	11%	43:39:25
3	feeder.eternal-september.org	4	37349	3195	29932	4222	8%	24:44:25
4	news-out.mixmin.net	2	24841	2219	22028	594	8%	24:08:57
5	news.redatomik.org	10	22899	1659	21214	26	7%	24:13:12
6	newsfeed.neva.ru	152	46544	1440	41962	3142	3%	72:28:48
7	newsfeed.fu-berlin.de	38	24147	1194	22507	446	4%	31:17:32
8	newsfeeds.xmission.com	3	24056	842	23203	11	3%	28:00:26
9	news-out.aioe.org	3	22312	259	22045	8	1%	24:09:42
10	nntp.pasdenom.info	3	21275	167	21096	12	0%	24:08:10
11	news.fcku.it	1	9297	97	9040	160	1%	23:59:56
12	news.freedyn.net	3	26084	75	25837	172	0%	24:09:51
13	news.swapon.de	5	1918	61	1856	1	3%	23:26:30
14	xanadu-bbs.net	20	721	23	696	2	3%	24:02:12
15	news.bbs.geek.nz	1	20093	19	20073	1	0%	23:59:46
16	localhost	40	5	5	0	0	100%	00:00:10
TOTAL: 16		1439	456222	23118	414288	18816	5%	511:32:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.9 MB	2.2 MB	55.6 MB	78.7 MB	26%	5.0 KB
2	csiph-out.news.weretis.net	14.0 MB	0.0 KB	9.8 MB	23.8 MB	58%	4.1 KB
3	feeder.eternal-september.org	7.8 MB	410.4 KB	53.3 MB	61.4 MB	12%	8.5 KB
4	news-out.mixmin.net	7.7 MB	0.0 KB	1.3 MB	9.0 MB	85%	3.3 KB
5	news.redatomik.org	3.8 MB	0.0 KB	446.3 KB	4.2 MB	89%	2.6 KB
6	newsfeed.neva.ru	3.3 MB	490.4 KB	32.3 MB	36.0 MB	9%	8.0 KB
7	newsfeed.fu-berlin.de	2.6 MB	0.9 KB	1.6 MB	4.2 MB	62%	2.6 KB
8	newsfeeds.xmission.com	2.6 MB	0.0 KB	539.7 KB	3.1 MB	82%	3.7 KB
9	news-out.aioe.org	981.9 KB	0.0 KB	8.7 KB	990.6 KB	99%	3.7 KB
10	nntp.pasdenom.info	447.8 KB	0.0 KB	29.4 KB	477.2 KB	93%	2.7 KB
11	news.freedyn.net	289.8 KB	0.0 KB	7.3 MB	7.6 MB	3%	31.5 KB
12	news.fcku.it	190.8 KB	0.0 KB	992.9 KB	1.2 MB	16%	4.6 KB
13	news.swapon.de	127.4 KB	0.0 KB	17.0 KB	144.4 KB	88%	2.3 KB
14	xanadu-bbs.net	53.1 KB	0.0 KB	2.7 KB	55.8 KB	95%	2.2 KB
15	news.bbs.geek.nz	28.1 KB	0.0 KB	3.6 KB	31.7 KB	88%	1.6 KB
16	localhost	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.3 KB
TOTAL: 16		64.7 MB	3.1 MB	163.2 MB	230.9 MB	28%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 3 04:15:00 - 04:59:59	253	1.1%	0.09	795.1 KB	1.2%	0.29
Mar 3 05:00:00 - 05:59:59	577	2.5%	0.16	1.9 MB	2.9%	0.54
Mar 3 06:00:00 - 06:59:59	577	2.5%	0.16	2.3 MB	3.6%	0.65
Mar 3 07:00:00 - 07:59:59	712	3.1%	0.20	1.8 MB	2.9%	0.52
Mar 3 08:00:00 - 08:59:59	878	3.8%	0.24	2.3 MB	3.6%	0.65
Mar 3 09:00:00 - 09:59:59	1099	4.8%	0.31	2.9 MB	4.5%	0.83
Mar 3 10:00:00 - 10:59:59	1099	4.8%	0.31	3.0 MB	4.7%	0.86
Mar 3 11:00:00 - 11:59:59	1172	5.1%	0.33	2.9 MB	4.5%	0.83
Mar 3 12:00:00 - 12:59:59	1120	4.9%	0.31	3.0 MB	4.6%	0.85
Mar 3 13:00:00 - 13:59:59	1172	5.1%	0.33	3.1 MB	4.7%	0.87
Mar 3 14:00:00 - 14:59:59	1248	5.4%	0.35	3.2 MB	4.9%	0.90
Mar 3 15:00:00 - 15:59:59	1360	5.9%	0.38	3.9 MB	6.1%	1.12
Mar 3 16:00:00 - 16:59:59	1258	5.5%	0.35	3.1 MB	4.8%	0.87
Mar 3 17:00:00 - 17:59:59	1284	5.6%	0.36	3.7 MB	5.8%	1.07
Mar 3 18:00:00 - 18:59:59	1384	6.0%	0.38	3.9 MB	6.1%	1.11
Mar 3 19:00:00 - 19:59:59	1271	5.5%	0.35	3.6 MB	5.6%	1.03
Mar 3 20:00:00 - 20:59:59	1214	5.3%	0.34	3.3 MB	5.2%	0.95
Mar 3 21:00:00 - 21:59:59	1088	4.7%	0.30	3.0 MB	4.7%	0.85
Mar 3 22:00:00 - 22:59:59	1023	4.4%	0.28	3.1 MB	4.8%	0.89
Mar 3 23:00:00 - 23:59:59	833	3.6%	0.23	2.5 MB	3.9%	0.71
Mar 4 00:00:00 - 00:59:59	738	3.2%	0.20	2.1 MB	3.3%	0.60
Mar 4 01:00:00 - 01:59:59	602	2.6%	0.17	1.7 MB	2.7%	0.49
Mar 4 02:00:00 - 02:59:59	473	2.1%	0.13	1.4 MB	2.2%	0.40
Mar 4 03:00:00 - 03:59:59	437	1.9%	0.12	1.4 MB	2.2%	0.41
Mar 4 04:00:00 - 04:15:00	118	0.5%	0.13	303.3 KB	0.5%	0.34
TOTAL: 24:00:00	22990	100.0%	0.27	64.3 MB	100.0%	0.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	7735	224	167	1	7343
2	feeder.eternal-september.org	4119	66	26	0	4027
3	newsfeed.neva.ru	3035	248	39	0	2748
4	csiph-out.news.weretis.net	931	175	0	0	756
5	newsfeed.fu-berlin.de	844	28	1	0	815
6	news-out.mixmin.net	639	8	0	0	631
7	news.freedyn.net	178	7	0	0	171
8	news.fcku.it	162	0	0	0	162
9	news.redatomik.org	30	0	0	0	30
10	newsfeeds.xmission.com	24	1	0	0	23
11	nntp.pasdenom.info	15	8	0	0	7
12	news.swapon.de	12	0	0	0	12
13	news-out.aioe.org	12	8	0	0	4
14	news.bbs.geek.nz	3	1	0	0	2
15	xanadu-bbs.net	2	0	0	0	2
TOTAL: 15		17741	774	233	1	16733

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	152
lada.talk	72
lada.auto	53
cakewalk.audio	28
alt.2600	26
mozilla.general	22
alt.survival	17
gmane.linux.alsa.devel	16
mozilla.support.firefox	15
gmane.comp.gis.openstreetmap.tagging	14
mozilla.dev.security.policy	14
mozilla.support.seamonkey	12
alt.windows7.general	12
mozilla.dev.platform	11
mozilla.support.thunderbird	8
it-alt.sport.calcio.juventus	8
spline.eisfair	8
mpc.lists.nanog	8
mozilla.dev.l10n	8
gmane.comp.gis.openstreetmap	8
TOTAL: 145	774

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

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13895
EMP (phn path)	1635
EMP (phn nph)	95
Too many newsgroups	66
Binary Payload	46
User-issued cancel	28
EMP (phl)	27
HTML Multipart	17
Scoring filter (11)	14
Scoring filter (10)	11
HTML Image Tags	8
Scoring filter (21)	6
HTML post	6
Scoring filter (14)	4
Scoring filter (9)	3
Scoring filter (12)	3
Scoring filter (20)	3
Scoring filter (8)	2
User-issued spam cancel	2
Binary Image: misplaced jpg	2
TOTAL: 23	15876

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	news.freedyn.net	90
	csiph-out.news.weretis.net	63
	feeder.eternal-september.org	29
	news.fcku.it	14
	newsfeeds.xmission.com	9
	news-out.mixmin.net	8
	news.redatomik.org	1
	TOTAL: 7	214
Including strange strings
	newsfeed.fu-berlin.de	410
	csiph-out.news.weretis.net	69
	csiph-out.feeder.erje.net	58
	news-out.mixmin.net	45
	newsfeed.neva.ru	17
	newsfeeds.xmission.com	14
	news.swapon.de	11
	feeder.eternal-september.org	7
	news.redatomik.org	4
	news-out.aioe.org	4
	TOTAL: 12	643
TOTAL: 2		857

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40589	3273	19006	38	0	8%	23:50:08
2	news.freedyn.net	23641	756	22097	3	0	3%	23:50:07
3	xanadu-bbs	24839	752	22213	0	1	3%	23:59:51
4	fu-berlin.de	21565	564	21000	0	0	2%	23:50:08
5	usenet.pasdenom.info	26901	233	21303	1283	9	0%	23:59:55
6	xmission	25183	93	21496	4	1	0%	23:59:56
7	aioe	21149	48	19470	1	0	0%	23:55:43
8	news.redatomik.org	21912	41	20676	3	0	0%	23:59:29
9	weretis.net	16525	11	16171	0	0	0%	23:53:24
10	eternal-september	20835	7	18938	3	0	0%	23:59:53
11	neva.ru	21904	6	20477	18	1	0%	23:59:55
12	news.mixmin.net	20704	5	19470	0	0	0%	23:50:08
13	news.swapon.de	1976	4	1904	5	0	0%	23:54:59
14	news.fcku.it	2711	3	2589	0	0	0%	22:22:27
15	au2pb	0	0	0	0	22339	0%	23:50:03
16	erje	16350	0	16056	44	2	0%	23:53:04
17	bwh	0	0	0	0	22331	0%	23:50:03
TOTAL: 17		306784	5796	262866	1402	44684	1%	404:59:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	7.5 MB	131.5 KB	7.6 MB	0.1 KB/s	2.3 KB	23:50:08
2	news.freedyn.net	5.1 MB	26.6 KB	5.2 MB	0.1 KB/s	7.0 KB	23:50:07
3	xanadu-bbs	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	7.0 KB	23:59:51
4	fu-berlin.de	4.6 MB	0.0 KB	4.6 MB	0.1 KB/s	8.4 KB	23:50:08
5	usenet.pasdenom.info	682.4 KB	5.9 MB	6.5 MB	0.1 KB/s	4.4 KB	23:59:55
6	xmission	207.3 KB	8.3 KB	215.6 KB	0.0 KB/s	2.2 KB	23:59:56
7	aioe	107.1 KB	1.0 KB	108.1 KB	0.0 KB/s	2.2 KB	23:55:43
8	news.redatomik.org	96.2 KB	5.8 KB	102.0 KB	0.0 KB/s	2.3 KB	23:59:29
9	weretis.net	17.9 KB	0.0 KB	17.9 KB	0.0 KB/s	1.6 KB	23:53:24
10	eternal-september	9.0 KB	6.3 KB	15.3 KB	0.0 KB/s	1.5 KB	23:59:53
11	neva.ru	7.5 KB	40.7 KB	48.1 KB	0.0 KB/s	2.0 KB	23:59:55
12	news.mixmin.net	6.4 KB	0.0 KB	6.4 KB	0.0 KB/s	1.3 KB	23:50:08
13	news.fcku.it	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	1.5 KB	22:22:27
14	news.swapon.de	3.8 KB	12.6 KB	16.4 KB	0.0 KB/s	1.8 KB	23:54:59
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
16	erje	0.0 KB	156.2 KB	156.2 KB	0.0 KB/s	3.6 KB	23:53:04
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		23.5 MB	6.2 MB	29.7 MB	0.0 KB/s	4.2 KB	404:59:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-02-70-24-220-69.dsl.bell.ca	532	8727	19.7 MB	49	0	0	07:34:17
2	dhcp-089-099-203-254.chello.nl	30	15	19.2 KB	4	0	0	06:10:48
3	194-73-239-109.cust.centrio.cz	5	6	18.9 KB	4	0	0	01:08:01
4	68-185-181-64.dhcp.mdsn.wi.charter.com	18	5	6.4 KB	5	5	5	05:04:44
TOTAL: 4		585	8753	19.7 MB	62	5	5	20:57:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	532	8727	19.7 MB	49	0	0	07:34:17
2	*.chello.nl	30	15	19.2 KB	4	0	0	06:10:48
3	*.cust.centrio.cz	5	6	18.9 KB	4	0	0	01:08:01
4	*.dhcp.mdsn.wi.charter.com	18	5	6.4 KB	5	5	5	05:04:44
5	*.bbox.io	2	0	0.0 KB	0	0	0	01:00:03
TOTAL: 5		587	8753	19.7 MB	62	5	5	20:57:55

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
ctomczyk	4
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-02-70-24-220-69.dsl.bell.ca	127.737	278.234	0.000	07:34:17
dhcp-089-099-203-254.chello.nl	2.320	0.522	0.000	06:10:48
68-185-181-64.dhcp.mdsn.wi.charter.com	1.649	0.380	0.020	05:04:44
194-73-239-109.cust.centrio.cz	0.514	0.128	0.012	01:08:01
tempe0.bbox.io	0.239	0.054	0.000	01:00:03
TOTAL: 5	132.459	279.318	0.032	20:57:55

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
tempe0.bbox.io	2	0
194-73-239-109.cust.centrio.cz	2	0
TOTAL: 3	9	0

Newsgroup request counts (by category):

Category		Count	Pct
1	uk	7850	89.7%
2	comp	415	4.7%
3	alt	301	3.4%
4	rec	186	2.1%
TOTAL: 4		8752	100.0%

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

Newsgroup		Count
1	uk.d-i-y	7850
2	comp.lang.forth	389
3	rec.boats	184
4	alt.abortion	169
5	alt.bumbling.idiots.the.fbi	68
6	alt.christnet.theology	54
7	comp.sys.ibm.ps2.hardware	20
8	alt.comp.freeware	9
9	comp.lang.javascript	6
10	rec.collecting.coins	2
11	alt.os.linux.mageia	1
TOTAL: 11		8752

Daily Usenet report for csiph.com

Mar 3 04:15:00 -- Mar 4 04:15:00