Daily Usenet report for news.niel.me: Feb 21 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	24310	77.2%	4.9 MB	82.6%
innd	3310	10.5%	507.9 KB	8.3%
innfeed	2080	6.6%	350.8 KB	5.8%
nnrpd	1798	5.7%	196.8 KB	3.2%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	31500	100.0%	5.9 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	28622	37.1%
Positive hits	24583	31.9%
Do not exist	23948	31.0%
Cache misses	8	0.0%
TOTAL: 4	77161	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.019	0.0%	20	0.000	0.950	2.000
article cancel	00:00:00.000	0.0%	20	0.000	0.000	0.000
article cleanup	00:00:00.474	0.0%	24197	0.000	0.020	0.053
article logging	00:00:01.901	0.0%	24197	0.023	0.079	0.141
article parse	00:00:09.102	0.0%	160224	0.028	0.057	0.089
article write	00:00:16.155	0.0%	22885	0.328	0.706	1.029
artlog/artcncl	00:00:00.000	0.0%	20	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	20	0.000	0.050	0.333
data move	00:00:01.217	0.0%	248769	0.000	0.005	0.010
hisgrep/artcncl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.002	0.0%	20	0.000	0.100	1.000
hisgrep/perl	00:00:00.000	0.0%	24	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	20	0.000	0.050	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.441	0.0%	77628	0.005	0.031	2.352
history sync	00:00:00.056	0.0%	398	0.000	0.141	1.000
history write	00:00:04.275	0.0%	24152	0.062	0.177	0.294
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:58:17.368	99.8%	213815	87.682	403.608	1419.468
nntp read	00:00:06.262	0.0%	213163	0.013	0.029	0.056
overview write	00:00:09.521	0.0%	22885	0.174	0.416	11.370
perl filter	00:01:19.802	0.1%	24152	2.766	3.304	5.780
python filter	00:00:00.117	0.0%	24152	0.000	0.005	0.023
site send	00:00:05.653	0.0%	46882	0.034	0.121	0.299
TOTAL: 24:01:05.282	24:00:34.367	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.225	0.0%	25399	0.000	0.009	0.037
article prepare	00:00:00.079	0.0%	19664	0.000	0.004	0.083
article read	00:00:00.629	0.0%	4758	0.000	0.132	0.714
backlog stats	00:00:01.463	0.0%	315660	0.000	0.005	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.967	0.0%	210825	0.009	0.024	0.048
data write	00:00:08.914	0.0%	175021	0.032	0.051	0.069
idle	23:54:26.758	100.0%	315660	146.666	272.657	911.624
status file	00:00:00.556	0.0%	257	0.000	2.163	8.000
TOTAL: 23:54:58.949	23:54:43.591	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:59.440	55.7%	1456	7.500	82.033	27455.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.105	0.0%	3225	0.000	0.033	0.500
TOTAL: 00:03:34.342	00:01:59.545	55.8%	-	-	-	-

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	4	23550	18581	4016	953	78%	24:08:31
2	feed-out.gegeweb.org	3	3469	2307	1160	2	66%	23:56:45
3	news.glou.org	3	4012	913	2909	190	22%	23:50:04
4	nntp.terraraq.uk	3	5178	414	4758	6	7%	31:49:46
5	gegeweb-out.news.tnetconsulting.net	1	3220	277	2803	140	8%	23:31:06
6	usenet.pasdenom.info	1	2849	269	2568	12	9%	24:37:44
7	news.samoylyk.net	2	6511	43	6468	0	0%	42:50:34
8	i2pn.org	1	283	27	256	0	9%	02:24:58
9	localhost	25	0	0	0	0	0%	00:00:01
TOTAL: 9		43	49072	22831	24938	1303	46%	197:09:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	267.3 MB	232.1 KB	8.9 MB	276.4 MB	96%	14.5 KB
2	feed-out.gegeweb.org	11.5 MB	20.5 KB	20.5 KB	11.6 MB	99%	5.1 KB
3	news.glou.org	6.0 MB	0.0 KB	573.2 KB	6.5 MB	91%	6.1 KB
4	nntp.terraraq.uk	1.7 MB	0.0 KB	14.6 KB	1.7 MB	99%	4.1 KB
5	usenet.pasdenom.info	1.0 MB	0.0 KB	17.3 KB	1.1 MB	98%	3.8 KB
6	gegeweb-out.news.tnetconsulting.net	950.3 KB	3.4 KB	531.8 KB	1.5 MB	63%	3.6 KB
7	news.samoylyk.net	789.9 KB	0.0 KB	0.0 KB	789.9 KB	100%	18.4 KB
8	i2pn.org	206.2 KB	0.0 KB	0.0 KB	206.2 KB	100%	7.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		289.4 MB	256.0 KB	10.0 MB	299.7 MB	96%	12.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 21 04:15:01 - 04:59:59	379	1.7%	0.14	5.7 MB	2.0%	2.15
Feb 21 05:00:00 - 05:59:59	823	3.6%	0.23	10.5 MB	3.6%	2.99
Feb 21 06:00:00 - 06:59:59	1362	5.9%	0.38	17.6 MB	6.0%	4.99
Feb 21 07:00:00 - 07:59:59	534	2.3%	0.15	7.2 MB	2.5%	2.04
Feb 21 08:00:00 - 08:59:59	1107	4.8%	0.31	12.7 MB	4.4%	3.62
Feb 21 09:00:00 - 09:59:59	1434	6.2%	0.40	16.2 MB	5.6%	4.62
Feb 21 10:00:00 - 10:59:59	549	2.4%	0.15	7.3 MB	2.5%	2.09
Feb 21 11:00:00 - 11:59:59	1304	5.7%	0.36	15.1 MB	5.2%	4.29
Feb 21 12:00:00 - 12:59:59	898	3.9%	0.25	11.3 MB	3.9%	3.20
Feb 21 13:00:00 - 13:59:59	668	2.9%	0.19	8.8 MB	3.0%	2.49
Feb 21 14:00:00 - 14:59:59	1443	6.3%	0.40	17.7 MB	6.1%	5.02
Feb 21 15:00:00 - 15:59:59	908	4.0%	0.25	10.2 MB	3.5%	2.91
Feb 21 16:00:00 - 16:59:59	746	3.2%	0.21	7.8 MB	2.7%	2.23
Feb 21 17:00:00 - 17:59:59	1340	5.8%	0.37	17.9 MB	6.2%	5.10
Feb 21 18:00:00 - 18:59:59	827	3.6%	0.23	12.4 MB	4.3%	3.52
Feb 21 19:00:00 - 19:59:59	1066	4.6%	0.30	13.2 MB	4.6%	3.77
Feb 21 20:00:00 - 20:59:59	1105	4.8%	0.31	12.7 MB	4.4%	3.62
Feb 21 21:00:00 - 21:59:59	386	1.7%	0.11	3.8 MB	1.3%	1.07
Feb 21 22:00:00 - 22:59:59	1331	5.8%	0.37	16.4 MB	5.7%	4.68
Feb 21 23:00:00 - 23:59:59	1029	4.5%	0.29	13.5 MB	4.6%	3.84
Feb 22 00:00:00 - 00:59:59	621	2.7%	0.17	8.2 MB	2.8%	2.33
Feb 22 01:00:00 - 01:59:59	1340	5.8%	0.37	14.6 MB	5.0%	4.15
Feb 22 02:00:00 - 02:59:59	596	2.6%	0.17	6.9 MB	2.4%	1.97
Feb 22 03:00:00 - 03:59:59	776	3.4%	0.22	18.3 MB	6.3%	5.21
Feb 22 04:00:00 - 04:15:01	391	1.7%	0.43	4.6 MB	1.6%	5.18
TOTAL: 24:00:00	22963	100.0%	0.27	290.6 MB	100.0%	3.44

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.nntp4.net	953	38	915
2	news.glou.org	191	0	191
3	gegeweb-out.news.tnetconsulting.net	142	2	140
4	usenet.pasdenom.info	15	0	15
5	nntp.terraraq.uk	6	0	6
TOTAL: 5		1307	40	1267

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	492
EMP (md5)	465
Bad path (news.giganews.com.POSTED)	208
EMP (phn path)	94
Cancel for rejected article	2
Cancels abusifs	2
Cancel of <17b5d256485f0e3b$61670$108566$60d9188e@news.vipernews.com> without Cancel-Key	1
Cancel of <17b5df548ba3eb3c$76806$105726$64d91c8e@news.vipernews.com> without Cancel-Key	1
Cancel of non-existing ID <ur5ceg$39g9i$1@dont-email.me>	1
Cancel of <ur59d9$du4r$2@solani.org> without Cancel-Key	1
TOTAL: 10	1267

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	36781	98	17974	4325	2544	0%	23:59:50
2	news.gegeweb.org	29899	83	20547	4	2	0%	23:59:56
3	news.nntp4.net	4901	63	4094	0	0	1%	23:58:16
4	nntp.terraraq.uk	22642	49	21707	47	0	0%	23:59:56
5	news.samoylyk.net	30337	39	22826	74	36	0%	23:59:56
6	news.pasdenom.info	25636	20	22449	110	20	0%	23:59:56
7	news.tnetconsulting.net	29157	11	22917	35	4	0%	23:59:56
8	news.aioe.org	0	0	0	0	10804	0%	23:59:56
TOTAL: 8		179353	363	132514	4595	13410	0%	191:57:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.gegeweb.org	757.0 KB	72.7 KB	829.7 KB	0.0 KB/s	9.5 KB	23:59:56
2	nntp.terraraq.uk	684.7 KB	597.8 KB	1.3 MB	0.0 KB/s	13.4 KB	23:59:56
3	news.nntp4.net	658.2 KB	0.0 KB	658.2 KB	0.0 KB/s	10.4 KB	23:58:16
4	i2pn.org	425.5 KB	57.8 MB	58.2 MB	0.7 KB/s	13.5 KB	23:59:50
5	news.samoylyk.net	99.9 KB	939.1 KB	1.0 MB	0.0 KB/s	9.2 KB	23:59:56
6	news.pasdenom.info	84.8 KB	1.9 MB	2.0 MB	0.0 KB/s	16.0 KB	23:59:56
7	news.tnetconsulting.net	23.5 KB	407.5 KB	431.0 KB	0.0 KB/s	9.4 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 8		2.7 MB	61.7 MB	64.4 MB	0.1 KB/s	13.3 KB	191:57:42

NNRP readership statistics:

System		Conn	Elapsed
1	162.243.135.4	1	00:00:01
2	cute.monitoring.internet-measurement.com	1	00:00:00
3	sprightly.monitoring.internet-measurement.com	1	00:00:00
4	88-165-5-23.subs.proxad.net	1	00:00:01
5	60.190.248.11	1	00:00:10
6	mail.gegeweb.eu	288	00:01:38
7	165.154.164.92	3	00:00:10
8	194.165.16.73	1	00:00:01
9	fabulous.monitoring.internet-measurement.com	1	00:00:00
10	gutsy.monitoring.internet-measurement.com	1	00:00:00
11	merciful.monitoring.internet-measurement.com	1	00:00:06
12	net-130-25-113-146.cust.vodafonedsl.it	1	00:01:23
TOTAL: 12		301	00:03:33

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	5	00:00:06
2	*.gegeweb.eu	288	00:01:38
3	unresolved	6	00:00:24
4	*.subs.proxad.net	1	00:00:01
5	*.cust.vodafonedsl.it	1	00:01:23
TOTAL: 5		301	00:03:33

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.278	4.197	1.513	00:01:38
net-130-25-113-146.cust.vodafonedsl.it	0.023	0.038	0.000	00:01:23
60.190.248.11	0.014	0.011	0.000	00:00:10
165.154.164.92	0.057	0.064	0.000	00:00:10
merciful.monitoring.internet-measurement.com	0.023	0.019	0.000	00:00:06
194.165.16.73	0.012	0.012	0.000	00:00:01
162.243.135.4	0.029	0.024	0.000	00:00:01
88-165-5-23.subs.proxad.net	0.029	0.029	0.000	00:00:01
sprightly.monitoring.internet-measurement.com	0.042	0.013	0.000	00:00:00
gutsy.monitoring.internet-measurement.com	0.018	0.009	0.000	00:00:00
fabulous.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
TOTAL: 11	4.541	4.428	1.513	00:03:33

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
165.154.164.92	3
162.243.135.4	1
194.165.16.73	1
TOTAL: 3	5

NNRP unrecognized commands (by command):

Command	Count
#026#003#001#001#027#001	1
MGLNDD_45.13.105.36_119	1
À#024	1
O¾“0ÅA½º;ÌÐ#Ç#021óî#036¦¬€#027û#002h Ö„ê©ž’!#010Ç(!#034...	1
#003	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.73	1	1
net-130-25-113-146.cust.vodafonedsl.it	1	1
TOTAL: 2	2	2

Daily Usenet report for news.niel.me

Feb 21 04:15:01 -- Feb 22 04:15:01