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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 15 11:51:40 csiph pgpverify[45507]: pgpverify: verification failed
Dec 15 11:51:40 csiph controlchan[25015]: skipping newgroup m control@usenet.org.uk (pgpverify failed) in <makegroup-uk.radio.amateur.moderated-20151215114905$7fc9@weathertop.principate.org.uk>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46247	48.5%	7.1 MB	61.2%
nnrpd	31874	33.4%	2.6 MB	23.0%
innd	15410	16.2%	1.5 MB	13.4%
innfeed	1840	1.9%	283.0 KB	2.4%
controlchan	2	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	95374	100.0%	11.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	262943	49.6%
Negative hits	221764	41.8%
Do not exist	45498	8.6%
Cache misses	176	0.0%
TOTAL: 4	530381	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.504	0.0%	194	0.000	2.598	23.000
article cleanup	00:00:00.275	0.0%	43284	0.000	0.006	0.023
article logging	00:00:00.591	0.0%	43836	0.000	0.013	0.067
article parse	00:00:05.648	0.0%	494794	0.003	0.011	0.075
article write	00:01:17.756	0.1%	28246	0.175	2.753	17.839
artlog/artcncl	00:00:00.002	0.0%	156	0.000	0.013	0.333
artlog/artparse	00:00:00.020	0.0%	2262	0.000	0.009	0.333
data move	00:00:00.470	0.0%	954593	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.123	0.0%	194	0.000	0.634	16.000
hishave/artcncl	00:00:00.001	0.0%	194	0.000	0.005	0.500
hishave/artparse	00:00:00.009	0.0%	2262	0.000	0.004	0.031
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.266	0.0%	528577	0.001	0.012	0.275
history sync	00:00:02.405	0.0%	352	0.000	6.832	215.500
history write	00:00:43.591	0.1%	43281	0.072	1.007	13.356
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.549	0.0%	2261	0.000	0.685	6.600
idle	23:55:28.884	99.6%	927190	12.712	92.892	350.242
nntp read	00:00:02.988	0.0%	930329	0.002	0.003	0.007
overview write	00:01:08.651	0.1%	28246	0.504	2.430	20.179
perl filter	00:01:12.727	0.1%	43281	1.029	1.680	3.030
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.476	0.0%	56493	0.000	0.008	0.024
TOTAL: 24:00:50.099	24:00:12.936	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.077	0.0%	28222	0.000	0.003	0.019
article prepare	00:00:00.000	0.0%	4684	0.000	0.000	0.000
article read	00:00:00.172	0.0%	946	0.000	0.182	15.000
backlog stats	00:00:00.100	0.0%	299164	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.308	0.0%	235564	0.001	0.006	0.011
data write	00:00:01.562	0.0%	212555	0.001	0.007	0.015
idle	23:55:11.255	100.0%	299164	130.638	287.840	739.330
status file	00:00:00.081	0.0%	175	0.000	0.463	1.000
TOTAL: 23:55:18.692	23:55:14.555	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.086	0.0%	2262	0.032	0.038	0.049
idle	20:41:41.482	99.9%	33701	0.000	2210.661	600127.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.411	0.0%	68434	0.000	0.006	0.333
readart	00:00:00.079	0.0%	103	0.000	0.767	72.000
TOTAL: 20:42:41.087	20:41:42.058	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	3	31281	9230	20985	1066	29%	24:08:47
2	feeder.eternal-september.org	8	41262	5872	33895	1495	14%	25:51:22
3	news-out.mixmin.net	4	33046	3782	26866	2398	11%	24:17:44
4	newsfeeds.xmission.com	1	29404	2739	26125	540	9%	23:35:06
5	xanadu-bbs.net	7	45277	2582	34375	8320	5%	27:38:59
6	newsfeed.neva.ru	143	62563	2405	56986	3172	3%	71:39:55
7	newsfeed.fu-berlin.de	65	33375	1376	31759	240	4%	36:01:37
8	news.swapon.de	2	2523	210	2306	7	8%	24:38:21
9	nntp.pasdenom.info	4	29391	139	29198	54	0%	24:08:21
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		263	308122	28335	262495	17292	9%	282:00:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	21.3 MB	11.0 KB	6.0 MB	27.3 MB	77%	2.7 KB
2	feeder.eternal-september.org	16.9 MB	0.0 KB	25.2 MB	42.1 MB	40%	5.8 KB
3	news-out.mixmin.net	13.4 MB	12.1 KB	5.3 MB	18.8 MB	71%	3.1 KB
4	newsfeeds.xmission.com	9.1 MB	12.1 KB	5.4 MB	14.5 MB	62%	4.5 KB
5	xanadu-bbs.net	7.8 MB	0.0 KB	645.1 MB	652.9 MB	1%	61.3 KB
6	newsfeed.neva.ru	7.6 MB	0.0 KB	15.9 MB	23.5 MB	32%	4.3 KB
7	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	598.9 KB	3.6 MB	83%	2.3 KB
8	news.swapon.de	556.4 KB	0.0 KB	141.2 KB	697.6 KB	79%	3.2 KB
9	nntp.pasdenom.info	349.7 KB	0.0 KB	578.6 KB	928.3 KB	37%	4.8 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.0 MB	35.2 KB	704.3 MB	784.3 MB	10%	17.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 15 04:15:00 - 04:59:59	487	1.7%	0.18	1.6 MB	1.9%	0.59
Dec 15 05:00:00 - 05:59:59	703	2.5%	0.20	2.3 MB	2.9%	0.67
Dec 15 06:00:00 - 06:59:59	611	2.2%	0.17	2.0 MB	2.5%	0.57
Dec 15 07:00:00 - 07:59:59	807	2.9%	0.22	2.1 MB	2.6%	0.60
Dec 15 08:00:00 - 08:59:59	1251	4.4%	0.35	3.6 MB	4.5%	1.01
Dec 15 09:00:00 - 09:59:59	1224	4.3%	0.34	3.1 MB	3.8%	0.87
Dec 15 10:00:00 - 10:59:59	1378	4.9%	0.38	3.5 MB	4.4%	1.01
Dec 15 11:00:00 - 11:59:59	1176	4.2%	0.33	2.9 MB	3.7%	0.83
Dec 15 12:00:00 - 12:59:59	1389	4.9%	0.39	3.6 MB	4.5%	1.02
Dec 15 13:00:00 - 13:59:59	1346	4.8%	0.37	3.3 MB	4.2%	0.94
Dec 15 14:00:00 - 14:59:59	1394	4.9%	0.39	3.4 MB	4.2%	0.95
Dec 15 15:00:00 - 15:59:59	1510	5.3%	0.42	4.5 MB	5.6%	1.28
Dec 15 16:00:00 - 16:59:59	1743	6.2%	0.48	5.0 MB	6.3%	1.43
Dec 15 17:00:00 - 17:59:59	1656	5.9%	0.46	4.8 MB	6.1%	1.38
Dec 15 18:00:00 - 18:59:59	1830	6.5%	0.51	4.6 MB	5.8%	1.31
Dec 15 19:00:00 - 19:59:59	1579	5.6%	0.44	4.7 MB	5.9%	1.34
Dec 15 20:00:00 - 20:59:59	1375	4.9%	0.38	3.7 MB	4.6%	1.05
Dec 15 21:00:00 - 21:59:59	1578	5.6%	0.44	4.6 MB	5.8%	1.31
Dec 15 22:00:00 - 22:59:59	1305	4.6%	0.36	4.3 MB	5.4%	1.22
Dec 15 23:00:00 - 23:59:59	972	3.4%	0.27	2.8 MB	3.5%	0.78
Dec 16 00:00:00 - 00:59:59	860	3.0%	0.24	2.5 MB	3.1%	0.71
Dec 16 01:00:00 - 01:59:59	750	2.7%	0.21	2.4 MB	3.0%	0.68
Dec 16 02:00:00 - 02:59:59	554	2.0%	0.15	1.6 MB	2.0%	0.45
Dec 16 03:00:00 - 03:59:59	605	2.1%	0.17	2.2 MB	2.8%	0.64
Dec 16 04:00:00 - 04:15:00	160	0.6%	0.18	635.3 KB	0.8%	0.71
TOTAL: 24:00:00	28243	100.0%	0.33	79.7 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8319	396	0	7923
2	newsfeed.neva.ru	3202	1453	0	1749
3	news-out.mixmin.net	2421	4	1	2416
4	feeder.eternal-september.org	1509	222	0	1287
5	au2pb.net	1083	1006	1	76
6	newsfeed.fu-berlin.de	658	50	0	608
7	newsfeeds.xmission.com	556	400	1	155
8	nntp.pasdenom.info	59	50	0	9
9	news.swapon.de	41	0	0	41
TOTAL: 9		17848	3581	3	14264

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	909
lada.talk	186
ut.test	151
alt.politics	95
mailing.freebsd.svn-ports-all	87
mailing.freebsd.pkg-fallout	82
mailing.freebsd.svn-ports-head	82
lada.auto	80
mailing.freebsd.ports-bugs	63
mailing.freebsd.svn-src-all	62
fa.freebsd.cvs-all	62
it-alt.sport.calcio.juventus	61
pld.cvs.commit	57
mailing.freebsd.svn-src-head	50
mailing.freebsd.questions	42
gmane.linux.alsa.devel	34
mozilla.dev.tree-alerts	33
alt.politics.scorched-earth	30
mailing.openssl.dev	29
mailing.openbsd.source-changes	29
TOTAL: 266	3581

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7302
EMP (phn path)	2853
EMP (phl)	849
PFM bot misc	156
Too many newsgroups	86
EMP (phn nph)	77
HTML Multipart	64
Binary: misplaced rar	19
HTML post	18
User-issued spam cancel	8
Binary Image: misplaced jpg	7
HTML Image Tags	2
Binary: misplaced par2	1
Binary: misplaced zip	1
Too many newsgroups (meow)	1
NewsAgent	1
TOTAL: 16	11445

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	1994
	feeder.eternal-september.org	170
	newsfeeds.xmission.com	41
	au2pb.net	30
	news-out.mixmin.net	24
	nntp.pasdenom.info	5
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	TOTAL: 8	2267
Including strange strings
	newsfeed.fu-berlin.de	440
	news.swapon.de	34
	feeder.eternal-september.org	30
	news-out.mixmin.net	20
	au2pb.net	20
	newsfeeds.xmission.com	4
	newsfeed.neva.ru	2
	xanadu-bbs.net	2
	TOTAL: 8	552
TOTAL: 2		2819

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	32735	193	27388	522	3	0%	23:59:57
2	au2pb	19554	147	18802	81	0	0%	23:59:52
3	xanadu-bbs	27307	75	25541	61	12	0%	23:59:57
4	fu-berlin.de	26435	20	26400	15	0	0%	23:59:53
5	xmission	29715	10	25178	2	0	0%	23:59:57
6	eternal-september	23122	8	21562	7	0	0%	23:59:57
7	news.mixmin.net	25480	6	24390	0	0	0%	23:59:57
8	neva.ru	26703	3	25528	24	0	0%	23:59:57
9	news.swapon.de	2316	0	2280	2	0	0%	23:57:02
TOTAL: 9		213367	462	197069	714	15	0%	215:56:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	804.6 KB	1.1 MB	1.9 MB	2.7 KB	23:59:57
2	au2pb	654.5 KB	327.4 KB	981.9 KB	4.3 KB	23:59:52
3	xanadu-bbs	520.8 KB	135.5 KB	656.2 KB	4.8 KB	23:59:57
4	fu-berlin.de	109.8 KB	19.0 KB	128.8 KB	3.7 KB	23:59:53
5	xmission	32.8 KB	4.1 KB	36.9 KB	3.1 KB	23:59:57
6	eternal-september	32.3 KB	35.1 KB	67.4 KB	4.5 KB	23:59:57
7	news.mixmin.net	28.6 KB	0.0 KB	28.6 KB	4.8 KB	23:59:57
8	neva.ru	15.4 KB	177.0 KB	192.4 KB	7.1 KB	23:59:57
9	news.swapon.de	0.0 KB	8.3 KB	8.3 KB	4.2 KB	23:57:02
TOTAL: 9		2.1 MB	1.8 MB	3.9 MB	3.4 KB	215:56:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	101	258.2 KB	94	04:23:07
2	tempe0.bbox.io	14	2	2.4 KB	2	04:11:20
TOTAL: 2		302	103	260.6 KB	96	20:42:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	317	101	258.2 KB	94	12:15:24
2	*.bbox.io	14	2	2.4 KB	2	04:11:20
3	*.dynamic.upc.nl	2	0	0.0 KB	0	00:00:43
4	*.chello.nl	4	0	0.0 KB	0	01:39:53
5	*.tampfl.dsl-w.verizon.net	3	0	0.0 KB	0	01:03:31
6	*.nielsenmedia.com	3	0	0.0 KB	0	01:31:43
TOTAL: 6		343	103	260.6 KB	96	20:42:37

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
216.246.166.29	0.388	0.205	0.000	07:52:14
202.89.176.220	30.378	6.395	0.748	04:23:07
tempe0.bbox.io	1.378	0.263	0.000	04:11:20
dhcp-089-099-203-254.chello.nl	0.294	0.055	0.000	01:39:53
challenger.nielsenmedia.com	0.250	0.054	0.000	01:31:43
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.266	0.025	0.000	01:03:31
92-108-162-83.dynamic.upc.nl	0.199	0.023	0.000	00:00:43
69.160.37.54	1.943	0.417	0.000	00:00:02
TOTAL: 8	35.096	7.437	0.748	20:42:37

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	4
216.246.166.29	4
challenger.nielsenmedia.com	3
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3
92-108-162-83.dynamic.upc.nl	2
TOTAL: 6	41

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	8	0
challenger.nielsenmedia.com	3	0
dhcp-089-099-203-254.chello.nl	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
TOTAL: 4	16	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	26
2	comp.arch	22
3	comp.sys.ibm.pc.games.action	20
4	comp.arch.embedded	10
5	comp.arch.fpga	7
6	comp.ai.philosophy	6
7	comp.sys.ibm.pc.hardware.storage	4
8	comp.ai.neural-nets	3
9	comp.sys.ibm.ps2.hardware	3
10	comp.sys.ibm.pc.games.rpg	1
11	comp.sys.ibm.pc.games.strategic	1
TOTAL: 11		103

Daily Usenet report for csiph.com

Dec 15 04:15:00 -- Dec 16 04:15:00