Daily Usenet report for news.niel.me: Sep 10 04:15:01

Unknown entries from news log file:

First 50 / 18535 lines (0.3%)

Sep 10 04:45:34 rpi-studio nnrpd[32623]: 151.236.30.71 noperm ihave without permission
Sep 10 04:45:50 rpi-studio nnrpd[32643]: server.samoylyk.net noperm ihave without permission
Sep 10 04:46:06 rpi-studio nnrpd[32651]: server.samoylyk.net noperm ihave without permission
Sep 10 04:46:21 rpi-studio nnrpd[32658]: server.samoylyk.net noperm ihave without permission
Sep 10 04:46:36 rpi-studio nnrpd[32661]: server.samoylyk.net noperm ihave without permission
Sep 10 04:46:52 rpi-studio nnrpd[32666]: server.samoylyk.net noperm ihave without permission
Sep 10 04:47:07 rpi-studio nnrpd[32673]: server.samoylyk.net noperm ihave without permission
Sep 10 04:47:23 rpi-studio nnrpd[32678]: server.samoylyk.net noperm ihave without permission
Sep 10 04:47:38 rpi-studio nnrpd[32683]: server.samoylyk.net noperm ihave without permission
Sep 10 04:47:53 rpi-studio nnrpd[32688]: server.samoylyk.net noperm ihave without permission
Sep 10 04:48:08 rpi-studio nnrpd[32695]: server.samoylyk.net noperm ihave without permission
Sep 10 04:48:23 rpi-studio nnrpd[32700]: server.samoylyk.net noperm ihave without permission
Sep 10 04:48:38 rpi-studio nnrpd[32703]: server.samoylyk.net noperm ihave without permission
Sep 10 04:48:53 rpi-studio nnrpd[32708]: server.samoylyk.net noperm ihave without permission
Sep 10 04:49:09 rpi-studio nnrpd[32713]: server.samoylyk.net noperm ihave without permission
Sep 10 04:49:24 rpi-studio nnrpd[32725]: server.samoylyk.net noperm ihave without permission
Sep 10 04:49:40 rpi-studio nnrpd[32728]: server.samoylyk.net noperm ihave without permission
Sep 10 04:49:55 rpi-studio nnrpd[32733]: server.samoylyk.net noperm ihave without permission
Sep 10 04:50:10 rpi-studio nnrpd[32742]: server.samoylyk.net noperm ihave without permission
Sep 10 04:50:26 rpi-studio nnrpd[32747]: server.samoylyk.net noperm ihave without permission
Sep 10 04:50:41 rpi-studio nnrpd[440]: server.samoylyk.net noperm ihave without permission
Sep 10 04:50:56 rpi-studio nnrpd[443]: server.samoylyk.net noperm ihave without permission
Sep 10 04:51:12 rpi-studio nnrpd[454]: server.samoylyk.net noperm ihave without permission
Sep 10 04:51:27 rpi-studio nnrpd[457]: server.samoylyk.net noperm ihave without permission
Sep 10 04:51:42 rpi-studio nnrpd[463]: server.samoylyk.net noperm ihave without permission
Sep 10 04:51:59 rpi-studio nnrpd[466]: server.samoylyk.net noperm ihave without permission
Sep 10 04:52:14 rpi-studio nnrpd[473]: server.samoylyk.net noperm ihave without permission
Sep 10 04:52:29 rpi-studio nnrpd[476]: server.samoylyk.net noperm ihave without permission
Sep 10 04:52:45 rpi-studio nnrpd[481]: server.samoylyk.net noperm ihave without permission
Sep 10 04:53:01 rpi-studio nnrpd[487]: server.samoylyk.net noperm ihave without permission
Sep 10 04:53:17 rpi-studio nnrpd[492]: server.samoylyk.net noperm ihave without permission
Sep 10 04:53:33 rpi-studio nnrpd[499]: server.samoylyk.net noperm ihave without permission
Sep 10 04:53:48 rpi-studio nnrpd[502]: server.samoylyk.net noperm ihave without permission
Sep 10 04:54:04 rpi-studio nnrpd[515]: server.samoylyk.net noperm ihave without permission
Sep 10 04:54:19 rpi-studio nnrpd[526]: server.samoylyk.net noperm ihave without permission
Sep 10 04:54:34 rpi-studio nnrpd[531]: server.samoylyk.net noperm ihave without permission
Sep 10 04:54:49 rpi-studio nnrpd[534]: server.samoylyk.net noperm ihave without permission
Sep 10 04:55:04 rpi-studio nnrpd[545]: server.samoylyk.net noperm ihave without permission
Sep 10 04:55:19 rpi-studio nnrpd[548]: server.samoylyk.net noperm ihave without permission
Sep 10 04:55:34 rpi-studio nnrpd[554]: server.samoylyk.net noperm ihave without permission
Sep 10 04:55:49 rpi-studio nnrpd[585]: server.samoylyk.net noperm ihave without permission
Sep 10 04:56:04 rpi-studio nnrpd[594]: server.samoylyk.net noperm ihave without permission
Sep 10 04:56:20 rpi-studio nnrpd[598]: server.samoylyk.net noperm ihave without permission
Sep 10 04:56:35 rpi-studio nnrpd[603]: server.samoylyk.net noperm ihave without permission
Sep 10 04:56:51 rpi-studio nnrpd[612]: server.samoylyk.net noperm ihave without permission
Sep 10 04:57:06 rpi-studio nnrpd[618]: server.samoylyk.net noperm ihave without permission
Sep 10 04:57:21 rpi-studio nnrpd[623]: server.samoylyk.net noperm ihave without permission
Sep 10 04:57:37 rpi-studio nnrpd[626]: server.samoylyk.net noperm ihave without permission
Sep 10 04:57:52 rpi-studio nnrpd[633]: server.samoylyk.net noperm ihave without permission
Sep 10 04:58:08 rpi-studio nnrpd[638]: server.samoylyk.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	87553	88.6%	9.2 MB	84.0%
inn	6720	6.8%	1.2 MB	10.7%
innd	2581	2.6%	348.5 KB	3.1%
innfeed	1942	2.0%	253.6 KB	2.3%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	98798	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	26234	61.3%
Do not exist	6668	15.6%
Negative hits	4964	11.6%
Cache misses	4911	11.5%
TOTAL: 4	42777	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.039	0.0%	30	0.500	1.300	1.550
article cancel	00:00:00.005	0.0%	33	0.000	0.152	1.000
article cleanup	00:00:00.098	0.0%	6640	0.000	0.015	0.600
article logging	00:00:00.359	0.0%	6641	0.000	0.054	0.500
article parse	00:00:00.699	0.0%	23481	0.000	0.030	0.200
article write	00:00:02.009	0.0%	5057	0.000	0.397	1.188
artlog/artcncl	00:00:00.000	0.0%	33	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.004	0.0%	30	0.000	0.133	0.500
data move	00:00:00.098	0.0%	28705	0.000	0.003	0.125
hisgrep/artcncl	00:00:00.001	0.0%	32	0.000	0.031	0.050
hisgrep/artcncl/perl	00:00:00.001	0.0%	30	0.000	0.033	0.050
hisgrep/perl	00:00:00.002	0.0%	38	0.000	0.053	0.500
hishave/artcncl	00:00:00.000	0.0%	33	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	30	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.395	0.0%	42714	0.000	0.009	0.250
history sync	00:00:00.058	0.0%	413	0.000	0.140	1.000
history write	00:00:00.712	0.0%	6640	0.000	0.107	0.600
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:59:51.037	99.9%	64117	30.370	1347.397	300240.000
nntp read	00:00:00.626	0.0%	27753	0.000	0.023	0.200
overview write	00:00:00.938	0.0%	5057	0.000	0.185	2.833
perl filter	00:00:19.989	0.0%	6640	1.806	3.010	13.667
python filter	00:00:00.022	0.0%	6642	0.000	0.003	0.167
site send	00:00:01.446	0.0%	10179	0.000	0.142	0.632
TOTAL: 24:01:13.541	24:00:18.538	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.050	0.0%	7312	0.000	0.007	0.143
article prepare	00:00:00.000	0.0%	840	0.000	0.000	0.000
article read	00:00:00.026	0.0%	144	0.000	0.181	3.000
backlog stats	00:00:00.196	0.0%	49920	0.000	0.004	0.019
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.515	0.0%	29267	0.000	0.018	0.065
data write	00:00:01.175	0.0%	29329	0.000	0.040	0.143
idle	23:35:32.486	99.0%	49920	47.721	1701.372	20597.889
status file	00:00:00.974	0.0%	359	0.000	2.713	14.000
TOTAL: 23:50:06.398	23:35:35.422	99.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:53:29.599	76.8%	57026	10.600	56.283	10011.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.216	0.1%	114965	0.000	0.037	1.000
readart	00:00:00.016	0.0%	55	0.152	0.291	0.500
TOTAL: 01:09:39.360	00:53:33.831	76.9%	-	-	-	-

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	2001:41d0:2:abb2::119	7	2611	2605	0	6	99%	21:45:23
2	news.nntp4.net	2	6350	1751	3150	1449	27%	00:15:36
3	news.samoylyk.net	2	1515	176	1315	24	11%	00:03:10
4	news.glou.org	2	5021	64	4950	7	1%	01:13:36
5	localhost	27	2	2	0	0	100%	00:00:00
6	nntp.terraraq.uk	2	0	0	0	0	0%	00:03:00
7	i2pn.org	2	1653	0	1653	0	0%	00:01:00
8	usenet.pasdenom.info	1	0	0	0	0	0%	00:00:00
9	gegeweb-out.news.tnetconsulting.net	2	1661	0	1661	0	0%	00:03:00
TOTAL: 9		47	18813	4598	12729	1486	24%	23:24:45

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	14.0 MB	10.5 MB	24.5 MB	57%	7.8 KB
2	2001:41d0:2:abb2::119	9.2 MB	9.6 KB	9.2 MB	99%	3.6 KB
3	news.samoylyk.net	1.4 MB	116.4 KB	1.5 MB	92%	7.7 KB
4	news.glou.org	219.6 KB	71.1 KB	290.7 KB	75%	4.1 KB
5	localhost	2.8 KB	0.0 KB	2.8 KB	100%	1.4 KB
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0%	0
7	i2pn.org	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
9	gegeweb-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		24.8 MB	10.7 MB	35.5 MB	69%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 10 05:15:01 - 05:59:59	512	10.1%	0.19	2.0 MB	7.3%	0.78
Sep 10 06:00:00 - 06:59:59	104	2.1%	0.03	400.5 KB	1.4%	0.11
Sep 10 07:00:00 - 07:59:59	55	1.1%	0.02	182.5 KB	0.6%	0.05
Sep 10 08:00:00 - 08:59:59	22	0.4%	0.01	92.7 KB	0.3%	0.03
Sep 10 09:00:00 - 09:59:59	32	0.6%	0.01	78.4 KB	0.3%	0.02
Sep 10 10:00:00 - 10:59:59	66	1.3%	0.02	253.8 KB	0.9%	0.07
Sep 10 11:00:00 - 11:59:59	67	1.3%	0.02	179.1 KB	0.6%	0.05
Sep 10 12:00:00 - 12:59:59	83	1.6%	0.02	518.8 KB	1.8%	0.14
Sep 10 13:00:00 - 13:59:59	84	1.7%	0.02	296.9 KB	1.0%	0.08
Sep 10 14:00:00 - 14:59:59	121	2.4%	0.03	617.3 KB	2.2%	0.17
Sep 10 15:00:00 - 15:59:59	77	1.5%	0.02	275.4 KB	1.0%	0.08
Sep 10 16:00:00 - 16:59:59	77	1.5%	0.02	257.9 KB	0.9%	0.07
Sep 10 17:00:00 - 17:59:59	144	2.8%	0.04	457.6 KB	1.6%	0.13
Sep 10 18:00:00 - 18:59:59	174	3.4%	0.05	610.5 KB	2.1%	0.17
Sep 10 19:00:00 - 19:59:59	112	2.2%	0.03	368.4 KB	1.3%	0.10
Sep 10 20:00:00 - 20:59:59	125	2.5%	0.03	443.2 KB	1.6%	0.12
Sep 10 21:00:00 - 21:59:59	145	2.9%	0.04	512.8 KB	1.8%	0.14
Sep 10 22:00:00 - 22:59:59	125	2.5%	0.03	368.8 KB	1.3%	0.10
Sep 10 23:00:00 - 23:59:59	143	2.8%	0.04	421.1 KB	1.5%	0.12
Sep 11 00:00:00 - 00:59:59	139	2.7%	0.04	340.2 KB	1.2%	0.09
Sep 11 01:00:00 - 01:59:59	111	2.2%	0.03	298.6 KB	1.0%	0.08
Sep 11 02:00:00 - 02:59:59	89	1.8%	0.02	331.6 KB	1.2%	0.09
Sep 11 03:00:00 - 03:59:59	2434	48.0%	0.68	18.6 MB	66.6%	5.28
Sep 11 04:00:00 - 04:15:02	27	0.5%	0.03	109.9 KB	0.4%	0.12
TOTAL: 23:00:01	5068	100.0%	0.06	27.8 MB	100.0%	0.34

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	1462	1	1461
2	news.samoylyk.net	44	0	44
3	nntp.terraraq.uk	29	0	29
4	i2pn.org	22	0	22
5	gegeweb-out.news.tnetconsulting.net	18	0	18
6	news.glou.org	7	0	7
7	2001:41d0:2:abb2::119	7	0	7
TOTAL: 7		1589	1	1588

Unwanted newsgroups [Top 20]:

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	762
EMP (phr nph)	301
Bad path (news.giganews.com.POSTED)	145
Too many newsgroups	130
EMP (phn nph)	93
EMP (phl)	69
EMP (phn path)	68
Too many newsgroups (meow)	5
Binary: misplaced binary	4
Cancels abusifs	2
Cancel of non-existing ID <km62v1Fm4dcU1@mid.individual.net>	1
Cancel of non-existing ID <5dd59ca2-5b75-4f2b-9c4a-4d40ad658eafn@googlegroups.com>	1
Cancel of non-existing ID <962e5007-619b-4685-bb36-2ad38a0c6922n@googlegroups.com>	1
hierarchy violation - crosspost outside sdnet	1
Cancel of non-existing ID <bb9780a3-e3f7-45e2-8093-b0ad695449e6n@googlegroups.com>	1
Cancel of non-existing ID <126b2be5-0c58-4a4c-b8ca-82d36101fa77n@googlegroups.com>	1
Cancel of non-existing ID <f058488a-43b3-489b-bd54-469e555cae27n@googlegroups.com>	1
Cancel of non-existing ID <5a3c0f8a-64bc-4e71-af6f-bd1c6ea8f1c9n@googlegroups.com>	1
TOTAL: 18	1587

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	2001:41d0:2:abb2::119	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	6763	86	4972	7	1069	1%	23:47:45
2	i2pn.org	6527	57	4783	3	574	0%	23:47:46
3	news.samoylyk.net	6096	40	4762	2	563	0%	23:57:53
4	news.nntp4.net	3127	7	2961	0	11	0%	23:47:12
5	news.gegeweb.org	2431	2	2425	2	0	0%	02:12:07
6	nntp.terraraq.uk	3634	2	3553	0	1	0%	22:55:41
7	news.pasdenom.info	4480	1	4352	2	42	0%	23:43:25
8	news.aioe.org	0	0	0	0	3634	0%	24:00:00
TOTAL: 8		33058	195	27808	16	5894	0%	168:11:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	187.5 KB	18.8 KB	206.3 KB	2.2 KB	23:47:45
2	i2pn.org	131.3 KB	8.9 KB	140.2 KB	2.3 KB	23:47:46
3	news.samoylyk.net	96.7 KB	7.5 KB	104.2 KB	2.5 KB	23:57:53
4	news.nntp4.net	19.0 KB	0.0 KB	19.0 KB	2.7 KB	23:47:12
5	nntp.terraraq.uk	11.9 KB	0.0 KB	11.9 KB	6.0 KB	22:55:41
6	news.gegeweb.org	2.8 KB	2.2 KB	5.0 KB	1.3 KB	02:12:07
7	news.pasdenom.info	1.6 KB	2.8 KB	4.4 KB	1.5 KB	23:43:25
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		450.8 KB	40.2 KB	491.1 KB	2.3 KB	168:11:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	2a0e:e701:1154:0:28cc:83c7:7eb5:dc9b	3	21	393.4 KB	4	2	0	00:02:37
TOTAL: 1		3	21	393.4 KB	4	2	0	01:09:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	?	266	21	393.4 KB	4	2	00:04:04
2	*.tnetconsulting.net	2583	0	0.0 KB	0	0	00:16:29
3	*.samoylyk.net	5223	0	0.0 KB	0	0	00:20:50
4	*.terraraq.uk	2611	0	0.0 KB	0	0	00:05:23
5	unresolved	91	0	0.0 KB	0	0	00:00:56
6	*.nntp4.net	2635	0	0.0 KB	0	0	00:03:33
7	*.gegeweb.eu	282	0	0.0 KB	0	0	00:00:26
8	*.glou.org	2628	0	0.0 KB	0	0	00:03:49
9	*.org	2592	0	0.0 KB	0	0	00:13:38
TOTAL: 9		18911	21	393.4 KB	4	2	01:09:11

NNRP auth users [Top 20]:

User	Conn
mailman	283
gerald	3
TOTAL: 2	286

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
server.samoylyk.net	109.076	85.250	0.000	00:20:50
tncsrv06.tnetconsulting.net	53.900	42.738	0.000	00:16:29
i2pn.org	53.910	42.943	0.000	00:13:38
nntp.terraraq.uk	54.643	43.686	0.000	00:05:23
news.glou.org	54.984	43.334	0.000	00:03:49
news.nntp4.net	54.750	42.758	0.000	00:03:33
2a0e:e701:1154:0:28cc:83c7:7eb5:dc9b	0.291	0.072	0.014	00:02:37
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	5.337	3.987	0.000	00:00:54
2001:41d0:2:abb2::25	0.011	0.016	0.005	00:00:32
151.236.30.71	0.027	0.020	0.000	00:00:28
mail.gegeweb.eu	4.124	3.494	1.421	00:00:26
104.156.155.25	2.311	0.977	0.000	00:00:16
104.152.52.121	0.014	0.009	0.000	00:00:10
192.241.219.17	0.011	0.011	0.000	00:00:00
104.152.52.231	0.029	0.017	0.000	00:00:00
45.227.254.49	0.027	0.020	0.000	00:00:00
45.227.254.8	0.027	0.000	0.000	00:00:00
TOTAL: 17	393.472	309.332	1.440	01:09:11

Curious NNRP server explorers [Top 20]:

System	Conn
server.samoylyk.net	5223
news.nntp4.net	2635
news.glou.org	2628
nntp.terraraq.uk	2611
i2pn.org	2592
tncsrv06.tnetconsulting.net	2583
mail.gegeweb.eu	282
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	262
104.156.155.25	85
104.152.52.231	1
151.236.30.71	1
45.227.254.49	1
45.227.254.8	1
192.241.219.17	1
2001:41d0:2:abb2::25	1
104.152.52.121	1
TOTAL: 16	18908

NNRP unrecognized commands (by host):

System	Conn
192.241.219.17	1
45.227.254.49	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
#003	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.49	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	grisbi	16	76.2%
2	fr	3	14.3%
3	news	2	9.5%
TOTAL: 3		21	100.0%

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

Newsgroup		Count
1	grisbi.user.french	15
2	fr.usenet.forums.annonces	3
3	news.announce.newgroups	2
4	grisbi.user	1
TOTAL: 4		21

Daily Usenet report for news.niel.me

Sep 10 04:15:01 -- Sep 11 04:15:02