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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 17 12:10:03 csiph pgpverify[70224]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44197	47.8%	6.7 MB	60.4%
nnrpd	31915	34.5%	2.6 MB	23.8%
innd	14486	15.7%	1.5 MB	13.3%
innfeed	1847	2.0%	270.5 KB	2.4%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	92450	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	250604	48.8%
Negative hits	218997	42.7%
Do not exist	43365	8.5%
Cache misses	113	0.0%
TOTAL: 4	513079	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.578	0.0%	197	0.000	2.934	26.000
article cleanup	00:00:00.287	0.0%	42508	0.000	0.007	0.036
article logging	00:00:00.613	0.0%	43064	0.000	0.014	0.111
article parse	00:00:03.482	0.0%	211494	0.001	0.016	0.137
article write	00:01:29.577	0.1%	27082	0.174	3.308	10.587
artlog/artcncl	00:00:00.001	0.0%	169	0.000	0.006	0.056
artlog/artparse	00:00:00.013	0.0%	949	0.000	0.014	0.333
data move	00:00:00.284	0.0%	657878	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.049	0.0%	195	0.000	0.251	1.600
hishave/artcncl	00:00:00.001	0.0%	197	0.000	0.005	0.067
hishave/artparse	00:00:00.001	0.0%	949	0.000	0.001	0.014
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.457	0.0%	513020	0.001	0.011	0.087
history sync	00:00:01.766	0.0%	355	0.000	4.975	47.000
history write	00:00:51.392	0.1%	42491	0.078	1.209	14.535
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:01.572	0.0%	949	0.000	1.656	55.333
idle	23:55:31.856	99.6%	637876	40.206	135.029	423.656
nntp read	00:00:02.052	0.0%	640489	0.001	0.003	0.007
overview write	00:01:04.182	0.1%	27082	0.289	2.370	15.938
perl filter	00:01:11.751	0.1%	42491	1.037	1.689	3.385
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.489	0.0%	54165	0.000	0.009	0.031
TOTAL: 24:00:58.020	24:00:25.403	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	27064	0.000	0.003	0.031
article prepare	00:00:00.000	0.0%	3764	0.000	0.000	0.000
article read	00:00:00.139	0.0%	765	0.000	0.182	44.000
backlog stats	00:00:00.104	0.0%	287592	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.243	0.0%	226847	0.001	0.005	0.013
data write	00:00:01.568	0.0%	204194	0.002	0.008	0.018
idle	23:54:25.315	100.0%	287592	180.341	299.262	794.249
status file	00:00:00.088	0.0%	175	0.000	0.503	1.000
TOTAL: 23:54:33.659	23:54:28.542	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.047	0.0%	1515	0.013	0.031	0.047
idle	21:50:15.042	99.9%	32963	0.000	2384.948	600164.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.408	0.0%	66928	0.000	0.006	0.333
readart	00:00:00.175	0.0%	103	0.000	1.699	72.000
TOTAL: 21:51:39.525	21:50:15.672	99.9%	-	-	-	-

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
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	au2pb.net	1	29613	9100	19653	860	30%	23:38:57
2	feeder.eternal-september.org	5	40312	6764	32168	1380	16%	25:42:25
3	news-out.mixmin.net	3	31600	2723	26695	2182	8%	24:08:49
4	newsfeed.neva.ru	150	61299	2638	55730	2931	4%	72:18:43
5	newsfeeds.xmission.com	1	27559	2377	24704	478	8%	23:19:34
6	xanadu-bbs.net	1	43503	1772	33301	8430	4%	24:13:01
7	newsfeed.fu-berlin.de	48	29052	1169	27732	151	4%	30:49:34
8	nntp.pasdenom.info	3	27821	242	27530	49	0%	24:08:34
9	news.swapon.de	1	2259	120	2131	8	5%	26:47:53
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		239	293018	26905	249644	16469	9%	275:07:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	22.7 MB	0.0 KB	3.7 MB	26.4 MB	86%	2.7 KB
2	feeder.eternal-september.org	18.3 MB	47.8 KB	10.7 MB	29.0 MB	62%	3.7 KB
3	news-out.mixmin.net	10.0 MB	13.0 KB	4.3 MB	14.2 MB	70%	3.0 KB
4	newsfeed.neva.ru	7.8 MB	7.1 KB	11.6 MB	19.4 MB	40%	3.6 KB
5	newsfeeds.xmission.com	7.6 MB	22.5 KB	2.1 MB	9.7 MB	78%	3.5 KB
6	xanadu-bbs.net	5.7 MB	63.0 KB	165.4 MB	171.2 MB	3%	17.2 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	432.4 KB	3.2 MB	86%	2.5 KB
8	nntp.pasdenom.info	559.1 KB	2.4 KB	293.3 KB	854.9 KB	65%	2.9 KB
9	news.swapon.de	323.6 KB	0.0 KB	27.9 KB	351.4 KB	92%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		75.8 MB	155.7 KB	198.4 MB	274.4 MB	27%	6.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 17 04:15:00 - 04:59:59	350	1.3%	0.13	1.0 MB	1.3%	0.38
Dec 17 05:00:00 - 05:59:59	577	2.1%	0.16	1.9 MB	2.5%	0.54
Dec 17 06:00:00 - 06:59:59	620	2.3%	0.17	2.1 MB	2.8%	0.60
Dec 17 07:00:00 - 07:59:59	763	2.8%	0.21	2.2 MB	2.9%	0.62
Dec 17 08:00:00 - 08:59:59	1291	4.8%	0.36	3.9 MB	5.1%	1.10
Dec 17 09:00:00 - 09:59:59	1211	4.5%	0.34	3.1 MB	4.1%	0.89
Dec 17 10:00:00 - 10:59:59	1379	5.1%	0.38	3.6 MB	4.7%	1.02
Dec 17 11:00:00 - 11:59:59	1405	5.2%	0.39	3.7 MB	4.8%	1.05
Dec 17 12:00:00 - 12:59:59	1355	5.0%	0.38	3.8 MB	5.0%	1.07
Dec 17 13:00:00 - 13:59:59	1407	5.2%	0.39	3.5 MB	4.7%	1.01
Dec 17 14:00:00 - 14:59:59	1285	4.7%	0.36	3.2 MB	4.3%	0.92
Dec 17 15:00:00 - 15:59:59	1499	5.5%	0.42	4.0 MB	5.3%	1.15
Dec 17 16:00:00 - 16:59:59	1554	5.7%	0.43	4.4 MB	5.8%	1.25
Dec 17 17:00:00 - 17:59:59	1577	5.8%	0.44	4.8 MB	6.3%	1.35
Dec 17 18:00:00 - 18:59:59	1595	5.9%	0.44	4.4 MB	5.8%	1.26
Dec 17 19:00:00 - 19:59:59	1389	5.1%	0.39	3.6 MB	4.8%	1.03
Dec 17 20:00:00 - 20:59:59	1419	5.2%	0.39	3.8 MB	5.0%	1.08
Dec 17 21:00:00 - 21:59:59	1300	4.8%	0.36	3.5 MB	4.6%	0.98
Dec 17 22:00:00 - 22:59:59	1220	4.5%	0.34	3.4 MB	4.4%	0.96
Dec 17 23:00:00 - 23:59:59	1040	3.8%	0.29	2.9 MB	3.9%	0.84
Dec 18 00:00:00 - 00:59:59	656	2.4%	0.18	2.0 MB	2.6%	0.57
Dec 18 01:00:00 - 01:59:59	673	2.5%	0.19	2.1 MB	2.8%	0.60
Dec 18 02:00:00 - 02:59:59	686	2.5%	0.19	2.2 MB	2.9%	0.63
Dec 18 03:00:00 - 03:59:59	699	2.6%	0.19	2.4 MB	3.2%	0.68
Dec 18 04:00:00 - 04:15:00	151	0.6%	0.17	464.8 KB	0.6%	0.52
TOTAL: 24:00:00	27101	100.0%	0.31	76.0 MB	100.0%	0.90

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8387	475	5	7907
2	newsfeed.neva.ru	2926	1393	3	1530
3	news-out.mixmin.net	2174	7	2	2165
4	feeder.eternal-september.org	1402	231	4	1167
5	au2pb.net	919	821	0	98
6	newsfeed.fu-berlin.de	560	50	0	510
7	newsfeeds.xmission.com	480	433	2	45
8	nntp.pasdenom.info	52	45	1	6
9	news.swapon.de	27	0	0	27
TOTAL: 9		16927	3455	17	13455

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	824
ut.test	216
lada.auto	155
mailing.freebsd.pkg-fallout	132
alt.politics	124
lada.talk	120
alt.windows7.general	94
mailing.gcc.gcc-cvs	63
mailing.freebsd.svn-ports-all	63
mailing.freebsd.ports-bugs	62
it-alt.sport.calcio.juventus	56
mailing.freebsd.svn-ports-head	54
mozilla.support.firefox	45
fa.freebsd.bugs	44
mailing.freebsd.bugs	44
gmane.linux.alsa.devel	42
mailing.openbsd.source-changes	38
fa.freebsd.cvs-all	34
mailing.freebsd.svn-src-all	34
mailing.openbsd.ports-changes	31
TOTAL: 254	3455

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6683
EMP (phn path)	3993
EMP (phl)	740
PFM bot misc	226
EMP (phn nph)	145
HTML Multipart	82
Too many newsgroups	55
HTML Image Tags	13
Binary Image: misplaced jpg	7
HTML post	5
Binary Image: misplaced png	1
Binary Payload	1
User-issued spam cancel	1
TOTAL: 13	11952

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	834
	feeder.eternal-september.org	70
	au2pb.net	18
	newsfeeds.xmission.com	13
	news-out.mixmin.net	7
	newsfeed.fu-berlin.de	1
	nntp.pasdenom.info	1
	TOTAL: 7	944
Including strange strings
	newsfeed.fu-berlin.de	402
	au2pb.net	47
	feeder.eternal-september.org	40
	xanadu-bbs.net	27
	news.swapon.de	19
	news-out.mixmin.net	13
	newsfeeds.xmission.com	8
	newsfeed.neva.ru	3
	TOTAL: 8	559
TOTAL: 2		1503

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	31033	96	26261	493	3	0%	23:59:56
2	au2pb	18357	85	17807	41	0	0%	23:59:33
3	xanadu-bbs	26480	68	25248	37	6	0%	23:59:56
4	fu-berlin.de	25486	20	25447	19	0	0%	23:59:31
5	news.mixmin.net	25359	16	24205	0	0	0%	23:59:56
6	xmission	28109	16	24389	3	0	0%	23:59:56
7	neva.ru	26205	9	24171	32	0	0%	23:59:56
8	eternal-september	21318	1	19692	0	0	0%	23:59:54
9	news.swapon.de	2106	0	2078	5	0	0%	23:52:48
TOTAL: 9		204453	311	189298	630	9	0%	215:51:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	434.1 KB	1.1 MB	1.5 MB	2.6 KB	23:59:56
2	xanadu-bbs	416.8 KB	74.6 KB	491.4 KB	4.7 KB	23:59:56
3	au2pb	337.9 KB	148.0 KB	485.8 KB	3.9 KB	23:59:33
4	fu-berlin.de	99.3 KB	24.6 KB	123.9 KB	3.2 KB	23:59:31
5	news.mixmin.net	76.8 KB	0.0 KB	76.8 KB	4.8 KB	23:59:56
6	neva.ru	43.4 KB	167.1 KB	210.5 KB	5.1 KB	23:59:56
7	xmission	37.0 KB	38.0 KB	75.0 KB	3.9 KB	23:59:56
8	eternal-september	6.0 KB	0.0 KB	6.0 KB	6.0 KB	23:59:54
9	news.swapon.de	0.0 KB	13.1 KB	13.1 KB	2.6 KB	23:52:48
TOTAL: 9		1.4 MB	1.5 MB	3.0 MB	3.2 KB	215:51:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	98	300.6 KB	83	04:13:22
2	tempe0.bbox.io	13	3	4.8 KB	2	03:56:53
3	dhcp-089-099-203-254.chello.nl	12	2	3.5 KB	2	05:33:06
TOTAL: 3		313	103	308.9 KB	87	21:51:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	98	300.6 KB	83	09:30:22
2	*.bbox.io	13	3	4.8 KB	2	03:56:53
3	*.chello.nl	12	2	3.5 KB	2	05:33:06
4	*.nielsenmedia.com	5	0	0.0 KB	0	02:08:06
5	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	00:43:07
TOTAL: 5		353	103	308.9 KB	87	21:51:35

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
dhcp-089-099-203-254.chello.nl	1.128	0.174	0.000	05:33:06
162.212.88.221	0.416	0.122	0.000	05:16:57
202.89.176.220	29.153	6.486	0.746	04:13:22
tempe0.bbox.io	1.542	0.237	0.000	03:56:53
challenger.nielsenmedia.com	0.464	0.087	0.000	02:08:06
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.177	0.024	0.000	00:43:07
69.160.37.54	2.082	0.461	0.000	00:00:02
TOTAL: 7	34.962	7.591	0.746	21:51:35

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.221	8
challenger.nielsenmedia.com	5
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
TOTAL: 4	40

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	11	0
tempe0.bbox.io	7	0
challenger.nielsenmedia.com	4	0
TOTAL: 3	22	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	48
2	comp.arch	26
3	comp.sys.ibm.pc.games.action	13
4	comp.sys.ibm.ps2.hardware	8
5	comp.arch.embedded	5
6	comp.ai.neural-nets	2
7	comp.sys.ibm.pc.games.war-historical	1
TOTAL: 7		103

Daily Usenet report for csiph.com

Dec 17 04:15:00 -- Dec 18 04:15:00