Daily Usenet report for csiph.com: Feb 6 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 6 17:15:02 csiph pgpverify[28796]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44062	41.8%	8.3 MB	58.3%
nnrpd	33958	32.2%	2.9 MB	20.3%
innd	24091	22.9%	2.5 MB	17.7%
innfeed	3125	3.0%	521.0 KB	3.6%
nocem	82	0.1%	8.1 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	105323	100.0%	14.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	487454	55.2%
Negative hits	351736	39.8%
Do not exist	43198	4.9%
Cache misses	653	0.1%
TOTAL: 4	883041	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.467	0.0%	237	0.000	1.970	55.000
article cleanup	00:00:00.247	0.0%	43001	0.000	0.006	0.020
article logging	00:00:00.735	0.0%	43571	0.000	0.017	0.529
article parse	00:00:02.210	0.0%	189964	0.000	0.012	0.106
article write	00:00:55.333	0.1%	24241	0.252	2.283	18.813
artlog/artcncl	00:00:00.002	0.0%	176	0.000	0.011	1.000
artlog/artparse	00:00:00.002	0.0%	335	0.000	0.006	0.250
data move	00:00:00.312	0.0%	895246	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.050	0.0%	237	0.000	0.211	15.000
hishave/artcncl	00:00:00.003	0.0%	237	0.000	0.013	0.500
hishave/artparse	00:00:00.000	0.0%	329	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:20.824	0.0%	884486	0.001	0.024	0.174
history sync	00:00:02.749	0.0%	353	0.000	7.788	87.000
history write	00:01:58.061	0.1%	42990	0.073	2.746	17.638
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.702	0.0%	329	0.000	2.134	19.600
idle	23:52:36.014	99.4%	906442	60.808	94.828	322.620
nntp read	00:00:03.002	0.0%	914966	0.002	0.003	0.006
overview write	00:01:55.462	0.1%	24241	0.753	4.763	38.462
perl filter	00:01:54.521	0.1%	42980	1.430	2.665	4.769
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.415	0.0%	48556	0.000	0.009	0.025
TOTAL: 24:00:48.077	23:59:51.111	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.058	0.0%	24227	0.000	0.002	0.015
article prepare	00:00:00.005	0.0%	32068	0.000	0.000	0.011
article read	00:00:00.629	0.0%	2919	0.000	0.215	8.200
backlog stats	00:00:00.253	0.0%	520801	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.127	0.0%	396922	0.002	0.005	0.012
data write	00:00:02.615	0.0%	377317	0.003	0.007	0.011
idle	23:53:14.876	100.0%	520801	96.041	165.120	416.529
status file	00:00:00.163	0.0%	243	0.000	0.671	1.000
TOTAL: 23:53:33.932	23:53:20.726	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:14.284	0.0%	77222	0.009	0.185	1.360
idle	175:42:29.581	99.9%	110269	389.310	5736.423	600199.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.291	0.0%	221556	0.000	0.006	0.167
readart	00:00:00.536	0.0%	103	0.000	5.204	69.000
TOTAL: 175:52:47.646	175:42:45.692	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	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	51412	5584	45383	445	10%	51:00:24
2	csiph-out.feeder.erje.net	1152	92178	3478	86836	1864	3%	94:57:11
3	usenet.blueworldhosting.com	288	28692	3424	22996	2272	11%	23:44:39
4	feeder.eternal-september.org	7	37824	3261	32998	1565	8%	25:36:10
5	news-out.aioe.org	2	22843	2091	20703	49	9%	24:09:58
6	newsfeed.neva.ru	144	52945	1634	46180	5131	3%	71:49:02
7	newsfeed.fu-berlin.de	21	25246	1248	23741	257	4%	26:18:49
8	news-out.mixmin.net	3	28265	1239	25230	1796	4%	24:08:29
9	xanadu-bbs.net	1	40454	872	34311	5271	2%	23:49:02
10	news.redatomik.org	1	21375	533	20829	13	2%	21:57:06
11	newsfeeds.xmission.com	1	25058	468	24574	16	1%	26:48:03
12	news.fcku.it	1	7178	277	6901	0	3%	24:37:42
13	news.freedyn.net	3	27986	219	27382	385	0%	24:08:49
14	news.swapon.de	3	2291	122	2163	6	5%	23:50:48
15	au2pb.net	1	26389	87	26260	42	0%	26:13:07
16	news.bbs.geek.nz	2	27172	43	27127	2	0%	23:59:41
17	nntp.pasdenom.info	2	22537	5	22531	1	0%	23:59:32
18	localhost	36	0	0	0	0	0%	00:00:00
TOTAL: 18		1671	539845	24585	496145	19115	4%	561:08:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	16.3 MB	2.3 KB	10.2 MB	26.5 MB	61%	4.5 KB
2	csiph-out.feeder.erje.net	14.6 MB	75.8 KB	4.9 MB	19.6 MB	74%	3.8 KB
3	usenet.blueworldhosting.com	10.2 MB	3.9 KB	25.7 MB	35.9 MB	28%	6.5 KB
4	feeder.eternal-september.org	8.4 MB	2.3 KB	16.8 MB	25.1 MB	33%	5.3 KB
5	newsfeed.neva.ru	5.3 MB	1.8 KB	43.5 MB	48.8 MB	10%	7.4 KB
6	news-out.aioe.org	5.1 MB	0.0 KB	120.5 KB	5.2 MB	97%	2.5 KB
7	news-out.mixmin.net	5.0 MB	0.0 KB	2.9 MB	7.9 MB	63%	2.6 KB
8	newsfeed.fu-berlin.de	2.7 MB	2.7 KB	522.3 KB	3.2 MB	84%	2.2 KB
9	xanadu-bbs.net	2.2 MB	4.4 KB	54.5 MB	56.7 MB	3%	9.5 KB
10	newsfeeds.xmission.com	1.2 MB	0.0 KB	115.4 KB	1.3 MB	91%	2.8 KB
11	news.redatomik.org	1.1 MB	0.0 KB	266.6 KB	1.4 MB	81%	2.6 KB
12	news.freedyn.net	771.4 KB	4.6 KB	1.5 MB	2.2 MB	33%	3.8 KB
13	news.fcku.it	499.8 KB	0.0 KB	0.0 KB	499.8 KB	100%	1.8 KB
14	news.swapon.de	314.4 KB	0.0 KB	10.5 KB	324.9 KB	96%	2.5 KB
15	au2pb.net	271.5 KB	2.3 KB	384.1 KB	657.9 KB	41%	5.1 KB
16	news.bbs.geek.nz	76.1 KB	0.0 KB	4.5 KB	80.5 KB	94%	1.8 KB
17	nntp.pasdenom.info	8.9 KB	0.0 KB	1.9 KB	10.8 KB	82%	1.8 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		73.9 MB	100.1 KB	161.4 MB	235.4 MB	31%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 6 04:15:00 - 04:59:59	371	1.5%	0.14	1.0 MB	1.4%	0.38
Feb 6 05:00:00 - 05:59:59	593	2.4%	0.16	1.7 MB	2.3%	0.48
Feb 6 06:00:00 - 06:59:59	476	2.0%	0.13	1.6 MB	2.2%	0.46
Feb 6 07:00:00 - 07:59:59	905	3.7%	0.25	2.9 MB	4.0%	0.82
Feb 6 08:00:00 - 08:59:59	840	3.5%	0.23	2.4 MB	3.3%	0.69
Feb 6 09:00:00 - 09:59:59	1084	4.5%	0.30	3.6 MB	5.0%	1.03
Feb 6 10:00:00 - 10:59:59	1092	4.5%	0.30	3.7 MB	5.0%	1.04
Feb 6 11:00:00 - 11:59:59	1203	5.0%	0.33	3.4 MB	4.6%	0.96
Feb 6 12:00:00 - 12:59:59	1116	4.6%	0.31	3.6 MB	4.9%	1.01
Feb 6 13:00:00 - 13:59:59	1263	5.2%	0.35	3.9 MB	5.3%	1.10
Feb 6 14:00:00 - 14:59:59	1301	5.4%	0.36	3.5 MB	4.8%	1.00
Feb 6 15:00:00 - 15:59:59	1192	4.9%	0.33	3.5 MB	4.8%	0.99
Feb 6 16:00:00 - 16:59:59	1351	5.6%	0.38	4.1 MB	5.6%	1.17
Feb 6 17:00:00 - 17:59:59	1311	5.4%	0.36	3.8 MB	5.1%	1.07
Feb 6 18:00:00 - 18:59:59	1395	5.8%	0.39	4.1 MB	5.6%	1.16
Feb 6 19:00:00 - 19:59:59	1286	5.3%	0.36	3.4 MB	4.6%	0.96
Feb 6 20:00:00 - 20:59:59	1119	4.6%	0.31	3.0 MB	4.1%	0.85
Feb 6 21:00:00 - 21:59:59	1371	5.7%	0.38	3.7 MB	5.1%	1.05
Feb 6 22:00:00 - 22:59:59	1294	5.3%	0.36	4.3 MB	5.9%	1.22
Feb 6 23:00:00 - 23:59:59	931	3.8%	0.26	2.8 MB	3.9%	0.81
Feb 7 00:00:00 - 00:59:59	743	3.1%	0.21	2.5 MB	3.5%	0.72
Feb 7 01:00:00 - 01:59:59	732	3.0%	0.20	2.2 MB	3.0%	0.62
Feb 7 02:00:00 - 02:59:59	668	2.8%	0.19	2.3 MB	3.1%	0.65
Feb 7 03:00:00 - 03:59:59	489	2.0%	0.14	1.6 MB	2.1%	0.44
Feb 7 04:00:00 - 04:15:00	126	0.5%	0.14	710.0 KB	0.9%	0.79
TOTAL: 24:00:00	24252	100.0%	0.28	73.1 MB	100.0%	0.87

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	5284	51	1	5232
2	newsfeed.neva.ru	5121	586	1	4534
3	usenet.blueworldhosting.com	2267	879	1	1387
4	csiph-out.feeder.erje.net	1882	266	11	1605
5	news-out.mixmin.net	1806	2	0	1804
6	feeder.eternal-september.org	1551	144	1	1406
7	newsfeed.fu-berlin.de	705	28	1	676
8	csiph-out.news.weretis.net	460	228	0	232
9	news.freedyn.net	387	1	1	385
10	news-out.aioe.org	60	44	0	16
11	au2pb.net	38	24	0	14
12	news.swapon.de	30	0	0	30
13	newsfeeds.xmission.com	22	14	0	8
14	news.redatomik.org	17	0	0	17
15	news.bbs.geek.nz	2	2	0	0
16	nntp.pasdenom.info	1	0	0	1
17	news.fcku.it	1	0	0	1
TOTAL: 17		19634	2269	17	17348

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.pkg-fallout	299
fa.linux.kernel	184
ut.test	148
lada.talk	107
mailing.freebsd.ports-bugs	103
alt.politics.scorched-earth	87
mailing.freebsd.svn-ports-all	62
mailing.freebsd.svn-ports-head	59
it-alt.sport.calcio.juventus	51
alt.survival	49
alt.binaries.multimedia.tatu	42
mailing.openssl.cvs	35
alt.windows7.general	32
pld.cvs.commit	32
lada.auto	31
alt.politics	29
lada.kitchen	29
mozilla.general	28
abg.test	27
eagle.support.eng	24
TOTAL: 217	2269

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11065
EMP (phn path)	2528
EMP (phl)	1010
PFM bot misc	885
Binary: misplaced rar	249
EMP (phn nph)	162
Scoring filter (9)	104
Too many newsgroups	42
Scoring filter (25)	42
HTML Multipart	32
Scoring filter (22)	31
Scoring filter (21)	30
Scoring filter (20)	22
Scoring filter (23)	21
Binary Payload	21
Scoring filter (11)	19
Scoring filter (18)	17
Scoring filter (10)	16
User-issued cancel	15
Scoring filter (28)	13
TOTAL: 43	16438

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	usenet.blueworldhosting.com	2
	TOTAL: 2	4
Huge articles
	xanadu-bbs.net	129
	usenet.blueworldhosting.com	75
	csiph-out.news.weretis.net	70
	feeder.eternal-september.org	42
	news-out.mixmin.net	4
	news.redatomik.org	4
	news.freedyn.net	3
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	1
	TOTAL: 9	330
Including strange strings
	newsfeed.fu-berlin.de	447
	csiph-out.news.weretis.net	35
	news.swapon.de	24
	csiph-out.feeder.erje.net	18
	news-out.aioe.org	11
	news-out.mixmin.net	9
	feeder.eternal-september.org	7
	newsfeeds.xmission.com	6
	xanadu-bbs.net	5
	news.redatomik.org	4
	TOTAL: 12	570
No colon-space in header
	usenet.blueworldhosting.com	6
	TOTAL: 1	6
TOTAL: 4		910

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	24462	1535	22493	4	0	6%	23:59:32
2	xanadu-bbs	25060	1476	21877	1	5	5%	23:59:51
3	fu-berlin.de	22641	1185	21456	0	0	5%	23:59:50
4	news.bbs.geek.nz	43141	762	22167	12	1	1%	23:59:49
5	usenet.pasdenom.info	30324	294	22086	1866	4	0%	23:59:50
6	aioe	21784	232	19773	21	0	1%	23:59:50
7	news.redatomik.org	24600	219	22181	14	0	0%	23:59:35
8	au2pb	23278	197	22676	7	0	0%	23:59:51
9	erje	21018	73	20622	15	15	0%	23:59:51
10	eternal-september	21209	18	19401	0	0	0%	23:59:50
11	xmission	27547	17	22478	5	0	0%	23:59:51
12	news.fcku.it	1986	10	1919	0	0	0%	23:12:05
13	news.mixmin.net	22850	8	21388	0	1	0%	23:59:40
14	weretis.net	17493	5	17119	4	0	0%	23:59:35
15	neva.ru	22556	5	21029	54	0	0%	23:59:50
16	bwh	20605	1	19436	0	0	0%	23:59:51
17	news.swapon.de	2256	0	2212	0	0	0%	23:58:39
TOTAL: 17		372810	6037	320313	2003	26	1%	407:07:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.1 MB	57.3 KB	10.1 MB	0.1 KB/s	6.7 KB	23:59:32
2	xanadu-bbs	9.8 MB	1.5 KB	9.8 MB	0.1 KB/s	6.8 KB	23:59:51
3	fu-berlin.de	9.2 MB	0.0 KB	9.2 MB	0.1 KB/s	7.9 KB	23:59:50
4	news.bbs.geek.nz	2.1 MB	55.5 KB	2.1 MB	0.0 KB/s	2.8 KB	23:59:49
5	usenet.pasdenom.info	942.8 KB	10.4 MB	11.4 MB	0.1 KB/s	5.4 KB	23:59:50
6	aioe	567.6 KB	71.3 KB	638.9 KB	0.0 KB/s	2.5 KB	23:59:50
7	news.redatomik.org	520.1 KB	28.5 KB	548.6 KB	0.0 KB/s	2.4 KB	23:59:35
8	au2pb	476.6 KB	18.7 KB	495.3 KB	0.0 KB/s	2.4 KB	23:59:51
9	erje	318.1 KB	59.7 KB	377.8 KB	0.0 KB/s	4.3 KB	23:59:51
10	eternal-september	47.8 KB	0.0 KB	47.8 KB	0.0 KB/s	2.7 KB	23:59:50
11	xmission	46.9 KB	15.5 KB	62.4 KB	0.0 KB/s	2.8 KB	23:59:51
12	neva.ru	42.8 KB	144.0 KB	186.8 KB	0.0 KB/s	3.2 KB	23:59:50
13	news.mixmin.net	26.2 KB	0.0 KB	26.2 KB	0.0 KB/s	3.3 KB	23:59:40
14	weretis.net	24.0 KB	80.4 KB	104.4 KB	0.0 KB/s	11.6 KB	23:59:35
15	news.fcku.it	22.2 KB	0.0 KB	22.2 KB	0.0 KB/s	2.2 KB	23:12:05
16	bwh	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:59:51
17	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:39
TOTAL: 17		34.1 MB	11.0 MB	45.0 MB	0.0 KB/s	5.7 KB	407:07:20

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	78	218.5 KB	73	04:21:28
2	tempe0.bbox.io	33	25	57.7 KB	8	06:02:32
TOTAL: 2		321	103	276.2 KB	81	175:52:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	397	78	218.5 KB	73	159:48:39
2	*.bbox.io	33	25	57.7 KB	8	06:02:32
3	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	01:34:49
4	*.nielsenmedia.com	1	0	0.0 KB	0	00:32:25
5	*.chello.nl	23	0	0.0 KB	0	07:54:14
TOTAL: 5		458	103	276.2 KB	81	175:52:41

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	11.543	8.589	0.000	130:59:27
162.212.88.221	0.830	0.525	0.000	24:27:43
dhcp-089-099-203-254.chello.nl	1.865	0.359	0.000	07:54:14
tempe0.bbox.io	3.244	0.529	0.000	06:02:32
202.89.176.220	29.465	6.800	0.740	04:21:28
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.334	0.064	0.000	01:34:49
challenger.nielsenmedia.com	0.101	0.008	0.000	00:32:25
TOTAL: 7	47.382	16.874	0.740	175:52:41

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	100
dhcp-089-099-203-254.chello.nl	23
162.212.88.221	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
71.94.21.184	1
challenger.nielsenmedia.com	1
TOTAL: 6	137

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	83	80.6%
2	bit	16	15.5%
3	news	4	3.9%
TOTAL: 3		103	100.0%

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

Newsgroup		Count
1	comp.arch	35
2	comp.os.vms	32
3	bit.listserv.ibm-main	16
4	comp.ai.neural-nets	8
5	comp.sys.ibm.pc.games.action	6
6	news.software.nntp	4
7	comp.arch.embedded	1
8	comp.os.qnx	1
TOTAL: 8		103

Daily Usenet report for csiph.com

Feb 6 04:15:00 -- Feb 7 04:15:00