Daily Usenet report for csiph.com: Jan 4 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 4 10:50:04 csiph pgpverify[70598]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	673557	49.4%	65.0 MB	52.7%
innd	649926	47.7%	54.7 MB	44.4%
nnrpd	35465	2.6%	3.0 MB	2.4%
innfeed	3164	0.2%	550.7 KB	0.4%
nocem	67	0.0%	6.2 KB	0.0%
controlchan	5	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1362185	100.0%	123.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	3654257	67.3%
Negative hits	1048375	19.3%
Do not exist	664639	12.2%
Cache misses	60362	1.1%
TOTAL: 4	5427633	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.692	0.0%	313	0.000	5.406	35.000
article cleanup	00:00:01.999	0.0%	664879	0.000	0.003	0.024
article logging	00:00:05.038	0.0%	665431	0.004	0.008	0.033
article parse	00:00:49.018	0.1%	2212570	0.004	0.022	0.110
article write	00:00:36.919	0.0%	27787	0.225	1.329	9.154
artlog/artcncl	00:00:00.001	0.0%	264	0.000	0.004	0.100
artlog/artparse	00:00:00.107	0.0%	10313	0.000	0.010	0.333
data move	00:00:01.346	0.0%	3756411	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.327	0.0%	310	0.000	1.055	8.000
hishave/artcncl	00:00:00.003	0.0%	313	0.000	0.010	0.333
hishave/artparse	00:00:00.032	0.0%	10083	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:11.150	0.0%	5491363	0.001	0.002	0.006
history sync	00:00:02.631	0.0%	322	0.000	8.171	79.667
history write	00:08:23.166	0.6%	664172	0.209	0.758	8.313
hiswrite/artcncl	00:00:00.036	0.0%	3	0.500	12.000	35.000
hiswrite/artparse	00:00:28.680	0.0%	10082	0.000	2.845	106.000
idle	23:28:32.987	98.5%	4068172	4.565	20.774	274.073
nntp read	00:00:13.166	0.0%	4110837	0.002	0.003	0.005
overview write	00:01:59.693	0.1%	27787	0.298	4.308	25.113
perl filter	00:06:15.659	0.4%	663837	0.388	0.566	4.412
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.510	0.0%	55635	0.000	0.009	0.029
TOTAL: 23:50:29.412	23:47:44.160	99.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.087	0.0%	28164	0.000	0.003	0.033
article prepare	00:00:00.007	0.0%	44168	0.000	0.000	0.003
article read	00:00:01.004	0.0%	6138	0.000	0.164	2.667
backlog stats	00:00:00.255	0.0%	560919	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.172	0.0%	408381	0.001	0.005	0.010
data write	00:00:02.654	0.0%	390648	0.003	0.007	0.012
idle	23:53:16.590	100.0%	560919	79.595	153.314	431.666
status file	00:00:00.161	0.0%	286	0.000	0.563	1.000
TOTAL: 23:53:30.863	23:53:22.930	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.305	0.0%	16625	0.000	0.078	0.239
idle	94:24:20.965	100.0%	50818	155.000	6687.807	600232.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.614	0.0%	102794	0.000	0.006	0.500
readart	00:00:00.015	0.0%	100	0.000	0.150	3.000
TOTAL: 94:26:08.281	94:24:22.899	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
shutdown	6
TOTAL: 11	173

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
admin@openwatcom.news-admin.org	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	usenet.blueworldhosting.com	290	668828	8575	63833	596420	1%	23:40:48
2	news-out.mixmin.net	5	30878	4672	25212	994	15%	24:10:54
3	feeder.eternal-september.org	14	43818	4196	38844	778	9%	26:30:29
4	csiph-out.feeder.erje.net	1160	2656499	2886	2626147	27466	0%	94:49:57
5	newsfeed.neva.ru	120	152738	1869	147613	3256	1%	59:57:57
6	au2pb.net	2	29263	1504	27474	285	5%	21:41:41
7	newsfeed.fu-berlin.de	64	31989	1462	30409	118	4%	36:28:41
8	news.redatomik.org	11	38916	874	28253	9789	2%	28:07:09
9	newsfeeds.xmission.com	3	30153	844	29228	81	2%	28:36:37
10	xanadu-bbs.net	12	680701	459	674596	5646	0%	28:29:13
11	news.freedyn.net	10	29375	339	28981	55	1%	24:08:52
12	news.fcku.it	6	2498	299	2199	0	11%	17:53:10
13	news.swapon.de	2	2093	76	2017	0	3%	18:27:59
14	nntp.pasdenom.info	5	27628	74	27524	30	0%	24:07:57
15	localhost	36	3	3	0	0	100%	00:00:00
16	news.bbs.geek.nz	4	37955	1	37947	7	0%	35:13:13
TOTAL: 16		1744	4463335	28133	3790277	644925	0%	492:24:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	24.1 MB	231.2 KB	795.6 MB	819.9 MB	2%	1.4 KB
2	csiph-out.feeder.erje.net	15.0 MB	1.1 MB	45.6 MB	61.7 MB	24%	2.1 KB
3	news-out.mixmin.net	14.2 MB	40.9 KB	2.1 MB	16.4 MB	86%	3.0 KB
4	feeder.eternal-september.org	11.6 MB	175.1 KB	10.1 MB	21.8 MB	53%	4.5 KB
5	newsfeed.neva.ru	5.7 MB	128.2 KB	21.9 MB	27.7 MB	20%	5.5 KB
6	au2pb.net	3.7 MB	8.1 KB	1.3 MB	5.0 MB	74%	2.9 KB
7	newsfeed.fu-berlin.de	3.5 MB	15.5 KB	562.5 KB	4.1 MB	86%	2.7 KB
8	newsfeeds.xmission.com	2.8 MB	1.2 KB	1.3 MB	4.1 MB	68%	4.6 KB
9	news.redatomik.org	2.3 MB	1.2 KB	2.2 GB	2.2 GB	0%	218.6 KB
10	news.freedyn.net	1.5 MB	2.7 KB	811.1 KB	2.2 MB	64%	5.8 KB
11	xanadu-bbs.net	1.3 MB	23.2 KB	267.6 MB	269.0 MB	0%	45.1 KB
12	news.fcku.it	578.5 KB	0.0 KB	0.0 KB	578.5 KB	100%	1.9 KB
13	news.swapon.de	230.7 KB	0.0 KB	0.0 KB	230.7 KB	100%	3.0 KB
14	nntp.pasdenom.info	171.3 KB	0.0 KB	197.1 KB	368.4 KB	46%	3.5 KB
15	localhost	9.6 KB	0.0 KB	0.0 KB	9.6 KB	100%	3.2 KB
16	news.bbs.geek.nz	1.3 KB	0.0 KB	8.2 KB	9.5 KB	13%	1.2 KB
TOTAL: 16		86.8 MB	1.7 MB	3.3 GB	3.4 GB	2%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 4 04:15:01 - 04:59:59	357	1.3%	0.13	1.3 MB	1.5%	0.49
Jan 4 05:00:00 - 05:59:59	680	2.4%	0.19	2.6 MB	3.0%	0.74
Jan 4 06:00:00 - 06:59:59	525	1.9%	0.15	2.5 MB	2.8%	0.70
Jan 4 07:00:00 - 07:59:59	592	2.1%	0.16	1.7 MB	1.9%	0.47
Jan 4 08:00:00 - 08:59:59	1028	3.7%	0.29	3.4 MB	3.9%	0.96
Jan 4 09:00:00 - 09:59:59	1046	3.7%	0.29	3.0 MB	3.5%	0.86
Jan 4 10:00:00 - 10:59:59	1226	4.4%	0.34	3.4 MB	3.9%	0.97
Jan 4 11:00:00 - 11:59:59	1307	4.7%	0.36	3.5 MB	4.1%	1.01
Jan 4 12:00:00 - 12:59:59	1347	4.8%	0.37	4.3 MB	5.0%	1.23
Jan 4 13:00:00 - 13:59:59	1229	4.4%	0.34	3.1 MB	3.6%	0.89
Jan 4 14:00:00 - 14:59:59	1305	4.6%	0.36	3.4 MB	3.9%	0.97
Jan 4 15:00:00 - 15:59:59	1405	5.0%	0.39	4.5 MB	5.2%	1.27
Jan 4 16:00:00 - 16:59:59	1609	5.7%	0.45	5.1 MB	5.8%	1.44
Jan 4 17:00:00 - 17:59:59	1644	5.9%	0.46	4.7 MB	5.4%	1.33
Jan 4 18:00:00 - 18:59:59	1659	5.9%	0.46	5.6 MB	6.5%	1.59
Jan 4 19:00:00 - 19:59:59	1699	6.1%	0.47	4.8 MB	5.6%	1.38
Jan 4 20:00:00 - 20:59:59	1914	6.8%	0.53	5.9 MB	6.8%	1.67
Jan 4 21:00:00 - 21:59:59	1613	5.7%	0.45	4.5 MB	5.2%	1.27
Jan 4 22:00:00 - 22:59:59	1723	6.1%	0.48	6.4 MB	7.4%	1.81
Jan 4 23:00:00 - 23:59:59	1222	4.4%	0.34	3.7 MB	4.3%	1.06
Jan 5 00:00:00 - 00:59:59	937	3.3%	0.26	2.9 MB	3.3%	0.82
Jan 5 01:00:00 - 01:59:59	719	2.6%	0.20	2.2 MB	2.5%	0.62
Jan 5 02:00:00 - 02:59:59	610	2.2%	0.17	2.0 MB	2.3%	0.57
Jan 5 03:00:00 - 03:59:59	508	1.8%	0.14	1.5 MB	1.8%	0.44
Jan 5 04:00:00 - 04:15:00	178	0.6%	0.20	615.7 KB	0.7%	0.68
TOTAL: 23:59:59	28082	100.0%	0.33	86.5 MB	100.0%	1.03

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	596517	1568	177	594772
2	csiph-out.feeder.erje.net	27326	535	684	26107
3	news.redatomik.org	9755	0	1	9754
4	xanadu-bbs.net	5589	75	13	5501
5	newsfeed.neva.ru	3258	1285	21	1952
6	news-out.mixmin.net	1006	1	12	993
7	feeder.eternal-september.org	758	219	16	523
8	newsfeed.fu-berlin.de	505	58	2	445
9	au2pb.net	305	218	2	85
10	newsfeeds.xmission.com	77	66	1	10
11	news.freedyn.net	63	0	1	62
12	nntp.pasdenom.info	31	27	0	4
13	news.swapon.de	10	0	0	10
14	news.fcku.it	6	0	0	6
15	news.bbs.geek.nz	5	4	0	1
TOTAL: 15		645211	4056	930	640225

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	782
alt.biology	326
lada.talk	187
mailing.freebsd.pkg-fallout	178
mailing.freebsd.ports-bugs	144
alt.binaries.multimedia.tatu	139
ut.test	108
mozilla.dev.platform	88
mailing.freebsd.svn-ports-all	82
mailing.freebsd.svn-ports-head	70
alt.survival	67
mailing.freebsd.python	65
mailing.freebsd.svn-src-all	65
fa.freebsd.cvs-all	64
pld.cvs.commit	59
lada.auto	52
mpc.lists.nanog	38
mailing.database.pgsql-general	37
fa.freebsd.bugs	37
mailing.freebsd.bugs	37
TOTAL: 257	4056

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	622430
EMP (phn path)	4000
PFM bot misc	1040
EMP (phl)	681
Binary: misplaced rar	298
EMP (phn nph)	210
User-issued cancel	149
Scoring filter (12)	61
HTML Multipart	60
Scoring filter (10)	46
Too many newsgroups	36
Scoring filter (11)	34
Scoring filter (8)	29
Scoring filter (21)	29
Scoring filter (20)	20
Scoring filter (9)	17
Binary Payload	14
HTML Image Tags	13
Scoring filter (19)	11
Scoring filter (13)	10
TOTAL: 36	629246

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	100
	usenet.blueworldhosting.com	5
	TOTAL: 2	105
Huge articles
	news.redatomik.org	9239
	xanadu-bbs.net	692
	usenet.blueworldhosting.com	75
	feeder.eternal-september.org	46
	news-out.mixmin.net	11
	newsfeeds.xmission.com	7
	news.freedyn.net	6
	au2pb.net	4
	newsfeed.fu-berlin.de	3
	nntp.pasdenom.info	1
	TOTAL: 10	10084
Including strange strings
	newsfeed.fu-berlin.de	392
	usenet.blueworldhosting.com	48
	feeder.eternal-september.org	35
	news-out.mixmin.net	28
	news.swapon.de	10
	news.freedyn.net	8
	csiph-out.feeder.erje.net	8
	newsfeed.neva.ru	8
	news.fcku.it	6
	au2pb.net	6
	TOTAL: 13	556
No colon-space in header
	csiph-out.feeder.erje.net	203
	usenet.blueworldhosting.com	25
	TOTAL: 2	228
TOTAL: 4		10973

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	29194	3414	24212	114	0	11%	23:59:26
2	news.bbs.geek.nz	50049	2310	24679	103	179	4%	23:59:25
3	xanadu-bbs	30100	1168	26322	91	101	3%	23:59:27
4	fu-berlin.de	26198	949	25214	29	0	3%	23:59:26
5	usenet.pasdenom.info	34629	430	26071	1505	8	1%	23:59:25
6	news.redatomik.org	31833	215	25928	14	0	0%	23:59:26
7	au2pb	25995	81	25323	103	0	0%	23:59:26
8	xmission	31135	27	26176	2	0	0%	23:59:27
9	news.mixmin.net	26724	26	21644	2	0	0%	23:59:26
10	neva.ru	21342	26	18830	165	5901	0%	23:58:56
11	eternal-september	27132	21	22389	11	0	0%	23:59:26
12	erje	23123	11	21179	296	5	0%	23:59:25
13	news.fcku.it	2740	9	2396	0	0	0%	22:54:21
14	weretis.net	4810	4	4613	2	0	0%	06:06:49
15	bwh	18598	3	18225	0	0	0%	23:59:27
16	news.swapon.de	2660	2	2532	2	0	0%	23:59:26
TOTAL: 16		386262	8696	315733	2439	6194	2%	364:52:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	17.5 MB	961.5 KB	18.4 MB	0.2 KB/s	5.3 KB	23:59:26
2	xanadu-bbs	9.0 MB	185.4 KB	9.2 MB	0.1 KB/s	7.5 KB	23:59:27
3	fu-berlin.de	8.3 MB	38.8 KB	8.3 MB	0.1 KB/s	8.7 KB	23:59:26
4	news.bbs.geek.nz	6.8 MB	391.0 KB	7.2 MB	0.1 KB/s	3.0 KB	23:59:25
5	usenet.pasdenom.info	1.6 MB	9.5 MB	11.1 MB	0.1 KB/s	5.9 KB	23:59:25
6	news.redatomik.org	741.5 KB	131.1 KB	872.7 KB	0.0 KB/s	3.8 KB	23:59:26
7	au2pb	383.5 KB	351.5 KB	735.0 KB	0.0 KB/s	4.0 KB	23:59:26
8	news.mixmin.net	138.8 KB	17.2 KB	156.0 KB	0.0 KB/s	5.6 KB	23:59:26
9	xmission	119.1 KB	4.1 KB	123.2 KB	0.0 KB/s	4.2 KB	23:59:27
10	eternal-september	95.0 KB	28.4 KB	123.4 KB	0.0 KB/s	3.9 KB	23:59:26
11	neva.ru	78.4 KB	829.8 KB	908.3 KB	0.0 KB/s	4.8 KB	23:58:56
12	news.fcku.it	25.1 KB	0.0 KB	25.1 KB	0.0 KB/s	2.8 KB	22:54:21
13	erje	23.6 KB	1.7 MB	1.7 MB	0.0 KB/s	5.7 KB	23:59:25
14	weretis.net	11.8 KB	50.7 KB	62.5 KB	0.0 KB/s	10.4 KB	06:06:49
15	bwh	9.6 KB	0.0 KB	9.6 KB	0.0 KB/s	3.2 KB	23:59:27
16	news.swapon.de	7.1 KB	3.8 KB	10.9 KB	0.0 KB/s	2.7 KB	23:59:26
TOTAL: 16		44.7 MB	14.1 MB	58.8 MB	0.0 KB/s	5.4 KB	364:52:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	287	85	279.4 KB	81	0	0	04:25:04
2	tempe0.bbox.io	372	15	33.0 KB	11	3	3	53:27:34
TOTAL: 2		659	100	312.4 KB	92	3	3	94:26:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	325	85	279.4 KB	81	0	0	30:03:41
2	*.bbox.io	372	15	33.0 KB	11	3	3	53:27:34
3	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	0	0	01:30:14
4	*.chello.nl	21	0	0.0 KB	0	0	0	08:43:17
5	*.nielsenmedia.com	2	0	0.0 KB	0	0	0	00:41:14
TOTAL: 5		724	100	312.4 KB	92	3	3	94:26:01

NNRP auth users [Top 20]:

User	Conn
moussa	287
kev009	3
TOTAL: 2	290

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tempe0.bbox.io	29.109	8.175	0.008	53:27:34
162.212.88.221	2.590	1.315	0.000	23:39:49
dhcp-089-099-203-254.chello.nl	1.315	0.548	0.000	08:43:17
202.89.176.220	27.507	7.776	0.718	04:25:04
50.96.155.42	0.089	0.131	0.000	01:58:45
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.307	0.094	0.000	01:30:14
challenger.nielsenmedia.com	0.166	0.024	0.000	00:41:14
76.235.175.221	0.089	0.024	0.000	00:00:01
TOTAL: 8	61.172	18.087	0.726	94:26:01

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	36
dhcp-089-099-203-254.chello.nl	21
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
challenger.nielsenmedia.com	2
50.96.155.42	1
76.235.175.221	1
TOTAL: 6	65

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	16	0
162.212.88.221	8	0
tempe0.bbox.io	6	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	33	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	79	79.0%
2	news	15	15.0%
3	bit	6	6.0%
TOTAL: 3		100	100.0%

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

Newsgroup		Count
1	comp.arch	25
2	comp.arch.embedded	17
3	news.admin.peering	15
4	comp.os.vms	15
5	bit.listserv.ibm-main	6
6	comp.os.os2.misc	4
7	comp.os.os2.announce	3
8	comp.sys.ibm.pc.games.war-historical	2
9	comp.answers	2
10	comp.unix.aix	2
11	comp.sys.ibm.pc.games.rpg	2
12	comp.arch.fpga	2
13	comp.sys.ibm.pc.hardware.storage	2
14	comp.sys.ibm.pc.games.action	1
15	comp.ai.philosophy	1
16	comp.os.os2.setup.misc	1
TOTAL: 16		100

Daily Usenet report for csiph.com

Jan 4 04:15:01 -- Jan 5 04:15:00