Daily Usenet report for news.niel.me: Jun 4 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	2139	27.4%	364.1 KB	29.3%
inn	2043	26.2%	376.2 KB	30.3%
nnrpd	1814	23.3%	208.3 KB	16.8%
innfeed	1799	23.1%	294.1 KB	23.7%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	7797	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	11076	60.1%
Negative hits	5349	29.0%
Do not exist	2011	10.9%
Cache misses	5	0.0%
TOTAL: 4	18441	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.023	0.0%	13	0.000	1.769	6.000
article cancel	00:00:00.000	0.0%	13	0.000	0.000	0.000
article cleanup	00:00:00.033	0.0%	2020	0.000	0.016	0.167
article logging	00:00:00.133	0.0%	2020	0.000	0.066	0.500
article parse	00:00:00.254	0.0%	5620	0.000	0.045	0.217
article write	00:00:00.894	0.0%	1830	0.000	0.489	1.000
artlog/artcncl	00:00:00.000	0.0%	13	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	13	0.000	0.154	1.000
data move	00:00:00.106	0.0%	19861	0.000	0.005	0.050
hisgrep/artcncl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	14	0.000	0.071	1.000
hishave/artcncl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	13	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.266	0.0%	18465	0.000	0.014	0.059
history sync	00:00:00.045	0.0%	340	0.000	0.132	1.000
history write	00:00:00.325	0.0%	2016	0.000	0.161	0.571
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:43.796	100.0%	22324	1155.796	3872.236	37519.688
nntp read	00:00:00.528	0.0%	21630	0.000	0.024	0.120
overview write	00:00:00.530	0.0%	1830	0.000	0.290	2.571
perl filter	00:00:08.199	0.0%	2016	1.750	4.067	17.000
python filter	00:00:00.010	0.0%	2016	0.000	0.005	0.125
site send	00:00:00.909	0.0%	3669	0.000	0.248	0.667
TOTAL: 24:01:02.428	24:00:56.054	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	1899	0.000	0.008	0.167
article prepare	00:00:00.004	0.0%	556	0.000	0.007	0.250
article read	00:00:00.029	0.0%	128	0.000	0.227	4.000
backlog stats	00:00:00.168	0.0%	28763	0.000	0.006	0.041
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.356	0.0%	14599	0.000	0.024	0.098
data write	00:00:00.695	0.0%	13042	0.000	0.053	0.111
idle	23:50:40.698	100.0%	28763	1482.175	2984.414	14647.805
status file	00:00:00.460	0.0%	260	0.000	1.769	5.000
TOTAL: 23:50:49.765	23:50:42.425	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:34.508	25.1%	1456	3.000	23.701	1563.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.099	0.1%	3253	0.000	0.030	0.273
TOTAL: 00:02:17.674	00:00:34.607	25.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	3	1790	962	828	0	53%	23:47:25
2	usenet.pasdenom.info	8	2339	241	2086	12	10%	43:29:22
3	news.nntp4.net	3	1945	239	1663	43	12%	23:45:44
4	i2pn.org	2	2262	167	2091	4	7%	23:56:33
5	news.glou.org	2	1516	106	1345	65	6%	23:51:55
6	news.samoylyk.net	2	2438	100	2333	5	4%	25:14:37
7	nntp.terraraq.uk	2	1988	85	1902	1	4%	23:56:36
8	gegeweb-out.news.tnetconsulting.net	3	348	41	292	15	11%	07:17:33
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		50	14626	1941	12540	145	13%	195:19:45

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.6 KB
2	news.nntp4.net	1.8 MB	6.0 KB	149.1 KB	1.9 MB	92%	7.0 KB
3	usenet.pasdenom.info	618.6 KB	0.0 KB	18.6 KB	637.2 KB	97%	2.5 KB
4	news.samoylyk.net	572.0 KB	0.0 KB	8.0 KB	580.0 KB	98%	5.5 KB
5	i2pn.org	445.9 KB	0.0 KB	8.1 KB	454.0 KB	98%	2.7 KB
6	nntp.terraraq.uk	413.0 KB	0.0 KB	2.4 KB	415.4 KB	99%	4.8 KB
7	news.glou.org	366.2 KB	0.0 KB	184.3 KB	550.5 KB	66%	3.2 KB
8	gegeweb-out.news.tnetconsulting.net	135.3 KB	0.0 KB	75.5 KB	210.7 KB	64%	3.8 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		7.7 MB	6.0 KB	446.0 KB	8.1 MB	94%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 4 04:15:01 - 04:59:59	54	3.0%	0.02	244.0 KB	3.1%	0.09
Jun 4 05:00:00 - 05:59:59	22	1.2%	0.01	128.3 KB	1.7%	0.04
Jun 4 06:00:00 - 06:59:59	71	3.9%	0.02	319.1 KB	4.1%	0.09
Jun 4 07:00:00 - 07:59:59	85	4.7%	0.02	322.7 KB	4.2%	0.09
Jun 4 08:00:00 - 08:59:59	37	2.0%	0.01	123.5 KB	1.6%	0.03
Jun 4 09:00:00 - 09:59:59	46	2.5%	0.01	170.3 KB	2.2%	0.05
Jun 4 10:00:00 - 10:59:59	79	4.3%	0.02	285.7 KB	3.7%	0.08
Jun 4 11:00:00 - 11:59:59	53	2.9%	0.01	197.4 KB	2.5%	0.05
Jun 4 12:00:00 - 12:59:59	69	3.8%	0.02	240.2 KB	3.1%	0.07
Jun 4 13:00:00 - 13:59:59	87	4.8%	0.02	304.5 KB	3.9%	0.08
Jun 4 14:00:00 - 14:59:59	76	4.2%	0.02	445.7 KB	5.7%	0.12
Jun 4 15:00:00 - 15:59:59	86	4.7%	0.02	284.4 KB	3.7%	0.08
Jun 4 16:00:00 - 16:59:59	83	4.5%	0.02	238.9 KB	3.1%	0.07
Jun 4 17:00:00 - 17:59:59	119	6.5%	0.03	413.0 KB	5.3%	0.11
Jun 4 18:00:00 - 18:59:59	118	6.5%	0.03	386.7 KB	5.0%	0.11
Jun 4 19:00:00 - 19:59:59	125	6.8%	0.03	388.5 KB	5.0%	0.11
Jun 4 20:00:00 - 20:59:59	115	6.3%	0.03	542.8 KB	7.0%	0.15
Jun 4 21:00:00 - 21:59:59	75	4.1%	0.02	260.1 KB	3.4%	0.07
Jun 4 22:00:00 - 22:59:59	86	4.7%	0.02	736.3 KB	9.5%	0.20
Jun 4 23:00:00 - 23:59:59	89	4.9%	0.02	374.6 KB	4.8%	0.10
Jun 5 00:00:00 - 00:59:59	66	3.6%	0.02	204.2 KB	2.6%	0.06
Jun 5 01:00:00 - 01:59:59	59	3.2%	0.02	171.7 KB	2.2%	0.05
Jun 5 02:00:00 - 02:59:59	64	3.5%	0.02	773.4 KB	10.0%	0.21
Jun 5 03:00:00 - 03:59:59	57	3.1%	0.02	180.2 KB	2.3%	0.05
Jun 5 04:00:00 - 04:15:02	6	0.3%	0.01	18.4 KB	0.2%	0.02
TOTAL: 24:00:01	1827	100.0%	0.02	7.6 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.glou.org	65	0	65
2	gegeweb-out.news.tnetconsulting.net	54	0	54
3	news.nntp4.net	43	1	42
4	usenet.pasdenom.info	10	0	10
5	i2pn.org	8	0	8
6	news.samoylyk.net	5	2	3
7	nntp.terraraq.uk	5	1	4
TOTAL: 7		190	4	186

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	87
Bad path (news.giganews.com.POSTED)	55
Too many newsgroups	27
Cancels abusifs	7
EMP (md5)	4
HTML Multipart	2
Too many newsgroups (meow)	2
Cancel of non-existing ID <v3np4d$i5ds$1@dont-email.me>	1
Cancel for rejected article	1
TOTAL: 9	186

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2812	69	1632	8	21	2%	23:48:00
2	news.gegeweb.org	930	41	833	1	9	4%	23:22:15
3	news.tnetconsulting.net	3125	14	1807	3	26	0%	23:48:15
4	news.nntp4.net	1702	2	1574	0	12	0%	23:48:09
5	nntp.terraraq.uk	1116	1	1031	0	0	0%	23:30:28
6	news.samoylyk.net	2052	0	1749	0	8	0%	23:47:16
7	news.aioe.org	0	0	0	0	1423	0%	24:00:00
8	news.pasdenom.info	1535	0	1385	0	0	0%	23:42:13
TOTAL: 8		13272	127	10011	12	1499	0%	189:46:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	183.4 KB	106.2 KB	289.6 KB	3.8 KB	23:48:00
2	news.gegeweb.org	160.3 KB	2.0 KB	162.3 KB	3.9 KB	23:22:15
3	news.tnetconsulting.net	35.8 KB	9.8 KB	45.5 KB	2.7 KB	23:48:15
4	nntp.terraraq.uk	2.1 KB	0.0 KB	2.1 KB	2.1 KB	23:30:28
5	news.nntp4.net	1.4 KB	0.0 KB	1.4 KB	0.7 KB	23:48:09
6	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:47:16
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:42:13
TOTAL: 8		383.0 KB	118.0 KB	501.0 KB	3.6 KB	189:46:36

NNRP readership statistics:

System		Conn	Elapsed
1	pretty.monitoring.internet-measurement.com	1	00:00:00
2	2607:ff10:c8:594::e	1	00:00:02
3	107.170.245.12	1	00:00:00
4	91.238.181.71	1	00:00:00
5	mail.gegeweb.eu	289	00:01:55
6	80.66.76.121	1	00:00:00
7	208.149.216.162.bc.googleusercontent.com	1	00:00:00
8	attentive.monitoring.internet-measurement.com	1	00:00:05
9	patient.monitoring.internet-measurement.com	2	00:00:00
10	vibrant.monitoring.internet-measurement.com	2	00:00:08
11	joyful.monitoring.internet-measurement.com	1	00:00:00
12	94.150.216.162.bc.googleusercontent.com	1	00:00:00
13	prudent.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 13		303	00:02:17

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.150.216.162.bc.googleusercontent.com	1	00:00:00
2	*.monitoring.internet-measurement.com	8	00:00:15
3	unresolved	3	00:00:02
4	?	1	00:00:02
5	*.gegeweb.eu	289	00:01:55
6	*.149.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 6		303	00:02:17

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.335	4.052	1.483	00:01:55
vibrant.monitoring.internet-measurement.com	0.052	0.029	0.000	00:00:08
attentive.monitoring.internet-measurement.com	0.050	0.017	0.000	00:00:05
2607:ff10:c8:594::e	0.014	0.010	0.000	00:00:02
prudent.monitoring.internet-measurement.com	0.031	0.023	0.000	00:00:01
107.170.245.12	0.014	0.010	0.000	00:00:00
208.149.216.162.bc.googleusercontent.com	0.026	0.013	0.000	00:00:00
91.238.181.71	0.035	0.015	0.000	00:00:00
94.150.216.162.bc.googleusercontent.com	0.067	0.021	0.000	00:00:00
80.66.76.121	0.039	0.024	0.000	00:00:00
patient.monitoring.internet-measurement.com	0.059	0.033	0.000	00:00:00
joyful.monitoring.internet-measurement.com	0.035	0.023	0.000	00:00:00
TOTAL: 12	4.757	4.270	1.483	00:02:17

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
94.150.216.162.bc.googleusercontent.com	3
208.149.216.162.bc.googleusercontent.com	3
91.238.181.71	1
107.170.245.12	1
TOTAL: 4	8

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.238.181.71	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jun 4 04:15:01 -- Jun 5 04:15:02