Daily Usenet report for news.niel.me: Dec 18 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	39758	77.5%	7.7 MB	83.2%
innd	7743	15.1%	1.0 MB	11.2%
innfeed	2003	3.9%	330.1 KB	3.5%
nnrpd	1811	3.5%	196.8 KB	2.1%
nocem	3	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	51319	100.0%	9.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	47762	38.6%
Do not exist	38819	31.4%
Positive hits	37075	30.0%
Cache misses	26	0.0%
TOTAL: 4	123682	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.282	0.0%	227	0.000	1.242	6.000
article cancel	00:00:00.045	0.0%	229	0.000	0.197	1.000
article cleanup	00:00:00.837	0.0%	39026	0.000	0.021	0.041
article logging	00:00:03.165	0.0%	39026	0.040	0.081	0.131
article parse	00:00:10.574	0.0%	197768	0.033	0.053	0.091
article write	00:00:26.832	0.0%	33362	0.556	0.804	3.085
artlog/artcncl	00:00:00.011	0.0%	229	0.000	0.048	0.500
artlog/artcncl/perl	00:00:00.018	0.0%	227	0.000	0.079	1.000
data move	00:00:01.924	0.0%	330737	0.002	0.006	0.012
hisgrep/artcncl	00:00:00.009	0.0%	229	0.000	0.039	1.000
hisgrep/artcncl/perl	00:00:00.007	0.0%	227	0.000	0.031	0.500
hisgrep/perl	00:00:00.020	0.0%	231	0.000	0.087	1.000
hishave/artcncl	00:00:00.000	0.0%	229	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.002	0.0%	227	0.000	0.009	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.596	0.0%	123885	0.008	0.021	0.118
history sync	00:00:00.044	0.0%	382	0.000	0.115	0.667
history write	00:00:08.195	0.0%	39025	0.109	0.210	0.532
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:46:11.017	99.7%	281671	186.400	303.798	579.221
nntp read	00:00:08.486	0.0%	281245	0.016	0.030	0.054
overview write	00:00:22.616	0.0%	33362	0.161	0.678	46.752
perl filter	00:02:35.365	0.2%	39025	3.423	3.981	4.799
python filter	00:00:00.174	0.0%	39025	0.000	0.004	0.023
site send	00:00:08.344	0.0%	68124	0.027	0.122	0.207
TOTAL: 23:51:06.475	23:50:20.563	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.310	0.0%	34057	0.000	0.009	0.026
article prepare	00:00:00.073	0.0%	24112	0.000	0.003	0.021
article read	00:00:00.701	0.0%	4156	0.000	0.169	0.444
backlog stats	00:00:02.413	0.0%	534757	0.001	0.005	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:08.514	0.0%	361476	0.013	0.024	0.040
data write	00:00:14.670	0.0%	283094	0.041	0.052	0.064
idle	23:51:29.560	99.9%	534757	102.811	160.614	285.644
status file	00:00:00.361	0.0%	201	0.500	1.796	6.000
TOTAL: 23:52:24.852	23:51:56.602	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:54.784	35.2%	1450	20.000	37.782	8854.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.099	0.1%	3205	0.000	0.031	0.500
TOTAL: 00:02:35.704	00:00:54.883	35.2%	-	-	-	-

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	6	38011	19313	14160	4538	50%	24:32:01
2	news.glou.org	4	16020	11480	3508	1032	71%	24:17:22
3	feed-out.gegeweb.org	3	3562	1254	2308	0	35%	23:59:17
4	nntp.terraraq.uk	2	4866	675	4164	27	13%	26:40:30
5	usenet.pasdenom.info	3	2610	426	2148	36	16%	20:47:24
6	i2pn.org	1	3625	214	3411	0	5%	23:05:42
7	gegeweb-out.news.tnetconsulting.net	2	3583	213	3323	47	5%	25:28:39
8	news.samoylyk.net	1	3766	35	3730	1	0%	23:59:48
9	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 9		48	76043	33610	36752	5681	44%	192:50:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	182.8 MB	2.8 KB	38.2 MB	221.0 MB	82%	9.5 KB
2	news.glou.org	86.6 MB	0.0 KB	7.1 MB	93.7 MB	92%	7.7 KB
3	feed-out.gegeweb.org	10.2 MB	0.0 KB	0.0 KB	10.2 MB	100%	8.4 KB
4	nntp.terraraq.uk	3.7 MB	0.0 KB	244.4 KB	3.9 MB	93%	5.7 KB
5	i2pn.org	3.4 MB	0.0 KB	0.0 KB	3.4 MB	100%	16.4 KB
6	usenet.pasdenom.info	1.5 MB	0.0 KB	44.4 KB	1.5 MB	97%	3.4 KB
7	gegeweb-out.news.tnetconsulting.net	570.0 KB	0.0 KB	128.7 KB	698.8 KB	81%	2.7 KB
8	news.samoylyk.net	308.8 KB	0.0 KB	2.5 KB	311.4 KB	99%	8.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		289.0 MB	2.8 KB	45.8 MB	334.8 MB	86%	8.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:01 - 04:59:59	991	2.9%	0.37	9.0 MB	3.1%	3.40
Dec 18 05:00:00 - 05:59:59	1598	4.8%	0.44	14.6 MB	5.1%	4.15
Dec 18 06:00:00 - 06:59:59	1269	3.8%	0.35	11.1 MB	3.8%	3.15
Dec 18 07:00:00 - 07:59:59	1420	4.2%	0.39	12.8 MB	4.4%	3.64
Dec 18 08:00:00 - 08:59:59	1670	5.0%	0.46	16.2 MB	5.6%	4.59
Dec 18 09:00:00 - 09:59:59	1600	4.8%	0.44	14.8 MB	5.1%	4.21
Dec 18 10:00:00 - 10:59:59	1653	4.9%	0.46	14.3 MB	5.0%	4.07
Dec 18 11:00:00 - 11:59:59	1604	4.8%	0.45	14.4 MB	5.0%	4.10
Dec 18 12:00:00 - 12:59:59	1425	4.2%	0.40	13.1 MB	4.6%	3.74
Dec 18 13:00:00 - 13:59:59	1638	4.9%	0.46	17.3 MB	6.0%	4.91
Dec 18 14:00:00 - 14:59:59	1807	5.4%	0.50	15.4 MB	5.3%	4.37
Dec 18 15:00:00 - 15:59:59	1682	5.0%	0.47	15.2 MB	5.3%	4.32
Dec 18 16:00:00 - 16:59:59	1450	4.3%	0.40	11.2 MB	3.9%	3.19
Dec 18 17:00:00 - 17:59:59	1334	4.0%	0.37	11.6 MB	4.0%	3.31
Dec 18 18:00:00 - 18:59:59	1206	3.6%	0.34	10.1 MB	3.5%	2.87
Dec 18 19:00:00 - 19:59:59	1386	4.1%	0.39	10.3 MB	3.6%	2.92
Dec 18 20:00:00 - 20:59:59	1312	3.9%	0.36	9.5 MB	3.3%	2.69
Dec 18 21:00:00 - 21:59:59	1043	3.1%	0.29	7.2 MB	2.5%	2.05
Dec 18 22:00:00 - 22:59:59	1153	3.4%	0.32	9.1 MB	3.2%	2.60
Dec 18 23:00:00 - 23:59:59	1103	3.3%	0.31	8.3 MB	2.9%	2.37
Dec 19 00:00:00 - 00:59:59	1074	3.2%	0.30	7.8 MB	2.7%	2.22
Dec 19 01:00:00 - 01:59:59	1055	3.1%	0.29	8.1 MB	2.8%	2.32
Dec 19 02:00:00 - 02:59:59	1179	3.5%	0.33	9.4 MB	3.2%	2.67
Dec 19 03:00:00 - 03:59:59	1524	4.5%	0.42	13.8 MB	4.8%	3.92
Dec 19 04:00:00 - 04:15:01	430	1.3%	0.48	4.2 MB	1.4%	4.73
TOTAL: 24:00:00	33606	100.0%	0.39	288.6 MB	100.0%	3.42

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	4538	110	1	4427
2	news.glou.org	1032	0	0	1032
3	usenet.pasdenom.info	53	0	0	53
4	gegeweb-out.news.tnetconsulting.net	44	0	0	44
5	nntp.terraraq.uk	27	0	0	27
TOTAL: 5		5694	110	1	5583

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.misc.gestion	57
fr.lettres.langue.allemande	53
TOTAL: 2	110

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4888
Too many newsgroups	434
Bad path (news.giganews.com.POSTED)	103
EMP (phn path)	52
EMP (phl)	44
Cancel for rejected article	37
User-issued spam cancel	18
Cancels abusifs	3
Cancel of non-existing ID <31956646-17b3-406b-b101-5160550f9b97n@googlegroups.com>	1
Cancel of non-existing ID <49d21291-43d7-410a-85fb-5e429eea7e74n@googlegroups.com>	1
Cancel of non-existing ID <8f02c054-e427-460a-8808-3cb8e19fc907n@googlegroups.com>	1
Cancel of non-existing ID <9c781e42-b64f-4d56-a0a1-f5a040c620dfn@googlegroups.com>	1
TOTAL: 12	5583

NoCeM on Spool:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	19734	720	13555	0	0	3%	23:59:53
2	i2pn.org	54692	273	30814	2254	83	0%	23:59:52
3	nntp.terraraq.uk	37314	148	29953	1442	0	0%	23:59:52
4	news.samoylyk.net	48054	29	32945	605	194	0%	23:59:53
5	news.pasdenom.info	37427	25	32890	15	370	0%	23:59:53
6	news.tnetconsulting.net	47474	20	33059	525	30	0%	23:59:53
7	news.gegeweb.org	36596	1	32343	0	1	0%	23:59:53
8	news.aioe.org	0	0	0	0	8877	0%	23:59:53
TOTAL: 8		281291	1216	205559	4841	9555	0%	191:59:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	6.8 MB	0.0 KB	6.8 MB	0.1 KB/s	9.7 KB	23:59:53
2	i2pn.org	1.4 MB	20.2 MB	21.6 MB	0.3 KB/s	8.7 KB	23:59:52
3	nntp.terraraq.uk	1.3 MB	11.9 MB	13.2 MB	0.2 KB/s	8.5 KB	23:59:52
4	news.pasdenom.info	100.7 KB	136.4 KB	237.1 KB	0.0 KB/s	5.9 KB	23:59:53
5	news.samoylyk.net	83.4 KB	5.1 MB	5.2 MB	0.1 KB/s	8.4 KB	23:59:53
6	news.tnetconsulting.net	56.5 KB	4.7 MB	4.8 MB	0.1 KB/s	9.0 KB	23:59:53
7	news.gegeweb.org	30.7 KB	0.0 KB	30.7 KB	0.0 KB/s	30.7 KB	23:59:53
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 8		9.7 MB	42.1 MB	51.8 MB	0.1 KB/s	8.8 KB	191:59:02

NNRP readership statistics:

System		Conn	Elapsed
1	88-165-5-23.subs.proxad.net	1	00:00:00
2	198.199.102.99	1	00:00:00
3	107.170.242.11	1	00:00:09
4	194.165.16.76	1	00:00:01
5	mail.gegeweb.eu	288	00:02:08
6	positive.monitoring.internet-measurement.com	2	00:00:00
7	streamlined.monitoring.internet-measurement.com	1	00:00:05
8	effusive.monitoring.internet-measurement.com	1	00:00:00
9	nifty.monitoring.internet-measurement.com	1	00:00:00
10	nurturing.monitoring.internet-measurement.com	1	00:00:06
11	93.150.216.162.bc.googleusercontent.com	1	00:00:01
12	auspicious.monitoring.internet-measurement.com	1	00:00:00
13	fetching.monitoring.internet-measurement.com	1	00:00:00
14	glowing.monitoring.internet-measurement.com	1	00:00:00
15	thriving.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 15		303	00:02:35

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.subs.proxad.net	1	00:00:00
2	*.monitoring.internet-measurement.com	10	00:00:12
3	unresolved	3	00:00:11
4	*.gegeweb.eu	288	00:02:08
5	*.150.216.162.bc.googleusercontent.com	1	00:00:01
TOTAL: 5		303	00:02:35

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.430	4.222	1.590	00:02:08
107.170.242.11	0.012	0.012	0.000	00:00:09
nurturing.monitoring.internet-measurement.com	0.051	0.008	0.000	00:00:06
streamlined.monitoring.internet-measurement.com	0.045	0.028	0.000	00:00:05
194.165.16.76	0.033	0.021	0.000	00:00:01
93.150.216.162.bc.googleusercontent.com	0.008	0.016	0.000	00:00:01
88-165-5-23.subs.proxad.net	0.013	0.016	0.000	00:00:00
198.199.102.99	0.016	0.032	0.000	00:00:00
glowing.monitoring.internet-measurement.com	0.052	0.012	0.000	00:00:00
fetching.monitoring.internet-measurement.com	0.023	0.005	0.000	00:00:00
effusive.monitoring.internet-measurement.com	0.024	0.004	0.000	00:00:00
auspicious.monitoring.internet-measurement.com	0.032	0.032	0.000	00:00:00
thriving.monitoring.internet-measurement.com	0.039	0.017	0.000	00:00:00
positive.monitoring.internet-measurement.com	0.038	0.025	0.000	00:00:00
TOTAL: 14	4.816	4.450	1.590	00:02:35

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
93.150.216.162.bc.googleusercontent.com	3
194.165.16.76	1
198.199.102.99	1
TOTAL: 3	5

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
Host: 45.13.105.36:119	1
#003	1
GET / HTTP/1.1	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.76	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Dec 18 04:15:01 -- Dec 19 04:15:01