Daily Usenet report for news.niel.me: Nov 8 04:15:02

Unknown entries from news log file:

First 50 / 2845 lines (1.8%)

Nov 8 04:15:07 rpi-studio nnrpd[13557]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:15:37 rpi-studio nnrpd[13567]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:16:07 rpi-studio nnrpd[13598]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:16:38 rpi-studio nnrpd[13610]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:17:09 rpi-studio nnrpd[13676]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:17:39 rpi-studio nnrpd[13684]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:18:09 rpi-studio nnrpd[13688]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:18:39 rpi-studio nnrpd[13702]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:19:10 rpi-studio nnrpd[13709]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:19:40 rpi-studio nnrpd[13712]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:20:10 rpi-studio nnrpd[13859]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:20:40 rpi-studio nnrpd[13867]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:21:10 rpi-studio nnrpd[13878]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:21:40 rpi-studio nnrpd[13899]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:22:10 rpi-studio nnrpd[13917]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:22:40 rpi-studio nnrpd[13925]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:23:10 rpi-studio nnrpd[13929]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:23:41 rpi-studio nnrpd[13945]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:24:11 rpi-studio nnrpd[13951]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:24:41 rpi-studio nnrpd[13954]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:25:11 rpi-studio nnrpd[13972]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:25:41 rpi-studio nnrpd[13983]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:26:11 rpi-studio nnrpd[13989]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:26:41 rpi-studio nnrpd[13992]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:27:11 rpi-studio nnrpd[14001]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:27:41 rpi-studio nnrpd[14004]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:28:11 rpi-studio nnrpd[14009]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:28:41 rpi-studio nnrpd[14036]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:29:11 rpi-studio nnrpd[14047]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:29:41 rpi-studio nnrpd[14051]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:30:11 rpi-studio nnrpd[14202]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:30:42 rpi-studio nnrpd[14205]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:31:12 rpi-studio nnrpd[14209]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:31:43 rpi-studio nnrpd[14212]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:32:14 rpi-studio nnrpd[14236]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:32:44 rpi-studio nnrpd[14249]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:33:14 rpi-studio nnrpd[14254]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:33:44 rpi-studio nnrpd[14269]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:34:14 rpi-studio nnrpd[14280]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:34:44 rpi-studio nnrpd[14283]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:35:14 rpi-studio nnrpd[14294]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:35:44 rpi-studio nnrpd[14303]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:36:14 rpi-studio nnrpd[14316]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:36:44 rpi-studio nnrpd[14319]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:37:14 rpi-studio nnrpd[14324]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:37:44 rpi-studio nnrpd[14327]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:38:14 rpi-studio nnrpd[14344]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:38:44 rpi-studio nnrpd[14358]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:39:14 rpi-studio nnrpd[14370]: mx.terraraq.uk noperm ihave without permission
Nov 8 04:39:45 rpi-studio nnrpd[14373]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	13332	60.8%	1.3 MB	46.8%
inn	4397	20.0%	824.0 KB	29.1%
innd	2490	11.4%	390.2 KB	13.8%
innfeed	1716	7.8%	291.6 KB	10.3%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	21937	100.0%	2.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	24021	63.2%
Negative hits	9636	25.4%
Do not exist	4330	11.4%
Cache misses	7	0.0%
TOTAL: 4	37994	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.029	0.0%	19	0.000	1.526	6.000
article cancel	00:00:00.001	0.0%	19	0.000	0.053	1.000
article cleanup	00:00:00.130	0.0%	4338	0.000	0.030	0.238
article logging	00:00:00.343	0.0%	4349	0.000	0.079	0.429
article parse	00:00:00.622	0.0%	7879	0.000	0.079	0.235
article write	00:00:03.014	0.0%	3786	0.000	0.796	12.841
artlog/artcncl	00:00:00.001	0.0%	19	0.000	0.053	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	19	0.000	0.053	1.000
data move	00:00:00.123	0.0%	40466	0.000	0.003	0.020
hisgrep/artcncl	00:00:00.000	0.0%	19	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	19	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	19	0.000	0.053	1.000
hishave/artcncl	00:00:00.000	0.0%	19	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:00.520	0.0%	38036	0.000	0.014	0.034
history sync	00:00:00.056	0.0%	408	0.000	0.137	1.000
history write	00:00:01.051	0.0%	4338	0.000	0.242	0.651
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:48.844	99.9%	46798	887.682	1845.994	5717.790
nntp read	00:00:01.466	0.0%	40809	0.000	0.036	0.071
overview write	00:00:06.875	0.0%	3786	0.083	1.816	157.852
perl filter	00:00:24.559	0.0%	4338	2.985	5.661	10.111
python filter	00:00:00.008	0.0%	4338	0.000	0.002	0.043
site send	00:00:01.501	0.0%	7648	0.020	0.196	0.467
TOTAL: 24:01:00.997	24:00:29.145	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	3905	0.000	0.009	0.091
article prepare	00:00:00.001	0.0%	576	0.000	0.002	0.125
article read	00:00:00.022	0.0%	113	0.000	0.195	7.000
backlog stats	00:00:00.129	0.0%	60104	0.000	0.002	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.876	0.0%	36355	0.000	0.024	0.058
data write	00:00:01.943	0.0%	31666	0.022	0.061	0.152
idle	23:57:28.433	100.0%	60104	700.860	1434.987	5101.297
status file	00:00:00.429	0.0%	169	0.000	2.538	9.000
TOTAL: 23:57:38.372	23:57:31.867	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:41.570	48.6%	9981	11.600	28.211	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.570	0.1%	20332	0.000	0.028	1.000
TOTAL: 00:09:38.963	00:04:42.140	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	3	3935	1608	2295	32	40%	24:35:07
2	i2pn.org	2	3990	1035	2682	273	25%	24:54:55
3	news.glou.org	1	3502	576	2823	103	16%	23:59:58
4	feed-out.gegeweb.org	3	2503	357	2146	0	14%	23:12:34
5	usenet.pasdenom.info	1	1563	88	1468	7	5%	12:03:04
6	gegeweb-out.news.tnetconsulting.net	1	1281	37	1236	8	2%	11:08:56
7	news.nntp4.net	3	4192	22	4015	155	0%	24:19:50
8	news.samoylyk.net	1	1987	5	1982	0	0%	10:51:45
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	22953	3728	18647	578	16%	155:06:09

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	5.3 MB	80.4 KB	5.4 MB	98%	3.4 KB
2	news.glou.org	3.4 MB	267.6 KB	3.6 MB	92%	5.5 KB
3	i2pn.org	3.1 MB	606.5 KB	3.7 MB	84%	2.9 KB
4	feed-out.gegeweb.org	1.0 MB	0.0 KB	1.0 MB	100%	2.9 KB
5	news.nntp4.net	295.1 KB	465.3 KB	760.3 KB	38%	4.3 KB
6	gegeweb-out.news.tnetconsulting.net	279.8 KB	32.4 KB	312.2 KB	89%	6.9 KB
7	usenet.pasdenom.info	217.5 KB	10.7 KB	228.2 KB	95%	2.4 KB
8	news.samoylyk.net	178.2 KB	0.0 KB	178.2 KB	100%	35.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		13.8 MB	1.4 MB	15.2 MB	90%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 8 04:15:02 - 04:59:59	82	2.2%	0.03	688.9 KB	4.8%	0.26
Nov 8 05:00:00 - 05:59:59	87	2.3%	0.02	264.1 KB	1.8%	0.07
Nov 8 06:00:00 - 06:59:59	73	1.9%	0.02	263.6 KB	1.8%	0.07
Nov 8 07:00:00 - 07:59:59	92	2.4%	0.03	307.8 KB	2.1%	0.09
Nov 8 08:00:00 - 08:59:59	74	2.0%	0.02	268.9 KB	1.9%	0.07
Nov 8 09:00:00 - 09:59:59	117	3.1%	0.03	318.8 KB	2.2%	0.09
Nov 8 10:00:00 - 10:59:59	120	3.2%	0.03	418.3 KB	2.9%	0.12
Nov 8 11:00:00 - 11:59:59	172	4.5%	0.05	701.8 KB	4.9%	0.19
Nov 8 12:00:00 - 12:59:59	153	4.0%	0.04	499.2 KB	3.5%	0.14
Nov 8 13:00:00 - 13:59:59	160	4.2%	0.04	475.4 KB	3.3%	0.13
Nov 8 14:00:00 - 14:59:59	178	4.7%	0.05	571.0 KB	4.0%	0.16
Nov 8 15:00:00 - 15:59:59	206	5.4%	0.06	632.8 KB	4.4%	0.18
Nov 8 16:00:00 - 16:59:59	161	4.2%	0.04	464.7 KB	3.2%	0.13
Nov 8 17:00:00 - 17:59:59	216	5.7%	0.06	846.8 KB	5.9%	0.24
Nov 8 18:00:00 - 18:59:59	268	7.1%	0.07	900.0 KB	6.2%	0.25
Nov 8 19:00:00 - 19:59:59	199	5.3%	0.06	616.2 KB	4.3%	0.17
Nov 8 20:00:00 - 20:59:59	225	5.9%	0.06	795.5 KB	5.5%	0.22
Nov 8 21:00:00 - 21:59:59	225	5.9%	0.06	702.2 KB	4.9%	0.20
Nov 8 22:00:00 - 22:59:59	223	5.9%	0.06	1.6 MB	11.2%	0.45
Nov 8 23:00:00 - 23:59:59	200	5.3%	0.06	668.8 KB	4.6%	0.19
Nov 9 00:00:00 - 00:59:59	170	4.5%	0.05	672.5 KB	4.7%	0.19
Nov 9 01:00:00 - 01:59:59	146	3.9%	0.04	550.9 KB	3.8%	0.15
Nov 9 02:00:00 - 02:59:59	109	2.9%	0.03	703.9 KB	4.9%	0.20
Nov 9 03:00:00 - 03:59:59	105	2.8%	0.03	358.7 KB	2.5%	0.10
Nov 9 04:00:00 - 04:15:02	29	0.8%	0.03	102.6 KB	0.7%	0.11
TOTAL: 24:00:00	3790	100.0%	0.04	14.1 MB	100.0%	0.17

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	258	0	258
2	news.nntp4.net	151	0	151
3	news.glou.org	103	0	103
4	news-out.aioe.org	40	1	39
5	usenet.pasdenom.info	8	0	8
6	gegeweb-out.news.tnetconsulting.net	7	2	5
7	news.samoylyk.net	2	0	2
TOTAL: 7		569	3	566

Unwanted newsgroups [Top 20]:

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

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	204
EMP (md5)	130
Bad path (news.giganews.com.POSTED)	100
EMP (phn path)	53
EMP (phn nph)	37
Too many newsgroups	25
Cancels abusifs	4
Cancel for rejected article	2
TOTAL: 8	555

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	8
	news.samoylyk.net	2
	i2pn.org	1
	TOTAL: 3	11
TOTAL: 1		11

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4766	36	2717	5	59	0%	23:59:54
2	news.nntp4.net	4302	29	3708	0	2	0%	23:59:54
3	news.gegeweb.org	3569	24	3404	2	0	0%	23:58:21
4	news.tnetconsulting.net	5817	12	3775	2	60	0%	23:59:54
5	news.aioe.org	1988	11	1663	1	0	0%	23:56:58
6	nntp.terraraq.uk	3190	6	2626	13	0	0%	23:59:23
7	news.samoylyk.net	4694	1	3778	2	0	0%	23:59:54
8	news.pasdenom.info	3698	0	3200	0	0	0%	23:59:54
TOTAL: 8		32024	119	24871	25	121	0%	191:54:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	727.1 KB	0.0 KB	727.1 KB	25.1 KB	23:59:54
2	i2pn.org	149.4 KB	24.2 KB	173.6 KB	4.2 KB	23:59:54
3	news.gegeweb.org	124.5 KB	3.8 KB	128.3 KB	4.9 KB	23:58:21
4	nntp.terraraq.uk	86.5 KB	1.0 MB	1.1 MB	59.4 KB	23:59:23
5	news.aioe.org	66.8 KB	1.4 KB	68.2 KB	5.7 KB	23:56:58
6	news.tnetconsulting.net	37.7 KB	2.8 KB	40.5 KB	2.9 KB	23:59:54
7	news.samoylyk.net	1.7 KB	2.8 KB	4.6 KB	1.5 KB	23:59:54
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:54
TOTAL: 8		1.2 MB	1.1 MB	2.2 MB	15.8 KB	191:54:12

NNRP readership statistics:

System		Conn	Elapsed
1	robust.monitoring.internet-measurement.com	1	00:00:03
2	192.241.201.224	1	00:00:00
3	mx.terraraq.uk	2845	00:08:12
4	104.156.155.22	81	00:00:18
5	165.227.84.126	1	00:00:10
6	104.152.52.114	1	00:00:10
7	104.152.52.133	1	00:00:00
8	mail.gegeweb.eu	288	00:00:38
TOTAL: 8		3219	00:09:34

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	1	00:00:03
2	*.terraraq.uk	2845	00:08:12
3	*.gegeweb.eu	288	00:00:38
4	unresolved	85	00:00:40
TOTAL: 4		3219	00:09:34

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.030	75.850	0.000	00:08:12
mail.gegeweb.eu	8.102	7.940	2.273	00:00:38
104.156.155.22	2.476	0.600	0.000	00:00:18
165.227.84.126	0.023	0.035	0.000	00:00:10
104.152.52.114	0.013	0.013	0.000	00:00:10
robust.monitoring.internet-measurement.com	0.023	0.034	0.000	00:00:03
192.241.201.224	0.027	0.027	0.000	00:00:00
104.152.52.133	0.028	0.028	0.000	00:00:00
TOTAL: 8	86.722	84.527	2.273	00:09:34

NNRP unrecognized commands (by host):

System	Conn
192.241.201.224	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

Daily Usenet report for news.niel.me

Nov 8 04:15:02 -- Nov 9 04:15:02