Daily Usenet report for news.niel.me: Jan 8 04:15:01

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jan 8 12:15:27 rpi-studio nnrpd[1241]: news.glou.org noperm ihave without permission
Jan 8 12:15:27 rpi-studio nnrpd[1242]: nntp.terraraq.uk noperm ihave without permission
Jan 8 12:15:27 rpi-studio nnrpd[1244]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Jan 8 12:15:27 rpi-studio nnrpd[1243]: news.nntp4.net noperm ihave without permission
Jan 8 12:15:27 rpi-studio nnrpd[1434]: i2pn.org noperm ihave without permission
Jan 8 12:15:27 rpi-studio nnrpd[1240]: tncsrv06.tnetconsulting.net noperm ihave without permission
Jan 8 18:05:40 rpi-studio nnrpd[1082]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3534	36.8%	681.7 KB	42.7%
innd	2349	24.5%	394.4 KB	24.7%
nnrpd	1910	19.9%	214.3 KB	13.4%
innfeed	1791	18.7%	303.6 KB	19.0%
nocem	5	0.1%	0.3 KB	0.0%
controlchan	5	0.1%	0.4 KB	0.0%
TOTAL: 6	9594	100.0%	1.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22890	64.5%
Negative hits	9163	25.8%
Do not exist	3428	9.7%
Cache misses	0	0.0%
TOTAL: 4	35481	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.025	0.0%	19	1.000	1.316	6.000
article cancel	00:00:00.005	0.0%	20	0.000	0.250	1.000
article cleanup	00:00:00.069	0.0%	3441	0.000	0.020	0.154
article logging	00:00:00.238	0.0%	3457	0.000	0.069	0.250
article parse	00:00:00.637	0.0%	7476	0.000	0.085	0.333
article write	00:00:05.256	0.0%	3258	0.200	1.613	4.077
artlog/artcncl	00:00:00.001	0.0%	20	0.000	0.050	0.500
artlog/artcncl/perl	00:00:00.002	0.0%	19	0.000	0.105	1.000
data move	00:00:00.114	0.0%	37251	0.000	0.003	0.014
hisgrep/artcncl	00:00:00.002	0.0%	19	0.000	0.105	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	19	0.000	0.000	0.000
hisgrep/perl	00:00:00.012	0.0%	29	0.000	0.414	1.500
hishave/artcncl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	19	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:05.253	0.0%	35559	0.000	0.148	0.472
history sync	00:00:00.056	0.0%	405	0.000	0.138	1.333
history write	00:00:01.813	0.0%	3438	0.000	0.527	1.533
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:50:26.119	99.9%	38947	1002.262	2203.664	11776.588
nntp read	00:00:01.075	0.0%	38471	0.000	0.028	0.104
overview write	00:00:02.012	0.0%	3258	0.000	0.618	4.200
perl filter	00:00:12.835	0.0%	3438	2.066	3.733	13.400
python filter	00:00:00.007	0.0%	3438	0.000	0.002	0.067
site send	00:00:01.064	0.0%	6592	0.000	0.161	0.550
TOTAL: 23:51:10.428	23:50:56.595	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	3421	0.000	0.010	0.077
article prepare	00:00:00.000	0.0%	420	0.000	0.000	0.000
article read	00:00:00.025	0.0%	83	0.000	0.301	7.000
backlog stats	00:00:00.127	0.0%	47012	0.000	0.003	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.676	0.0%	28134	0.000	0.024	0.065
data write	00:00:01.365	0.0%	25281	0.000	0.054	0.106
idle	23:39:08.730	100.0%	47012	897.964	1811.213	7066.170
status file	00:00:00.421	0.0%	201	0.000	2.095	7.000
TOTAL: 23:39:14.733	23:39:11.379	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.026	0.0%	15	1.733	1.733	1.733
idle	00:13:29.888	68.6%	1464	11.800	553.202	45303.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.065	0.0%	3542	0.000	0.018	0.250
readart	00:00:00.110	0.0%	41	2.683	2.683	2.683
TOTAL: 00:19:40.787	00:13:30.089	68.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	3500	1458	2008	34	41%	25:37:23
2	i2pn.org	3	2431	862	1486	83	35%	18:19:31
3	news.glou.org	4	3742	283	3438	21	7%	28:14:44
4	feed-out.gegeweb.org	1	1009	157	852	0	15%	08:54:48
5	nntp.terraraq.uk	4	3670	111	3550	9	3%	20:55:50
6	usenet.pasdenom.info	6	1067	68	997	2	6%	12:46:59
7	news.samoylyk.net	3	2458	36	2422	0	1%	13:34:56
8	gegeweb-out.news.tnetconsulting.net	3	3046	25	3021	0	0%	20:34:40
9	news.nntp4.net	3	2604	19	2555	30	0%	13:34:40
10	2001:41d0:2:abb2::119	2	0	0	0	0	0%	00:00:00
11	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 11		57	23527	3019	20329	179	12%	162:33:31

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	5.2 MB	150.8 KB	5.4 MB	97%	3.7 KB
2	i2pn.org	2.8 MB	336.1 KB	3.1 MB	89%	3.4 KB
3	news.glou.org	2.7 MB	268.2 KB	2.9 MB	91%	9.9 KB
4	news.samoylyk.net	1.8 MB	0.0 KB	1.8 MB	100%	52.1 KB
5	nntp.terraraq.uk	700.9 KB	71.5 KB	772.4 KB	90%	6.4 KB
6	feed-out.gegeweb.org	488.4 KB	0.0 KB	488.4 KB	100%	3.1 KB
7	news.nntp4.net	228.9 KB	260.7 KB	489.6 KB	46%	10.0 KB
8	usenet.pasdenom.info	148.5 KB	2.9 KB	151.4 KB	98%	2.2 KB
9	gegeweb-out.news.tnetconsulting.net	109.0 KB	0.0 KB	109.0 KB	100%	4.4 KB
10	2001:41d0:2:abb2::119	0.0 KB	0.0 KB	0.0 KB	0%	0
11	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 11		14.1 MB	1.1 MB	15.2 MB	93%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 8 04:15:01 - 04:59:59	57	1.7%	0.02	280.7 KB	1.9%	0.10
Jan 8 05:00:00 - 05:59:59	100	3.0%	0.03	530.5 KB	3.6%	0.15
Jan 8 06:00:00 - 06:59:59	79	2.4%	0.02	299.1 KB	2.1%	0.08
Jan 8 07:00:00 - 07:59:59	110	3.3%	0.03	482.0 KB	3.3%	0.13
Jan 8 08:00:00 - 08:59:59	58	1.8%	0.02	718.5 KB	4.9%	0.20
Jan 8 09:00:00 - 09:59:59	73	2.2%	0.02	263.0 KB	1.8%	0.07
Jan 8 10:00:00 - 10:59:59	95	2.9%	0.03	303.0 KB	2.1%	0.08
Jan 8 11:00:00 - 11:59:59	141	4.3%	0.04	847.6 KB	5.8%	0.24
Jan 8 12:00:00 - 12:59:59	143	4.3%	0.04	810.6 KB	5.6%	0.23
Jan 8 13:00:00 - 13:59:59	181	5.5%	0.05	612.0 KB	4.2%	0.17
Jan 8 14:00:00 - 14:59:59	125	3.8%	0.03	458.9 KB	3.2%	0.13
Jan 8 15:00:00 - 15:59:59	112	3.4%	0.03	413.6 KB	2.8%	0.11
Jan 8 16:00:00 - 16:59:59	141	4.3%	0.04	583.6 KB	4.0%	0.16
Jan 8 17:00:00 - 17:59:59	178	5.4%	0.05	1.0 MB	7.2%	0.29
Jan 8 18:00:00 - 18:59:59	202	6.1%	0.06	1009.2 KB	6.9%	0.28
Jan 8 19:00:00 - 19:59:59	205	6.2%	0.06	762.5 KB	5.2%	0.21
Jan 8 20:00:00 - 20:59:59	224	6.8%	0.06	801.8 KB	5.5%	0.22
Jan 8 21:00:00 - 21:59:59	176	5.3%	0.05	608.6 KB	4.2%	0.17
Jan 8 22:00:00 - 22:59:59	192	5.8%	0.05	703.1 KB	4.8%	0.20
Jan 8 23:00:00 - 23:59:59	181	5.5%	0.05	887.4 KB	6.1%	0.25
Jan 9 00:00:00 - 00:59:59	159	4.8%	0.04	895.2 KB	6.1%	0.25
Jan 9 01:00:00 - 01:59:59	136	4.1%	0.04	425.8 KB	2.9%	0.12
Jan 9 02:00:00 - 02:59:59	92	2.8%	0.03	367.6 KB	2.5%	0.10
Jan 9 03:00:00 - 03:59:59	110	3.3%	0.03	370.4 KB	2.5%	0.10
Jan 9 04:00:00 - 04:15:02	24	0.7%	0.03	78.5 KB	0.5%	0.09
TOTAL: 24:00:01	3294	100.0%	0.04	14.2 MB	100.0%	0.17

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	100	0	2	98
2	news-out.aioe.org	44	1	0	43
3	news.glou.org	23	0	0	23
4	gegeweb-out.news.tnetconsulting.net	10	1	1	8
5	nntp.terraraq.uk	9	0	0	9
6	news.nntp4.net	7	0	0	7
7	usenet.pasdenom.info	3	0	0	3
8	news.samoylyk.net	3	0	0	3
9	feed-out.gegeweb.org	2	0	0	2
TOTAL: 9		201	2	3	196

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
Bad path (news.giganews.com.POSTED)	94
EMP (md5)	59
Too many newsgroups	15
Cancels abusifs	2
Cancel of non-existing ID <6a33498a-63e4-4466-ad84-aabd438bb3afn@googlegroups.com>	1
Cancel of non-existing ID <de336b57-d7dd-495e-a697-b5e8fefdf25an@googlegroups.com>	1
Cancel of non-existing ID <fd45497e-3099-4f08-8ed3-c73a8b103d19n@googlegroups.com>	1
Cancel of non-existing ID <9149f5b1-b6e7-4f76-a2e2-a40d02d47bfen@googlegroups.com>	1
Cancel of non-existing ID <141b2a19-bc93-42a6-a1c8-1c36b5d9c8e8n@googlegroups.com>	1
Cancel of non-existing ID <d37dee26-3943-46af-9df1-8d423d54d12cn@googlegroups.com>	1
Cancel of non-existing ID <k20vhuFnjvgU1@mid.individual.net>	1
Cancel of non-existing ID <16a6649f-4482-4c6f-b6f8-744151cb426cn@googlegroups.com>	1
Cancel of non-existing ID <cc7403d2-0d27-4abd-977d-cb055261877en@googlegroups.com>	1
TOTAL: 13	179

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	12
	feed-out.gegeweb.org	2
	news.samoylyk.net	2
	i2pn.org	1
	TOTAL: 4	17
TOTAL: 1		17

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	3883	32	3191	1	39	0%	23:42:14
2	i2pn.org	4054	29	2224	2	74	0%	23:45:00
3	news.gegeweb.org	2975	6	2890	11	2	0%	23:42:14
4	news.tnetconsulting.net	5079	4	3221	2	68	0%	23:42:14
5	nntp.terraraq.uk	2145	3	1941	6	1	0%	23:32:08
6	news.aioe.org	1504	2	1389	1	3	0%	23:37:19
7	news.samoylyk.net	3654	1	3193	4	5	0%	23:40:13
8	news.pasdenom.info	2663	1	2513	0	4	0%	23:43:00
TOTAL: 8		25957	78	20562	27	196	0%	189:24:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	372.2 KB	11.6 KB	383.8 KB	11.6 KB	23:42:14
2	i2pn.org	88.9 KB	10.5 KB	99.4 KB	3.2 KB	23:45:00
3	news.pasdenom.info	31.3 KB	0.0 KB	31.3 KB	31.3 KB	23:43:00
4	news.aioe.org	24.3 KB	1.5 KB	25.8 KB	8.6 KB	23:37:19
5	news.gegeweb.org	22.5 KB	43.6 KB	66.1 KB	3.9 KB	23:42:14
6	nntp.terraraq.uk	16.2 KB	385.3 KB	401.6 KB	44.6 KB	23:32:08
7	news.tnetconsulting.net	15.1 KB	10.5 KB	25.6 KB	4.3 KB	23:42:14
8	news.samoylyk.net	3.2 KB	16.2 KB	19.3 KB	3.9 KB	23:40:13
TOTAL: 8		573.7 KB	479.2 KB	1.0 MB	10.0 KB	189:24:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	2a0e:e701:1154:0:e96e:24cd:a965:fc1c	1	41	89.6 KB	2	0	0	00:04:48
TOTAL: 1		1	41	89.6 KB	2	0	0	00:19:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	?	3	41	89.6 KB	2	00:07:33
2	*.gegeweb.eu	288	0	0.0 KB	0	00:01:34
3	*.tnetconsulting.net	1	0	0.0 KB	0	00:02:16
4	*.org	1	0	0.0 KB	0	00:01:13
5	*.glou.org	1	0	0.0 KB	0	00:02:16
6	*.terraraq.uk	1	0	0.0 KB	0	00:02:16
7	*.ip.linodeusercontent.com	10	0	0.0 KB	0	00:00:00
8	*.nntp4.net	1	0	0.0 KB	0	00:02:16
9	unresolved	2	0	0.0 KB	0	00:00:11
TOTAL: 9		308	41	89.6 KB	2	00:19:40

NNRP auth users [Top 20]:

User	Conn
mailman	288
gerald	1
TOTAL: 2	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
2a0e:e701:1154:0:e96e:24cd:a965:fc1c	0.094	0.080	0.013	00:04:48
2a03:f80:ed31:8c5a::1	0.045	0.054	0.000	00:02:45
tncsrv06.tnetconsulting.net	0.014	0.014	0.000	00:02:16
news.glou.org	0.032	0.021	0.000	00:02:16
nntp.terraraq.uk	0.021	0.032	0.000	00:02:16
news.nntp4.net	0.030	0.010	0.000	00:02:16
mail.gegeweb.eu	3.874	4.366	1.865	00:01:34
i2pn.org	0.015	0.015	0.000	00:01:13
146.190.125.99	0.032	0.021	0.000	00:00:11
107.170.246.18	0.037	0.009	0.000	00:00:00
170-187-195-167.ip.linodeusercontent.com	0.226	0.167	0.000	00:00:00
TOTAL: 11	4.420	4.789	1.878	00:19:40

Curious NNRP server explorers [Top 20]:

System	Conn
mail.gegeweb.eu	288
170-187-195-167.ip.linodeusercontent.com	10
2a03:f80:ed31:8c5a::1	2
news.glou.org	1
i2pn.org	1
tncsrv06.tnetconsulting.net	1
nntp.terraraq.uk	1
146.190.125.99	1
news.nntp4.net	1
107.170.246.18	1
TOTAL: 10	307

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
107.170.246.18	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	40	97.6%
2	grisbi	1	2.4%
TOTAL: 2		41	100.0%

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

Newsgroup		Count
1	fr.misc.bavardages.linux	40
2	grisbi.test	1
TOTAL: 2		41

Daily Usenet report for news.niel.me

Jan 8 04:15:01 -- Jan 9 04:15:02