Daily Usenet report for news.niel.me: Jan 16 04:15:02

Unknown entries from news log file:

First 50 / 1306 lines (3.8%)

Jan 16 04:15:08 rpi-studio nnrpd[21305]: news.nntp4.net noperm ihave without permission
Jan 16 04:15:38 rpi-studio nnrpd[21309]: news.nntp4.net noperm ihave without permission
Jan 16 04:15:45 rpi-studio nnrpd[21179]: 46.165.242.75 noperm ihave without permission
Jan 16 04:16:08 rpi-studio nnrpd[21340]: news.nntp4.net noperm ihave without permission
Jan 16 04:16:38 rpi-studio nnrpd[21350]: news.nntp4.net noperm ihave without permission
Jan 16 04:17:08 rpi-studio nnrpd[21419]: news.nntp4.net noperm ihave without permission
Jan 16 04:17:38 rpi-studio nnrpd[21421]: news.nntp4.net noperm ihave without permission
Jan 16 04:18:12 rpi-studio nnrpd[21431]: news.nntp4.net noperm ihave without permission
Jan 16 04:18:42 rpi-studio nnrpd[21434]: news.nntp4.net noperm ihave without permission
Jan 16 04:19:12 rpi-studio nnrpd[21440]: news.nntp4.net noperm ihave without permission
Jan 16 04:19:42 rpi-studio nnrpd[21450]: news.nntp4.net noperm ihave without permission
Jan 16 04:20:13 rpi-studio nnrpd[21458]: news.nntp4.net noperm ihave without permission
Jan 16 04:20:43 rpi-studio nnrpd[21461]: news.nntp4.net noperm ihave without permission
Jan 16 04:21:10 rpi-studio nnrpd[21463]: 46.165.242.75 noperm ihave without permission
Jan 16 04:21:13 rpi-studio nnrpd[21468]: news.nntp4.net noperm ihave without permission
Jan 16 04:21:43 rpi-studio nnrpd[21474]: news.nntp4.net noperm ihave without permission
Jan 16 04:22:13 rpi-studio nnrpd[21480]: news.nntp4.net noperm ihave without permission
Jan 16 04:22:44 rpi-studio nnrpd[21482]: news.nntp4.net noperm ihave without permission
Jan 16 04:23:14 rpi-studio nnrpd[21488]: news.nntp4.net noperm ihave without permission
Jan 16 04:23:44 rpi-studio nnrpd[21490]: news.nntp4.net noperm ihave without permission
Jan 16 04:24:14 rpi-studio nnrpd[21639]: news.nntp4.net noperm ihave without permission
Jan 16 04:24:44 rpi-studio nnrpd[21641]: news.nntp4.net noperm ihave without permission
Jan 16 04:25:14 rpi-studio nnrpd[21654]: news.nntp4.net noperm ihave without permission
Jan 16 04:25:44 rpi-studio nnrpd[21657]: news.nntp4.net noperm ihave without permission
Jan 16 04:26:16 rpi-studio nnrpd[21663]: news.nntp4.net noperm ihave without permission
Jan 16 04:26:47 rpi-studio nnrpd[21667]: news.nntp4.net noperm ihave without permission
Jan 16 04:26:50 rpi-studio nnrpd[21662]: 46.165.242.75 noperm ihave without permission
Jan 16 04:27:17 rpi-studio nnrpd[21671]: news.nntp4.net noperm ihave without permission
Jan 16 04:27:47 rpi-studio nnrpd[21675]: news.nntp4.net noperm ihave without permission
Jan 16 04:28:17 rpi-studio nnrpd[21679]: news.nntp4.net noperm ihave without permission
Jan 16 04:28:47 rpi-studio nnrpd[21690]: news.nntp4.net noperm ihave without permission
Jan 16 04:29:17 rpi-studio nnrpd[21695]: news.nntp4.net noperm ihave without permission
Jan 16 04:29:47 rpi-studio nnrpd[21699]: news.nntp4.net noperm ihave without permission
Jan 16 04:30:17 rpi-studio nnrpd[21710]: news.nntp4.net noperm ihave without permission
Jan 16 04:30:47 rpi-studio nnrpd[21714]: news.nntp4.net noperm ihave without permission
Jan 16 04:31:17 rpi-studio nnrpd[21718]: news.nntp4.net noperm ihave without permission
Jan 16 04:31:47 rpi-studio nnrpd[21721]: news.nntp4.net noperm ihave without permission
Jan 16 04:31:53 rpi-studio nnrpd[21722]: 46.165.242.75 noperm ihave without permission
Jan 16 04:32:17 rpi-studio nnrpd[21728]: news.nntp4.net noperm ihave without permission
Jan 16 04:32:47 rpi-studio nnrpd[21738]: news.nntp4.net noperm ihave without permission
Jan 16 04:33:17 rpi-studio nnrpd[21743]: news.nntp4.net noperm ihave without permission
Jan 16 04:33:47 rpi-studio nnrpd[21747]: news.nntp4.net noperm ihave without permission
Jan 16 04:34:17 rpi-studio nnrpd[21892]: news.nntp4.net noperm ihave without permission
Jan 16 04:34:47 rpi-studio nnrpd[21896]: news.nntp4.net noperm ihave without permission
Jan 16 04:35:17 rpi-studio nnrpd[21908]: news.nntp4.net noperm ihave without permission
Jan 16 04:35:47 rpi-studio nnrpd[21911]: news.nntp4.net noperm ihave without permission
Jan 16 04:36:17 rpi-studio nnrpd[21916]: news.nntp4.net noperm ihave without permission
Jan 16 04:36:47 rpi-studio nnrpd[21919]: news.nntp4.net noperm ihave without permission
Jan 16 04:36:58 rpi-studio nnrpd[21920]: 46.165.242.75 noperm ihave without permission
Jan 16 04:37:17 rpi-studio nnrpd[21932]: news.nntp4.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	9368	44.7%	981.6 KB	36.1%
inn	5478	26.1%	877.7 KB	32.2%
innd	3920	18.7%	550.5 KB	20.2%
innfeed	2185	10.4%	310.7 KB	11.4%
nocem	14	0.1%	1.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	20966	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	19384	50.0%
Negative hits	14149	36.5%
Do not exist	4420	11.4%
Cache misses	794	2.0%
TOTAL: 4	38747	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.015	0.0%	8	1.000	1.875	5.000
article cancel	00:00:00.004	0.0%	17	0.000	0.235	1.000
article cleanup	00:00:00.137	0.0%	5427	0.000	0.025	0.222
article logging	00:00:00.417	0.0%	5449	0.000	0.077	0.500
article parse	00:00:01.105	0.0%	14260	0.000	0.077	0.174
article write	00:00:02.721	0.0%	3980	0.000	0.684	14.444
artlog/artcncl	00:00:00.002	0.0%	17	0.000	0.118	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
data move	00:00:00.129	0.0%	34311	0.000	0.004	0.031
hisgrep/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
hisgrep/perl	00:00:00.004	0.0%	13	0.000	0.308	1.000
hishave/artcncl	00:00:00.000	0.0%	17	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.535	0.0%	38829	0.000	0.014	0.125
history sync	00:00:00.067	0.0%	381	0.000	0.176	1.000
history write	00:00:01.014	0.0%	4429	0.000	0.229	0.833
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
idle	23:59:10.911	99.9%	42355	580.251	2038.742	300294.000
nntp read	00:00:01.328	0.0%	38855	0.000	0.034	0.133
overview write	00:00:02.709	0.0%	3980	0.107	0.681	7.667
perl filter	00:00:23.176	0.0%	4429	2.915	5.233	12.200
python filter	00:00:00.017	0.0%	4431	0.000	0.004	0.111
site send	00:00:01.241	0.0%	8039	0.000	0.154	0.688
TOTAL: 24:01:06.531	23:59:45.534	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	4969	0.000	0.010	0.111
article prepare	00:00:00.000	0.0%	388	0.000	0.000	0.000
article read	00:00:00.018	0.0%	93	0.000	0.194	6.000
backlog stats	00:00:00.095	0.0%	41148	0.000	0.002	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.697	0.0%	26290	0.000	0.027	0.108
data write	00:00:01.577	0.0%	22893	0.000	0.069	0.286
idle	23:56:59.599	100.0%	41148	700.536	2095.353	22291.444
status file	00:00:02.613	0.0%	447	1.000	5.846	116.417
TOTAL: 23:57:14.931	23:57:04.648	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:11:10.663	87.7%	5116	6.500	1538.441	613188.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.440	0.0%	13026	0.000	0.034	0.333
readart	00:00:00.066	0.0%	22	0.500	3.000	7.667
TOTAL: 02:29:34.909	02:11:11.169	87.7%	-	-	-	-

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	4
reserve	2
TOTAL: 11	172

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	18	1939	752	963	224	38%	12:21:26
2	188.165.224.178	166	1285	717	523	45	55%	15:38:46
3	nntp.terraraq.uk	17	4175	632	3020	523	15%	29:02:51
4	gegeweb-out.news.tnetconsulting.net	17	4202	609	3378	215	14%	31:36:53
5	feed-out.gegeweb.org	112	1716	490	1124	102	28%	11:41:48
6	usenet.pasdenom.info	12	2975	304	2531	140	10%	25:39:44
7	i2pn.org	10	3908	300	3465	143	7%	25:28:07
8	news.nntp4.net	6	4465	164	4115	186	3%	11:04:29
9	localhost	34	2	2	0	0	100%	00:00:00
10	37.187.2.128	1	156	2	154	0	1%	01:33:42
TOTAL: 10		393	24823	3972	19273	1578	16%	164:07:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	nntp.terraraq.uk	3.4 MB	1.3 MB	2.9 MB	7.6 MB	44%	6.7 KB
2	feed-out.gegeweb.org	2.9 MB	707.6 KB	716.5 KB	4.3 MB	67%	7.5 KB
3	188.165.224.178	2.7 MB	253.0 KB	255.4 KB	3.2 MB	84%	4.3 KB
4	gegeweb-out.news.tnetconsulting.net	2.4 MB	1.4 MB	1.6 MB	5.4 MB	44%	6.7 KB
5	news-out.aioe.org	2.2 MB	538.8 KB	750.8 KB	3.4 MB	63%	3.6 KB
6	news.nntp4.net	1.3 MB	1.2 MB	1.6 MB	4.1 MB	32%	11.9 KB
7	i2pn.org	1.3 MB	720.0 KB	790.6 KB	2.8 MB	46%	6.4 KB
8	usenet.pasdenom.info	826.2 KB	525.0 KB	541.6 KB	1.8 MB	43%	4.3 KB
9	37.187.2.128	7.8 KB	0.0 KB	0.0 KB	7.8 KB	100%	3.9 KB
10	localhost	2.3 KB	0.0 KB	0.0 KB	2.3 KB	100%	1.1 KB
TOTAL: 10		17.1 MB	6.5 MB	9.1 MB	32.7 MB	52%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 16 04:15:02 - 04:59:59	112	2.8%	0.04	289.9 KB	1.6%	0.11
Jan 16 05:00:00 - 05:59:59	109	2.7%	0.03	291.7 KB	1.6%	0.08
Jan 16 06:00:00 - 06:59:59	73	1.8%	0.02	336.4 KB	1.8%	0.09
Jan 16 07:00:00 - 07:59:59	69	1.7%	0.02	251.8 KB	1.4%	0.07
Jan 16 08:00:00 - 08:59:59	115	2.9%	0.03	369.6 KB	2.0%	0.10
Jan 16 09:00:00 - 09:59:59	60	1.5%	0.02	179.8 KB	1.0%	0.05
Jan 16 10:00:00 - 10:59:59	76	1.9%	0.02	235.6 KB	1.3%	0.07
Jan 16 11:00:00 - 11:59:59	131	3.3%	0.04	653.8 KB	3.6%	0.18
Jan 16 12:00:00 - 12:59:59	108	2.7%	0.03	368.1 KB	2.0%	0.10
Jan 16 13:00:00 - 13:59:59	167	4.2%	0.05	500.9 KB	2.7%	0.14
Jan 16 14:00:00 - 14:59:59	116	2.9%	0.03	377.9 KB	2.1%	0.10
Jan 16 15:00:00 - 15:59:59	141	3.5%	0.04	489.2 KB	2.7%	0.14
Jan 16 16:00:00 - 16:59:59	182	4.6%	0.05	533.1 KB	2.9%	0.15
Jan 16 17:00:00 - 17:59:59	204	5.1%	0.06	655.7 KB	3.6%	0.18
Jan 16 18:00:00 - 18:59:59	250	6.3%	0.07	2.1 MB	11.8%	0.60
Jan 16 19:00:00 - 19:59:59	307	7.7%	0.09	819.9 KB	4.5%	0.23
Jan 16 20:00:00 - 20:59:59	248	6.2%	0.07	757.8 KB	4.1%	0.21
Jan 16 21:00:00 - 21:59:59	233	5.8%	0.06	772.8 KB	4.2%	0.21
Jan 16 22:00:00 - 22:59:59	245	6.1%	0.07	924.4 KB	5.0%	0.26
Jan 16 23:00:00 - 23:59:59	244	6.1%	0.07	891.0 KB	4.9%	0.25
Jan 17 00:00:00 - 00:59:59	245	6.1%	0.07	2.5 MB	14.2%	0.72
Jan 17 01:00:00 - 01:59:59	161	4.0%	0.04	1.1 MB	6.2%	0.32
Jan 17 02:00:00 - 02:59:59	152	3.8%	0.04	1.2 MB	6.5%	0.33
Jan 17 03:00:00 - 03:59:59	197	4.9%	0.05	794.4 KB	4.3%	0.22
Jan 17 04:00:00 - 04:15:01	39	1.0%	0.04	729.6 KB	4.0%	0.81
TOTAL: 23:59:59	3984	100.0%	0.05	17.9 MB	100.0%	0.21

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	nntp.terraraq.uk	421	0	167	254
2	news-out.aioe.org	230	0	146	84
3	gegeweb-out.news.tnetconsulting.net	200	0	167	33
4	news.nntp4.net	188	2	123	63
5	usenet.pasdenom.info	144	0	132	12
6	i2pn.org	135	0	123	12
7	feed-out.gegeweb.org	87	0	83	4
8	188.165.224.178	63	0	57	6
9	37.187.2.128	1	0	0	1
TOTAL: 9		1469	2	998	469

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
fr.petites-annonces.informatique.materiel	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	212
Too many newsgroups	90
Bad path (news.giganews.com.POSTED)	67
EMP (md5)	56
User-issued spam cancel	14
Cancels abusifs	4
Cancel of <ss13c3$mg$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <e6fe122ed081ff1d4eeb5c2d1ea97d90@dizum.com>	1
Cancel of non-existing ID <F61FJ.108035$hm7.28190@fx07.iad>	1
Cancel of <ss12pr$sph$1@dont-email.me> without Cancel-Key	1
TOTAL: 10	447

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	nntp.terraraq.uk	8
	188.165.224.178	5
	usenet.pasdenom.info	4
	news-out.aioe.org	3
	news.nntp4.net	1
	feed-out.gegeweb.org	1
	TOTAL: 6	22
TOTAL: 1		22

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4786	62	3609	19	243	1%	23:20:12
2	news.tnetconsulting.net	4482	6	3653	1	486	0%	23:19:09
3	news.aioe.org	1649	3	1593	0	7	0%	22:53:46
4	news.gegeweb.org	2883	3	2851	0	121	0%	23:19:28
5	news.pasdenom.info	3533	2	3482	0	152	0%	23:19:24
6	news.nntp4.net	3918	1	3760	0	240	0%	23:19:28
7	nntp.terraraq.uk	2282	0	2243	0	68	0%	23:17:42
TOTAL: 7		23533	77	21191	20	1317	0%	162:49:09

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	164.9 KB	92.5 KB	257.3 KB	3.2 KB	23:20:12
2	news.tnetconsulting.net	17.0 KB	1.2 KB	18.1 KB	2.6 KB	23:19:09
3	news.aioe.org	16.2 KB	0.0 KB	16.2 KB	5.4 KB	22:53:46
4	news.gegeweb.org	4.8 KB	0.0 KB	4.8 KB	1.6 KB	23:19:28
5	news.nntp4.net	3.9 KB	0.0 KB	3.9 KB	3.9 KB	23:19:28
6	news.pasdenom.info	3.3 KB	0.0 KB	3.3 KB	1.7 KB	23:19:24
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:17:42
TOTAL: 7		210.2 KB	93.6 KB	303.8 KB	3.1 KB	162:49:09

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	192.168.1.90	2	6	136.1 KB	4	0	00:00:47
2	localhost	1	4	4.8 KB	1	0	00:01:14
3	router.lan	3	4	5.3 KB	4	1	00:02:04
4	mbp-gniel.niel.me	7	2	2.7 KB	2	1	00:07:16
TOTAL: 4		13	16	148.8 KB	11	2	02:29:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	131	6	136.1 KB	4	0	02:02:48
2	*.lan	3	4	5.3 KB	4	1	00:02:04
3	?	1	4	4.8 KB	1	0	00:01:14
4	*.niel.me	13	2	2.7 KB	2	1	00:17:14
5	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:03
6	*.gegeweb.eu	255	0	0.0 KB	0	0	00:01:22
7	*.ch1.censys-scanner.com	1	0	0.0 KB	0	0	00:00:01
8	*.nntp4.net	1167	0	0.0 KB	0	0	00:04:43
TOTAL: 8		1572	16	148.8 KB	11	2	02:29:32

NNRP auth users [Top 20]:

User	Conn
mailman	256
gerald	9
TOTAL: 2	265

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
46.165.242.75	3.138	3.293	0.000	01:44:57
192.168.1.200	0.068	0.041	0.000	00:15:18
router.niel.me	0.351	0.220	0.049	00:09:57
mbp-gniel.niel.me	0.269	0.323	0.000	00:07:16
news.nntp4.net	32.866	34.946	0.000	00:04:43
router.lan	0.262	0.124	0.024	00:02:04
sys.gegeweb.eu	7.053	8.368	2.911	00:01:22
localhost	0.033	0.011	0.000	00:01:14
192.168.1.90	0.077	0.089	0.000	00:00:47
37.187.2.128	0.027	0.036	0.000	00:00:41
188.165.224.178	0.020	0.041	0.016	00:00:25
88.165.5.23	0.383	0.397	0.000	00:00:10
143.198.157.115	0.022	0.033	0.000	00:00:10
185.193.88.166	0.038	0.038	0.000	00:00:10
212.90.61.180	0.034	0.045	0.000	00:00:07
battery.census.shodan.io	0.010	0.050	0.000	00:00:03
scanner-09.ch1.censys-scanner.com	0.048	0.029	0.000	00:00:01
TOTAL: 17	44.699	48.084	3.000	02:29:32

Curious NNRP server explorers [Top 20]:

System	Conn
news.nntp4.net	1167
sys.gegeweb.eu	255
46.165.242.75	109
88.165.5.23	13
router.niel.me	6
192.168.1.200	2
185.193.88.166	1
37.187.2.128	1
battery.census.shodan.io	1
scanner-09.ch1.censys-scanner.com	1
143.198.157.115	1
212.90.61.180	1
188.165.224.178	1
TOTAL: 13	1559

NNRP unrecognized commands (by host):

System	Conn
scanner-09.ch1.censys-scanner.com	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
46.165.242.75	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	local	8	50.0%
2	fr	6	37.5%
3	news	1	6.2%
4	grisbi	1	6.2%
TOTAL: 4		16	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	local.test	8
2	fr.misc.bavardages.dinosaures	3
3	fr.usenet.forums.evolution	2
4	fr.comp.ordinosaures	1
5	grisbi.test	1
6	news.announce.newgroups	1
TOTAL: 6		16

Daily Usenet report for news.niel.me

Jan 16 04:15:02 -- Jan 17 04:15:01