Daily Usenet report for news.niel.me: Oct 8 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2423	29.7%	450.8 KB	34.1%
innd	2084	25.5%	360.1 KB	27.2%
nnrpd	1867	22.9%	201.1 KB	15.2%
innfeed	1788	21.9%	309.9 KB	23.4%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8164	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13130	59.9%
Negative hits	6367	29.1%
Do not exist	2412	11.0%
Cache misses	4	0.0%
TOTAL: 4	21913	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.004	0.0%	5	0.000	0.800	1.000
article cancel	00:00:00.001	0.0%	5	0.000	0.200	1.000
article cleanup	00:00:00.066	0.0%	2414	0.000	0.027	0.333
article logging	00:00:00.177	0.0%	2414	0.000	0.073	0.417
article parse	00:00:00.333	0.0%	5640	0.000	0.059	0.235
article write	00:00:01.172	0.0%	2249	0.000	0.521	1.125
artlog/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.123	0.0%	22649	0.000	0.005	0.032
hisgrep/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.003	0.0%	33	0.000	0.091	1.000
hishave/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.316	0.0%	21926	0.000	0.014	0.073
history sync	00:00:00.055	0.0%	395	0.000	0.139	1.000
history write	00:00:00.406	0.0%	2413	0.000	0.168	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:50.310	100.0%	24597	1025.950	3514.669	17673.441
nntp read	00:00:00.695	0.0%	23839	0.000	0.029	0.107
overview write	00:00:00.727	0.0%	2249	0.000	0.323	2.917
perl filter	00:00:09.173	0.0%	2413	0.737	3.801	10.000
python filter	00:00:00.014	0.0%	2413	0.000	0.006	0.091
site send	00:00:00.885	0.0%	4543	0.000	0.195	0.600
TOTAL: 24:01:12.025	24:01:04.460	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	2562	0.000	0.009	0.200
article prepare	00:00:00.003	0.0%	556	0.000	0.005	0.250
article read	00:00:00.032	0.0%	133	0.000	0.241	6.000
backlog stats	00:00:00.174	0.0%	32023	0.000	0.005	0.044
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.417	0.0%	16901	0.000	0.025	0.103
data write	00:00:00.862	0.0%	15206	0.000	0.057	0.140
idle	23:50:58.643	100.0%	32023	845.110	2681.156	6898.427
status file	00:00:00.438	0.0%	215	0.000	2.037	6.000
TOTAL: 23:51:05.369	23:51:00.591	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:15.236	20.1%	1455	7.500	51.709	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.120	0.0%	3255	0.000	0.037	0.500
TOTAL: 00:06:14.878	00:01:15.356	20.1%	-	-	-	-

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	feed-out.gegeweb.org	2	2233	947	1286	0	42%	23:59:58
2	news.samoylyk.net	1	2285	335	1950	0	14%	22:39:51
3	gegeweb-out.news.tnetconsulting.net	2	2247	295	1912	40	13%	27:55:38
4	news.nntp4.net	1	2114	294	1714	106	13%	21:06:31
5	news.glou.org	3	1546	81	1460	5	5%	23:58:06
6	i2pn.org	1	597	51	546	0	8%	07:49:08
7	usenet.pasdenom.info	4	167	3	164	0	1%	06:46:12
8	nntp.terraraq.uk	1	77	1	76	0	1%	02:04:04
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	11266	2007	9108	151	17%	136:19:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.4 MB	0.0 KB	0.0 KB	3.4 MB	100%	3.7 KB
2	news.samoylyk.net	2.2 MB	0.0 KB	0.0 KB	2.2 MB	100%	6.8 KB
3	news.nntp4.net	1.2 MB	0.0 KB	331.9 KB	1.5 MB	78%	3.8 KB
4	gegeweb-out.news.tnetconsulting.net	1.2 MB	4.1 KB	159.7 KB	1.3 MB	87%	4.0 KB
5	news.glou.org	358.2 KB	0.0 KB	30.7 KB	388.9 KB	92%	4.5 KB
6	i2pn.org	151.4 KB	0.0 KB	0.0 KB	151.4 KB	100%	3.0 KB
7	usenet.pasdenom.info	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.9 KB
8	nntp.terraraq.uk	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.4 MB	4.1 KB	522.3 KB	8.9 MB	94%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 8 04:15:02 - 04:59:59	37	1.6%	0.01	158.5 KB	1.7%	0.06
Oct 8 05:00:00 - 05:59:59	46	2.0%	0.01	143.7 KB	1.6%	0.04
Oct 8 06:00:00 - 06:59:59	40	1.8%	0.01	141.8 KB	1.5%	0.04
Oct 8 07:00:00 - 07:59:59	200	8.9%	0.06	515.8 KB	5.6%	0.14
Oct 8 08:00:00 - 08:59:59	34	1.5%	0.01	106.3 KB	1.1%	0.03
Oct 8 09:00:00 - 09:59:59	59	2.6%	0.02	290.5 KB	3.1%	0.08
Oct 8 10:00:00 - 10:59:59	101	4.5%	0.03	403.5 KB	4.4%	0.11
Oct 8 11:00:00 - 11:59:59	74	3.3%	0.02	372.2 KB	4.0%	0.10
Oct 8 12:00:00 - 12:59:59	80	3.6%	0.02	222.0 KB	2.4%	0.06
Oct 8 13:00:00 - 13:59:59	102	4.5%	0.03	594.4 KB	6.4%	0.17
Oct 8 14:00:00 - 14:59:59	75	3.3%	0.02	278.8 KB	3.0%	0.08
Oct 8 15:00:00 - 15:59:59	100	4.4%	0.03	436.4 KB	4.7%	0.12
Oct 8 16:00:00 - 16:59:59	153	6.8%	0.04	598.4 KB	6.5%	0.17
Oct 8 17:00:00 - 17:59:59	118	5.2%	0.03	419.2 KB	4.5%	0.12
Oct 8 18:00:00 - 18:59:59	157	7.0%	0.04	611.2 KB	6.6%	0.17
Oct 8 19:00:00 - 19:59:59	130	5.8%	0.04	653.4 KB	7.0%	0.18
Oct 8 20:00:00 - 20:59:59	106	4.7%	0.03	843.4 KB	9.1%	0.23
Oct 8 21:00:00 - 21:59:59	138	6.1%	0.04	522.9 KB	5.6%	0.15
Oct 8 22:00:00 - 22:59:59	112	5.0%	0.03	416.3 KB	4.5%	0.12
Oct 8 23:00:00 - 23:59:59	117	5.2%	0.03	492.2 KB	5.3%	0.14
Oct 9 00:00:00 - 00:59:59	67	3.0%	0.02	334.7 KB	3.6%	0.09
Oct 9 01:00:00 - 01:59:59	71	3.2%	0.02	262.1 KB	2.8%	0.07
Oct 9 02:00:00 - 02:59:59	61	2.7%	0.02	193.3 KB	2.1%	0.05
Oct 9 03:00:00 - 03:59:59	63	2.8%	0.02	236.0 KB	2.5%	0.07
Oct 9 04:00:00 - 04:15:02	7	0.3%	0.01	21.8 KB	0.2%	0.02
TOTAL: 24:00:00	2248	100.0%	0.03	9.1 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	122	0	0	122
2	gegeweb-out.news.tnetconsulting.net	28	1	1	26
3	usenet.pasdenom.info	10	0	0	10
4	news.glou.org	5	0	0	5
TOTAL: 4		165	1	1	163

Unwanted newsgroups [Top 20]:

Newsgroup	Count
soc.culture.iran	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	100
Bad path (news.giganews.com.POSTED)	29
Too many newsgroups (meow)	3
Cancels abusifs	3
Cancel of <0067c8ea4c9643171269eab4f973bd9327916d14@i2pn2.org> without Cancel-Key	1
Cancel of <85fe3c6a48e899b24595ac4b70a1a2718d87c144@i2pn2.org> without Cancel-Key	1
Cancel of <747b20004199151a1a7a6d9f36d74e180c6b9c0f@i2pn2.org> without Cancel-Key	1
Cancel of <9afe23eca1d0ef585289816a82123ab3bed7fa92@i2pn2.org> without Cancel-Key	1
Cancel of <625c764c480dbb0007fb7c34e1b1202c68fe90c7@i2pn2.org> without Cancel-Key	1
Cancel of <75f0a2b8b21625fcff86a00f36a66d1a15572294@i2pn2.org> without Cancel-Key	1
Cancel of <7578ff4d0bdc0708984352aed67a8f123eca46aa@i2pn2.org> without Cancel-Key	1
Cancel of <2560d709e06efdd1f357c9edc8ce9567c7d64977@i2pn2.org> without Cancel-Key	1
Cancel of <3c29e39e1e440c8a748e31371ab98c56d7c4c7c5@i2pn2.org> without Cancel-Key	1
Cancel of <4f4cef51fd35cb685b573d032ed10e9c6f425043@i2pn2.org> without Cancel-Key	1
Cancel of <5638e8c4aa04ac31e7a7b82668f7f2c18f2807a1@i2pn2.org> without Cancel-Key	1
Cancel of <e5ce77d6a11d800c41fa61d3fde20a5a31428980@i2pn2.org> without Cancel-Key	1
Cancel of <5a18956c000016583e0767adf97432d27a47a386@i2pn2.org> without Cancel-Key	1
Cancel of <8fdaa5044cf7915e84775732b8f3f9deb1b40966@i2pn2.org> without Cancel-Key	1
Cancel of <d9350f46de1b508b85d8ab2d9f12a13642f93c71@i2pn2.org> without Cancel-Key	1
Cancel of <8ed2e0772c8290ccc5c13e9d0951bce678c09592@i2pn2.org> without Cancel-Key	1
TOTAL: 32	163

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3398	81	1968	8	140	2%	23:59:12
2	news.gegeweb.org	1421	32	1280	0	0	2%	23:46:20
3	news.samoylyk.net	2278	5	1884	3	68	0%	23:59:56
4	news.tnetconsulting.net	3633	4	2241	0	46	0%	23:59:56
5	news.pasdenom.info	1736	1	1533	2	0	0%	23:59:44
6	news.nntp4.net	2123	1	1906	1	62	0%	23:59:56
7	news.aioe.org	0	0	0	0	1857	0%	23:59:59
8	nntp.terraraq.uk	1175	0	1142	1	0	0%	23:40:03
TOTAL: 8		15764	124	11954	15	2173	0%	191:25:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	337.1 KB	36.9 KB	374.1 KB	4.2 KB	23:59:12
2	news.gegeweb.org	121.3 KB	0.0 KB	121.3 KB	3.8 KB	23:46:20
3	news.tnetconsulting.net	17.4 KB	0.0 KB	17.4 KB	4.3 KB	23:59:56
4	news.samoylyk.net	15.9 KB	10.0 KB	25.9 KB	3.2 KB	23:59:56
5	news.pasdenom.info	2.3 KB	7.7 KB	9.9 KB	3.3 KB	23:59:44
6	news.nntp4.net	2.3 KB	4.4 KB	6.6 KB	3.3 KB	23:59:56
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
8	nntp.terraraq.uk	0.0 KB	4.4 KB	4.4 KB	4.4 KB	23:40:03
TOTAL: 8		496.2 KB	63.3 KB	559.5 KB	4.0 KB	191:25:06

NNRP readership statistics:

System		Conn	Elapsed
1	206.168.34.200	1	00:00:31
2	206.168.34.197	1	00:00:34
3	101.36.107.65	3	00:00:11
4	198.235.24.183	1	00:00:10
5	chan.probe.onyphe.net	1	00:00:11
6	stirring.monitoring.internet-measurement.com	1	00:00:02
7	server-0-3.survey.inspici.com	1	00:00:03
8	marvellous.monitoring.internet-measurement.com	1	00:00:00
9	ingenious.monitoring.internet-measurement.com	1	00:00:06
10	206.168.34.196	2	00:01:46
11	185.242.226.41	1	00:00:01
12	80.66.76.134	1	00:00:00
13	prepared.monitoring.internet-measurement.com	1	00:00:00
14	219.211.203.35.bc.googleusercontent.com	1	00:00:01
15	mail.gegeweb.eu	288	00:01:36
16	205.210.31.131	1	00:00:10
17	147.185.133.80	1	00:00:12
18	91.238.181.23	1	00:00:00
19	splendid.monitoring.internet-measurement.com	1	00:00:00
20	206.168.34.201	1	00:00:31
TOTAL: 20		310	00:06:14

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	14	00:04:11
2	*.probe.onyphe.net	1	00:00:11
3	*.survey.inspici.com	1	00:00:03
4	*.gegeweb.eu	288	00:01:36
5	*.monitoring.internet-measurement.com	5	00:00:09
6	*.211.203.35.bc.googleusercontent.com	1	00:00:01
TOTAL: 6		310	00:06:14

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
206.168.34.196	0.075	0.046	0.000	00:01:46
mail.gegeweb.eu	5.134	4.380	1.724	00:01:36
206.168.34.197	0.036	0.027	0.000	00:00:34
206.168.34.200	0.025	0.007	0.000	00:00:31
206.168.34.201	0.050	0.016	0.000	00:00:31
147.185.133.80	0.059	0.019	0.000	00:00:12
101.36.107.65	0.067	0.052	0.000	00:00:11
chan.probe.onyphe.net	0.031	0.023	0.000	00:00:11
205.210.31.131	0.025	0.025	0.000	00:00:10
198.235.24.183	0.033	0.018	0.000	00:00:10
ingenious.monitoring.internet-measurement.com	0.058	0.027	0.000	00:00:06
server-0-3.survey.inspici.com	0.026	0.026	0.000	00:00:03
stirring.monitoring.internet-measurement.com	0.021	0.030	0.000	00:00:02
219.211.203.35.bc.googleusercontent.com	0.071	0.019	0.000	00:00:01
185.242.226.41	0.027	0.018	0.000	00:00:01
91.238.181.23	0.037	0.025	0.000	00:00:00
80.66.76.134	0.029	0.021	0.000	00:00:00
prepared.monitoring.internet-measurement.com	0.044	0.020	0.000	00:00:00
splendid.monitoring.internet-measurement.com	0.019	0.009	0.000	00:00:00
marvellous.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
TOTAL: 20	5.883	4.820	1.724	00:06:14

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
chan.probe.onyphe.net	6
185.242.226.41	5
219.211.203.35.bc.googleusercontent.com	4
147.185.133.80	4
101.36.107.65	2
80.66.76.134	1
TOTAL: 6	22

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	4
Accept-Encoding: gzip	3
Host: 45.13.105.36:563	2
Host: 45.13.105.36:119	2
User-Agent: Expanse, a Palo Alto Network...	2
Accept: text/html,application/xhtml+xml,...	1
#026#003#001#001#027#001	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
User-Agent: Mozilla/5.0 (X11; Ubuntu; Li...	1
À#024	1
#003	1
Accept: /	1
Accept-Language: en-US,en;q=0.5	1
Connection: close	1
TOTAL: 14	22

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.134	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Oct 8 04:15:02 -- Oct 9 04:15:02