Daily Usenet report for news.niel.me: Oct 6 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2227	27.4%	380.1 KB	30.7%
innd	2175	26.8%	366.4 KB	29.6%
innfeed	1864	22.9%	283.7 KB	22.9%
nnrpd	1858	22.9%	208.7 KB	16.8%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8126	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7672	51.7%
Negative hits	4978	33.6%
Do not exist	2179	14.7%
Cache misses	1	0.0%
TOTAL: 4	14830	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	5	1.000	2.200	6.000
article cancel	00:00:00.001	0.0%	5	0.000	0.200	1.000
article cleanup	00:00:00.058	0.0%	2179	0.000	0.027	0.500
article logging	00:00:00.180	0.0%	2179	0.000	0.083	0.333
article parse	00:00:00.461	0.0%	7101	0.000	0.065	0.267
article write	00:00:00.863	0.0%	1682	0.000	0.513	1.400
artlog/artcncl	00:00:00.001	0.0%	5	0.000	0.200	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.097	0.0%	18803	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.000	0.0%	8	0.000	0.000	0.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.257	0.0%	14820	0.000	0.017	0.067
history sync	00:00:00.040	0.0%	352	0.000	0.114	1.000
history write	00:00:00.369	0.0%	2179	0.000	0.169	0.556
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:41.141	100.0%	20043	1015.611	4282.849	24011.920
nntp read	00:00:00.555	0.0%	19290	0.000	0.029	0.105
overview write	00:00:00.531	0.0%	1682	0.000	0.316	2.250
perl filter	00:00:09.328	0.0%	2179	1.583	4.281	18.000
python filter	00:00:00.014	0.0%	2179	0.000	0.006	0.071
site send	00:00:00.600	0.0%	3426	0.000	0.175	0.458
TOTAL: 23:51:03.049	23:50:54.507	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.019	0.0%	1702	0.000	0.011	0.167
article prepare	00:00:00.000	0.0%	156	0.000	0.000	0.000
article read	00:00:00.009	0.0%	39	0.000	0.231	5.000
backlog stats	00:00:00.116	0.0%	21866	0.000	0.005	0.030
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.293	0.0%	10534	0.000	0.028	0.128
data write	00:00:00.573	0.0%	8865	0.000	0.065	0.148
idle	23:59:32.521	100.0%	21866	1938.194	3950.083	8359.960
status file	00:00:03.086	0.0%	305	0.000	10.118	56.000
TOTAL: 23:59:57.717	23:59:36.617	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:51.544	35.4%	1458	10.800	35.353	10023.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.129	0.1%	3305	0.000	0.039	0.500
TOTAL: 00:02:25.444	00:00:51.673	35.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	2	2195	665	1012	518	30%	24:31:01
2	feed-out.gegeweb.org	3	1672	560	1112	0	33%	23:57:27
3	news.samoylyk.net	3	2950	336	2613	1	11%	37:49:26
4	gegeweb-out.news.tnetconsulting.net	3	1227	83	1142	2	6%	23:52:28
5	news.glou.org	4	1010	68	931	11	6%	19:42:09
6	nntp.terraraq.uk	3	335	4	330	1	1%	09:07:20
7	localhost	25	0	0	0	0	0%	00:00:00
8	usenet.pasdenom.info	8	698	0	698	0	0%	22:41:31
TOTAL: 8		51	10087	1716	7838	533	17%	161:41:22

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	2.8 MB	8.1 MB	10.8 MB	25%	9.4 KB
2	news.samoylyk.net	2.5 MB	1.9 KB	2.5 MB	99%	7.7 KB
3	feed-out.gegeweb.org	2.0 MB	0.0 KB	2.0 MB	100%	3.6 KB
4	gegeweb-out.news.tnetconsulting.net	359.0 KB	5.9 KB	364.9 KB	98%	4.3 KB
5	news.glou.org	241.2 KB	25.8 KB	267.1 KB	90%	3.4 KB
6	nntp.terraraq.uk	90.2 KB	2.4 KB	92.6 KB	97%	18.5 KB
7	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
8	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		8.0 MB	8.1 MB	16.1 MB	49%	7.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 6 04:15:01 - 04:59:59	21	1.2%	0.01	146.6 KB	2.0%	0.05
Oct 6 05:00:00 - 05:59:59	56	3.3%	0.02	297.5 KB	4.0%	0.08
Oct 6 06:00:00 - 06:59:59	31	1.8%	0.01	99.0 KB	1.3%	0.03
Oct 6 07:00:00 - 07:59:59	38	2.3%	0.01	107.9 KB	1.4%	0.03
Oct 6 08:00:00 - 08:59:59	51	3.0%	0.01	188.6 KB	2.5%	0.05
Oct 6 09:00:00 - 09:59:59	45	2.7%	0.01	197.3 KB	2.6%	0.05
Oct 6 10:00:00 - 10:59:59	62	3.7%	0.02	277.2 KB	3.7%	0.08
Oct 6 11:00:00 - 11:59:59	54	3.2%	0.01	250.4 KB	3.3%	0.07
Oct 6 12:00:00 - 12:59:59	68	4.0%	0.02	397.5 KB	5.3%	0.11
Oct 6 13:00:00 - 13:59:59	90	5.3%	0.03	371.0 KB	5.0%	0.10
Oct 6 14:00:00 - 14:59:59	85	5.0%	0.02	785.6 KB	10.5%	0.22
Oct 6 15:00:00 - 15:59:59	55	3.3%	0.02	192.0 KB	2.6%	0.05
Oct 6 16:00:00 - 16:59:59	85	5.0%	0.02	291.9 KB	3.9%	0.08
Oct 6 17:00:00 - 17:59:59	77	4.6%	0.02	279.9 KB	3.7%	0.08
Oct 6 18:00:00 - 18:59:59	75	4.4%	0.02	335.1 KB	4.5%	0.09
Oct 6 19:00:00 - 19:59:59	71	4.2%	0.02	256.3 KB	3.4%	0.07
Oct 6 20:00:00 - 20:59:59	135	8.0%	0.04	593.9 KB	7.9%	0.16
Oct 6 21:00:00 - 21:59:59	111	6.6%	0.03	497.5 KB	6.7%	0.14
Oct 6 22:00:00 - 22:59:59	106	6.3%	0.03	351.0 KB	4.7%	0.10
Oct 6 23:00:00 - 23:59:59	95	5.6%	0.03	401.8 KB	5.4%	0.11
Oct 7 00:00:00 - 00:59:59	67	4.0%	0.02	277.4 KB	3.7%	0.08
Oct 7 01:00:00 - 01:59:59	66	3.9%	0.02	250.4 KB	3.3%	0.07
Oct 7 02:00:00 - 02:59:59	63	3.7%	0.02	317.3 KB	4.2%	0.09
Oct 7 03:00:00 - 03:59:59	63	3.7%	0.02	231.2 KB	3.1%	0.06
Oct 7 04:00:00 - 04:15:01	16	0.9%	0.02	86.8 KB	1.2%	0.10
TOTAL: 24:00:00	1686	100.0%	0.02	7.3 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	511	169	342
2	news.glou.org	14	0	14
3	gegeweb-out.news.tnetconsulting.net	6	0	6
TOTAL: 3		531	169	362

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	93
linux.kernel.git	75
news.test	1
TOTAL: 3	169

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	162
Too many newsgroups	85
Bad path (news.giganews.com.POSTED)	77
EMP (md5)	34
Cancel of non-existing ID <10bvqnp$5akv$1@dont-email.me>	2
Cancels abusifs	1
Cancel of non-existing ID <10c0aro$kcp$2@reader2.panix.com>	1
TOTAL: 7	362

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	2496	13	1648	20	16	0%	23:56:26
2	news.pasdenom.info	1426	3	1147	1	0	0%	23:56:56
3	news.nntp4.net	1191	1	1028	0	0	0%	23:46:23
4	nntp.terraraq.uk	1009	1	975	0	0	0%	23:55:49
5	i2pn.org	0	0	0	0	1683	0%	24:00:00
6	news.samoylyk.net	1684	0	1363	0	0	0%	23:58:42
7	news.gegeweb.org	1142	0	1111	0	0	0%	23:20:58
8	news.aioe.org	0	0	0	0	1303	0%	24:00:00
TOTAL: 8		8948	18	7272	21	3002	0%	190:55:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	31.1 KB	124.5 KB	155.5 KB	4.7 KB	23:56:26
2	nntp.terraraq.uk	10.6 KB	0.0 KB	10.6 KB	10.6 KB	23:55:49
3	news.pasdenom.info	7.5 KB	3.7 KB	11.1 KB	2.8 KB	23:56:56
4	news.nntp4.net	6.4 KB	0.0 KB	6.4 KB	6.4 KB	23:46:23
5	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
6	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:58:42
7	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:20:58
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		55.6 KB	128.1 KB	183.7 KB	4.7 KB	190:55:14

NNRP readership statistics:

System		Conn	Elapsed
1	194.165.16.167	1	00:00:00
2	199.45.154.126	3	00:00:08
3	azpdsgwc39l8.stretchoid.com	2	00:00:11
4	yousef.probe.onyphe.net	1	00:00:05
5	147.185.132.94	1	00:00:03
6	61.211.203.35.bc.googleusercontent.com	2	00:00:02
7	azpdws9lo290.stretchoid.com	2	00:00:11
8	151.150.216.162.bc.googleusercontent.com	2	00:00:05
9	194.165.16.166	1	00:00:00
10	elizabeth.probe.onyphe.net	1	00:00:03
11	198.235.24.203	1	00:00:01
12	63.210.203.35.bc.googleusercontent.com	1	00:00:01
13	mail.gegeweb.eu	288	00:01:34
14	147.185.132.247	1	00:00:00
TOTAL: 14		307	00:02:28

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:34
2	*.probe.onyphe.net	2	00:00:08
3	*.stretchoid.com	4	00:00:22
4	*.150.216.162.bc.googleusercontent.com	2	00:00:05
5	*.210.203.35.bc.googleusercontent.com	1	00:00:01
6	unresolved	8	00:00:14
7	*.211.203.35.bc.googleusercontent.com	2	00:00:02
TOTAL: 7		307	00:02:28

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	5.261	4.591	1.983	00:01:34
azpdws9lo290.stretchoid.com	0.044	0.059	0.000	00:00:11
azpdsgwc39l8.stretchoid.com	0.054	0.046	0.000	00:00:11
199.45.154.126	0.072	0.063	0.000	00:00:08
151.150.216.162.bc.googleusercontent.com	0.156	0.074	0.000	00:00:05
yousef.probe.onyphe.net	0.024	0.020	0.000	00:00:05
147.185.132.94	0.031	0.020	0.000	00:00:03
elizabeth.probe.onyphe.net	0.024	0.028	0.000	00:00:03
61.211.203.35.bc.googleusercontent.com	0.110	0.051	0.000	00:00:02
198.235.24.203	0.026	0.019	0.000	00:00:01
63.210.203.35.bc.googleusercontent.com	0.060	0.019	0.000	00:00:01
147.185.132.247	0.022	0.018	0.000	00:00:00
194.165.16.167	0.014	0.014	0.000	00:00:00
194.165.16.166	0.029	0.022	0.000	00:00:00
TOTAL: 14	5.927	5.044	1.983	00:02:28

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
61.211.203.35.bc.googleusercontent.com	4
147.185.132.247	4
63.210.203.35.bc.googleusercontent.com	4
151.150.216.162.bc.googleusercontent.com	4
yousef.probe.onyphe.net	2
azpdws9lo290.stretchoid.com	1
azpdsgwc39l8.stretchoid.com	1
199.45.154.126	1
194.165.16.166	1
TOTAL: 9	22

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	4
Host: 45.13.105.36:563	4
Accept-Encoding: gzip	2
User-Agent: curl/7.68.0	2
MGLNDD_45.13.105.36_119	2
User-Agent: Hello from Palo Alto Network...	2
Accept: /	2
EHLO www.censys.io	1
#003	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
#026#003#003#001½#001	1
TOTAL: 11	22

NNRP client timeouts [Top 20]:

System	Conn	Peer
61.211.203.35.bc.googleusercontent.com	1	2
199.45.154.126	1	2
151.150.216.162.bc.googleusercontent.com	1	2
194.165.16.166	1	1
TOTAL: 4	4	9

Daily Usenet report for news.niel.me

Oct 6 04:15:01 -- Oct 7 04:15:01