Daily Usenet report for csiph.com: May 20 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	220638	82.1%	22.5 MB	72.8%
inn	28898	10.8%	6.0 MB	19.6%
innd	15781	5.9%	1.8 MB	5.8%
innfeed	3235	1.2%	540.0 KB	1.7%
nocem	74	0.0%	7.7 KB	0.0%
TOTAL: 5	268626	100.0%	30.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	334425	56.0%
Negative hits	234138	39.2%
Do not exist	28075	4.7%
Cache misses	346	0.1%
TOTAL: 4	596984	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.023	0.0%	231	0.000	4.429	54.000
article cleanup	00:00:00.222	0.0%	27767	0.000	0.008	0.034
article logging	00:00:04.374	0.0%	28317	0.000	0.154	1.535
article parse	00:00:03.523	0.0%	82394	0.004	0.043	0.272
article write	00:02:23.307	0.2%	19100	0.186	7.503	66.079
artlog/artcncl	00:00:00.004	0.0%	147	0.000	0.027	0.250
artlog/artparse	00:00:00.022	0.0%	455	0.000	0.048	0.889
data move	00:00:00.295	0.0%	568614	0.000	0.001	0.020
hisgrep/artcncl	00:00:00.470	0.0%	231	0.000	2.035	18.000
hishave/artcncl	00:00:00.213	0.0%	231	0.000	0.922	18.833
hishave/artparse	00:00:00.000	0.0%	454	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:22.197	0.2%	598888	0.002	0.237	1.124
history sync	00:00:04.409	0.0%	366	0.000	12.046	119.000
history write	00:03:12.683	0.2%	27734	0.195	6.948	46.767
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:02.424	0.0%	454	0.000	5.339	44.000
idle	23:44:14.602	98.8%	556271	87.094	153.620	575.308
nntp read	00:00:03.051	0.0%	578323	0.001	0.005	0.016
overview write	00:05:56.202	0.4%	19100	1.266	18.649	168.914
perl filter	00:01:02.524	0.1%	27596	1.394	2.266	8.664
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.388	0.0%	38265	0.000	0.010	0.039
TOTAL: 24:00:53.071	23:59:31.933	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.090	0.0%	19157	0.000	0.005	0.024
article prepare	00:00:00.001	0.0%	22282	0.000	0.000	0.011
article read	00:00:01.338	0.0%	3181	0.000	0.421	42.667
backlog stats	00:00:00.242	0.0%	341298	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.952	0.0%	266862	0.003	0.007	0.014
data write	00:00:01.627	0.0%	251962	0.001	0.006	0.014
idle	23:53:30.336	100.0%	341298	149.085	252.009	853.457
status file	00:00:00.460	0.0%	285	0.000	1.614	52.000
TOTAL: 23:53:45.574	23:53:36.046	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.577	0.0%	1430	0.020	1.802	4.661
idle	38:58:37.535	99.8%	248399	9.500	564.888	600047.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.248	0.0%	544599	0.000	0.008	4.404
readart	00:01:37.410	0.1%	14665	0.000	6.642	77.000
TOTAL: 39:02:48.676	39:00:21.770	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	40637	5611	34500	526	13%	48:10:30
2	csiph-out.feeder.erje.net	1440	112977	3955	104142	4880	3%	118:43:33
3	feeder.eternal-september.org	4	25663	2482	22000	1181	9%	24:41:35
4	news-out.mixmin.net	3	20772	1868	18156	748	8%	24:08:54
5	news-out.aioe.org	2	18790	1272	17488	30	6%	24:09:56
6	newsfeed.neva.ru	150	25627	1128	23473	1026	4%	71:21:39
7	newsfeed.fu-berlin.de	21	22249	956	21003	290	4%	31:40:10
8	newsfeeds.xmission.com	2	20164	655	19501	8	3%	27:01:33
9	news.redatomik.org	1	17784	529	17252	3	2%	23:14:11
10	news.freedyn.net	2	21486	449	20736	301	2%	23:59:46
11	news.fcku.it	1	7448	314	7024	110	4%	23:59:57
12	news.swapon.de	6	1429	27	1401	1	1%	23:13:04
13	xanadu-bbs.net	19	554	19	503	32	3%	22:43:43
14	nntp.pasdenom.info	2	17051	18	17032	1	0%	23:59:22
15	localhost	43	8	8	0	0	100%	00:00:01
16	news.bbs.geek.nz	1	20869	1	20868	0	0%	23:59:44
TOTAL: 16		1702	373508	19292	345079	9137	5%	535:07:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.3 MB	125.9 KB	27.5 MB	43.9 MB	37%	5.1 KB
2	csiph-out.news.weretis.net	15.9 MB	0.0 KB	5.4 MB	21.3 MB	74%	3.6 KB
3	feeder.eternal-september.org	7.5 MB	151.9 KB	19.3 MB	26.9 MB	27%	7.5 KB
4	news-out.mixmin.net	6.9 MB	0.0 KB	1.7 MB	8.6 MB	80%	3.4 KB
5	news-out.aioe.org	3.0 MB	0.0 KB	84.7 KB	3.1 MB	97%	2.4 KB
6	newsfeed.neva.ru	2.9 MB	89.7 KB	4.8 MB	7.8 MB	37%	3.7 KB
7	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	695.8 KB	2.9 MB	76%	2.4 KB
8	newsfeeds.xmission.com	2.2 MB	0.0 KB	407.4 KB	2.6 MB	84%	3.9 KB
9	news.redatomik.org	1.4 MB	0.0 KB	301.5 KB	1.7 MB	82%	3.2 KB
10	news.freedyn.net	1.1 MB	0.0 KB	490.2 KB	1.6 MB	70%	2.2 KB
11	news.fcku.it	707.9 KB	0.0 KB	2.7 MB	3.3 MB	20%	8.1 KB
12	news.swapon.de	87.7 KB	0.0 KB	2.2 KB	89.9 KB	97%	3.2 KB
13	xanadu-bbs.net	47.2 KB	0.0 KB	89.8 KB	137.0 KB	34%	2.7 KB
14	nntp.pasdenom.info	42.3 KB	0.0 KB	1.3 KB	43.6 KB	97%	2.3 KB
15	localhost	30.0 KB	0.0 KB	0.0 KB	30.0 KB	100%	3.7 KB
16	news.bbs.geek.nz	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 16		60.3 MB	367.5 KB	63.5 MB	124.1 MB	48%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 20 04:15:00 - 04:59:59	287	1.5%	0.11	1.1 MB	1.8%	0.42
May 20 05:00:00 - 05:59:59	430	2.3%	0.12	1.4 MB	2.3%	0.40
May 20 06:00:00 - 06:59:59	626	3.3%	0.17	2.0 MB	3.4%	0.57
May 20 07:00:00 - 07:59:59	812	4.3%	0.23	2.7 MB	4.4%	0.75
May 20 08:00:00 - 08:59:59	924	4.8%	0.26	3.3 MB	5.5%	0.94
May 20 09:00:00 - 09:59:59	925	4.8%	0.26	3.4 MB	5.6%	0.96
May 20 10:00:00 - 10:59:59	826	4.3%	0.23	2.3 MB	3.8%	0.65
May 20 11:00:00 - 11:59:59	937	4.9%	0.26	3.1 MB	5.2%	0.88
May 20 12:00:00 - 12:59:59	935	4.9%	0.26	2.7 MB	4.5%	0.76
May 20 13:00:00 - 13:59:59	1040	5.4%	0.29	3.6 MB	6.0%	1.02
May 20 14:00:00 - 14:59:59	967	5.1%	0.27	3.1 MB	5.2%	0.88
May 20 15:00:00 - 15:59:59	1040	5.4%	0.29	3.4 MB	5.6%	0.95
May 20 16:00:00 - 16:59:59	1087	5.7%	0.30	2.8 MB	4.7%	0.80
May 20 17:00:00 - 17:59:59	1153	6.0%	0.32	3.4 MB	5.7%	0.98
May 20 18:00:00 - 18:59:59	1044	5.5%	0.29	3.5 MB	5.9%	1.00
May 20 19:00:00 - 19:59:59	1015	5.3%	0.28	2.9 MB	4.8%	0.82
May 20 20:00:00 - 20:59:59	955	5.0%	0.27	2.6 MB	4.4%	0.75
May 20 21:00:00 - 21:59:59	866	4.5%	0.24	2.3 MB	3.8%	0.64
May 20 22:00:00 - 22:59:59	779	4.1%	0.22	2.4 MB	4.1%	0.69
May 20 23:00:00 - 23:59:59	651	3.4%	0.18	1.9 MB	3.2%	0.55
May 21 00:00:00 - 00:59:59	483	2.5%	0.13	1.5 MB	2.5%	0.42
May 21 01:00:00 - 01:59:59	395	2.1%	0.11	1.2 MB	2.0%	0.34
May 21 02:00:00 - 02:59:59	424	2.2%	0.12	1.5 MB	2.5%	0.43
May 21 03:00:00 - 03:59:59	400	2.1%	0.11	1.4 MB	2.4%	0.40
May 21 04:00:00 - 04:15:00	93	0.5%	0.10	305.8 KB	0.5%	0.34
TOTAL: 24:00:00	19094	100.0%	0.22	59.7 MB	100.0%	0.71

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	4921	122	12	1	4786
2	feeder.eternal-september.org	1194	45	11	0	1138
3	newsfeed.neva.ru	1046	184	11	0	851
4	news-out.mixmin.net	793	0	0	0	793
5	newsfeed.fu-berlin.de	610	34	0	0	576
6	csiph-out.news.weretis.net	584	126	0	0	458
7	news.freedyn.net	301	2	0	0	299
8	news.fcku.it	122	1	0	0	121
9	news-out.aioe.org	40	19	0	0	21
10	xanadu-bbs.net	32	0	0	0	32
11	newsfeeds.xmission.com	7	1	0	0	6
12	news.redatomik.org	4	0	0	0	4
13	news.swapon.de	3	0	0	0	3
14	nntp.pasdenom.info	1	0	0	0	1
TOTAL: 14		9658	534	34	1	9089

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	127
lada.talk	42
mpc.lists.freebsd.ports.bugs	37
spline.eisfair	34
it-alt.sport.calcio.juventus	27
lada.auto	20
alt.windows7.general	13
cakewalk.audio	13
mpc.lists.freebsd.x11	12
alt.survival	12
mozilla.support.seamonkey	11
alt.philosophy	9
mpc.lists.freebsd.gecko	8
att.jobs	7
alt.politics.usa	7
lada.commerce	7
alt.politics	6
lada.photo	4
mozilla.dev.security.policy	4
gmane.linux.alsa.devel	4
TOTAL: 103	534

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

Groups	Count
comp.os.os2.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6489
EMP (phn path)	1132
EMP (phl)	92
EMP (phn nph)	44
Too many newsgroups	40
Binary Payload	29
Scoring filter (10)	17
HTML Multipart	16
Scoring filter (8)	13
User-issued cancel	13
Binary: misplaced par2	10
Scoring filter (15)	6
Scoring filter (12)	6
Binary: misplaced rar	6
Scoring filter (11)	5
HTML post	5
Too many newsgroups (meow)	5
Scoring filter (9)	4
Scoring filter (24)	4
Scoring filter (14)	3
TOTAL: 26	7946

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	65	0	70	70
TOTAL: 1	65	0	70	70

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	136
	TOTAL: 1	136
Huge articles
	csiph-out.feeder.erje.net	197
	feeder.eternal-september.org	126
	csiph-out.news.weretis.net	62
	news.fcku.it	49
	news-out.mixmin.net	11
	newsfeeds.xmission.com	6
	news.freedyn.net	1
	xanadu-bbs.net	1
	news.redatomik.org	1
	TOTAL: 9	454
Including strange strings
	newsfeed.fu-berlin.de	356
	csiph-out.news.weretis.net	69
	csiph-out.feeder.erje.net	50
	news-out.mixmin.net	47
	newsfeed.neva.ru	15
	news-out.aioe.org	9
	feeder.eternal-september.org	2
	news.swapon.de	2
	news.fcku.it	1
	TOTAL: 9	551
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		1142

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	34711	976	17370	61	1	0	2%	23:59:53
2	news.freedyn.net	19229	885	17757	1	0	0	4%	23:59:54
3	xanadu-bbs	19883	732	18336	1	0	94	3%	23:59:54
4	fu-berlin.de	18121	630	17490	0	0	0	3%	23:59:53
5	usenet.pasdenom.info	19869	154	16944	1979	0	3	0%	23:59:51
6	news.redatomik.org	18331	23	17903	0	0	0	0%	23:59:54
7	aioe	17156	20	16282	0	0	0	0%	23:59:34
8	news.mixmin.net	16769	10	16211	0	0	0	0%	23:59:54
9	weretis.net	12093	10	11993	1	0	2	0%	23:59:54
10	eternal-september	16809	10	15762	0	0	0	0%	23:59:54
11	news.swapon.de	1522	8	1488	0	0	0	0%	23:42:32
12	xmission	18919	8	17812	1	0	0	0%	23:59:54
13	neva.ru	17499	7	17055	14	0	0	0%	23:59:39
14	news.fcku.it	1417	1	1398	0	0	0	0%	22:03:23
15	erje	15140	0	14993	45	0	0	0%	23:59:54
16	bwh	0	0	0	0	0	18401	0%	23:58:42
17	au2pb	0	0	0	0	0	18407	0%	23:58:42
TOTAL: 17		247468	3474	218794	2103	1	36907	1%	405:41:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.7 MB	0.9 KB	7.7 MB	0.1 KB/s	8.9 KB	23:59:54
2	xanadu-bbs	7.2 MB	11.7 KB	7.3 MB	0.1 KB/s	10.1 KB	23:59:54
3	fu-berlin.de	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	11.4 KB	23:59:53
4	news.bbs.geek.nz	2.6 MB	248.8 KB	2.9 MB	0.0 KB/s	2.8 KB	23:59:53
5	usenet.pasdenom.info	589.4 KB	9.3 MB	9.9 MB	0.1 KB/s	4.8 KB	23:59:51
6	news.redatomik.org	58.3 KB	0.0 KB	58.3 KB	0.0 KB/s	2.5 KB	23:59:54
7	aioe	49.7 KB	0.0 KB	49.7 KB	0.0 KB/s	2.5 KB	23:59:34
8	eternal-september	35.9 KB	0.0 KB	35.9 KB	0.0 KB/s	3.6 KB	23:59:54
9	news.mixmin.net	34.3 KB	0.0 KB	34.3 KB	0.0 KB/s	3.4 KB	23:59:54
10	weretis.net	34.2 KB	2.3 KB	36.5 KB	0.0 KB/s	3.3 KB	23:59:54
11	news.swapon.de	30.0 KB	0.0 KB	30.0 KB	0.0 KB/s	3.8 KB	23:42:32
12	xmission	30.0 KB	11.7 KB	41.7 KB	0.0 KB/s	4.6 KB	23:59:54
13	neva.ru	13.5 KB	46.3 KB	59.8 KB	0.0 KB/s	2.8 KB	23:59:39
14	news.fcku.it	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	22:03:23
15	erje	0.0 KB	251.3 KB	251.3 KB	0.0 KB/s	5.6 KB	23:59:54
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		25.5 MB	9.9 MB	35.4 MB	0.0 KB/s	6.5 KB	405:41:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	784	619	936.6 KB	12	0	0	12:47:11
2	tempe0.bbox.io	12	85	169.8 KB	6	1	1	03:30:49
3	144.138.90.254	288	37	122.9 KB	36	0	0	03:34:18
4	dhcp-089-099-203-254.chello.nl	40	16	43.3 KB	5	0	0	08:43:40
5	96-42-16-100.dhcp.mdsn.wi.charter.com	28	9	31.9 KB	8	7	7	07:06:02
6	204-73-239-109.cust.centrio.cz	3	5	14.9 KB	2	0	0	01:00:23
7	ip98-169-80-165.dc.dc.cox.net	4	1	2.3 KB	6	0	0	00:12:53
TOTAL: 7		1159	772	1.3 MB	75	8	8	39:02:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	784	619	936.6 KB	12	0	0	12:47:11
2	*.bbox.io	12	85	169.8 KB	6	1	1	03:30:49
3	unresolved	289	37	122.9 KB	36	0	0	05:09:08
4	*.chello.nl	40	16	43.3 KB	5	0	0	08:43:40
5	*.dhcp.mdsn.wi.charter.com	28	9	31.9 KB	8	7	7	07:06:02
6	*.cust.centrio.cz	3	5	14.9 KB	2	0	0	01:00:23
7	*.dc.dc.cox.net	4	1	2.3 KB	6	0	0	00:12:53
8	*.156.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:32:29
TOTAL: 8		1161	772	1.3 MB	75	8	8	39:02:37

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	7
ctomczyk	2
kev009	1
TOTAL: 4	298

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	65.273	20.687	0.000	12:47:11
dhcp-089-099-203-254.chello.nl	3.149	0.678	0.000	08:43:40
96-42-16-100.dhcp.mdsn.wi.charter.com	2.667	0.398	0.027	07:06:02
144.138.90.254	24.447	5.867	1.094	03:34:18
tempe0.bbox.io	1.284	0.197	0.004	03:30:49
50.96.155.107	0.084	0.091	0.000	01:34:49
204-73-239-109.cust.centrio.cz	0.300	0.024	0.007	01:00:23
h215.156.22.98.dynamic.ip.windstream.net	0.079	0.053	0.000	00:32:29
ip98-169-80-165.dc.dc.cox.net	0.256	0.338	0.000	00:12:53
TOTAL: 9	97.539	28.333	1.132	39:02:37

Curious NNRP server explorers [Top 20]:

System	Conn
50.96.155.107	1
h215.156.22.98.dynamic.ip.windstream.net	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	5	0
96-42-16-100.dhcp.mdsn.wi.charter.com	4	0
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	4	0
204-73-239-109.cust.centrio.cz	2	0
h215.156.22.98.dynamic.ip.windstream.net	1	0
50.96.155.107	1	0
TOTAL: 6	17	0

Newsgroup request counts (by category):

Category		Count	Pct
1	sci	1000	55.7%
2	free	594	33.1%
3	comp	175	9.8%
4	alt	21	1.2%
5	uk	4	0.2%
TOTAL: 5		1794	100.0%

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

Newsgroup		Count
1	free.usenet	592
2	sci.electronics.basics	500
3	sci.electronics.design	500
4	comp.sys.ibm.ps2.hardware	119
5	comp.protocols.tcp-ip	12
6	comp.security.unix	11
7	alt.drugs	11
8	alt.drugs.hard	7
9	comp.os.vms	6
10	comp.arch.fpga	6
11	comp.lang.javascript	5
12	comp.os.msdos.programmer	5
13	comp.sys.ibm.pc.games.action	4
14	uk.rec.drugs.cannabis	4
15	alt.support.chronic-pain	3
16	free.at	2
17	comp.arch	2
18	comp.windows.x	2
19	comp.ai.philosophy	1
20	comp.os.cpm	1
21	comp.arch.embedded	1
TOTAL: 21		1794

Daily Usenet report for csiph.com

May 20 04:15:00 -- May 21 04:15:00