Daily Usenet report for csiph.com: Jan 1 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jan 1 06:04:05 csiph pgpverify[62035]: pgpverify: verification failed
Jan 1 14:00:03 csiph pgpverify[82531]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43429	40.8%	7.8 MB	56.6%
nnrpd	36379	34.2%	3.1 MB	22.2%
innd	23665	22.2%	2.4 MB	17.7%
innfeed	2919	2.7%	483.1 KB	3.4%
nocem	70	0.1%	7.3 KB	0.1%
controlchan	9	0.0%	1.3 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 7	106473	100.0%	13.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	507570	61.0%
Negative hits	281694	33.8%
Do not exist	42460	5.1%
Cache misses	860	0.1%
TOTAL: 4	832584	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.506	0.0%	341	0.000	4.416	59.000
article cleanup	00:00:00.243	0.0%	41598	0.000	0.006	0.019
article logging	00:00:00.637	0.0%	42171	0.000	0.015	0.175
article parse	00:00:02.845	0.0%	191272	0.000	0.015	0.115
article write	00:00:29.197	0.0%	23257	0.164	1.255	8.019
artlog/artcncl	00:00:00.002	0.0%	308	0.000	0.006	1.000
artlog/artparse	00:00:00.014	0.0%	1035	0.000	0.014	0.500
data move	00:00:00.218	0.0%	760685	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.547	0.0%	334	0.000	1.638	20.000
hishave/artcncl	00:00:00.184	0.0%	341	0.000	0.540	11.000
hishave/artparse	00:00:00.002	0.0%	343	0.000	0.006	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:36.596	0.0%	834861	0.001	0.044	0.334
history sync	00:00:02.951	0.0%	358	0.000	8.243	104.333
history write	00:01:47.257	0.1%	42260	0.075	2.538	11.803
hiswrite/artcncl	00:00:00.020	0.0%	7	0.000	2.857	6.667
hiswrite/artparse	00:00:01.145	0.0%	342	0.000	3.348	47.000
idle	23:53:02.966	99.5%	791812	32.972	108.590	479.747
nntp read	00:00:02.646	0.0%	803837	0.001	0.003	0.006
overview write	00:01:38.942	0.1%	23257	0.171	4.254	21.278
perl filter	00:02:03.608	0.1%	41473	1.586	2.980	4.813
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.420	0.0%	46582	0.000	0.009	0.034
TOTAL: 24:00:49.666	23:59:51.946	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.073	0.0%	24016	0.000	0.003	0.027
article prepare	00:00:00.003	0.0%	33924	0.000	0.000	0.005
article read	00:00:00.675	0.0%	4563	0.000	0.148	3.294
backlog stats	00:00:00.217	0.0%	472397	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.836	0.0%	342790	0.002	0.005	0.011
data write	00:00:02.394	0.0%	323331	0.003	0.007	0.014
idle	23:54:17.032	100.0%	472397	96.737	182.171	575.156
status file	00:00:00.175	0.0%	270	0.000	0.648	1.000
TOTAL: 23:54:29.303	23:54:22.405	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:16.721	0.0%	97385	0.000	0.172	20.200
idle	234:41:59.520	99.8%	131689	406.373	6416.022	600150.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.565	0.0%	264831	0.000	0.006	0.200
readart	00:00:00.122	0.0%	103	0.000	1.184	14.000
TOTAL: 235:05:31.526	234:42:17.928	99.8%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	Other	Bad PGP	OK
bofh-control@killfile.org	1	0	1
moderator@dana.de	0	1	0
us-control@lists.killfile.org	0	1	0
TOTAL	1	2	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	39627	8096	18439	13092	20%	23:49:45
2	csiph-out.feeder.erje.net	1152	166228	3603	159757	2868	2%	95:12:15
3	feeder.eternal-september.org	3	37977	3443	34167	367	9%	25:01:58
4	news-out.mixmin.net	3	25008	1837	22870	301	7%	24:08:53
5	newsfeed.neva.ru	147	74926	1533	71724	1669	2%	72:04:56
6	newsfeed.fu-berlin.de	28	23851	1333	22473	45	5%	29:29:03
7	news.redatomik.org	1	23606	1076	22196	334	4%	22:24:26
8	xanadu-bbs.net	4	41528	588	40301	639	1%	28:15:21
9	newsfeeds.xmission.com	1	21955	551	21378	26	2%	25:24:50
10	au2pb.net	1	20606	517	20048	41	2%	22:06:32
11	news.fcku.it	2	4937	391	4546	0	7%	20:05:15
12	news.freedyn.net	8	13090	62	13012	16	0%	13:19:51
13	nntp.pasdenom.info	2	20595	23	20558	14	0%	23:59:29
14	news.swapon.de	4	126	1	125	0	0%	04:14:42
15	news.bbs.geek.nz	1	5700	0	5697	3	0%	09:38:16
16	localhost	31	0	0	0	0	0%	00:00:00
TOTAL: 16		1676	519760	23054	477291	19415	4%	439:15:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.8 MB	35.5 KB	146.9 MB	166.7 MB	11%	8.1 KB
2	csiph-out.feeder.erje.net	16.3 MB	194.6 KB	10.8 MB	27.3 MB	59%	4.3 KB
3	feeder.eternal-september.org	9.5 MB	4.8 KB	5.3 MB	14.7 MB	64%	4.0 KB
4	news-out.mixmin.net	5.6 MB	0.0 KB	719.1 KB	6.3 MB	88%	3.0 KB
5	newsfeed.neva.ru	4.9 MB	66.6 KB	6.4 MB	11.4 MB	42%	3.7 KB
6	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	128.4 KB	3.1 MB	95%	2.3 KB
7	news.redatomik.org	2.5 MB	1.6 KB	28.4 MB	30.8 MB	7%	22.4 KB
8	newsfeeds.xmission.com	1.8 MB	0.0 KB	357.1 KB	2.1 MB	83%	3.7 KB
9	xanadu-bbs.net	1.4 MB	0.0 KB	15.0 MB	16.5 MB	8%	13.8 KB
10	au2pb.net	1.1 MB	0.0 KB	151.6 KB	1.2 MB	87%	2.2 KB
11	news.fcku.it	778.5 KB	0.0 KB	0.0 KB	778.5 KB	100%	2.0 KB
12	news.freedyn.net	231.0 KB	0.0 KB	324.1 KB	555.0 KB	41%	7.1 KB
13	nntp.pasdenom.info	65.2 KB	0.0 KB	43.9 KB	109.1 KB	59%	2.9 KB
14	news.swapon.de	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	2.7 KB
15	news.bbs.geek.nz	0.0 KB	0.0 KB	4.9 KB	4.9 KB	0%	1.6 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		66.9 MB	303.1 KB	214.4 MB	281.6 MB	23%	6.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 1 04:15:00 - 04:59:59	336	1.4%	0.12	916.2 KB	1.3%	0.34
Jan 1 05:00:00 - 05:59:59	469	2.0%	0.13	1.3 MB	2.0%	0.37
Jan 1 06:00:00 - 06:59:59	381	1.6%	0.11	1.3 MB	1.9%	0.37
Jan 1 07:00:00 - 07:59:59	471	2.0%	0.13	1.5 MB	2.2%	0.41
Jan 1 08:00:00 - 08:59:59	788	3.4%	0.22	2.3 MB	3.4%	0.64
Jan 1 09:00:00 - 09:59:59	996	4.3%	0.28	2.7 MB	4.0%	0.76
Jan 1 10:00:00 - 10:59:59	1147	4.9%	0.32	4.1 MB	6.1%	1.16
Jan 1 11:00:00 - 11:59:59	1099	4.7%	0.31	3.2 MB	4.7%	0.90
Jan 1 12:00:00 - 12:59:59	1154	5.0%	0.32	3.5 MB	5.3%	1.01
Jan 1 13:00:00 - 13:59:59	1175	5.1%	0.33	3.7 MB	5.4%	1.04
Jan 1 14:00:00 - 14:59:59	1143	4.9%	0.32	3.0 MB	4.5%	0.87
Jan 1 15:00:00 - 15:59:59	1213	5.2%	0.34	3.2 MB	4.8%	0.92
Jan 1 16:00:00 - 16:59:59	1610	6.9%	0.45	4.5 MB	6.7%	1.28
Jan 1 17:00:00 - 17:59:59	1308	5.6%	0.36	3.7 MB	5.5%	1.05
Jan 1 18:00:00 - 18:59:59	1323	5.7%	0.37	3.7 MB	5.5%	1.05
Jan 1 19:00:00 - 19:59:59	1360	5.9%	0.38	4.0 MB	5.9%	1.12
Jan 1 20:00:00 - 20:59:59	1234	5.3%	0.34	3.1 MB	4.7%	0.89
Jan 1 21:00:00 - 21:59:59	1299	5.6%	0.36	3.7 MB	5.5%	1.06
Jan 1 22:00:00 - 22:59:59	1023	4.4%	0.28	2.7 MB	4.0%	0.76
Jan 1 23:00:00 - 23:59:59	997	4.3%	0.28	3.2 MB	4.7%	0.90
Jan 2 00:00:00 - 00:59:59	757	3.3%	0.21	2.3 MB	3.4%	0.65
Jan 2 01:00:00 - 01:59:59	721	3.1%	0.20	2.1 MB	3.2%	0.61
Jan 2 02:00:00 - 02:59:59	630	2.7%	0.17	1.7 MB	2.6%	0.49
Jan 2 03:00:00 - 03:59:59	469	2.0%	0.13	1.5 MB	2.2%	0.42
Jan 2 04:00:00 - 04:15:00	142	0.6%	0.16	488.6 KB	0.7%	0.54
TOTAL: 24:00:00	23245	100.0%	0.27	67.3 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	usenet.blueworldhosting.com	13077	613	3	0	12461
2	csiph-out.feeder.erje.net	2892	599	16	2	2275
3	newsfeed.neva.ru	1673	576	8	0	1089
4	xanadu-bbs.net	550	41	0	0	509
5	newsfeed.fu-berlin.de	446	23	0	0	423
6	news.redatomik.org	401	0	1	0	400
7	feeder.eternal-september.org	357	136	2	1	218
8	news-out.mixmin.net	317	6	0	0	311
9	au2pb.net	68	52	0	0	16
10	news.freedyn.net	38	5	0	0	33
11	newsfeeds.xmission.com	23	17	0	0	6
12	nntp.pasdenom.info	14	14	0	0	0
13	news.fcku.it	10	0	0	0	10
14	news.swapon.de	7	0	0	0	7
15	news.bbs.geek.nz	3	3	0	0	0
TOTAL: 15		19876	2085	30	3	17758

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	212
fa.linux.kernel	187
mailing.freebsd.pkg-fallout	86
mailing.freebsd.svn-ports-all	83
mailing.freebsd.svn-ports-head	83
mailing.freebsd.ports-bugs	67
mpc.lists.freebsd.ports.bugs	67
pld.cvs.commit	61
alt.politics.scorched-earth	54
lada.talk	46
alt.windows7.general	38
mailing.openbsd.ports-changes	38
alt.survival	34
alt.binaries.pictures.female.nonude	33
24hoursupport.helpdesk	31
cakewalk.audio	31
mozilla.support.thunderbird	25
fa.openbsd.tech	22
mozilla.support.seamonkey	22
mailing.openbsd.tech	22
TOTAL: 335	2085

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

Groups	Count
alt.culture.china	2
houston.weather	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11967
EMP (phn path)	2849
EMP (phl)	704
Too many newsgroups	95
User-issued cancel	78
Scoring filter (26)	49
Scoring filter (18)	47
Too many newsgroups (low_xpost)	29
Scoring filter (19)	26
Scoring filter (22)	18
Scoring filter (21)	18
Scoring filter (11)	18
Scoring filter (10)	17
HTML Multipart	15
Scoring filter (8)	14
Scoring filter (17)	13
Binary Payload	12
HTML Image Tags	11
Scoring filter (14)	9
Scoring filter (27)	8
TOTAL: 37	16054

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	92
	usenet.blueworldhosting.com	3
	TOTAL: 2	95
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	usenet.blueworldhosting.com	175
	news.redatomik.org	85
	xanadu-bbs.net	38
	feeder.eternal-september.org	32
	newsfeeds.xmission.com	5
	news-out.mixmin.net	4
	news.freedyn.net	3
	news.swapon.de	1
	newsfeed.fu-berlin.de	1
	TOTAL: 9	344
Including strange strings
	newsfeed.fu-berlin.de	399
	usenet.blueworldhosting.com	70
	csiph-out.feeder.erje.net	28
	newsfeed.neva.ru	13
	feeder.eternal-september.org	11
	news-out.mixmin.net	10
	news.fcku.it	10
	news.freedyn.net	10
	au2pb.net	8
	news.redatomik.org	7
	TOTAL: 13	573
No colon-space in header
	csiph-out.feeder.erje.net	551
	usenet.blueworldhosting.com	118
	TOTAL: 2	669
TOTAL: 5		1686

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	23950	3468	19619	14	0	14%	23:59:55
2	xanadu-bbs	23555	1084	21549	47	816	4%	23:59:56
3	fu-berlin.de	21666	912	20741	13	0	4%	23:59:39
4	news.bbs.geek.nz	41951	453	21689	61	1	1%	23:59:55
5	usenet.pasdenom.info	29122	187	21407	1627	5	0%	23:59:56
6	news.redatomik.org	25668	134	20873	20	0	0%	23:59:56
7	au2pb	22020	75	21453	71	0	0%	23:59:56
8	erje	16495	25	16038	90	0	0%	23:59:56
9	neva.ru	23700	11	20329	172	0	0%	23:59:56
10	news.fcku.it	1071	8	883	0	0	0%	21:35:14
11	news.mixmin.net	23445	7	20202	0	0	0%	23:59:56
12	xmission	26418	7	21714	5	0	0%	23:59:56
13	eternal-september	21960	2	18420	1	0	0%	23:59:56
14	news.swapon.de	2126	1	2018	3	1	0%	23:45:22
15	bwh	14291	0	14026	0	0	0%	23:59:47
TOTAL: 15		317438	6374	260961	2124	823	2%	357:19:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	15.9 MB	130.7 KB	16.0 MB	0.2 KB/s	4.7 KB	23:59:55
2	xanadu-bbs	7.9 MB	97.8 KB	8.0 MB	0.1 KB/s	7.2 KB	23:59:56
3	fu-berlin.de	7.3 MB	20.0 KB	7.3 MB	0.1 KB/s	8.1 KB	23:59:39
4	news.bbs.geek.nz	1.3 MB	226.4 KB	1.5 MB	0.0 KB/s	3.0 KB	23:59:55
5	usenet.pasdenom.info	737.6 KB	8.5 MB	9.2 MB	0.1 KB/s	5.2 KB	23:59:56
6	news.redatomik.org	508.4 KB	65.4 KB	573.8 KB	0.0 KB/s	3.7 KB	23:59:56
7	au2pb	407.2 KB	196.1 KB	603.2 KB	0.0 KB/s	4.1 KB	23:59:56
8	neva.ru	68.5 KB	696.2 KB	764.8 KB	0.0 KB/s	4.2 KB	23:59:56
9	erje	52.0 KB	300.3 KB	352.3 KB	0.0 KB/s	3.1 KB	23:59:56
10	xmission	20.4 KB	10.4 KB	30.9 KB	0.0 KB/s	2.6 KB	23:59:56
11	news.fcku.it	20.2 KB	0.0 KB	20.2 KB	0.0 KB/s	2.5 KB	21:35:14
12	news.mixmin.net	18.7 KB	0.0 KB	18.7 KB	0.0 KB/s	2.7 KB	23:59:56
13	news.swapon.de	4.8 KB	6.6 KB	11.4 KB	0.0 KB/s	2.8 KB	23:45:22
14	eternal-september	3.0 KB	0.7 KB	3.7 KB	0.0 KB/s	1.2 KB	23:59:56
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:47
TOTAL: 15		34.2 MB	10.2 MB	44.4 MB	0.0 KB/s	5.4 KB	357:19:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	88	313.2 KB	80	04:35:24
2	challenger.nielsenmedia.com	4	7	12.1 KB	2	01:00:27
3	c-73-195-247-24.hsd1.nj.comcast.net	5	7	12.1 KB	2	01:43:40
4	dhcp-089-099-203-254.chello.nl	16	1	0.8 KB	1	06:41:58
5	tempe0.bbox.io	295	0	0.0 KB	1	49:00:15
TOTAL: 5		608	103	338.2 KB	86	235:05:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	574	88	313.2 KB	80	170:02:31
2	*.hsd1.nj.comcast.net	5	7	12.1 KB	2	01:43:40
3	*.nielsenmedia.com	4	7	12.1 KB	2	01:00:27
4	*.chello.nl	16	1	0.8 KB	1	06:41:58
5	*.bbox.io	295	0	0.0 KB	1	49:00:15
6	*.dynamic.caiway.nl	8	0	0.0 KB	0	06:36:28
TOTAL: 6		902	103	338.2 KB	86	235:05:22

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
63.142.120.8	24.385	11.542	0.000	165:27:06
tempe0.bbox.io	24.731	4.884	0.000	49:00:15
dhcp-089-099-203-254.chello.nl	1.295	0.244	0.000	06:41:58
041-020-158-163.dynamic.caiway.nl	0.957	0.507	0.000	06:36:28
202.89.176.220	29.813	5.938	0.747	04:35:24
c-73-195-247-24.hsd1.nj.comcast.net	0.563	0.092	0.000	01:43:40
challenger.nielsenmedia.com	0.431	0.047	0.000	01:00:27
TOTAL: 7	82.175	23.254	0.747	235:05:22

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	286
041-020-158-163.dynamic.caiway.nl	8
TOTAL: 2	294

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	12	0
041-020-158-163.dynamic.caiway.nl	8	0
c-73-195-247-24.hsd1.nj.comcast.net	3	0
tempe0.bbox.io	2	0
challenger.nielsenmedia.com	2	0
TOTAL: 5	27	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	36
2	comp.os.vms	28
3	comp.sys.ibm.pc.games.action	19
4	comp.lang.awk	14
5	comp.sys.ibm.ps2.hardware	3
6	comp.sys.ibm.pc.games.rpg	2
7	comp.sys.ibm.pc.games.war-historical	2
TOTAL: 7		104

Daily Usenet report for csiph.com

Jan 1 04:15:00 -- Jan 2 04:15:00