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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 3 15:30:03 csiph pgpverify[12911]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	26446	54.1%	5.5 MB	67.8%
innd	12318	25.2%	1.5 MB	18.5%
nnrpd	7125	14.6%	639.3 KB	7.6%
innfeed	2891	5.9%	489.5 KB	5.8%
nocem	134	0.3%	15.9 KB	0.2%
controlchan	12	0.0%	2.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	48927	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	292738	57.2%
Negative hits	193081	37.7%
Do not exist	25519	5.0%
Cache misses	284	0.1%
TOTAL: 4	511622	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.543	0.0%	269	0.000	2.019	32.000
article cleanup	00:00:00.146	0.0%	25234	0.000	0.006	0.033
article logging	00:00:00.445	0.0%	25815	0.000	0.017	0.231
article parse	00:00:01.500	0.0%	72184	0.000	0.021	0.181
article write	00:00:08.502	0.0%	18677	0.213	0.455	6.338
artlog/artcncl	00:00:00.008	0.0%	206	0.000	0.039	0.857
artlog/artparse	00:00:00.012	0.0%	366	0.000	0.033	1.000
data move	00:00:00.183	0.0%	500718	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.247	0.0%	267	0.000	0.925	32.000
hishave/artcncl	00:00:00.062	0.0%	269	0.000	0.230	3.929
hishave/artparse	00:00:00.005	0.0%	363	0.000	0.014	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:28.081	0.0%	512330	0.002	0.055	0.453
history sync	00:00:01.488	0.0%	364	0.000	4.088	55.333
history write	00:00:40.633	0.0%	25211	0.276	1.612	11.127
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.568	0.0%	362	0.000	1.569	10.000
idle	23:39:40.068	99.2%	505321	100.147	168.566	561.596
nntp read	00:00:02.470	0.0%	507987	0.002	0.005	0.011
overview write	00:08:26.188	0.6%	18677	0.341	27.102	4105.424
perl filter	00:00:45.254	0.1%	24911	1.239	1.817	2.725
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.334	0.0%	37484	0.000	0.009	0.038
TOTAL: 23:50:59.739	23:50:16.737	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	18733	0.000	0.003	0.026
article prepare	00:00:00.001	0.0%	22416	0.000	0.000	0.003
article read	00:00:00.442	0.0%	2617	0.000	0.169	12.333
backlog stats	00:00:00.198	0.0%	359214	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.762	0.0%	269201	0.001	0.007	0.012
data write	00:00:01.518	0.0%	255053	0.001	0.006	0.015
idle	23:54:12.289	100.0%	359214	149.206	239.557	718.216
status file	00:00:00.225	0.0%	264	0.000	0.852	16.000
TOTAL: 23:54:26.351	23:54:16.484	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	27:04:23.882	100.0%	8145	86.889	11966.100	600198.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.112	0.0%	16711	0.000	0.007	0.167
readart	00:00:01.806	0.0%	236	0.000	7.653	60.000
TOTAL: 27:05:03.178	27:04:25.800	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	36600	6203	30017	380	16%	46:34:09
2	csiph-out.feeder.erje.net	1152	80730	2960	74624	3146	3%	94:56:47
3	newsfeeds.xmission.com	3	18111	2242	15865	4	12%	25:29:24
4	feeder.eternal-september.org	2	21122	2211	18809	102	10%	23:59:44
5	news-out.aioe.org	4	18165	1420	16708	37	7%	24:09:35
6	newsfeed.fu-berlin.de	16	17443	1079	16231	133	6%	26:23:53
7	newsfeed.neva.ru	141	24664	1027	21317	2320	4%	71:47:40
8	news.fcku.it	2	8076	647	7211	218	8%	23:59:41
9	news.redatomik.org	2	17817	428	17350	39	2%	24:07:26
10	news-out.mixmin.net	1	19682	351	18896	435	1%	23:59:49
11	news.swapon.de	7	1595	52	1543	0	3%	24:37:52
12	nntp.pasdenom.info	3	16725	24	16696	5	0%	23:58:46
13	news.freedyn.net	2	20821	23	20730	68	0%	25:36:39
14	localhost	48	16	16	0	0	100%	00:00:05
15	news.bbs.geek.nz	2	19483	1	19482	0	0%	23:59:43
16	xanadu-bbs.net	22	507	1	504	2	0%	21:55:42
TOTAL: 16		1412	321557	18685	295983	6889	5%	505:36:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	18.5 MB	5.3 KB	8.0 MB	26.6 MB	69%	4.1 KB
2	csiph-out.feeder.erje.net	14.5 MB	22.7 KB	14.2 MB	28.7 MB	50%	4.8 KB
3	newsfeeds.xmission.com	6.2 MB	0.0 KB	182.3 KB	6.4 MB	97%	2.9 KB
4	feeder.eternal-september.org	5.6 MB	0.0 KB	3.0 MB	8.6 MB	64%	3.8 KB
5	news-out.aioe.org	3.4 MB	0.0 KB	54.2 KB	3.4 MB	98%	2.4 KB
6	newsfeed.neva.ru	3.0 MB	46.0 KB	25.2 MB	28.3 MB	10%	8.7 KB
7	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	311.2 KB	2.9 MB	89%	2.4 KB
8	news.fcku.it	1.6 MB	0.0 KB	6.2 MB	7.9 MB	20%	9.3 KB
9	news-out.mixmin.net	1.3 MB	2.2 KB	1.2 MB	2.5 MB	50%	3.2 KB
10	news.redatomik.org	976.4 KB	0.0 KB	1.5 MB	2.4 MB	39%	5.3 KB
11	news.swapon.de	128.4 KB	0.0 KB	0.0 KB	128.4 KB	100%	2.5 KB
12	news.freedyn.net	101.4 KB	0.0 KB	208.7 KB	310.1 KB	32%	3.4 KB
13	nntp.pasdenom.info	64.7 KB	0.0 KB	9.4 KB	74.1 KB	87%	2.6 KB
14	localhost	47.0 KB	0.0 KB	0.0 KB	47.0 KB	100%	2.9 KB
15	xanadu-bbs.net	2.1 KB	0.0 KB	4.1 KB	6.2 KB	33%	2.1 KB
16	news.bbs.geek.nz	0.5 KB	0.0 KB	0.0 KB	0.5 KB	100%	0.5 KB
TOTAL: 16		58.0 MB	76.2 KB	60.1 MB	118.1 MB	49%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 3 04:15:00 - 04:59:59	253	1.4%	0.09	708.9 KB	1.2%	0.26
Mar 3 05:00:00 - 05:59:59	381	2.0%	0.11	1.1 MB	1.9%	0.31
Mar 3 06:00:00 - 06:59:59	500	2.7%	0.14	1.5 MB	2.6%	0.44
Mar 3 07:00:00 - 07:59:59	490	2.6%	0.14	1.7 MB	2.9%	0.48
Mar 3 08:00:00 - 08:59:59	778	4.2%	0.22	2.2 MB	3.7%	0.61
Mar 3 09:00:00 - 09:59:59	907	4.8%	0.25	3.0 MB	5.1%	0.85
Mar 3 10:00:00 - 10:59:59	1094	5.8%	0.30	4.7 MB	8.0%	1.32
Mar 3 11:00:00 - 11:59:59	1031	5.5%	0.29	3.3 MB	5.7%	0.95
Mar 3 12:00:00 - 12:59:59	849	4.5%	0.24	2.5 MB	4.2%	0.71
Mar 3 13:00:00 - 13:59:59	939	5.0%	0.26	2.9 MB	4.9%	0.82
Mar 3 14:00:00 - 14:59:59	1050	5.6%	0.29	3.5 MB	5.9%	0.98
Mar 3 15:00:00 - 15:59:59	1072	5.7%	0.30	3.7 MB	6.3%	1.05
Mar 3 16:00:00 - 16:59:59	978	5.2%	0.27	2.6 MB	4.4%	0.73
Mar 3 17:00:00 - 17:59:59	968	5.2%	0.27	2.6 MB	4.4%	0.73
Mar 3 18:00:00 - 18:59:59	1005	5.4%	0.28	2.7 MB	4.6%	0.76
Mar 3 19:00:00 - 19:59:59	894	4.8%	0.25	2.7 MB	4.6%	0.76
Mar 3 20:00:00 - 20:59:59	939	5.0%	0.26	2.7 MB	4.6%	0.77
Mar 3 21:00:00 - 21:59:59	993	5.3%	0.28	2.9 MB	4.9%	0.82
Mar 3 22:00:00 - 22:59:59	853	4.6%	0.24	2.8 MB	4.9%	0.81
Mar 3 23:00:00 - 23:59:59	644	3.4%	0.18	2.1 MB	3.5%	0.59
Mar 4 00:00:00 - 00:59:59	610	3.3%	0.17	1.9 MB	3.2%	0.53
Mar 4 01:00:00 - 01:59:59	459	2.5%	0.13	1.4 MB	2.5%	0.41
Mar 4 02:00:00 - 02:59:59	593	3.2%	0.16	2.0 MB	3.4%	0.56
Mar 4 03:00:00 - 03:59:59	373	2.0%	0.10	1.4 MB	2.4%	0.39
Mar 4 04:00:00 - 04:15:00	76	0.4%	0.08	235.6 KB	0.4%	0.26
TOTAL: 24:00:00	18729	100.0%	0.22	58.4 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	3176	107	10	3059
2	newsfeed.neva.ru	2324	174	13	2137
3	newsfeed.fu-berlin.de	619	18	0	601
4	news-out.mixmin.net	444	0	2	442
5	csiph-out.news.weretis.net	442	94	1	347
6	news.fcku.it	222	2	0	220
7	feeder.eternal-september.org	102	49	0	53
8	news.freedyn.net	64	0	0	64
9	news.redatomik.org	46	0	0	46
10	news-out.aioe.org	40	21	0	19
11	news.swapon.de	17	0	0	17
12	nntp.pasdenom.info	5	3	0	2
13	news.bbs.geek.nz	4	4	0	0
14	newsfeeds.xmission.com	4	1	0	3
15	xanadu-bbs.net	2	0	0	2
TOTAL: 15		7511	473	26	7012

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	147
alt.windows7.general	42
it-alt.sport.calcio.juventus	35
alt.binaries.erotica-underground	20
alt.survival	14
alt.bbs.synchronet	13
mpc.lists.freebsd.gnome	13
lada.commerce	12
mpc.lists.freebsd.office	10
mozilla.support.bugzilla	10
mpc.lists.nanog	8
lada.auto	8
cakewalk.audio	8
oesterreich.tratsch	6
biz.mlm	6
lada.soft	4
mozilla.support.thunderbird	4
alt.politics.usa	4
spline.eisfair	4
gmane.comp.gis.openstreetmap.imports	4
TOTAL: 91	473

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4039
Binary: misplaced rar	648
EMP (fsl)	412
EMP (phn path)	318
EMP (phl)	105
EMP (phn nph)	70
Scoring filter (9)	35
Too many newsgroups	27
Binary Payload	24
Binary: misplaced par2	19
HTML Multipart	18
User-issued cancel	18
Binary: misplaced txt	12
Scoring filter (10)	6
Scoring filter (14)	6
Too many newsgroups (meow)	2
Scoring filter (11)	2
Binary Image: misplaced jpg	1
HTML Image Tags	1
User-issued spam cancel	1
TOTAL: 20	5764

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	297
	TOTAL: 1	297
Bad Message-ID's offered
	csiph-out.feeder.erje.net	8
	TOTAL: 1	8
Huge articles
	csiph-out.feeder.erje.net	180
	csiph-out.news.weretis.net	96
	news.fcku.it	62
	news-out.mixmin.net	10
	feeder.eternal-september.org	7
	news.redatomik.org	6
	newsfeeds.xmission.com	2
	TOTAL: 7	363
Including strange strings
	newsfeed.fu-berlin.de	466
	csiph-out.news.weretis.net	50
	csiph-out.feeder.erje.net	29
	news.swapon.de	17
	news.redatomik.org	7
	feeder.eternal-september.org	4
	newsfeed.neva.ru	4
	news-out.aioe.org	3
	news-out.mixmin.net	2
	news.fcku.it	2
	TOTAL: 10	584
No colon-space in header
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
TOTAL: 5		1255

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20831	1065	17663	0	0	5%	23:59:50
2	news.freedyn.net	19865	914	17776	20	1	4%	23:59:25
3	news.bbs.geek.nz	33933	900	17103	6	2	2%	23:59:49
4	fu-berlin.de	17524	684	16838	0	0	3%	23:59:53
5	usenet.pasdenom.info	20440	154	17282	1268	11	0%	23:59:53
6	aioe	16212	97	15382	0	0	0%	23:59:44
7	eternal-september	19294	57	15568	57	0	0%	23:59:53
8	erje	16433	38	15611	35	10	0%	23:59:40
9	news.redatomik.org	18663	29	17572	0	17	0%	23:59:53
10	neva.ru	17180	23	16626	190	0	0%	23:59:53
11	weretis.net	12906	23	11078	6	0	0%	23:59:34
12	xmission	17800	20	15772	3	0	0%	23:59:11
13	news.swapon.de	1619	11	1590	0	0	0%	23:41:54
14	news.mixmin.net	17192	11	16771	5	0	0%	23:59:39
15	news.fcku.it	1319	0	1315	0	0	0%	22:01:51
TOTAL: 15		251211	4026	213947	1590	41	1%	357:40:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.4 MB	0.0 KB	9.4 MB	0.1 KB/s	9.0 KB	23:59:50
2	news.freedyn.net	8.9 MB	99.6 KB	9.0 MB	0.1 KB/s	9.9 KB	23:59:25
3	fu-berlin.de	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	12.5 KB	23:59:53
4	news.bbs.geek.nz	3.1 MB	18.3 KB	3.1 MB	0.0 KB/s	3.5 KB	23:59:49
5	usenet.pasdenom.info	468.9 KB	9.2 MB	9.7 MB	0.1 KB/s	7.0 KB	23:59:53
6	aioe	215.1 KB	0.0 KB	215.1 KB	0.0 KB/s	2.2 KB	23:59:44
7	eternal-september	200.4 KB	187.6 KB	388.1 KB	0.0 KB/s	3.4 KB	23:59:53
8	erje	139.0 KB	54.9 KB	193.9 KB	0.0 KB/s	2.7 KB	23:59:40
9	weretis.net	91.6 KB	22.3 KB	113.9 KB	0.0 KB/s	3.9 KB	23:59:34
10	news.redatomik.org	76.6 KB	0.0 KB	76.6 KB	0.0 KB/s	2.6 KB	23:59:53
11	xmission	56.8 KB	40.4 KB	97.2 KB	0.0 KB/s	4.2 KB	23:59:11
12	neva.ru	47.3 KB	408.8 KB	456.1 KB	0.0 KB/s	2.1 KB	23:59:53
13	news.mixmin.net	41.9 KB	5.1 KB	47.1 KB	0.0 KB/s	2.9 KB	23:59:39
14	news.swapon.de	27.8 KB	0.0 KB	27.8 KB	0.0 KB/s	2.5 KB	23:41:54
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:01:51
TOTAL: 15		31.0 MB	10.0 MB	41.0 MB	0.0 KB/s	7.5 KB	357:40:02

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	47	78	266.7 KB	55	0	0	00:40:35
2	194-73-239-109.cust.centrio.cz	22	35	121.4 KB	16	6	0	03:04:23
3	96-42-18-226.dhcp.mdsn.wi.charter.com	24	10	23.0 KB	10	10	10	06:58:54
4	a131033.upc-a.chello.nl	37	6	8.3 KB	2	0	0	11:30:51
5	cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	4	3	5.3 KB	2	0	0	01:08:11
6	tempe0.bbox.io	10	2	11.5 KB	2	0	0	03:37:59
7	mtrlpq2314w-lp130-05-70-55-71-209.dsl.bell.ca	50	2	11.6 KB	2	0	0	00:04:05
TOTAL: 7		194	136	447.9 KB	89	16	10	27:05:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	47	78	266.7 KB	55	0	0	00:40:35
2	*.cust.centrio.cz	22	35	121.4 KB	16	6	0	03:04:23
3	*.dhcp.mdsn.wi.charter.com	24	10	23.0 KB	10	10	10	06:58:54
4	*.upc-a.chello.nl	37	6	8.3 KB	2	0	0	11:30:51
5	*.19-1.cable.virginm.net	4	3	5.3 KB	2	0	0	01:08:11
6	*.bbox.io	10	2	11.5 KB	2	0	0	03:37:59
7	*.dsl.bell.ca	50	2	11.6 KB	2	0	0	00:04:05
TOTAL: 7		194	136	447.9 KB	89	16	10	27:05:01

NNRP auth users [Top 20]:

User	Conn
moussa	47
ctomczyk	20
ohlandl	10
TOTAL: 3	77

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.862	0.500	0.000	11:30:51
96-42-18-226.dhcp.mdsn.wi.charter.com	2.468	0.488	0.037	06:58:54
tempe0.bbox.io	1.012	0.226	0.000	03:37:59
194-73-239-109.cust.centrio.cz	2.535	0.435	0.071	03:04:23
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.468	0.062	0.000	01:08:11
141.168.117.108	5.025	1.086	0.158	00:40:35
mtrlpq2314w-lp130-05-70-55-71-209.dsl.bell.ca	5.036	0.811	0.000	00:04:05
TOTAL: 7	19.406	3.608	0.266	27:05:01

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	9	0
tempe0.bbox.io	5	0
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	2	0
194-73-239-109.cust.centrio.cz	2	0
TOTAL: 4	18	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	83	61.0%
2	pl	34	25.0%
3	alt	16	11.8%
4	muc	2	1.5%
5	sci	1	0.7%
TOTAL: 5		136	100.0%

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

Newsgroup		Count
1	pl.comp.lang.javascript	33
2	comp.sys.ibm.ps2.hardware	27
3	alt.comp.freeware	16
4	comp.os.vms	14
5	comp.ai.philosophy	13
6	comp.arch	8
7	comp.os.msdos.programmer	5
8	comp.sys.ibm.pc.games.action	3
9	comp.os.os2.misc	3
10	comp.lang.pl1	3
11	comp.arch.fpga	2
12	comp.bbs.misc	2
13	muc.lists.freebsd.current	2
14	comp.ai.nat-lang	1
15	sci.logic	1
16	comp.lang.javascript	1
17	comp.arch.embedded	1
18	pl.comp.www	1
TOTAL: 18		136

Daily Usenet report for csiph.com

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