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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 17 11:10:03 csiph pgpverify[83761]: pgpverify: verification failed
May 17 20:10:02 csiph controlchan[65293]: Multiple control headers in article <tIdqG-7Gg-27@gated-at.bofh.it>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	143668	73.6%	14.2 MB	60.0%
inn	33056	16.9%	7.2 MB	30.4%
innd	15006	7.7%	1.7 MB	7.3%
innfeed	3291	1.7%	535.5 KB	2.2%
nocem	71	0.0%	7.0 KB	0.0%
controlchan	4	0.0%	0.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	195097	100.0%	23.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	404210	56.5%
Negative hits	279087	39.0%
Do not exist	31953	4.5%
Cache misses	473	0.1%
TOTAL: 4	715723	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.785	0.0%	260	0.000	6.865	61.000
article cleanup	00:00:00.234	0.0%	31774	0.000	0.007	0.027
article logging	00:00:05.057	0.0%	32374	0.000	0.156	1.875
article parse	00:00:07.055	0.0%	95440	0.003	0.074	0.928
article write	00:03:53.522	0.3%	23763	0.200	9.827	57.581
artlog/artcncl	00:00:00.002	0.0%	210	0.000	0.010	0.143
artlog/artparse	00:00:00.310	0.0%	520	0.000	0.596	12.667
data move	00:00:00.247	0.0%	678661	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.591	0.0%	258	0.000	2.291	42.000
hishave/artcncl	00:00:00.160	0.0%	260	0.000	0.615	4.300
hishave/artparse	00:00:00.003	0.0%	517	0.000	0.006	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:52.000	0.2%	720533	0.003	0.239	1.301
history sync	00:00:05.017	0.0%	348	0.000	14.417	86.000
history write	00:04:18.860	0.3%	31737	0.068	8.156	53.543
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:05.554	0.0%	517	0.000	10.743	94.500
idle	23:39:49.414	98.5%	653347	69.500	130.389	338.913
nntp read	00:00:03.394	0.0%	688384	0.002	0.005	0.066
overview write	00:06:50.902	0.5%	23763	0.331	17.292	127.862
perl filter	00:01:10.808	0.1%	31731	1.629	2.232	8.182
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.427	0.0%	47590	0.000	0.009	0.035
TOTAL: 24:01:00.172	23:59:25.342	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	23805	0.000	0.003	0.014
article prepare	00:00:00.006	0.0%	29176	0.000	0.000	0.008
article read	00:00:01.694	0.0%	5026	0.000	0.337	10.571
backlog stats	00:00:00.231	0.0%	406570	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.148	0.0%	327070	0.003	0.007	0.011
data write	00:00:01.821	0.0%	311869	0.001	0.006	0.012
idle	23:54:10.109	100.0%	406570	139.646	211.649	612.418
status file	00:00:00.384	0.0%	330	0.000	1.164	40.000
TOTAL: 23:54:27.295	23:54:16.458	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.165	0.0%	62	2.661	2.661	2.661
idle	20:49:23.609	99.5%	182188	116.997	411.463	570747.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.077	0.0%	376014	0.000	0.006	0.035
readart	00:04:57.711	0.4%	27345	0.000	10.887	62.000
TOTAL: 20:56:12.418	20:54:23.562	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	50848	6627	43558	663	13%	51:21:39
2	csiph-out.feeder.erje.net	1440	132115	5461	121648	5006	4%	118:57:19
3	feeder.eternal-september.org	4	29870	3301	25626	943	11%	24:26:25
4	news-out.mixmin.net	3	24922	1654	22877	391	6%	24:08:53
5	news-out.aioe.org	4	24017	1639	22315	63	6%	26:42:04
6	newsfeed.neva.ru	146	31090	1607	28548	935	5%	72:09:00
7	newsfeed.fu-berlin.de	49	22588	1041	21218	329	4%	30:14:43
8	news.redatomik.org	1	24550	1018	23523	9	4%	26:14:19
9	newsfeeds.xmission.com	3	20815	695	20110	10	3%	20:35:18
10	news.fcku.it	2	10622	488	10024	110	4%	23:59:53
11	xanadu-bbs.net	14	1575	262	1299	14	16%	39:45:13
12	news.swapon.de	2	2931	103	2825	3	3%	30:51:40
13	news.freedyn.net	2	25453	53	25374	26	0%	23:59:44
14	nntp.pasdenom.info	2	22826	18	22807	1	0%	23:59:23
15	news.bbs.geek.nz	2	21240	3	21237	0	0%	23:59:42
16	localhost	36	2	2	0	0	100%	00:00:08
TOTAL: 16		1713	445464	23972	412989	8503	5%	561:25:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.7 MB	216.0 KB	27.8 MB	50.7 MB	44%	5.0 KB
2	csiph-out.news.weretis.net	19.6 MB	0.0 KB	7.1 MB	26.7 MB	73%	3.8 KB
3	feeder.eternal-september.org	9.3 MB	8.2 KB	23.9 MB	33.2 MB	27%	8.0 KB
4	news-out.mixmin.net	6.8 MB	16.4 KB	1.2 MB	8.0 MB	84%	4.0 KB
5	newsfeed.neva.ru	4.7 MB	20.7 KB	4.3 MB	9.0 MB	52%	3.6 KB
6	news-out.aioe.org	4.2 MB	0.0 KB	81.0 KB	4.3 MB	98%	2.6 KB
7	news.redatomik.org	3.0 MB	0.0 KB	80.3 KB	3.1 MB	97%	3.1 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	880.9 KB	3.5 MB	75%	2.6 KB
9	newsfeeds.xmission.com	2.3 MB	0.0 KB	408.3 KB	2.6 MB	84%	3.8 KB
10	news.fcku.it	1.3 MB	0.0 KB	1.1 MB	2.4 MB	54%	4.1 KB
11	xanadu-bbs.net	868.3 KB	0.0 KB	686.1 KB	1.5 MB	55%	5.6 KB
12	news.swapon.de	265.1 KB	0.0 KB	1.3 MB	1.5 MB	16%	14.8 KB
13	news.freedyn.net	229.9 KB	0.0 KB	56.4 KB	286.2 KB	80%	3.6 KB
14	nntp.pasdenom.info	38.0 KB	0.0 KB	4.1 KB	42.2 KB	90%	2.2 KB
15	news.bbs.geek.nz	6.2 KB	0.0 KB	0.0 KB	6.2 KB	100%	2.1 KB
16	localhost	2.5 KB	0.0 KB	0.0 KB	2.5 KB	100%	1.2 KB
TOTAL: 16		77.8 MB	261.3 KB	68.9 MB	147.0 MB	52%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 17 04:15:00 - 04:59:59	622	2.6%	0.23	3.5 MB	4.5%	1.32
May 17 05:00:00 - 05:59:59	600	2.5%	0.17	2.5 MB	3.3%	0.72
May 17 06:00:00 - 06:59:59	760	3.2%	0.21	2.7 MB	3.5%	0.78
May 17 07:00:00 - 07:59:59	1022	4.3%	0.28	3.3 MB	4.2%	0.93
May 17 08:00:00 - 08:59:59	1133	4.8%	0.31	3.0 MB	3.8%	0.85
May 17 09:00:00 - 09:59:59	1043	4.4%	0.29	2.8 MB	3.6%	0.80
May 17 10:00:00 - 10:59:59	1213	5.1%	0.34	4.2 MB	5.4%	1.20
May 17 11:00:00 - 11:59:59	1098	4.6%	0.30	3.4 MB	4.4%	0.98
May 17 12:00:00 - 12:59:59	1107	4.7%	0.31	3.1 MB	4.0%	0.88
May 17 13:00:00 - 13:59:59	1272	5.4%	0.35	3.7 MB	4.7%	1.05
May 17 14:00:00 - 14:59:59	1226	5.2%	0.34	3.8 MB	4.9%	1.08
May 17 15:00:00 - 15:59:59	1376	5.8%	0.38	4.9 MB	6.3%	1.39
May 17 16:00:00 - 16:59:59	1333	5.6%	0.37	4.5 MB	5.9%	1.29
May 17 17:00:00 - 17:59:59	1283	5.4%	0.36	4.3 MB	5.5%	1.22
May 17 18:00:00 - 18:59:59	1149	4.8%	0.32	3.9 MB	5.1%	1.12
May 17 19:00:00 - 19:59:59	1133	4.8%	0.31	3.0 MB	3.9%	0.86
May 17 20:00:00 - 20:59:59	1260	5.3%	0.35	3.8 MB	4.9%	1.07
May 17 21:00:00 - 21:59:59	1069	4.5%	0.30	3.2 MB	4.2%	0.92
May 17 22:00:00 - 22:59:59	920	3.9%	0.26	3.0 MB	3.8%	0.85
May 17 23:00:00 - 23:59:59	758	3.2%	0.21	2.3 MB	2.9%	0.64
May 18 00:00:00 - 00:59:59	582	2.5%	0.16	1.7 MB	2.2%	0.48
May 18 01:00:00 - 01:59:59	577	2.4%	0.16	1.8 MB	2.4%	0.53
May 18 02:00:00 - 02:59:59	560	2.4%	0.16	2.6 MB	3.3%	0.73
May 18 03:00:00 - 03:59:59	511	2.2%	0.14	2.0 MB	2.6%	0.58
May 18 04:00:00 - 04:15:00	136	0.6%	0.15	492.6 KB	0.6%	0.55
TOTAL: 24:00:00	23743	100.0%	0.27	77.6 MB	100.0%	0.92

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	5047	301	27	2	4717
2	feeder.eternal-september.org	954	45	2	0	907
3	newsfeed.neva.ru	931	254	5	0	672
4	newsfeed.fu-berlin.de	798	14	0	0	784
5	csiph-out.news.weretis.net	711	220	1	0	490
6	news-out.mixmin.net	416	0	3	0	413
7	news.fcku.it	106	13	1	0	92
8	news-out.aioe.org	69	56	0	0	13
9	news.freedyn.net	26	6	0	0	20
10	xanadu-bbs.net	13	0	0	0	13
11	newsfeeds.xmission.com	12	0	0	0	12
12	news.redatomik.org	11	0	1	0	10
13	news.swapon.de	9	0	0	0	9
14	nntp.pasdenom.info	1	1	0	0	0
TOTAL: 14		9104	910	40	2	8152

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	147
it-alt.sport.calcio.juventus	116
lada.auto	72
lada.talk	65
mpc.lists.freebsd.ports.bugs	37
it.annunci.immobiliari	34
it.annunci.varie	31
alt.windows7.general	26
mozilla.support.thunderbird	26
lada.commerce	24
gmane.linux.kernel.renesas-soc	24
mozilla.dev.platform	22
mozilla.support.seamonkey	22
mozilla.dev.security.policy	21
it.annunci.commerciali	19
lada.photo	16
gmane.linux.alsa.devel	15
mpc.lists.freebsd.gnome	14
gmane.linux.kernel	14
alt.survival	10
TOTAL: 100	910

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5911
EMP (phn path)	734
EMP (phl)	126
Too many newsgroups	51
User-issued cancel	50
EMP (phn nph)	37
HTML Multipart	36
Scoring filter (8)	22
Binary Payload	18
Scoring filter (10)	9
User-issued spam cancel	4
Scoring filter (9)	4
HTML post	4
HTML Image Tags	4
Scoring filter (13)	4
Scoring filter (12)	3
Too many newsgroups (meow)	2
Scoring filter (14)	2
Scoring filter (15)	2
Binary Image: misplaced gif	1
TOTAL: 23	7027

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	10
	TOTAL: 1	10
Huge articles
	csiph-out.feeder.erje.net	208
	feeder.eternal-september.org	204
	csiph-out.news.weretis.net	71
	news-out.mixmin.net	11
	xanadu-bbs.net	10
	newsfeeds.xmission.com	7
	newsfeed.fu-berlin.de	3
	news.swapon.de	2
	TOTAL: 8	516
Including strange strings
	newsfeed.fu-berlin.de	461
	csiph-out.feeder.erje.net	50
	csiph-out.news.weretis.net	43
	news-out.mixmin.net	24
	newsfeed.neva.ru	7
	news.swapon.de	7
	news-out.aioe.org	6
	news.redatomik.org	3
	newsfeeds.xmission.com	2
	TOTAL: 9	603
No colon-space in header
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
TOTAL: 5		1135

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41501	3373	19612	62	0	8%	23:59:53
2	news.freedyn.net	25032	821	22849	13	0	3%	23:59:53
3	xanadu-bbs	24482	706	22890	0	0	2%	23:59:50
4	fu-berlin.de	22425	675	21750	0	0	3%	23:59:43
5	usenet.pasdenom.info	25012	48	22136	1541	6	0%	23:59:53
6	news.redatomik.org	22460	12	22082	2	0	0%	23:59:53
7	aioe	20252	7	19329	2	706	0%	23:59:06
8	xmission	23844	7	22064	0	0	0%	23:59:53
9	weretis.net	16299	6	16179	0	0	0%	23:59:19
10	news.mixmin.net	21571	4	21152	0	0	0%	23:59:53
11	news.swapon.de	2075	2	2055	0	4	0%	23:59:27
12	neva.ru	21442	2	21016	7	0	0%	23:59:53
13	eternal-september	20082	2	19321	0	0	0%	23:59:53
14	news.fcku.it	2311	0	2298	0	0	0%	22:51:48
15	bwh	0	0	0	0	23051	0%	23:58:42
16	erje	18277	0	18172	15	0	0%	23:59:38
17	au2pb	0	0	0	0	23049	0%	23:58:42
TOTAL: 17		307065	5665	272905	1642	46816	1%	406:45:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.2 MB	94.1 KB	9.2 MB	0.1 KB/s	11.3 KB	23:59:53
2	news.bbs.geek.nz	9.0 MB	393.5 KB	9.4 MB	0.1 KB/s	2.8 KB	23:59:53
3	xanadu-bbs	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	12.7 KB	23:59:50
4	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	13.1 KB	23:59:43
5	usenet.pasdenom.info	217.7 KB	10.4 MB	10.6 MB	0.1 KB/s	6.8 KB	23:59:53
6	news.mixmin.net	43.8 KB	0.0 KB	43.8 KB	0.0 KB/s	10.9 KB	23:59:53
7	news.redatomik.org	24.8 KB	41.5 KB	66.3 KB	0.0 KB/s	4.7 KB	23:59:53
8	aioe	15.4 KB	3.4 KB	18.8 KB	0.0 KB/s	2.1 KB	23:59:06
9	xmission	15.1 KB	0.0 KB	15.1 KB	0.0 KB/s	2.2 KB	23:59:53
10	weretis.net	9.4 KB	0.0 KB	9.4 KB	0.0 KB/s	1.6 KB	23:59:19
11	news.swapon.de	2.5 KB	0.0 KB	2.5 KB	0.0 KB/s	1.2 KB	23:59:27
12	neva.ru	2.5 KB	25.5 KB	28.0 KB	0.0 KB/s	3.1 KB	23:59:53
13	eternal-september	2.5 KB	0.0 KB	2.5 KB	0.0 KB/s	1.2 KB	23:59:53
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:51:48
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	erje	0.0 KB	76.6 KB	76.6 KB	0.0 KB/s	5.1 KB	23:59:38
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		35.9 MB	11.0 MB	46.9 MB	0.0 KB/s	6.6 KB	406:45:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	150	5281	19.8 MB	29	0	0	07:31:41
2	144.138.90.254	288	113	431.9 KB	101	0	0	03:42:42
3	204-73-239-109.cust.centrio.cz	11	13	39.7 KB	5	0	0	02:26:55
4	68-185-181-64.dhcp.mdsn.wi.charter.com	22	2	2.5 KB	2	2	2	05:42:08
TOTAL: 4		471	5409	20.3 MB	137	2	2	20:56:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	150	5281	19.8 MB	29	0	0	07:31:41
2	unresolved	293	113	431.9 KB	101	0	0	03:42:55
3	*.cust.centrio.cz	11	13	39.7 KB	5	0	0	02:26:55
4	*.dhcp.mdsn.wi.charter.com	22	2	2.5 KB	2	2	2	05:42:08
5	*.156.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	00:32:22
6	*.bbox.io	2	0	0.0 KB	0	0	0	01:00:04
TOTAL: 6		480	5409	20.3 MB	137	2	2	20:56:07

NNRP auth users [Top 20]:

User	Conn
moussa	288
ctomczyk	7
ohlandl	2
TOTAL: 3	297

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	20.376	10.873	0.000	07:31:41
68-185-181-64.dhcp.mdsn.wi.charter.com	1.898	0.466	0.008	05:42:08
144.138.90.254	27.384	6.850	1.049	03:42:42
204-73-239-109.cust.centrio.cz	1.042	0.187	0.023	02:26:55
tempe0.bbox.io	0.235	0.051	0.000	01:00:04
h215.156.22.98.dynamic.ip.windstream.net	0.068	0.041	0.000	00:32:22
195.38.136.204	0.151	0.008	0.000	00:00:05
92.54.28.204	0.134	0.030	0.000	00:00:05
212.61.180.204	0.079	0.024	0.000	00:00:02
TOTAL: 9	51.367	18.530	1.080	20:56:07

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	2
h215.156.22.98.dynamic.ip.windstream.net	2
92.54.28.204	2
195.38.136.204	2
212.61.180.204	1
TOTAL: 5	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	6	0
204-73-239-109.cust.centrio.cz	4	0
tempe0.bbox.io	2	0
h215.156.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 4	13	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	3150	58.2%
2	de	1399	25.9%
3	free	728	13.5%
4	comp	127	2.3%
5	uk	4	0.1%
6	pl	1	0.0%
TOTAL: 6		5409	100.0%

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

Newsgroup		Count
1	alt.free.newsservers	3137
2	de.alt.comm.android	1399
3	free.usenet	728
4	comp.arch.embedded	53
5	comp.arch.fpga	19
6	comp.os.vms	15
7	comp.ai.philosophy	13
8	comp.lang.javascript	12
9	alt.drugs	6
10	comp.arch	4
11	comp.sys.ibm.ps2.hardware	4
12	uk.rec.drugs.cannabis	4
13	alt.drugs.hard	3
14	alt.support.chronic-pain	3
15	comp.sys.ibm.pc.games.action	2
16	comp.sys.ibm.pc.hardware.storage	2
17	comp.os.linux.networking	1
18	comp.ai.neural-nets	1
19	alt.arts.poetry.comments	1
20	comp.os.cpm	1
21	pl.comp.www	1
TOTAL: 21		5409

Daily Usenet report for csiph.com

May 17 04:15:00 -- May 18 04:15:00