Daily Usenet report for csiph.com: Sep 22 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 22 06:30:02 csiph pgpverify[33163]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52895	49.4%	7.7 MB	61.0%
nnrpd	32011	29.9%	2.7 MB	21.3%
innd	20273	18.9%	2.0 MB	15.8%
innfeed	1874	1.8%	253.1 KB	2.0%
controlchan	4	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	107058	100.0%	12.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	286994	47.6%
Negative hits	259130	43.0%
Do not exist	51979	8.6%
Cache misses	4783	0.8%
TOTAL: 4	602886	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.366	0.0%	269	0.000	1.361	24.000
article cleanup	00:00:00.306	0.0%	51326	0.000	0.006	0.021
article logging	00:00:00.637	0.0%	51915	0.000	0.012	0.034
article parse	00:00:02.302	0.0%	263607	0.002	0.009	0.017
article write	00:00:07.148	0.0%	29689	0.163	0.241	0.364
artlog/artcncl	00:00:00.005	0.0%	190	0.000	0.026	1.000
artlog/artparse	00:00:00.006	0.0%	815	0.000	0.007	1.000
data move	00:00:00.327	0.0%	766173	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	269	0.000	0.007	0.500
hishave/artcncl	00:00:00.000	0.0%	269	0.000	0.000	0.000
hishave/artparse	00:00:00.002	0.0%	815	0.000	0.002	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.597	0.0%	603622	0.000	0.003	0.009
history sync	00:00:00.616	0.0%	344	0.000	1.791	3.667
history write	00:00:14.343	0.0%	51320	0.074	0.279	0.360
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.245	0.0%	804	0.000	0.305	3.000
idle	23:57:59.166	99.8%	750761	57.965	114.922	336.405
nntp read	00:00:02.604	0.0%	749438	0.001	0.003	0.006
overview write	00:00:05.695	0.0%	29689	0.091	0.192	1.351
perl filter	00:01:38.486	0.1%	51320	1.018	1.919	3.810
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.532	0.0%	59379	0.000	0.009	0.030
TOTAL: 24:00:58.282	24:00:14.385	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.088	0.0%	29621	0.000	0.003	0.022
article prepare	00:00:00.001	0.0%	6656	0.000	0.000	0.050
article read	00:00:00.206	0.0%	1294	0.000	0.159	5.222
backlog stats	00:00:00.109	0.0%	333089	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.288	0.0%	254417	0.001	0.005	0.010
data write	00:00:01.850	0.0%	227555	0.002	0.008	0.014
idle	23:54:33.773	100.0%	333089	144.061	258.411	645.567
status file	00:00:00.103	0.0%	191	0.000	0.539	1.000
TOTAL: 23:54:41.913	23:54:37.418	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.315	0.0%	14223	0.000	0.022	0.333
idle	45:13:14.691	99.2%	45591	0.000	3570.764	600163.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:02:33.596	0.1%	197836	0.000	0.776	2198.409
readart	00:19:14.409	0.7%	588196	0.000	1.963	1.963
TOTAL: 45:36:21.232	45:35:03.011	100.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ba-mod@panix.com	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	feeder.eternal-september.org	4	47631	8143	36881	2607	17%	24:37:18
2	au2pb.net	1	33213	6037	26237	939	18%	25:09:45
3	xanadu-bbs.net	1	51702	4733	37583	9386	9%	23:50:29
4	news-out.mixmin.net	3	34786	3403	29271	2112	9%	24:09:34
5	newsfeed.neva.ru	152	66124	3016	56096	7012	4%	72:25:05
6	newsfeeds.xmission.com	1	30732	2011	28590	131	6%	23:58:48
7	newsfeed.fu-berlin.de	73	32818	1416	31221	181	4%	35:30:32
8	nntp.pasdenom.info	2	36767	840	35769	158	2%	27:44:12
9	news.swapon.de	0	1842	198	1642	2	10%	16:00:01
10	localhost	50	12977	0	12977	0	0%	00:01:02
TOTAL: 10		287	348592	29797	296267	22528	8%	273:26:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	23.0 MB	35.0 KB	15.5 MB	38.5 MB	59%	3.7 KB
2	au2pb.net	16.0 MB	37.9 KB	5.1 MB	21.2 MB	75%	3.1 KB
3	xanadu-bbs.net	13.3 MB	44.5 KB	134.8 MB	148.1 MB	8%	10.7 KB
4	news-out.mixmin.net	10.6 MB	0.0 KB	6.3 MB	16.9 MB	62%	3.1 KB
5	newsfeed.neva.ru	10.5 MB	6.5 KB	49.9 MB	60.4 MB	17%	6.2 KB
6	newsfeeds.xmission.com	5.7 MB	0.0 KB	1.3 MB	7.0 MB	81%	3.3 KB
7	newsfeed.fu-berlin.de	3.3 MB	6.4 KB	634.6 KB	3.9 MB	84%	2.5 KB
8	nntp.pasdenom.info	1.9 MB	0.0 KB	1.1 MB	3.0 MB	64%	3.1 KB
9	news.swapon.de	541.3 KB	0.0 KB	4.9 KB	546.2 KB	99%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		84.9 MB	130.4 KB	214.5 MB	299.5 MB	28%	5.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 04:15:00 - 04:59:59	461	1.6%	0.17	1.4 MB	1.6%	0.53
Sep 22 05:00:00 - 05:59:59	656	2.2%	0.18	1.9 MB	2.2%	0.53
Sep 22 06:00:00 - 06:59:59	945	3.2%	0.26	3.0 MB	3.5%	0.84
Sep 22 07:00:00 - 07:59:59	1276	4.3%	0.35	3.8 MB	4.5%	1.08
Sep 22 08:00:00 - 08:59:59	1402	4.7%	0.39	4.0 MB	4.7%	1.14
Sep 22 09:00:00 - 09:59:59	1411	4.8%	0.39	4.0 MB	4.7%	1.13
Sep 22 10:00:00 - 10:59:59	1485	5.0%	0.41	4.0 MB	4.7%	1.14
Sep 22 11:00:00 - 11:59:59	1429	4.8%	0.40	4.1 MB	4.9%	1.17
Sep 22 12:00:00 - 12:59:59	1474	5.0%	0.41	3.9 MB	4.6%	1.10
Sep 22 13:00:00 - 13:59:59	1719	5.8%	0.48	4.6 MB	5.5%	1.32
Sep 22 14:00:00 - 14:59:59	1717	5.8%	0.48	4.7 MB	5.6%	1.34
Sep 22 15:00:00 - 15:59:59	1807	6.1%	0.50	4.9 MB	5.8%	1.38
Sep 22 16:00:00 - 16:59:59	1787	6.0%	0.50	4.9 MB	5.8%	1.39
Sep 22 17:00:00 - 17:59:59	1770	6.0%	0.49	4.7 MB	5.6%	1.34
Sep 22 18:00:00 - 18:59:59	1730	5.8%	0.48	5.0 MB	5.9%	1.42
Sep 22 19:00:00 - 19:59:59	1495	5.0%	0.42	4.0 MB	4.7%	1.14
Sep 22 20:00:00 - 20:59:59	1426	4.8%	0.40	3.8 MB	4.5%	1.08
Sep 22 21:00:00 - 21:59:59	1357	4.6%	0.38	3.9 MB	4.7%	1.12
Sep 22 22:00:00 - 22:59:59	970	3.3%	0.27	2.9 MB	3.4%	0.82
Sep 22 23:00:00 - 23:59:59	745	2.5%	0.21	2.0 MB	2.4%	0.58
Sep 23 00:00:00 - 00:59:59	727	2.4%	0.20	2.3 MB	2.7%	0.66
Sep 23 01:00:00 - 01:59:59	663	2.2%	0.18	2.9 MB	3.5%	0.83
Sep 23 02:00:00 - 02:59:59	545	1.8%	0.15	1.6 MB	1.9%	0.46
Sep 23 03:00:00 - 03:59:59	517	1.7%	0.14	1.7 MB	2.0%	0.47
Sep 23 04:00:00 - 04:15:00	161	0.5%	0.18	471.1 KB	0.5%	0.52
TOTAL: 24:00:00	29675	100.0%	0.34	84.4 MB	100.0%	1.00

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9333	619	2	8712
2	newsfeed.neva.ru	6990	1922	1	5067
3	feeder.eternal-september.org	2658	313	1	2344
4	news-out.mixmin.net	2135	4	0	2131
5	au2pb.net	933	857	1	75
6	newsfeed.fu-berlin.de	648	27	1	620
7	nntp.pasdenom.info	171	112	0	59
8	newsfeeds.xmission.com	139	112	0	27
9	news.swapon.de	24	0	0	24
TOTAL: 9		23031	3966	6	19059

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1179
lada.talk	285
it-alt.sport.calcio.juventus	164
alt.politics	129
lada.auto	116
mailing.freebsd.svn-ports-all	78
mailing.freebsd.svn-ports-head	75
mozilla.dev.tree-alerts	66
mailing.freebsd.svn-src-all	58
mailing.freebsd.pkg-fallout	56
fa.freebsd.cvs-all	55
alt.windows7.general	53
mailing.freebsd.ports-bugs	50
mpc.lists.freebsd.ports.bugs	50
mailing.gcc.gcc-cvs	39
mailing.freebsd.svn-src-head	39
mailing.openbsd.ports-changes	37
mailing.database.pgsql-general	36
pld.cvs.commit	36
alt.survival	35
TOTAL: 260	3966

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13706
EMP (phn path)	2407
EMP (phl)	730
EMP (phn nph)	322
Binary: misplaced rar	189
PFM bot misc	113
HTML Multipart	53
Too many newsgroups (low_xpost)	51
Too many newsgroups	42
HTML post	18
Binary Image: misplaced jpg	16
HTML Image Tags	7
Binary Payload	2
Binary: misplaced bin	1
TOTAL: 14	17657

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	623
	feeder.eternal-september.org	66
	news-out.mixmin.net	42
	nntp.pasdenom.info	38
	au2pb.net	33
	newsfeeds.xmission.com	9
	newsfeed.fu-berlin.de	2
	TOTAL: 7	813
Including strange strings
	newsfeed.fu-berlin.de	457
	feeder.eternal-september.org	47
	news-out.mixmin.net	25
	news.swapon.de	18
	au2pb.net	17
	xanadu-bbs.net	11
	newsfeed.neva.ru	8
	newsfeeds.xmission.com	5
	nntp.pasdenom.info	1
	TOTAL: 9	589
TOTAL: 2		1402

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	usenet.pasdenom.info	35910	331	27981	620	0	2	0%	23:59:57
2	au2pb	24743	241	23397	145	0	0	0%	23:59:57
3	xanadu-bbs	26675	83	24820	17	0	10	0%	23:59:58
4	xmission	34844	37	27436	6	0	0	0%	23:59:58
5	fu-berlin.de	27752	34	27690	28	0	0	0%	23:59:54
6	news.mixmin.net	26929	24	26042	23	1	0	0%	23:59:57
7	eternal-september	22214	8	21057	3	1	0	0%	23:59:44
8	neva.ru	27904	7	26466	51	0	0	0%	23:59:56
9	news.swapon.de	2283	1	2268	5	0	0	0%	23:58:56
TOTAL: 9		229254	766	207157	898	2	12	0%	215:58:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	859.0 KB	543.1 KB	1.4 MB	3.6 KB	23:59:57
2	usenet.pasdenom.info	771.8 KB	1.6 MB	2.4 MB	2.5 KB	23:59:57
3	xanadu-bbs	291.7 KB	36.1 KB	327.8 KB	3.3 KB	23:59:58
4	fu-berlin.de	291.3 KB	35.0 KB	326.3 KB	5.3 KB	23:59:54
5	news.mixmin.net	96.2 KB	42.9 KB	139.1 KB	3.0 KB	23:59:57
6	xmission	79.7 KB	157.0 KB	236.7 KB	5.5 KB	23:59:58
7	eternal-september	28.0 KB	4.5 KB	32.5 KB	3.0 KB	23:59:44
8	neva.ru	23.2 KB	229.0 KB	252.3 KB	4.3 KB	23:59:56
9	news.swapon.de	2.7 KB	9.5 KB	12.2 KB	2.0 KB	23:58:56
TOTAL: 9		2.4 MB	2.6 MB	5.0 MB	3.1 KB	215:58:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	287	83	232.8 KB	79	0	0	04:46:52
TOTAL: 1		287	83	232.8 KB	79	0	0	45:36:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	340	83	232.8 KB	79	33:51:50
2	*.tu.ok.cox.net	2	0	0.0 KB	0	02:37:03
3	*.br.br.cox.net	4	0	0.0 KB	0	00:05:54
4	*.nielsenmedia.com	10	0	0.0 KB	0	02:03:34
5	*.chello.nl	11	0	0.0 KB	0	04:41:17
6	*.ip-188-165-239.eu	14	0	0.0 KB	0	00:01:57
7	*.bbox.io	2	0	0.0 KB	0	00:30:11
8	*.tampfl.dsl-w.verizon.net	16	0	0.0 KB	0	01:44:26
TOTAL: 8		399	83	232.8 KB	79	45:36:17

NNRP auth users [Top 20]:

User	Conn
moussa	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.198	1.941	0.742	0.000	23:03:41
71.94.21.184	0.221	0.215	0.000	05:36:17
202.89.176.220	27.165	5.884	0.694	04:46:52
dhcp-089-099-203-254.chello.nl	0.945	0.152	0.000	04:41:17
ip72-222-107-224.tu.ok.cox.net	0.220	0.112	0.000	02:37:03
challenger.nielsenmedia.com	0.752	0.148	0.000	02:03:34
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.180	0.236	0.000	01:44:26
tempe0.bbox.io	0.149	0.015	0.000	00:30:11
173.0.3.73	19.516	57.531	0.000	00:24:57
ip70-186-189-240.br.br.cox.net	0.300	0.040	0.000	00:05:54
ns342002.ip-188-165-239.eu	1.062	0.258	0.000	00:01:57
69.160.37.54	1.909	0.397	0.000	00:00:02
TOTAL: 12	55.360	65.730	0.694	45:36:17

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.198	22
pool-71-98-160-84.tampfl.dsl-w.verizon.net	16
ns342002.ip-188-165-239.eu	14
dhcp-089-099-203-254.chello.nl	11
challenger.nielsenmedia.com	10
173.0.3.73	4
ip70-186-189-240.br.br.cox.net	4
71.94.21.184	2
tempe0.bbox.io	2
ip72-222-107-224.tu.ok.cox.net	2
TOTAL: 11	112

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	9	0
challenger.nielsenmedia.com	4	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
ip72-222-107-224.tu.ok.cox.net	2	0
tempe0.bbox.io	1	0
TOTAL: 5	19	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	30
2	comp.arch.embedded	27
3	comp.os.vms	17
4	comp.ai.neural-nets	5
5	comp.sys.ibm.pc.games.action	2
6	comp.arch.fpga	1
7	comp.os.os2.misc	1
TOTAL: 7		83

Daily Usenet report for csiph.com

Sep 22 04:15:00 -- Sep 23 04:15:00