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

Unknown entries from news log file:

First 50 / 2856 lines (1.8%)

Nov 10 04:15:29 rpi-studio nnrpd[13181]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:15:59 rpi-studio nnrpd[13210]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:16:29 rpi-studio nnrpd[13229]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:16:59 rpi-studio nnrpd[13285]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:17:29 rpi-studio nnrpd[13305]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:18:00 rpi-studio nnrpd[13307]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:18:31 rpi-studio nnrpd[13312]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:19:01 rpi-studio nnrpd[13339]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:19:31 rpi-studio nnrpd[13343]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:20:01 rpi-studio nnrpd[13350]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:20:31 rpi-studio nnrpd[13366]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:21:03 rpi-studio nnrpd[13370]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:21:33 rpi-studio nnrpd[13373]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:22:03 rpi-studio nnrpd[13386]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:22:34 rpi-studio nnrpd[13530]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:23:04 rpi-studio nnrpd[13535]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:23:34 rpi-studio nnrpd[13549]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:24:04 rpi-studio nnrpd[13574]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:24:34 rpi-studio nnrpd[13578]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:25:04 rpi-studio nnrpd[13587]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:25:34 rpi-studio nnrpd[13591]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:26:04 rpi-studio nnrpd[13600]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:26:35 rpi-studio nnrpd[13603]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:27:06 rpi-studio nnrpd[13614]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:27:36 rpi-studio nnrpd[13618]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:28:06 rpi-studio nnrpd[13627]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:28:36 rpi-studio nnrpd[13630]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:29:06 rpi-studio nnrpd[13651]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:29:36 rpi-studio nnrpd[13666]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:30:06 rpi-studio nnrpd[13678]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:30:36 rpi-studio nnrpd[13681]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:31:06 rpi-studio nnrpd[13686]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:31:36 rpi-studio nnrpd[13689]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:32:06 rpi-studio nnrpd[13696]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:32:36 rpi-studio nnrpd[13841]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:33:06 rpi-studio nnrpd[13845]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:33:36 rpi-studio nnrpd[13861]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:34:07 rpi-studio nnrpd[13887]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:34:37 rpi-studio nnrpd[13897]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:35:07 rpi-studio nnrpd[13910]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:35:37 rpi-studio nnrpd[13914]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:36:07 rpi-studio nnrpd[13918]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:36:37 rpi-studio nnrpd[13921]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:37:07 rpi-studio nnrpd[13926]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:37:38 rpi-studio nnrpd[13929]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:38:09 rpi-studio nnrpd[13939]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:38:39 rpi-studio nnrpd[13948]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:39:10 rpi-studio nnrpd[13965]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:39:40 rpi-studio nnrpd[13973]: mx.terraraq.uk noperm ihave without permission
Nov 10 04:40:10 rpi-studio nnrpd[13982]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	12988	62.4%	1.3 MB	49.5%
inn	3707	17.8%	685.4 KB	25.9%
innd	2409	11.6%	384.0 KB	14.5%
innfeed	1708	8.2%	267.8 KB	10.1%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	20814	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	19997	62.2%
Negative hits	8494	26.4%
Do not exist	3661	11.4%
Cache misses	2	0.0%
TOTAL: 4	32154	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	8	0.000	1.375	3.500
article cancel	00:00:00.004	0.0%	8	0.000	0.500	1.000
article cleanup	00:00:00.118	0.0%	3677	0.000	0.032	0.167
article logging	00:00:00.332	0.0%	3695	0.000	0.090	1.500
article parse	00:00:00.619	0.0%	6623	0.000	0.093	0.333
article write	00:00:02.028	0.0%	3179	0.150	0.638	3.875
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:00.117	0.0%	34614	0.000	0.003	0.021
hisgrep/artcncl	00:00:00.001	0.0%	8	0.000	0.125	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	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%	8	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.411	0.0%	32289	0.000	0.013	0.048
history sync	00:00:00.103	0.0%	421	0.000	0.245	15.333
history write	00:00:00.906	0.0%	3676	0.000	0.246	0.811
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:08.592	99.9%	40699	788.120	2123.113	5939.475
nntp read	00:00:01.233	0.0%	34933	0.010	0.035	0.099
overview write	00:00:03.271	0.0%	3179	0.065	1.029	50.238
perl filter	00:00:21.244	0.0%	3676	3.550	5.779	10.143
python filter	00:00:00.005	0.0%	3676	0.000	0.001	0.143
site send	00:00:01.422	0.0%	6427	0.000	0.221	0.621
TOTAL: 24:01:11.909	24:00:40.417	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	3195	0.000	0.009	0.129
article prepare	00:00:00.001	0.0%	352	0.000	0.003	0.062
article read	00:00:00.010	0.0%	65	0.000	0.154	4.000
backlog stats	00:00:00.102	0.0%	49455	0.000	0.002	0.013
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.647	0.0%	29090	0.000	0.022	0.070
data write	00:00:01.690	0.0%	25971	0.018	0.065	0.131
idle	23:49:56.726	100.0%	49455	853.314	1734.844	7035.849
status file	00:00:00.387	0.0%	174	0.000	2.224	5.000
TOTAL: 23:50:04.866	23:49:59.591	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:27.485	48.6%	10014	11.600	26.711	9229.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.580	0.1%	20349	0.000	0.029	1.000
TOTAL: 00:09:10.410	00:04:28.065	48.7%	-	-	-	-

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	3050	1399	1612	39	45%	22:20:01
2	i2pn.org	1	3444	881	2336	227	25%	25:14:52
3	news.glou.org	2	2784	371	2349	64	13%	22:37:31
4	feed-out.gegeweb.org	2	2216	213	2003	0	9%	23:59:04
5	usenet.pasdenom.info	1	2347	116	2231	0	4%	23:59:53
6	news.nntp4.net	2	3410	52	3188	170	1%	22:52:55
7	gegeweb-out.news.tnetconsulting.net	2	3051	33	3014	4	1%	23:59:21
8	news.samoylyk.net	1	25	0	25	0	0%	00:15:13
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		37	20327	3065	16758	504	15%	165:18:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	4.6 MB	1.2 KB	116.1 KB	4.7 MB	97%	3.4 KB
2	i2pn.org	3.0 MB	0.0 KB	460.2 KB	3.5 MB	86%	3.2 KB
3	news.glou.org	2.0 MB	0.0 KB	141.5 KB	2.2 MB	93%	5.1 KB
4	news.nntp4.net	672.6 KB	0.0 KB	444.7 KB	1.1 MB	60%	5.0 KB
5	feed-out.gegeweb.org	613.8 KB	0.0 KB	0.0 KB	613.8 KB	100%	2.9 KB
6	usenet.pasdenom.info	276.2 KB	0.0 KB	0.0 KB	276.2 KB	100%	2.4 KB
7	gegeweb-out.news.tnetconsulting.net	194.7 KB	0.0 KB	23.6 KB	218.3 KB	89%	5.9 KB
8	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		11.4 MB	1.2 KB	1.2 MB	12.5 MB	90%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 10 04:15:01 - 04:59:59	54	1.7%	0.02	472.9 KB	3.9%	0.18
Nov 10 05:00:00 - 05:59:59	52	1.6%	0.01	230.2 KB	1.9%	0.06
Nov 10 06:00:00 - 06:59:59	63	2.0%	0.02	238.7 KB	2.0%	0.07
Nov 10 07:00:00 - 07:59:59	64	2.0%	0.02	241.4 KB	2.0%	0.07
Nov 10 08:00:00 - 08:59:59	63	2.0%	0.02	425.4 KB	3.5%	0.12
Nov 10 09:00:00 - 09:59:59	82	2.6%	0.02	417.8 KB	3.4%	0.12
Nov 10 10:00:00 - 10:59:59	140	4.4%	0.04	431.6 KB	3.6%	0.12
Nov 10 11:00:00 - 11:59:59	131	4.1%	0.04	384.8 KB	3.2%	0.11
Nov 10 12:00:00 - 12:59:59	153	4.8%	0.04	749.8 KB	6.2%	0.21
Nov 10 13:00:00 - 13:59:59	163	5.1%	0.05	544.5 KB	4.5%	0.15
Nov 10 14:00:00 - 14:59:59	203	6.4%	0.06	763.2 KB	6.3%	0.21
Nov 10 15:00:00 - 15:59:59	163	5.1%	0.05	582.8 KB	4.8%	0.16
Nov 10 16:00:00 - 16:59:59	166	5.2%	0.05	596.9 KB	4.9%	0.17
Nov 10 17:00:00 - 17:59:59	160	5.0%	0.04	535.9 KB	4.4%	0.15
Nov 10 18:00:00 - 18:59:59	210	6.6%	0.06	1.4 MB	11.5%	0.39
Nov 10 19:00:00 - 19:59:59	156	4.9%	0.04	428.2 KB	3.5%	0.12
Nov 10 20:00:00 - 20:59:59	169	5.3%	0.05	559.7 KB	4.6%	0.16
Nov 10 21:00:00 - 21:59:59	172	5.4%	0.05	572.5 KB	4.7%	0.16
Nov 10 22:00:00 - 22:59:59	138	4.3%	0.04	451.2 KB	3.7%	0.13
Nov 10 23:00:00 - 23:59:59	220	6.9%	0.06	617.9 KB	5.1%	0.17
Nov 11 00:00:00 - 00:59:59	153	4.8%	0.04	456.7 KB	3.8%	0.13
Nov 11 01:00:00 - 01:59:59	108	3.4%	0.03	403.2 KB	3.3%	0.11
Nov 11 02:00:00 - 02:59:59	89	2.8%	0.02	338.4 KB	2.8%	0.09
Nov 11 03:00:00 - 03:59:59	79	2.5%	0.02	242.2 KB	2.0%	0.07
Nov 11 04:00:00 - 04:15:01	24	0.8%	0.03	52.9 KB	0.4%	0.06
TOTAL: 24:00:00	3175	100.0%	0.04	11.9 MB	100.0%	0.14

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	216	0	0	216
2	news.nntp4.net	172	0	0	172
3	news.glou.org	65	0	0	65
4	news-out.aioe.org	55	5	1	49
5	usenet.pasdenom.info	5	0	0	5
6	gegeweb-out.news.tnetconsulting.net	3	0	0	3
TOTAL: 6		516	5	1	510

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.survival	4
news.test	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	173
EMP (md5)	131
EMP (phn nph)	60
Bad path (news.giganews.com.POSTED)	59
EMP (phn path)	44
Too many newsgroups	23
Cancels abusifs	2
TOTAL: 7	492

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	16
	news.nntp4.net	1
	i2pn.org	1
	TOTAL: 3	18
TOTAL: 1		18

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4172	29	2288	4	20	0%	23:56:43
2	news.nntp4.net	3468	12	3111	0	0	0%	23:59:10
3	news.tnetconsulting.net	5023	9	3158	5	15	0%	23:59:48
4	news.gegeweb.org	3039	8	2953	2	0	0%	23:59:54
5	nntp.terraraq.uk	2481	7	2121	6	0	0%	23:59:18
6	news.aioe.org	1413	2	1257	1	0	0%	23:55:09
7	news.pasdenom.info	2949	1	2646	0	0	0%	23:59:27
8	news.samoylyk.net	3790	0	3169	2	0	0%	23:59:54
TOTAL: 8		26335	68	20703	20	35	0%	191:49:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	242.6 KB	0.0 KB	242.6 KB	20.2 KB	23:59:10
2	nntp.terraraq.uk	173.8 KB	331.8 KB	505.6 KB	38.9 KB	23:59:18
3	i2pn.org	134.3 KB	28.5 KB	162.7 KB	4.9 KB	23:56:43
4	news.tnetconsulting.net	87.1 KB	40.5 KB	127.6 KB	9.1 KB	23:59:48
5	news.gegeweb.org	18.0 KB	8.3 KB	26.3 KB	2.6 KB	23:59:54
6	news.aioe.org	4.5 KB	2.1 KB	6.6 KB	2.2 KB	23:55:09
7	news.pasdenom.info	1.6 KB	0.0 KB	1.6 KB	1.6 KB	23:59:27
8	news.samoylyk.net	0.0 KB	4.2 KB	4.2 KB	2.1 KB	23:59:54
TOTAL: 8		661.9 KB	415.3 KB	1.1 MB	12.2 KB	191:49:23

NNRP readership statistics:

System		Conn	Elapsed
1	192.241.202.219	1	00:00:09
2	popular.monitoring.internet-measurement.com	1	00:00:00
3	angelic.monitoring.internet-measurement.com	1	00:00:00
4	swanky.monitoring.internet-measurement.com	1	00:00:06
5	stellar.monitoring.internet-measurement.com	1	00:00:00
6	mx.terraraq.uk	2856	00:07:59
7	183.136.225.9	1	00:00:00
8	famous.monitoring.internet-measurement.com	1	00:00:00
9	splendid.monitoring.internet-measurement.com	1	00:00:00
10	elite.monitoring.internet-measurement.com	1	00:00:05
11	robust.monitoring.internet-measurement.com	1	00:00:00
12	innovative.monitoring.internet-measurement.com	1	00:00:00
13	esteemed.monitoring.internet-measurement.com	1	00:00:00
14	plentiful.monitoring.internet-measurement.com	1	00:00:00
15	tantalizing.monitoring.internet-measurement.com	1	00:00:00
16	unrivalled.monitoring.internet-measurement.com	1	00:00:00
17	talented.monitoring.internet-measurement.com	1	00:00:00
18	red.census.shodan.io	1	00:00:03
19	novel.monitoring.internet-measurement.com	1	00:00:00
20	instinctive.monitoring.internet-measurement.com	1	00:00:00
21	luminous.monitoring.internet-measurement.com	1	00:00:00
22	192.241.198.220	1	00:00:00
23	mail.gegeweb.eu	288	00:00:35
24	valued.monitoring.internet-measurement.com	1	00:00:00
25	refreshing.monitoring.internet-measurement.com	1	00:00:00
26	profound.monitoring.internet-measurement.com	1	00:00:00
27	admired.monitoring.internet-measurement.com	1	00:00:00
28	courageous.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 28		3170	00:09:06

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.census.shodan.io	1	00:00:03
2	*.terraraq.uk	2856	00:07:59
3	*.gegeweb.eu	288	00:00:35
4	*.monitoring.internet-measurement.com	22	00:00:16
5	unresolved	3	00:00:11
TOTAL: 5		3170	00:09:06

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	76.291	76.867	0.000	00:07:59
mail.gegeweb.eu	7.645	8.347	2.384	00:00:35
192.241.202.219	0.022	0.032	0.000	00:00:09
swanky.monitoring.internet-measurement.com	0.049	0.037	0.000	00:00:06
elite.monitoring.internet-measurement.com	0.067	0.022	0.000	00:00:05
red.census.shodan.io	0.018	0.037	0.000	00:00:03
192.241.198.220	0.033	0.022	0.000	00:00:00
183.136.225.9	0.044	0.011	0.000	00:00:00
admired.monitoring.internet-measurement.com	0.061	0.020	0.000	00:00:00
plentiful.monitoring.internet-measurement.com	0.073	0.010	0.000	00:00:00
unrivalled.monitoring.internet-measurement.com	0.018	0.018	0.000	00:00:00
innovative.monitoring.internet-measurement.com	0.053	0.021	0.000	00:00:00
valued.monitoring.internet-measurement.com	0.084	0.021	0.000	00:00:00
robust.monitoring.internet-measurement.com	0.077	0.010	0.000	00:00:00
stellar.monitoring.internet-measurement.com	0.072	0.010	0.000	00:00:00
tantalizing.monitoring.internet-measurement.com	0.026	0.013	0.000	00:00:00
popular.monitoring.internet-measurement.com	0.045	0.034	0.000	00:00:00
famous.monitoring.internet-measurement.com	0.024	0.012	0.000	00:00:00
refreshing.monitoring.internet-measurement.com	0.024	0.012	0.000	00:00:00
profound.monitoring.internet-measurement.com	0.029	0.010	0.000	00:00:00
TOTAL: 28	84.881	85.637	2.384	00:09:06

NNRP unrecognized commands (by host):

System	Conn
183.136.225.9	5
192.241.198.220	1
TOTAL: 2	6

NNRP unrecognized commands (by command):

Command	Count
Accept: /	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
Host: 45.13.105.36:119	1
GET / HTTP/1.1	1
MGLNDD_45.13.105.36_119	1
Connection: keep-alive	1
TOTAL: 6	6

Daily Usenet report for news.niel.me

Nov 10 04:15:01 -- Nov 11 04:15:01