Daily Usenet report for news.niel.me: Nov 17 04:15:01

Unknown entries from news log file:

First 50 / 2858 lines (1.7%)

Nov 17 04:15:14 rpi-studio nnrpd[6718]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:15:44 rpi-studio nnrpd[6722]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:16:14 rpi-studio nnrpd[6753]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:16:44 rpi-studio nnrpd[6815]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:17:14 rpi-studio nnrpd[6830]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:17:44 rpi-studio nnrpd[6832]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:18:14 rpi-studio nnrpd[6842]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:18:44 rpi-studio nnrpd[6844]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:19:15 rpi-studio nnrpd[6873]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:19:45 rpi-studio nnrpd[6888]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:20:15 rpi-studio nnrpd[6896]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:20:46 rpi-studio nnrpd[7039]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:21:16 rpi-studio nnrpd[7046]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:21:49 rpi-studio nnrpd[7048]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:22:19 rpi-studio nnrpd[7061]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:22:50 rpi-studio nnrpd[7065]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:23:20 rpi-studio nnrpd[7069]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:23:50 rpi-studio nnrpd[7074]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:24:20 rpi-studio nnrpd[7090]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:24:50 rpi-studio nnrpd[7094]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:25:20 rpi-studio nnrpd[7103]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:25:50 rpi-studio nnrpd[7117]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:26:20 rpi-studio nnrpd[7122]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:26:50 rpi-studio nnrpd[7125]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:27:21 rpi-studio nnrpd[7132]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:27:52 rpi-studio nnrpd[7137]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:28:22 rpi-studio nnrpd[7148]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:28:53 rpi-studio nnrpd[7153]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:29:23 rpi-studio nnrpd[7170]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:29:53 rpi-studio nnrpd[7173]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:30:23 rpi-studio nnrpd[7183]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:30:53 rpi-studio nnrpd[7329]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:31:23 rpi-studio nnrpd[7334]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:31:54 rpi-studio nnrpd[7337]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:32:24 rpi-studio nnrpd[7343]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:32:54 rpi-studio nnrpd[7347]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:33:24 rpi-studio nnrpd[7364]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:33:54 rpi-studio nnrpd[7368]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:34:25 rpi-studio nnrpd[7389]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:34:55 rpi-studio nnrpd[7392]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:35:25 rpi-studio nnrpd[7404]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:35:55 rpi-studio nnrpd[7407]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:36:25 rpi-studio nnrpd[7411]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:36:56 rpi-studio nnrpd[7415]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:37:26 rpi-studio nnrpd[7419]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:37:56 rpi-studio nnrpd[7422]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:38:26 rpi-studio nnrpd[7428]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:38:57 rpi-studio nnrpd[7431]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:39:27 rpi-studio nnrpd[7461]: mx.terraraq.uk noperm ihave without permission
Nov 17 04:39:57 rpi-studio nnrpd[7464]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	13465	63.0%	1.3 MB	49.7%
inn	3780	17.7%	713.4 KB	26.2%
innd	2408	11.3%	383.6 KB	14.1%
innfeed	1722	8.1%	273.1 KB	10.0%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	21377	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	21258	62.0%
Negative hits	9327	27.2%
Do not exist	3726	10.9%
Cache misses	2	0.0%
TOTAL: 4	34313	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.616	0.0%	10	1.000	61.600	304.000
article cancel	00:00:00.002	0.0%	10	0.000	0.200	1.000
article cleanup	00:00:00.128	0.0%	3744	0.000	0.034	0.200
article logging	00:00:00.271	0.0%	3760	0.000	0.072	0.400
article parse	00:00:00.617	0.0%	7444	0.000	0.083	0.286
article write	00:00:02.237	0.0%	3289	0.250	0.680	5.423
artlog/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:00:00.123	0.0%	37529	0.000	0.003	0.021
hisgrep/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	10	0.000	0.100	1.000
hishave/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	10	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.482	0.0%	34458	0.000	0.014	0.053
history sync	00:00:00.059	0.0%	422	0.000	0.140	1.000
history write	00:00:00.819	0.0%	3744	0.000	0.219	0.909
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:09.756	99.9%	43407	809.208	1990.687	6061.414
nntp read	00:00:01.361	0.0%	37645	0.006	0.036	0.132
overview write	00:00:02.881	0.0%	3289	0.111	0.876	12.844
perl filter	00:00:23.059	0.0%	3744	4.088	6.159	21.590
python filter	00:00:00.009	0.0%	3744	0.000	0.002	0.105
site send	00:00:01.618	0.0%	6651	0.000	0.243	0.571
TOTAL: 24:01:11.579	24:00:44.039	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.037	0.0%	3294	0.000	0.011	0.125
article prepare	00:00:00.001	0.0%	396	0.000	0.003	0.250
article read	00:00:00.017	0.0%	66	0.000	0.258	8.000
backlog stats	00:00:00.099	0.0%	52813	0.000	0.002	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.700	0.0%	31060	0.000	0.023	0.062
data write	00:00:01.821	0.0%	27817	0.000	0.065	0.127
idle	23:59:48.472	100.0%	52813	894.589	1635.743	6743.910
status file	00:00:00.471	0.0%	185	1.000	2.546	6.000
TOTAL: 23:59:54.854	23:59:51.618	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:19.431	46.0%	10034	11.600	25.855	1558.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.561	0.1%	20530	0.000	0.027	1.000
TOTAL: 00:09:23.557	00:04:19.992	46.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	news-out.aioe.org	1	3299	1349	1899	51	40%	23:09:56
2	i2pn.org	2	3511	863	2328	320	24%	23:10:40
3	news.glou.org	1	3165	654	2471	40	20%	23:59:58
4	usenet.pasdenom.info	11	3027	159	2867	1	5%	28:41:33
5	feed-out.gegeweb.org	3	2138	133	2004	1	6%	23:58:29
6	news.nntp4.net	2	3809	39	3721	49	1%	25:36:01
7	gegeweb-out.news.tnetconsulting.net	2	3388	38	3350	0	1%	23:59:56
8	news.samoylyk.net	1	256	2	254	0	0%	02:06:37
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		48	22593	3237	18894	462	14%	174:43:10

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	4.4 MB	169.5 KB	4.5 MB	96%	3.3 KB
2	news.glou.org	3.8 MB	249.0 KB	4.0 MB	93%	5.9 KB
3	i2pn.org	2.9 MB	809.0 KB	3.7 MB	78%	3.2 KB
4	news.nntp4.net	560.3 KB	90.3 KB	650.6 KB	86%	7.4 KB
5	feed-out.gegeweb.org	479.9 KB	1.1 KB	481.0 KB	99%	3.6 KB
6	usenet.pasdenom.info	391.9 KB	1.3 KB	393.2 KB	99%	2.5 KB
7	gegeweb-out.news.tnetconsulting.net	246.4 KB	0.0 KB	246.4 KB	100%	6.5 KB
8	news.samoylyk.net	87.5 KB	0.0 KB	87.5 KB	100%	43.7 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		12.7 MB	1.3 MB	14.0 MB	90%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:01 - 04:59:59	33	1.0%	0.01	183.0 KB	1.4%	0.07
Nov 17 05:00:00 - 05:59:59	77	2.3%	0.02	312.5 KB	2.4%	0.09
Nov 17 06:00:00 - 06:59:59	72	2.2%	0.02	416.7 KB	3.2%	0.12
Nov 17 07:00:00 - 07:59:59	52	1.6%	0.01	204.5 KB	1.6%	0.06
Nov 17 08:00:00 - 08:59:59	74	2.3%	0.02	353.6 KB	2.7%	0.10
Nov 17 09:00:00 - 09:59:59	98	3.0%	0.03	335.5 KB	2.6%	0.09
Nov 17 10:00:00 - 10:59:59	127	3.9%	0.04	395.2 KB	3.0%	0.11
Nov 17 11:00:00 - 11:59:59	134	4.1%	0.04	424.0 KB	3.2%	0.12
Nov 17 12:00:00 - 12:59:59	148	4.5%	0.04	1.4 MB	11.2%	0.41
Nov 17 13:00:00 - 13:59:59	170	5.2%	0.05	531.5 KB	4.1%	0.15
Nov 17 14:00:00 - 14:59:59	189	5.8%	0.05	712.9 KB	5.4%	0.20
Nov 17 15:00:00 - 15:59:59	153	4.7%	0.04	529.2 KB	4.0%	0.15
Nov 17 16:00:00 - 16:59:59	169	5.1%	0.05	645.4 KB	4.9%	0.18
Nov 17 17:00:00 - 17:59:59	171	5.2%	0.05	575.1 KB	4.4%	0.16
Nov 17 18:00:00 - 18:59:59	212	6.5%	0.06	706.0 KB	5.4%	0.20
Nov 17 19:00:00 - 19:59:59	196	6.0%	0.05	665.4 KB	5.1%	0.18
Nov 17 20:00:00 - 20:59:59	200	6.1%	0.06	827.3 KB	6.3%	0.23
Nov 17 21:00:00 - 21:59:59	185	5.6%	0.05	606.4 KB	4.6%	0.17
Nov 17 22:00:00 - 22:59:59	147	4.5%	0.04	431.7 KB	3.3%	0.12
Nov 17 23:00:00 - 23:59:59	181	5.5%	0.05	696.8 KB	5.3%	0.19
Nov 18 00:00:00 - 00:59:59	176	5.4%	0.05	685.5 KB	5.2%	0.19
Nov 18 01:00:00 - 01:59:59	122	3.7%	0.03	622.4 KB	4.7%	0.17
Nov 18 02:00:00 - 02:59:59	91	2.8%	0.03	379.1 KB	2.9%	0.11
Nov 18 03:00:00 - 03:59:59	79	2.4%	0.02	241.5 KB	1.8%	0.07
Nov 18 04:00:00 - 04:15:01	30	0.9%	0.03	173.3 KB	1.3%	0.19
TOTAL: 24:00:00	3286	100.0%	0.04	12.8 MB	100.0%	0.15

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	336	0	336
2	news-out.aioe.org	64	7	57
3	news.glou.org	40	0	40
4	news.nntp4.net	23	0	23
5	usenet.pasdenom.info	6	0	6
6	gegeweb-out.news.tnetconsulting.net	2	2	0
7	feed-out.gegeweb.org	2	1	1
8	news.samoylyk.net	1	0	1
TOTAL: 8		474	10	464

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	3
fr.petites-annonces.divers	3
news.test	1
alt.tv.pol-incorrect	1
france.haute-normandie.rouen	1
soc.culture.mexico	1
TOTAL: 6	10

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	178
EMP (phl)	105
Bad path (news.giganews.com.POSTED)	75
Too many newsgroups	32
EMP (phn path)	27
EMP (md5)	27
Cancel for rejected article	2
Cancels abusifs	2
TOTAL: 8	448

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	13
	news.nntp4.net	1
	news.samoylyk.net	1
	feed-out.gegeweb.org	1
	TOTAL: 4	16
TOTAL: 1		16

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	3730	30	3219	0	0	0%	23:59:49
2	nntp.terraraq.uk	2833	10	2303	14	0	0%	23:59:56
3	i2pn.org	4125	10	2365	3	5	0%	23:53:17
4	news.aioe.org	1650	8	1399	1	0	0%	23:48:33
5	news.samoylyk.net	4002	5	3264	4	0	0%	23:59:06
6	news.tnetconsulting.net	5107	4	3277	3	3	0%	23:59:44
7	news.gegeweb.org	3248	2	3149	5	0	0%	23:59:56
8	news.pasdenom.info	3284	0	2822	0	0	0%	23:59:56
TOTAL: 8		27979	69	21798	30	8	0%	191:40:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	778.3 KB	0.0 KB	778.3 KB	25.9 KB	23:59:49
2	news.aioe.org	189.0 KB	2.1 KB	191.1 KB	21.2 KB	23:48:33
3	news.samoylyk.net	107.5 KB	9.7 KB	117.2 KB	13.0 KB	23:59:06
4	nntp.terraraq.uk	98.8 KB	1.0 MB	1.1 MB	48.3 KB	23:59:56
5	i2pn.org	29.3 KB	7.8 KB	37.1 KB	2.9 KB	23:53:17
6	news.tnetconsulting.net	15.0 KB	10.7 KB	25.6 KB	3.7 KB	23:59:44
7	news.gegeweb.org	3.2 KB	18.4 KB	21.6 KB	3.1 KB	23:59:56
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		1.2 MB	1.1 MB	2.3 MB	23.5 KB	191:40:17

NNRP readership statistics:

System		Conn	Elapsed
1	107.189.6.177	2	00:00:01
2	159.203.9.149	8	00:00:01
3	mx.terraraq.uk	2858	00:08:15
4	classical.monitoring.internet-measurement.com	1	00:00:01
5	104.156.155.20	81	00:00:19
6	mail.gegeweb.eu	288	00:00:34
7	electrifying.monitoring.internet-measurement.com	1	00:00:03
TOTAL: 7		3239	00:09:18

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	91	00:00:23
2	*.monitoring.internet-measurement.com	2	00:00:04
3	*.gegeweb.eu	288	00:00:34
4	*.terraraq.uk	2858	00:08:15
TOTAL: 4		3239	00:09:18

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
mx.terraraq.uk	77.108	76.004	0.000	00:08:15
mail.gegeweb.eu	8.002	7.943	2.297	00:00:34
104.156.155.20	2.354	0.701	0.000	00:00:19
electrifying.monitoring.internet-measurement.com	0.023	0.034	0.000	00:00:03
classical.monitoring.internet-measurement.com	0.043	0.011	0.000	00:00:01
159.203.9.149	0.244	0.174	0.000	00:00:01
107.189.6.177	0.056	0.041	0.000	00:00:01
TOTAL: 7	87.830	84.908	2.297	00:09:18

NNRP unrecognized commands (by host):

System	Conn
159.203.9.149	42
107.189.6.177	7
TOTAL: 2	49

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:119	8
Accept: /	7
Connection: keep-alive	7
Accept-Encoding: gzip, deflate	7
User-Agent: Mozilla/5.0 (Windows NT 10.0...	7
GET / HTTP/1.1	2
Upgrade-Insecure-Requests: 1	1
GET /system_api.php HTTP/1.1	1
Accept-Language: en-US;q=0.7,en;q=0.3	1
Accept-Encoding: gzip, deflate, br	1
Accept: text/html, application/xhtml+xml...	1
GET /c/version.js HTTP/1.1	1
GET /stream/live.php HTTP/1.1	1
GET /streaming/clients_live.php HTTP/1.1	1
GET /flu/403.html HTTP/1.1	1
User-Agent: AlexaMediaPlayer/2.1.4676.0 ...	1
GET /stalker_portal/c/version.js HTTP/1....	1
TOTAL: 17	49

Daily Usenet report for news.niel.me

Nov 17 04:15:01 -- Nov 18 04:15:01