Daily Usenet report for csiph.com: Sep 18 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21199	49.4%	5.5 MB	65.7%
innd	17691	41.2%	2.2 MB	26.7%
innfeed	3313	7.7%	571.4 KB	6.7%
nnrpd	640	1.5%	72.0 KB	0.8%
nocem	81	0.2%	8.4 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	42925	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	310464	58.7%
Negative hits	197569	37.4%
Do not exist	20378	3.9%
Cache misses	303	0.1%
TOTAL: 4	528714	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.519	0.0%	172	0.000	8.831	141.143
article cleanup	00:00:00.133	0.0%	19444	0.000	0.007	0.052
article logging	00:00:00.609	0.0%	19858	0.000	0.031	1.876
article parse	00:00:01.934	0.0%	81800	0.000	0.024	0.080
article write	00:00:46.960	0.1%	16730	0.435	2.807	48.828
artlog/artcncl	00:00:00.002	0.0%	130	0.000	0.015	0.200
artlog/artparse	00:00:00.022	0.0%	1081	0.000	0.020	0.333
data move	00:00:00.294	0.0%	517241	0.000	0.001	0.061
hisgrep/artcncl	00:00:00.013	0.0%	171	0.000	0.076	1.000
hishave/artcncl	00:00:00.002	0.0%	172	0.000	0.012	1.000
hishave/artparse	00:00:00.002	0.0%	1081	0.000	0.002	0.030
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:18.706	0.0%	531425	0.004	0.035	0.211
history sync	00:00:00.952	0.0%	366	0.000	2.601	58.000
history write	00:00:24.427	0.0%	19443	0.206	1.256	9.222
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.123	0.0%	1081	0.000	1.039	5.000
idle	23:45:46.857	99.6%	520642	93.222	164.310	566.874
nntp read	00:00:03.170	0.0%	523714	0.001	0.006	0.391
overview write	00:02:24.989	0.2%	16730	0.087	8.666	1053.284
perl filter	00:00:34.075	0.0%	19443	1.344	1.753	11.655
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.297	0.0%	33536	0.000	0.009	0.034
TOTAL: 23:51:00.843	23:50:26.086	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.057	0.0%	16883	0.000	0.003	0.036
article prepare	00:00:00.002	0.0%	17248	0.000	0.000	0.005
article read	00:00:02.711	0.0%	1257	0.000	2.157	326.000
backlog stats	00:00:00.201	0.0%	364559	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.484	0.0%	279091	0.000	0.005	0.010
data write	00:00:01.811	0.0%	265820	0.002	0.007	0.177
idle	23:54:28.541	100.0%	364559	137.780	236.089	969.218
status file	00:00:00.901	0.0%	261	0.000	3.452	60.000
TOTAL: 23:54:43.756	23:54:35.708	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	21:01:16.460	100.0%	556	341.000	136108.741	600156.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.011	0.0%	1282	0.000	0.009	0.167
readart	00:00:00.460	0.0%	61	0.000	7.541	49.000
TOTAL: 21:01:34.720	21:01:16.931	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	33983	3698	30026	259	10%	49:18:04
2	feeder.eternal-september.org	2	18659	2171	16430	58	11%	25:35:50
3	newsfeeds.xmission.com	3	16425	1946	14472	7	11%	24:56:19
4	news.freedyn.net	2	19809	1818	16901	1090	9%	24:09:02
5	csiph-out.news.xs4all.nl	864	18160	1695	15247	1218	9%	71:13:43
6	csiph-out.feeder.erje.net	1440	79932	1376	78067	489	1%	118:41:49
7	news-out.aioe.org	3	16486	1349	15089	48	8%	24:09:47
8	newsfeed.neva.ru	145	25029	868	23822	339	3%	71:58:02
9	newsfeed.fu-berlin.de	15	15371	857	14479	35	5%	25:18:44
10	news-out.mixmin.net	2	17119	851	16139	129	4%	23:59:37
11	news.fcku.it	9	2261	266	1992	3	11%	22:27:41
12	csiph-out.feed.uzoreto.com	864	25660	145	25324	191	0%	71:16:40
13	nntp.pasdenom.info	2	11342	57	11277	8	0%	23:58:15
14	news.swapon.de	6	1520	26	1494	0	1%	24:24:55
15	localhost	45	13	13	0	0	100%	00:00:00
16	news.samoylyk.net	2	16318	10	16308	0	0%	24:55:05
17	news.bbs.nz	2	15413	8	15405	0	0%	23:59:52
18	xanadu-bbs.net	19	497	1	496	0	0%	21:42:13
TOTAL: 18		3428	333997	17155	312968	3874	5%	672:05:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	11.8 MB	24.6 KB	8.4 MB	20.2 MB	58%	5.2 KB
2	csiph-out.feeder.erje.net	9.2 MB	0.0 KB	18.7 MB	28.0 MB	33%	15.4 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	687.3 KB	6.5 MB	89%	3.0 KB
4	newsfeeds.xmission.com	5.2 MB	0.0 KB	514.5 KB	5.7 MB	91%	3.0 KB
5	news.freedyn.net	5.1 MB	0.0 KB	55.0 MB	60.1 MB	8%	21.2 KB
6	csiph-out.news.xs4all.nl	4.7 MB	0.0 KB	13.8 MB	18.5 MB	25%	6.5 KB
7	news-out.aioe.org	3.1 MB	0.0 KB	101.3 KB	3.2 MB	96%	2.4 KB
8	news-out.mixmin.net	2.6 MB	0.0 KB	455.5 KB	3.0 MB	85%	3.1 KB
9	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	212.7 KB	2.2 MB	90%	2.5 KB
10	newsfeed.neva.ru	1.9 MB	0.0 KB	1.2 MB	3.1 MB	61%	2.6 KB
11	news.fcku.it	416.9 KB	0.0 KB	98.9 KB	515.8 KB	80%	1.9 KB
12	csiph-out.feed.uzoreto.com	414.7 KB	0.0 KB	4.1 MB	4.5 MB	8%	13.8 KB
13	nntp.pasdenom.info	117.7 KB	0.0 KB	11.8 KB	129.5 KB	90%	2.0 KB
14	news.swapon.de	61.2 KB	0.0 KB	0.0 KB	61.2 KB	100%	2.4 KB
15	news.samoylyk.net	35.2 KB	0.0 KB	0.0 KB	35.2 KB	100%	3.5 KB
16	news.bbs.nz	25.3 KB	0.0 KB	0.0 KB	25.3 KB	100%	3.2 KB
17	localhost	25.2 KB	0.0 KB	0.0 KB	25.2 KB	100%	1.9 KB
18	xanadu-bbs.net	10.4 KB	0.0 KB	0.0 KB	10.4 KB	100%	10.4 KB
TOTAL: 18		52.5 MB	24.6 KB	103.3 MB	155.8 MB	33%	7.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 18 04:15:00 - 04:59:59	323	1.9%	0.12	1.7 MB	3.3%	0.65
Sep 18 05:00:00 - 05:59:59	289	1.7%	0.08	819.4 KB	1.5%	0.23
Sep 18 06:00:00 - 06:59:59	490	2.9%	0.14	1.6 MB	3.0%	0.45
Sep 18 07:00:00 - 07:59:59	714	4.2%	0.20	2.1 MB	4.1%	0.60
Sep 18 08:00:00 - 08:59:59	697	4.1%	0.19	1.9 MB	3.7%	0.54
Sep 18 09:00:00 - 09:59:59	795	4.7%	0.22	2.1 MB	4.1%	0.60
Sep 18 10:00:00 - 10:59:59	783	4.7%	0.22	2.4 MB	4.7%	0.69
Sep 18 11:00:00 - 11:59:59	819	4.9%	0.23	2.9 MB	5.5%	0.81
Sep 18 12:00:00 - 12:59:59	798	4.7%	0.22	2.3 MB	4.5%	0.66
Sep 18 13:00:00 - 13:59:59	1008	6.0%	0.28	2.9 MB	5.6%	0.82
Sep 18 14:00:00 - 14:59:59	905	5.4%	0.25	2.5 MB	4.9%	0.72
Sep 18 15:00:00 - 15:59:59	1042	6.2%	0.29	3.7 MB	7.3%	1.07
Sep 18 16:00:00 - 16:59:59	995	5.9%	0.28	2.5 MB	4.9%	0.72
Sep 18 17:00:00 - 17:59:59	958	5.7%	0.27	2.7 MB	5.2%	0.77
Sep 18 18:00:00 - 18:59:59	901	5.4%	0.25	2.7 MB	5.3%	0.78
Sep 18 19:00:00 - 19:59:59	993	5.9%	0.28	2.7 MB	5.2%	0.77
Sep 18 20:00:00 - 20:59:59	894	5.3%	0.25	2.9 MB	5.5%	0.81
Sep 18 21:00:00 - 21:59:59	738	4.4%	0.20	2.1 MB	4.1%	0.60
Sep 18 22:00:00 - 22:59:59	677	4.0%	0.19	2.4 MB	4.7%	0.68
Sep 18 23:00:00 - 23:59:59	511	3.0%	0.14	1.6 MB	3.1%	0.45
Sep 19 00:00:00 - 00:59:59	360	2.1%	0.10	1016.6 KB	1.9%	0.28
Sep 19 01:00:00 - 01:59:59	315	1.9%	0.09	1.0 MB	2.0%	0.29
Sep 19 02:00:00 - 02:59:59	390	2.3%	0.11	1.4 MB	2.8%	0.41
Sep 19 03:00:00 - 03:59:59	300	1.8%	0.08	871.7 KB	1.6%	0.24
Sep 19 04:00:00 - 04:15:00	107	0.6%	0.12	798.8 KB	1.5%	0.89
TOTAL: 24:00:00	16802	100.0%	0.19	51.7 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1277	40	0	0	1237
2	news.freedyn.net	1087	13	0	0	1074
3	csiph-out.feeder.erje.net	491	46	0	1	444
4	newsfeed.fu-berlin.de	342	24	0	0	318
5	newsfeed.neva.ru	338	135	0	0	203
6	csiph-out.news.weretis.net	270	105	1	0	164
7	csiph-out.feed.uzoreto.com	192	13	0	0	179
8	news-out.mixmin.net	138	0	0	0	138
9	feeder.eternal-september.org	55	21	0	0	34
10	news-out.aioe.org	50	27	0	0	23
11	nntp.pasdenom.info	8	0	0	0	8
12	newsfeeds.xmission.com	5	0	0	0	5
13	news.fcku.it	3	2	0	0	1
14	news.swapon.de	2	0	0	0	2
TOTAL: 14		4258	426	1	1	3830

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	108
it-alt.sport.calcio.juventus	61
mozilla.general	24
lada.auto	19
spline.eisfair	16
eagle.suggest.eng	15
alt.bbs.synchronet	14
mozilla.support.firefox	11
alt.survival	11
mozilla.dev.apps.thunderbird	10
mozilla.dev.platform	9
alt.binaries.pictures.purebeauty	9
test.post	8
mozilla.support.seamonkey	7
oesterreich.tratsch	7
eagle.support.eng	6
alt.politics.usa	4
at.jobs	4
alt.job	4
mozilla.dev.security.policy	4
TOTAL: 72	426

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

Groups	Count
uk.radio.amateur.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2019
EMP (phn nph)	91
EMP (phn path)	47
Binary Payload	42
EMP (phl)	35
HTML Multipart	19
Too many newsgroups	18
Binary: misplaced rar	12
User-issued cancel	9
HTML Image Tags	6
Scoring filter (10)	5
User-issued spam cancel	4
Scoring filter (8)	2
Scoring filter (12)	2
Too many newsgroups (meow)	1
Binary: misplaced BRK	1
HTML post	1
TOTAL: 17	2314

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	459
	csiph-out.feeder.erje.net	322
	csiph-out.news.xs4all.nl	138
	csiph-out.news.weretis.net	120
	csiph-out.feed.uzoreto.com	46
	newsfeeds.xmission.com	5
	news-out.mixmin.net	4
	feeder.eternal-september.org	4
	newsfeed.fu-berlin.de	2
	news.fcku.it	1
	TOTAL: 10	1101
Including strange strings
	newsfeed.fu-berlin.de	307
	csiph-out.news.xs4all.nl	64
	csiph-out.news.weretis.net	13
	news-out.mixmin.net	11
	news.freedyn.net	7
	news-out.aioe.org	6
	csiph-out.feeder.erje.net	4
	news.swapon.de	2
	feeder.eternal-september.org	1
	TOTAL: 9	415
TOTAL: 3		1561

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19560	1025	15776	0	0	5%	23:59:55
2	news.freedyn.net	15342	524	14319	20	0	3%	23:56:31
3	news.samoylyk.net	17701	505	16249	2	61	2%	23:55:03
4	fu-berlin.de	15697	480	15217	0	0	3%	23:50:08
5	news.bbs.nz	31472	402	15826	9	0	1%	23:50:08
6	usenet.pasdenom.info	17335	170	16033	542	27	0%	23:59:55
7	neva.ru	16424	93	14897	173	0	0%	23:59:47
8	newsfeed.xs4all.nl	15525	52	14460	2	39	0%	23:56:36
9	aioe	14858	30	14290	0	0	0%	23:50:08
10	erje	15911	26	15304	4	0	0%	23:56:17
11	news.uzoreto.com	16377	20	16003	186	0	0%	23:50:08
12	news.mixmin.net	15897	17	15304	0	0	0%	23:59:15
13	news.swapon.de	1646	15	1593	0	0	0%	23:47:53
14	xmission	19019	15	14421	0	0	0%	23:59:52
15	weretis.net	12788	14	12525	3	0	0%	23:50:08
16	eternal-september	14733	13	14107	3	0	0%	23:59:49
17	news.fcku.it	1792	0	1788	0	0	0%	22:04:11
18	news.redatomik.org	0	0	0	0	16245	0%	23:50:08
TOTAL: 18		262077	3401	228112	944	16372	1%	428:35:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.0 MB	0.0 KB	8.0 MB	0.1 KB/s	8.0 KB	23:59:55
2	news.freedyn.net	6.7 MB	40.0 KB	6.7 MB	0.1 KB/s	12.7 KB	23:56:31
3	news.samoylyk.net	6.5 MB	2.0 KB	6.5 MB	0.1 KB/s	13.2 KB	23:55:03
4	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	13.5 KB	23:50:08
5	news.bbs.nz	941.0 KB	32.1 KB	973.1 KB	0.0 KB/s	2.4 KB	23:50:08
6	usenet.pasdenom.info	449.2 KB	6.8 MB	7.3 MB	0.1 KB/s	10.5 KB	23:59:55
7	neva.ru	192.0 KB	392.0 KB	584.0 KB	0.0 KB/s	2.2 KB	23:59:47
8	newsfeed.xs4all.nl	174.5 KB	7.8 KB	182.3 KB	0.0 KB/s	3.4 KB	23:56:36
9	erje	100.7 KB	12.0 KB	112.7 KB	0.0 KB/s	3.8 KB	23:56:17
10	news.mixmin.net	69.8 KB	0.0 KB	69.8 KB	0.0 KB/s	4.1 KB	23:59:15
11	aioe	56.5 KB	0.0 KB	56.5 KB	0.0 KB/s	1.9 KB	23:50:08
12	news.uzoreto.com	48.3 KB	543.0 KB	591.4 KB	0.0 KB/s	2.9 KB	23:50:08
13	xmission	30.7 KB	0.0 KB	30.7 KB	0.0 KB/s	2.0 KB	23:59:52
14	news.swapon.de	27.4 KB	0.0 KB	27.4 KB	0.0 KB/s	1.8 KB	23:47:53
15	weretis.net	26.8 KB	3.7 KB	30.4 KB	0.0 KB/s	1.8 KB	23:50:08
16	eternal-september	25.2 KB	7.0 KB	32.2 KB	0.0 KB/s	2.0 KB	23:59:49
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:04:11
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:08
TOTAL: 18		29.6 MB	7.8 MB	37.5 MB	0.0 KB/s	8.8 KB	428:35:52

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	68-185-181-139.dhcp.mdsn.wi.charter.com	35	25	50.3 KB	20	13	12	08:24:15
2	a131033.upc-a.chello.nl	38	22	38.1 KB	7	0	0	09:32:00
3	91.218.217.131.siec.idealan.pl	2	14	31.1 KB	1	0	0	00:34:48
TOTAL: 3		75	61	119.5 KB	28	13	12	21:01:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	35	25	50.3 KB	20	13	12	08:24:15
2	*.upc-a.chello.nl	38	22	38.1 KB	7	0	0	09:32:00
3	*.218.217.131.siec.idealan.pl	2	14	31.1 KB	1	0	0	00:34:48
4	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:30:14
5	unresolved	4	0	0.0 KB	0	0	0	00:00:13
TOTAL: 5		84	61	119.5 KB	28	13	12	21:01:32

NNRP auth users [Top 20]:

User	Conn
ohlandl	12
ctomczyk	2
TOTAL: 2	14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.277	0.622	0.000	09:32:00
68-185-181-139.dhcp.mdsn.wi.charter.com	3.404	0.542	0.031	08:24:15
ip72-222-200-117.ph.ph.cox.net	0.539	0.126	0.000	02:30:14
91.218.217.131.siec.idealan.pl	0.222	0.041	0.005	00:34:48
159.203.201.184	0.067	0.015	0.000	00:00:09
94.167.122.190	0.133	0.031	0.000	00:00:02
94.167.100.13	0.069	0.015	0.000	00:00:01
TOTAL: 7	7.711	1.392	0.036	21:01:32

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	5
94.167.122.190	2
159.203.201.184	1
94.167.100.13	1
TOTAL: 4	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-139.dhcp.mdsn.wi.charter.com	7	0
ip72-222-200-117.ph.ph.cox.net	5	0
91.218.217.131.siec.idealan.pl	1	0
TOTAL: 3	13	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	47
2	comp.lang.javascript	14
TOTAL: 2		61

Daily Usenet report for csiph.com

Sep 18 04:15:00 -- Sep 19 04:15:00