Daily Usenet report for news.niel.me: May 10 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4254	39.1%	784.4 KB	44.8%
innd	2512	23.1%	412.7 KB	23.6%
nnrpd	2377	21.9%	253.7 KB	14.5%
innfeed	1731	15.9%	299.3 KB	17.1%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	10876	100.0%	1.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	20509	59.0%
Negative hits	10160	29.2%
Do not exist	4001	11.5%
Cache misses	96	0.3%
TOTAL: 4	34766	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.098	0.0%	95	0.000	1.032	3.000
article cancel	00:00:00.019	0.0%	95	0.000	0.200	1.000
article cleanup	00:00:00.074	0.0%	4014	0.000	0.018	0.188
article logging	00:00:00.277	0.0%	4056	0.000	0.068	0.250
article parse	00:00:00.801	0.0%	11636	0.000	0.069	0.217
article write	00:00:03.317	0.0%	3410	0.091	0.973	2.444
artlog/artcncl	00:00:00.002	0.0%	95	0.000	0.021	0.023
artlog/artcncl/perl	00:00:00.006	0.0%	95	0.000	0.063	1.000
data move	00:00:00.143	0.0%	40992	0.000	0.003	0.019
hisgrep/artcncl	00:00:00.009	0.0%	95	0.000	0.095	1.000
hisgrep/artcncl/perl	00:00:00.004	0.0%	95	0.000	0.042	1.000
hisgrep/perl	00:00:00.003	0.0%	104	0.000	0.029	0.031
hishave/artcncl	00:00:00.002	0.0%	95	0.000	0.021	1.000
hishave/artcncl/perl	00:00:00.002	0.0%	95	0.000	0.021	0.023
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.415	0.0%	34625	0.000	0.012	0.038
history sync	00:00:00.056	0.0%	400	0.000	0.140	1.000
history write	00:00:00.924	0.0%	4010	0.000	0.230	0.679
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:18.838	99.9%	42137	567.476	2036.662	7151.571
nntp read	00:00:01.370	0.0%	41705	0.006	0.033	0.087
overview write	00:00:02.151	0.0%	3410	0.111	0.631	6.636
perl filter	00:00:18.564	0.0%	4010	2.974	4.629	12.846
python filter	00:00:00.007	0.0%	4010	0.000	0.002	0.067
site send	00:00:01.228	0.0%	6871	0.000	0.179	0.450
TOTAL: 23:51:06.466	23:50:48.310	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	3525	0.000	0.006	0.111
article prepare	00:00:00.001	0.0%	316	0.000	0.003	0.250
article read	00:00:00.019	0.0%	70	0.000	0.271	7.000
backlog stats	00:00:00.128	0.0%	45932	0.000	0.003	0.016
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.622	0.0%	27378	0.000	0.023	0.069
data write	00:00:01.312	0.0%	24132	0.015	0.054	0.107
idle	23:52:11.594	100.0%	45932	569.762	1870.844	4418.779
status file	00:00:00.455	0.0%	196	1.000	2.321	7.000
TOTAL: 23:52:19.526	23:52:14.152	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:02.593	37.4%	1451	11.400	43.138	11753.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.099	0.1%	3352	0.000	0.030	1.000
TOTAL: 00:02:47.320	00:01:02.692	37.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	171

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	2	4228	814	3046	368	19%	27:34:42
2	feed-out.gegeweb.org	2	2270	701	1569	0	30%	23:59:45
3	i2pn.org	2	4310	680	3500	130	15%	29:02:36
4	news.glou.org	2	3084	338	2681	65	10%	23:59:45
5	nntp.terraraq.uk	1	2666	284	2338	44	10%	21:19:32
6	news.samoylyk.net	1	2341	271	2069	1	11%	18:22:39
7	usenet.pasdenom.info	1	2242	203	2014	25	9%	24:23:51
8	gegeweb-out.news.tnetconsulting.net	2	3346	104	3240	2	3%	23:59:32
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	24487	3395	20457	635	13%	192:42:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.0 MB	0.0 KB	1.2 MB	5.2 MB	76%	4.5 KB
2	news.samoylyk.net	3.6 MB	0.0 KB	5.5 KB	3.6 MB	99%	13.5 KB
3	i2pn.org	2.6 MB	0.0 KB	438.3 KB	3.0 MB	85%	3.8 KB
4	gegeweb-out.news.tnetconsulting.net	2.0 MB	3.4 KB	5.4 KB	2.0 MB	99%	19.4 KB
5	feed-out.gegeweb.org	1.9 MB	0.0 KB	0.0 KB	1.9 MB	100%	2.8 KB
6	news.glou.org	1.2 MB	97.5 KB	292.3 KB	1.6 MB	75%	4.0 KB
7	nntp.terraraq.uk	1.1 MB	1.5 KB	196.6 KB	1.3 MB	85%	4.2 KB
8	usenet.pasdenom.info	455.8 KB	0.0 KB	31.1 KB	486.8 KB	93%	2.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		16.8 MB	102.4 KB	2.1 MB	19.0 MB	88%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 10 04:15:02 - 04:59:59	70	2.0%	0.03	752.7 KB	4.0%	0.28
May 10 05:00:00 - 05:59:59	108	3.2%	0.03	640.8 KB	3.4%	0.18
May 10 06:00:00 - 06:59:59	103	3.0%	0.03	1.9 MB	10.1%	0.53
May 10 07:00:00 - 07:59:59	106	3.1%	0.03	336.0 KB	1.8%	0.09
May 10 08:00:00 - 08:59:59	181	5.3%	0.05	401.0 KB	2.1%	0.11
May 10 09:00:00 - 09:59:59	103	3.0%	0.03	358.0 KB	1.9%	0.10
May 10 10:00:00 - 10:59:59	153	4.5%	0.04	801.7 KB	4.2%	0.22
May 10 11:00:00 - 11:59:59	135	3.9%	0.04	609.3 KB	3.2%	0.17
May 10 12:00:00 - 12:59:59	116	3.4%	0.03	404.0 KB	2.1%	0.11
May 10 13:00:00 - 13:59:59	102	3.0%	0.03	302.0 KB	1.6%	0.08
May 10 14:00:00 - 14:59:59	170	5.0%	0.05	509.0 KB	2.7%	0.14
May 10 15:00:00 - 15:59:59	157	4.6%	0.04	1.7 MB	9.0%	0.48
May 10 16:00:00 - 16:59:59	143	4.2%	0.04	477.9 KB	2.5%	0.13
May 10 17:00:00 - 17:59:59	176	5.1%	0.05	1.1 MB	6.0%	0.31
May 10 18:00:00 - 18:59:59	190	5.6%	0.05	750.3 KB	3.9%	0.21
May 10 19:00:00 - 19:59:59	170	5.0%	0.05	645.3 KB	3.4%	0.18
May 10 20:00:00 - 20:59:59	168	4.9%	0.05	747.6 KB	3.9%	0.21
May 10 21:00:00 - 21:59:59	179	5.2%	0.05	576.0 KB	3.0%	0.16
May 10 22:00:00 - 22:59:59	204	6.0%	0.06	975.3 KB	5.1%	0.27
May 10 23:00:00 - 23:59:59	130	3.8%	0.04	437.9 KB	2.3%	0.12
May 11 00:00:00 - 00:59:59	137	4.0%	0.04	1.3 MB	7.2%	0.38
May 11 01:00:00 - 01:59:59	163	4.8%	0.05	1.1 MB	6.0%	0.32
May 11 02:00:00 - 02:59:59	120	3.5%	0.03	864.3 KB	4.5%	0.24
May 11 03:00:00 - 03:59:59	122	3.6%	0.03	493.5 KB	2.6%	0.14
May 11 04:00:00 - 04:15:02	16	0.5%	0.02	659.6 KB	3.5%	0.73
TOTAL: 24:00:00	3422	100.0%	0.04	18.6 MB	100.0%	0.22

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	312	1	0	311
2	i2pn.org	136	0	0	136
3	news.glou.org	65	0	1	64
4	nntp.terraraq.uk	55	0	1	54
5	news.samoylyk.net	34	0	0	34
6	usenet.pasdenom.info	30	0	0	30
7	gegeweb-out.news.tnetconsulting.net	6	1	2	3
8	feed-out.gegeweb.org	4	0	0	4
TOTAL: 8		642	2	4	636

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	138
EMP (phl)	110
EMP (phn nph)	99
EMP (phn path)	62
Bad path (news.giganews.com.POSTED)	59
EMP (phr nph)	57
Too many newsgroups	37
Too many newsgroups (meow)	14
Cancels abusifs	7
Cancel of non-existing ID <a40uthtbqc6a0pafbh1u8arq83pu0icnmg@4ax.com>	1
Cancel of non-existing ID <bac8uhpmuv4pcmgr3fcr61ncksed5u9vr5@4ax.com>	1
Cancel of non-existing ID <7vu2uh55gggr53t0inu8rbj4bc734ei3ju@4ax.com>	1
Cancel for rejected article	1
Cancel of non-existing ID <kbc8uh56395lssq5ruet09dpdgce5pre11@4ax.com>	1
Cancel of non-existing ID <120uthtt21c9e7qbrnjkrd8pb017cgekcl@4ax.com>	1
Cancel of non-existing ID <aec8uhpnf4bm0va64j07tjhepunj1guv57@4ax.com>	1
Cancel of non-existing ID <rnu2uhpcikj66mlmiv15aovlq26fgchc5d@4ax.com>	1
Cancel of non-existing ID <sru2uhh2mla8c4c23n1ktbj7g5bp2nd48r@4ax.com>	1
Cancel of non-existing ID <jtu2uhpk8hl2c23rnqh6erja7s83ii7sm8@4ax.com>	1
TOTAL: 19	593

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	33
	feed-out.gegeweb.org	4
	news.nntp4.net	3
	nntp.terraraq.uk	2
	i2pn.org	1
	TOTAL: 5	43
TOTAL: 1		43

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4558	31	2728	0	7	0%	23:59:43
2	news.gegeweb.org	2872	9	2700	16	0	0%	23:59:12
3	news.tnetconsulting.net	5375	8	3403	2	87	0%	23:59:54
4	news.nntp4.net	2746	4	2674	0	0	0%	23:59:54
5	nntp.terraraq.uk	2383	3	2267	2	0	0%	23:59:54
6	news.pasdenom.info	3087	2	2971	1	19	0%	23:59:26
7	news.samoylyk.net	3312	1	3040	0	0	0%	23:59:38
8	news.aioe.org	0	0	0	0	2543	0%	23:59:54
TOTAL: 8		24333	58	19783	21	2656	0%	191:57:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	77.7 KB	0.0 KB	77.7 KB	2.5 KB	23:59:43
2	news.gegeweb.org	21.5 KB	53.8 KB	75.3 KB	3.0 KB	23:59:12
3	news.tnetconsulting.net	17.8 KB	4.1 KB	21.9 KB	2.2 KB	23:59:54
4	nntp.terraraq.uk	12.5 KB	66.9 KB	79.4 KB	15.9 KB	23:59:54
5	news.nntp4.net	5.5 KB	0.0 KB	5.5 KB	1.4 KB	23:59:54
6	news.pasdenom.info	4.6 KB	1.8 KB	6.4 KB	2.1 KB	23:59:26
7	news.samoylyk.net	2.0 KB	0.0 KB	2.0 KB	2.0 KB	23:59:38
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:54
TOTAL: 8		141.6 KB	126.7 KB	268.3 KB	3.4 KB	191:57:35

NNRP readership statistics:

System		Conn	Elapsed
1	luminous.monitoring.internet-measurement.com	1	00:00:00
2	clever.monitoring.internet-measurement.com	1	00:00:00
3	kind.monitoring.internet-measurement.com	1	00:00:05
4	198.199.105.190	1	00:00:09
5	104.156.155.32	85	00:00:19
6	36.156.22.4	1	00:00:24
7	intuitive.monitoring.internet-measurement.com	1	00:00:00
8	88.165.5.23	1	00:00:00
9	194.165.16.76	1	00:00:00
10	208.100.26.244	10	00:00:04
11	sh-chi-us-gd13-wk101.internet-census.org	1	00:00:11
12	mail.gegeweb.eu	288	00:01:26
13	194.165.16.72	1	00:00:00
14	superb.monitoring.internet-measurement.com	1	00:00:02
TOTAL: 14		394	00:02:46

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:26
2	*.internet-census.org	1	00:00:11
3	*.monitoring.internet-measurement.com	5	00:00:08
4	unresolved	100	00:01:00
TOTAL: 4		394	00:02:46

NNRP auth users [Top 20]:

User	Conn
mailman	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.432	4.864	1.988	00:01:26
36.156.22.4	0.018	0.027	0.000	00:00:24
104.156.155.32	2.616	1.365	0.000	00:00:19
sh-chi-us-gd13-wk101.internet-census.org	0.021	0.031	0.000	00:00:11
198.199.105.190	0.032	0.021	0.000	00:00:09
kind.monitoring.internet-measurement.com	0.055	0.033	0.000	00:00:05
208.100.26.244	0.347	0.170	0.000	00:00:04
superb.monitoring.internet-measurement.com	0.041	0.010	0.000	00:00:02
88.165.5.23	0.027	0.027	0.000	00:00:00
194.165.16.72	0.025	0.000	0.000	00:00:00
194.165.16.76	0.056	0.011	0.000	00:00:00
intuitive.monitoring.internet-measurement.com	0.042	0.021	0.000	00:00:00
clever.monitoring.internet-measurement.com	0.029	0.029	0.000	00:00:00
luminous.monitoring.internet-measurement.com	0.029	0.039	0.000	00:00:00
TOTAL: 14	7.770	6.648	1.988	00:02:46

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	27
TOTAL: 1	27

NNRP unrecognized commands (by host):

System	Conn
194.165.16.72	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.72	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

May 10 04:15:02 -- May 11 04:15:02