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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 17 12:10:02 csiph pgpverify[24048]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44901	55.9%	8.7 MB	69.3%
innd	22750	28.3%	2.5 MB	19.9%
nnrpd	9300	11.6%	829.8 KB	6.5%
innfeed	3205	4.0%	545.7 KB	4.3%
nocem	97	0.1%	10.2 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	80258	100.0%	12.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	520572	56.9%
Negative hits	349464	38.2%
Do not exist	44084	4.8%
Cache misses	527	0.1%
TOTAL: 4	914647	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.444	0.0%	205	0.000	2.166	15.375
article cleanup	00:00:00.245	0.0%	43969	0.000	0.006	0.027
article logging	00:00:00.631	0.0%	44548	0.000	0.014	0.108
article parse	00:00:01.998	0.0%	180934	0.001	0.011	0.103
article write	00:01:30.433	0.1%	25747	0.180	3.512	16.537
artlog/artcncl	00:00:00.003	0.0%	113	0.000	0.027	0.167
artlog/artparse	00:00:00.003	0.0%	265	0.000	0.011	0.333
data move	00:00:00.278	0.0%	921398	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.041	0.0%	203	0.000	0.202	8.000
hishave/artcncl	00:00:00.011	0.0%	205	0.000	0.054	9.000
hishave/artparse	00:00:00.000	0.0%	265	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:16.965	0.0%	916391	0.001	0.019	0.085
history sync	00:00:02.599	0.0%	365	0.000	7.121	52.000
history write	00:01:25.283	0.1%	43937	0.068	1.941	15.067
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.587	0.0%	265	0.000	2.215	35.000
idle	23:53:27.509	99.5%	933285	54.363	92.156	317.864
nntp read	00:00:02.992	0.0%	943185	0.001	0.003	0.006
overview write	00:01:27.054	0.1%	25747	0.319	3.381	31.247
perl filter	00:01:46.133	0.1%	43923	1.194	2.416	3.894
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.468	0.0%	51584	0.000	0.009	0.036
TOTAL: 24:00:59.150	24:00:03.677	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.063	0.0%	25701	0.000	0.002	0.020
article prepare	00:00:00.006	0.0%	44364	0.000	0.000	0.006
article read	00:00:01.233	0.0%	5056	0.000	0.244	9.641
backlog stats	00:00:00.291	0.0%	628725	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.353	0.0%	450163	0.002	0.005	0.010
data write	00:00:03.137	0.0%	429328	0.003	0.007	0.014
idle	23:52:42.995	100.0%	628725	65.794	136.726	397.993
status file	00:00:00.195	0.0%	273	0.000	0.714	1.250
TOTAL: 23:52:59.055	23:52:50.273	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	11:33:03.770	99.9%	9284	605.000	4479.079	600131.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.113	0.0%	18956	0.000	0.006	0.071
readart	00:00:00.516	0.0%	78	0.000	6.615	13.000
TOTAL: 11:33:28.400	11:33:04.399	99.9%	-	-	-	-

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	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	usenet.blueworldhosting.com	288	30965	5869	22923	2173	18%	23:44:44
2	csiph-out.feeder.erje.net	1152	94157	4432	87921	1804	4%	94:59:27
3	feeder.eternal-september.org	6	38335	3248	33888	1199	8%	24:01:08
4	news-out.mixmin.net	3	30556	2741	25628	2187	8%	24:08:44
5	news-out.aioe.org	4	24927	2530	22339	58	10%	24:09:28
6	csiph-out.news.weretis.net	4	57404	1974	55180	250	3%	51:44:18
7	newsfeed.neva.ru	150	55613	1274	49362	4977	2%	71:34:18
8	newsfeed.fu-berlin.de	34	27410	1272	25853	285	4%	28:04:38
9	newsfeeds.xmission.com	1	25581	547	25023	11	2%	25:24:50
10	news.fcku.it	1	10251	501	9750	0	4%	31:32:23
11	news.freedyn.net	3	29703	488	28624	591	1%	24:09:15
12	xanadu-bbs.net	1	42701	369	37335	4997	0%	24:12:18
13	news.redatomik.org	1	23013	274	22732	7	1%	23:17:53
14	news.swapon.de	4	2350	226	2114	10	9%	23:05:52
15	au2pb.net	1	27210	66	27113	31	0%	24:24:17
16	nntp.pasdenom.info	2	24833	7	24823	3	0%	23:59:31
17	news.bbs.geek.nz	1	23970	2	23968	0	0%	23:59:44
18	localhost	35	0	0	0	0	0%	00:00:00
TOTAL: 18		1691	568979	25820	524576	18583	4%	566:32:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	17.1 MB	7.8 KB	4.8 MB	21.9 MB	78%	3.6 KB
2	usenet.blueworldhosting.com	16.5 MB	1.8 KB	22.8 MB	39.3 MB	41%	5.0 KB
3	feeder.eternal-september.org	8.1 MB	5.5 KB	11.5 MB	19.5 MB	41%	4.5 KB
4	news-out.mixmin.net	8.0 MB	0.0 KB	4.0 MB	12.0 MB	66%	2.5 KB
5	csiph-out.news.weretis.net	6.1 MB	1.8 KB	7.8 MB	14.0 MB	43%	6.4 KB
6	news-out.aioe.org	5.8 MB	1.8 KB	109.8 KB	5.9 MB	98%	2.3 KB
7	newsfeed.neva.ru	3.4 MB	99.7 KB	31.7 MB	35.2 MB	9%	5.8 KB
8	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	621.7 KB	3.6 MB	82%	2.3 KB
9	newsfeeds.xmission.com	1.8 MB	1.8 KB	504.2 KB	2.3 MB	78%	4.2 KB
10	news.freedyn.net	1.4 MB	1.8 KB	4.5 MB	5.9 MB	23%	5.6 KB
11	xanadu-bbs.net	955.6 KB	14.1 KB	52.3 MB	53.3 MB	1%	10.2 KB
12	news.fcku.it	851.8 KB	0.0 KB	0.0 KB	851.8 KB	100%	1.7 KB
13	news.redatomik.org	681.8 KB	0.0 KB	88.5 KB	770.4 KB	88%	2.7 KB
14	news.swapon.de	589.5 KB	0.0 KB	21.5 KB	611.0 KB	96%	2.6 KB
15	au2pb.net	263.1 KB	0.0 KB	189.4 KB	452.5 KB	58%	4.7 KB
16	nntp.pasdenom.info	14.4 KB	1.8 KB	55.0 KB	71.2 KB	20%	7.1 KB
17	news.bbs.geek.nz	4.6 KB	0.0 KB	0.0 KB	4.6 KB	100%	2.3 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		74.4 MB	138.0 KB	140.9 MB	215.5 MB	34%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 17 04:15:00 - 04:59:59	421	1.6%	0.16	1.2 MB	1.7%	0.47
Jan 17 05:00:00 - 05:59:59	463	1.8%	0.13	1.2 MB	1.7%	0.35
Jan 17 06:00:00 - 06:59:59	448	1.7%	0.12	1.5 MB	2.0%	0.41
Jan 17 07:00:00 - 07:59:59	542	2.1%	0.15	1.7 MB	2.2%	0.47
Jan 17 08:00:00 - 08:59:59	837	3.3%	0.23	2.4 MB	3.2%	0.68
Jan 17 09:00:00 - 09:59:59	939	3.6%	0.26	2.7 MB	3.7%	0.77
Jan 17 10:00:00 - 10:59:59	1338	5.2%	0.37	4.8 MB	6.4%	1.36
Jan 17 11:00:00 - 11:59:59	1130	4.4%	0.31	3.7 MB	4.9%	1.04
Jan 17 12:00:00 - 12:59:59	1147	4.5%	0.32	3.4 MB	4.5%	0.95
Jan 17 13:00:00 - 13:59:59	1230	4.8%	0.34	3.5 MB	4.7%	0.99
Jan 17 14:00:00 - 14:59:59	1378	5.4%	0.38	4.2 MB	5.6%	1.19
Jan 17 15:00:00 - 15:59:59	1451	5.6%	0.40	4.5 MB	6.1%	1.28
Jan 17 16:00:00 - 16:59:59	1512	5.9%	0.42	4.0 MB	5.4%	1.14
Jan 17 17:00:00 - 17:59:59	1457	5.7%	0.40	3.8 MB	5.2%	1.09
Jan 17 18:00:00 - 18:59:59	1731	6.7%	0.48	4.8 MB	6.5%	1.36
Jan 17 19:00:00 - 19:59:59	1594	6.2%	0.44	4.6 MB	6.2%	1.30
Jan 17 20:00:00 - 20:59:59	1480	5.8%	0.41	4.0 MB	5.3%	1.12
Jan 17 21:00:00 - 21:59:59	1538	6.0%	0.43	4.0 MB	5.4%	1.14
Jan 17 22:00:00 - 22:59:59	1323	5.1%	0.37	3.6 MB	4.8%	1.02
Jan 17 23:00:00 - 23:59:59	934	3.6%	0.26	2.6 MB	3.4%	0.73
Jan 18 00:00:00 - 00:59:59	815	3.2%	0.23	2.3 MB	3.0%	0.64
Jan 18 01:00:00 - 01:59:59	723	2.8%	0.20	2.1 MB	2.9%	0.61
Jan 18 02:00:00 - 02:59:59	594	2.3%	0.17	1.6 MB	2.2%	0.46
Jan 18 03:00:00 - 03:59:59	603	2.3%	0.17	1.9 MB	2.5%	0.53
Jan 18 04:00:00 - 04:15:00	109	0.4%	0.12	383.0 KB	0.5%	0.43
TOTAL: 24:00:00	25737	100.0%	0.30	74.2 MB	100.0%	0.88

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	4948	825	14	4109
2	xanadu-bbs.net	4899	8	2	4889
3	news-out.mixmin.net	2201	1	0	2200
4	usenet.blueworldhosting.com	2167	762	1	1404
5	csiph-out.feeder.erje.net	1811	1047	2	762
6	feeder.eternal-september.org	1233	120	3	1110
7	newsfeed.fu-berlin.de	781	70	1	710
8	news.freedyn.net	592	5	1	586
9	csiph-out.news.weretis.net	241	139	2	100
10	news-out.aioe.org	83	54	1	28
11	news.swapon.de	39	0	0	39
12	au2pb.net	31	22	1	8
13	newsfeeds.xmission.com	11	6	1	4
14	news.redatomik.org	8	0	1	7
15	nntp.pasdenom.info	3	1	1	1
16	news.fcku.it	2	0	0	2
17	news.bbs.geek.nz	1	0	1	0
TOTAL: 17		19051	3060	32	15959

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	263
mailing.freebsd.svn-ports-head	253
ut.test	210
fa.linux.kernel	194
lada.talk	108
it-alt.sport.calcio.juventus	99
mailing.freebsd.pkg-fallout	91
mailing.freebsd.ports-bugs	69
mozilla.general	67
fa.netbsd.bugs	65
lada.auto	64
mailing.freebsd.gnome	63
alt.survival	55
alt.biology	52
spline.eisfair	51
mailing.openbsd.ports-changes	46
alt.binaries.multimedia.tatu	45
mpc.lists.freebsd.gnome	43
fa.freebsd.cvs-all	39
mailing.freebsd.svn-src-all	39
TOTAL: 228	3060

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10685
EMP (phn path)	2351
EMP (phl)	1191
EMP (phn nph)	207
PFM bot misc	110
Too many newsgroups	100
User-issued cancel	73
Binary: misplaced rar	65
Scoring filter (9)	63
Scoring filter (26)	32
Scoring filter (10)	28
Binary Payload	20
HTML Multipart	20
Scoring filter (20)	18
Scoring filter (11)	12
Binary: misplaced par2	10
Scoring filter (27)	8
Scoring filter (21)	8
Scoring filter (17)	7
Scoring filter (12)	7
TOTAL: 45	15098

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	11
	usenet.blueworldhosting.com	3
	TOTAL: 2	14
Huge articles
	usenet.blueworldhosting.com	78
	xanadu-bbs.net	63
	csiph-out.news.weretis.net	51
	feeder.eternal-september.org	40
	news.freedyn.net	24
	news-out.mixmin.net	5
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	1
	nntp.pasdenom.info	1
	news.redatomik.org	1
	TOTAL: 11	268
Including strange strings
	newsfeed.fu-berlin.de	474
	news.swapon.de	29
	news-out.aioe.org	21
	feeder.eternal-september.org	16
	news-out.mixmin.net	16
	csiph-out.news.weretis.net	9
	csiph-out.feeder.erje.net	8
	news.freedyn.net	2
	news.fcku.it	2
	usenet.blueworldhosting.com	1
	TOTAL: 11	579
TOTAL: 3		861

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	26757	1974	19190	100	0	7%	23:59:56
2	news.freedyn.net	29846	1835	23384	8	0	6%	23:59:56
3	xanadu-bbs	27668	1565	23807	0	5	5%	23:59:56
4	fu-berlin.de	24069	1169	22899	0	0	4%	23:59:46
5	news.bbs.geek.nz	46812	965	23457	13	2	2%	23:59:54
6	aioe	24280	541	20398	35	0	2%	23:59:56
7	usenet.pasdenom.info	33432	330	23497	1901	4	0%	23:59:56
8	au2pb	25250	200	24167	5	0	0%	23:59:56
9	news.redatomik.org	30192	197	23937	14	0	0%	23:59:54
10	xmission	27799	35	23906	5	0	0%	23:59:56
11	neva.ru	27999	16	22804	152	0	0%	23:59:56
12	news.mixmin.net	25153	14	21401	0	0	0%	23:59:55
13	weretis.net	21789	12	21166	2	0	0%	23:59:51
14	news.fcku.it	2639	9	2257	0	0	0%	22:59:03
15	eternal-september	25762	9	20428	0	0	0%	23:59:53
16	news.swapon.de	2303	3	2045	2	0	0%	23:45:30
17	bwh	19613	0	18457	1	0	0%	23:59:45
TOTAL: 17		421363	8874	337200	2238	11	2%	406:42:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.5 MB	39.5 KB	10.5 MB	0.1 KB/s	5.8 KB	23:59:56
2	xanadu-bbs	9.7 MB	0.0 KB	9.7 MB	0.1 KB/s	6.3 KB	23:59:56
3	fu-berlin.de	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	7.4 KB	23:59:46
4	erje	5.6 MB	583.0 KB	6.1 MB	0.1 KB/s	3.0 KB	23:59:56
5	news.bbs.geek.nz	2.6 MB	70.1 KB	2.7 MB	0.0 KB/s	2.8 KB	23:59:54
6	aioe	1.6 MB	129.8 KB	1.7 MB	0.0 KB/s	3.0 KB	23:59:56
7	usenet.pasdenom.info	1.2 MB	9.7 MB	10.9 MB	0.1 KB/s	5.0 KB	23:59:56
8	au2pb	880.3 KB	81.8 KB	962.1 KB	0.0 KB/s	4.7 KB	23:59:56
9	news.redatomik.org	668.8 KB	79.7 KB	748.5 KB	0.0 KB/s	3.5 KB	23:59:54
10	xmission	121.9 KB	8.5 KB	130.3 KB	0.0 KB/s	3.3 KB	23:59:56
11	neva.ru	67.9 KB	548.0 KB	615.9 KB	0.0 KB/s	3.7 KB	23:59:56
12	news.mixmin.net	53.2 KB	0.0 KB	53.2 KB	0.0 KB/s	3.8 KB	23:59:55
13	weretis.net	34.5 KB	39.5 KB	74.0 KB	0.0 KB/s	5.3 KB	23:59:51
14	eternal-september	26.9 KB	0.0 KB	26.9 KB	0.0 KB/s	3.0 KB	23:59:53
15	news.fcku.it	18.6 KB	0.0 KB	18.6 KB	0.0 KB/s	2.1 KB	22:59:03
16	news.swapon.de	8.6 KB	5.4 KB	13.9 KB	0.0 KB/s	2.8 KB	23:45:30
17	bwh	0.0 KB	1.2 KB	1.2 KB	0.0 KB/s	1.2 KB	23:59:45
TOTAL: 17		41.3 MB	11.3 MB	52.6 MB	0.0 KB/s	4.8 KB	406:42:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	59	71	228.9 KB	28	06:09:15
2	tempe0.bbox.io	21	7	14.6 KB	2	03:52:50
TOTAL: 2		80	78	243.6 KB	30	11:33:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	59	71	228.9 KB	28	06:09:15
2	*.bbox.io	21	7	14.6 KB	2	03:52:50
3	*.chello.nl	2	0	0.0 KB	0	00:30:17
4	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	01:01:04
TOTAL: 4		84	78	243.6 KB	30	11:33:27

NNRP auth users [Top 20]:

User	Conn
moussa	59
TOTAL: 1	59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
202.89.176.220	6.315	1.520	0.172	06:09:15
tempe0.bbox.io	1.663	0.334	0.000	03:52:50
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.148	0.036	0.000	01:01:04
dhcp-089-099-203-254.chello.nl	0.138	0.052	0.000	00:30:17
TOTAL: 4	8.264	1.942	0.172	11:33:27

Curious NNRP server explorers [Top 20]:

System	Conn
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
dhcp-089-099-203-254.chello.nl	2
TOTAL: 2	4

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	27
2	comp.arch	18
3	comp.sys.ibm.pc.games.action	11
4	comp.sys.ibm.pc.hardware.storage	6
5	comp.unix.programmer	4
6	comp.unix.misc	3
7	comp.os.msdos.djgpp	2
8	comp.ai.neural-nets	2
9	comp.os.os2.announce	2
10	comp.sys.ibm.pc.games.war-historical	1
11	comp.ai.philosophy	1
12	comp.os.os2.apps	1
TOTAL: 12		78

Daily Usenet report for csiph.com

Jan 17 04:15:00 -- Jan 18 04:15:00