Daily Usenet report for news.niel.me: Dec 25 04:15:01

Unknown entries from news log file:

First 50 / 4100 lines (1.2%)

Dec 25 22:08:52 rpi-studio innfeed[1046]: news.aioe.org:0 connect: Network is unreachable
Dec 25 22:08:52 rpi-studio innfeed[1046]: news.aioe.org:1 connect: Network is unreachable
Dec 25 22:09:42 rpi-studio nnrpd[1411]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:09:42 rpi-studio nnrpd[1430]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:09:42 rpi-studio nnrpd[1410]: news.glou.org noperm ihave without permission
Dec 25 22:10:01 rpi-studio nnrpd[1409]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:10:01 rpi-studio nnrpd[1720]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:10:05 rpi-studio nnrpd[1725]: i2pn.org noperm ihave without permission
Dec 25 22:10:17 rpi-studio nnrpd[1873]: news.nntp4.net noperm ihave without permission
Dec 25 22:10:32 rpi-studio nnrpd[1879]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:10:35 rpi-studio nnrpd[1883]: i2pn.org noperm ihave without permission
Dec 25 22:10:47 rpi-studio nnrpd[1889]: news.nntp4.net noperm ihave without permission
Dec 25 22:11:02 rpi-studio nnrpd[1893]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:11:05 rpi-studio nnrpd[1899]: i2pn.org noperm ihave without permission
Dec 25 22:11:17 rpi-studio nnrpd[1954]: news.nntp4.net noperm ihave without permission
Dec 25 22:11:29 rpi-studio nnrpd[1870]: news.glou.org noperm ihave without permission
Dec 25 22:11:29 rpi-studio nnrpd[1872]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:11:29 rpi-studio nnrpd[1871]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:11:32 rpi-studio nnrpd[1972]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:11:35 rpi-studio nnrpd[1973]: i2pn.org noperm ihave without permission
Dec 25 22:11:44 rpi-studio nnrpd[1977]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:11:47 rpi-studio nnrpd[1990]: news.nntp4.net noperm ihave without permission
Dec 25 22:11:59 rpi-studio nnrpd[1996]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:11:59 rpi-studio nnrpd[1997]: news.glou.org noperm ihave without permission
Dec 25 22:12:00 rpi-studio nnrpd[1998]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:12:02 rpi-studio nnrpd[2005]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:12:06 rpi-studio nnrpd[2006]: i2pn.org noperm ihave without permission
Dec 25 22:12:15 rpi-studio nnrpd[2011]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:12:17 rpi-studio nnrpd[2012]: news.nntp4.net noperm ihave without permission
Dec 25 22:12:29 rpi-studio nnrpd[2020]: news.glou.org noperm ihave without permission
Dec 25 22:12:30 rpi-studio nnrpd[2023]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:12:30 rpi-studio nnrpd[2024]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:12:32 rpi-studio nnrpd[2025]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:12:36 rpi-studio nnrpd[2026]: i2pn.org noperm ihave without permission
Dec 25 22:12:46 rpi-studio nnrpd[2027]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:12:47 rpi-studio nnrpd[2028]: news.nntp4.net noperm ihave without permission
Dec 25 22:12:59 rpi-studio nnrpd[2029]: news.glou.org noperm ihave without permission
Dec 25 22:13:00 rpi-studio nnrpd[2030]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:13:01 rpi-studio nnrpd[2031]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:13:02 rpi-studio nnrpd[2034]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:13:06 rpi-studio nnrpd[2035]: i2pn.org noperm ihave without permission
Dec 25 22:13:16 rpi-studio nnrpd[2037]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:13:17 rpi-studio nnrpd[2038]: news.nntp4.net noperm ihave without permission
Dec 25 22:13:29 rpi-studio nnrpd[2051]: news.glou.org noperm ihave without permission
Dec 25 22:13:30 rpi-studio nnrpd[2052]: tncsrv06.tnetconsulting.net noperm ihave without permission
Dec 25 22:13:31 rpi-studio nnrpd[2053]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Dec 25 22:13:33 rpi-studio nnrpd[2054]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 25 22:13:33 rpi-studio nnrpd[2055]: nntp.terraraq.uk noperm ihave without permission
Dec 25 22:13:36 rpi-studio nnrpd[2056]: i2pn.org noperm ihave without permission
Dec 25 22:13:46 rpi-studio nnrpd[2057]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	19780	73.4%	2.0 MB	61.6%
inn	3058	11.4%	575.4 KB	17.5%
innd	2268	8.4%	377.7 KB	11.5%
innfeed	1819	6.8%	305.1 KB	9.3%
nocem	4	0.0%	0.3 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	26931	100.0%	3.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	18975	63.8%
Negative hits	7599	25.5%
Do not exist	2999	10.1%
Cache misses	191	0.6%
TOTAL: 4	29764	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.024	0.0%	11	1.000	2.182	9.000
article cancel	00:00:00.004	0.0%	13	0.000	0.308	1.000
article cleanup	00:00:00.058	0.0%	3013	0.000	0.019	0.200
article logging	00:00:00.221	0.0%	3032	0.000	0.073	0.400
article parse	00:00:00.579	0.0%	7499	0.000	0.077	0.500
article write	00:00:06.086	0.0%	2683	0.125	2.268	132.667
artlog/artcncl	00:00:00.002	0.0%	13	0.000	0.154	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	11	0.000	0.091	1.000
data move	00:00:00.089	0.0%	29631	0.000	0.003	0.043
hisgrep/artcncl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.002	0.0%	11	0.000	0.182	1.000
hisgrep/perl	00:00:00.007	0.0%	15	0.000	0.467	3.000
hishave/artcncl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	11	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:02.008	0.0%	29902	0.000	0.067	1.162
history sync	00:00:00.065	0.0%	405	0.000	0.160	1.000
history write	00:00:01.686	0.0%	3012	0.000	0.560	31.154
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:14.580	99.9%	39104	281.768	2209.865	21439.679
nntp read	00:00:01.003	0.0%	30367	0.000	0.033	0.114
overview write	00:00:02.671	0.0%	2683	0.071	0.996	59.167
perl filter	00:00:14.110	0.0%	3012	1.833	4.685	14.062
python filter	00:00:00.009	0.0%	3012	0.000	0.003	0.105
site send	00:00:01.169	0.0%	5444	0.000	0.215	0.600
TOTAL: 24:01:13.921	24:00:44.374	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	2699	0.000	0.014	0.200
article prepare	00:00:00.000	0.0%	196	0.000	0.000	0.000
article read	00:00:00.017	0.0%	34	0.000	0.500	1.200
backlog stats	00:00:00.084	0.0%	39778	0.000	0.002	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.599	0.0%	23188	0.000	0.026	0.094
data write	00:00:01.329	0.0%	20772	0.011	0.064	0.143
idle	23:47:23.526	100.0%	39778	734.357	2153.038	7319.659
status file	00:00:00.559	0.0%	211	1.000	2.649	6.000
TOTAL: 23:47:30.841	23:47:26.153	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:18:27.378	79.2%	13742	11.800	80.583	25631.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.903	0.1%	28082	0.000	0.032	0.500
readart	00:00:00.023	0.0%	7	3.000	3.286	3.333
TOTAL: 00:23:18.258	00:18:28.304	79.3%	-	-	-	-

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	2057	823	1206	28	40%	19:52:33
2	i2pn.org	3	1929	590	1205	134	30%	18:05:10
3	46.165.242.75	5	450	296	129	25	65%	02:52:19
4	2001:41d0:2:abb2::119	2	414	177	237	0	42%	04:50:58
5	usenet.pasdenom.info	4	1928	131	1796	1	6%	26:03:19
6	feed-out.gegeweb.org	1	664	112	552	0	16%	13:04:02
7	gegeweb-out.news.tnetconsulting.net	3	2597	54	2541	2	2%	23:59:49
8	news.nntp4.net	3	921	24	891	6	2%	02:19:11
9	news.glou.org	2	730	22	707	1	3%	01:12:40
10	news.samoylyk.net	3	2760	19	2741	0	0%	24:02:50
11	nntp.terraraq.uk	3	71	4	67	0	5%	01:15:02
12	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 12		56	14521	2252	12072	197	15%	137:37:53

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	2.7 MB	153.9 KB	2.8 MB	94%	3.4 KB
2	news.samoylyk.net	2.1 MB	0.0 KB	2.1 MB	100%	111.9 KB
3	i2pn.org	1.6 MB	282.1 KB	1.9 MB	85%	2.7 KB
4	news.nntp4.net	1.5 MB	22.6 KB	1.5 MB	98%	52.1 KB
5	46.165.242.75	1005.4 KB	66.5 KB	1.0 MB	93%	3.3 KB
6	news.glou.org	918.0 KB	23.8 KB	941.8 KB	97%	40.9 KB
7	2001:41d0:2:abb2::119	573.2 KB	0.0 KB	573.2 KB	100%	3.2 KB
8	gegeweb-out.news.tnetconsulting.net	509.6 KB	12.7 KB	522.2 KB	97%	9.3 KB
9	feed-out.gegeweb.org	345.7 KB	0.0 KB	345.7 KB	100%	3.1 KB
10	usenet.pasdenom.info	326.9 KB	1.3 KB	328.1 KB	99%	2.5 KB
11	nntp.terraraq.uk	16.5 KB	0.0 KB	16.5 KB	100%	4.1 KB
12	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 12		11.5 MB	562.8 KB	12.1 MB	95%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 25 04:15:01 - 04:59:59	34	1.3%	0.01	119.1 KB	0.8%	0.04
Dec 25 05:00:00 - 05:59:59	65	2.4%	0.02	760.0 KB	5.1%	0.21
Dec 25 06:00:00 - 06:59:59	52	1.9%	0.01	198.4 KB	1.3%	0.06
Dec 25 07:00:00 - 07:59:59	64	2.4%	0.02	154.2 KB	1.0%	0.04
Dec 25 08:00:00 - 08:59:59	57	2.1%	0.02	406.6 KB	2.7%	0.11
Dec 25 09:00:00 - 09:59:59	52	1.9%	0.01	145.2 KB	1.0%	0.04
Dec 25 10:00:00 - 10:59:59	71	2.6%	0.02	196.1 KB	1.3%	0.05
Dec 25 11:00:00 - 11:59:59	98	3.6%	0.03	406.0 KB	2.7%	0.11
Dec 25 12:00:00 - 12:59:59	88	3.3%	0.02	464.7 KB	3.1%	0.13
Dec 25 13:00:00 - 13:59:59	110	4.1%	0.03	341.6 KB	2.3%	0.09
Dec 25 14:00:00 - 14:59:59	102	3.8%	0.03	280.0 KB	1.9%	0.08
Dec 25 15:00:00 - 15:59:59	139	5.2%	0.04	380.7 KB	2.5%	0.11
Dec 25 16:00:00 - 16:59:59	127	4.7%	0.04	441.5 KB	3.0%	0.12
Dec 25 17:00:00 - 17:59:59	172	6.4%	0.05	639.0 KB	4.3%	0.18
Dec 25 18:00:00 - 18:59:59	162	6.0%	0.04	670.0 KB	4.5%	0.19
Dec 25 19:00:00 - 19:59:59	186	6.9%	0.05	1.5 MB	10.1%	0.42
Dec 25 20:00:00 - 20:59:59	127	4.7%	0.04	960.2 KB	6.4%	0.27
Dec 25 21:00:00 - 21:59:59	156	5.8%	0.04	1.1 MB	7.5%	0.31
Dec 25 22:00:00 - 22:59:59	166	6.2%	0.05	570.6 KB	3.8%	0.16
Dec 25 23:00:00 - 23:59:59	169	6.3%	0.05	499.4 KB	3.3%	0.14
Dec 26 00:00:00 - 00:59:59	100	3.7%	0.03	351.1 KB	2.3%	0.10
Dec 26 01:00:00 - 01:59:59	91	3.4%	0.03	340.5 KB	2.3%	0.09
Dec 26 02:00:00 - 02:59:59	96	3.6%	0.03	305.0 KB	2.0%	0.08
Dec 26 03:00:00 - 03:59:59	176	6.6%	0.05	2.7 MB	18.7%	0.78
Dec 26 04:00:00 - 04:15:02	25	0.9%	0.03	898.6 KB	6.0%	1.00
TOTAL: 24:00:01	2685	100.0%	0.03	14.6 MB	100.0%	0.17

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	152	0	0	152
2	news.nntp4.net	99	0	0	99
3	46.165.242.75	31	1	0	30
4	news-out.aioe.org	30	1	0	29
5	news.glou.org	10	0	0	10
6	news.samoylyk.net	10	0	1	9
7	nntp.terraraq.uk	9	0	0	9
8	usenet.pasdenom.info	3	0	0	3
9	2001:41d0:2:abb2::119	3	0	0	3
10	gegeweb-out.news.tnetconsulting.net	1	0	0	1
11	feed-out.gegeweb.org	1	0	0	1
TOTAL: 11		349	2	1	346

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
soc.culture.russia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	90
Bad path (news.giganews.com.POSTED)	85
EMP (md5)	65
EMP (phn nph)	62
Too many newsgroups	18
Cancels abusifs	3
Cancel of non-existing ID <4eeb87a6-d0b5-44d3-ba86-d2552ed29895n@googlegroups.com>	1
Cancel of non-existing ID <f4f4b5e5-37de-4571-af3f-8531b9976fc8n@googlegroups.com>	1
Cancel of non-existing ID <c054e090-cf49-42ad-be78-f1b23faf2d52n@googlegroups.com>	1
Cancel of non-existing ID <18ce4529-017c-4456-9446-7d717ae4ac77n@googlegroups.com>	1
TOTAL: 10	327

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	2	0	2	2
TOTAL: 1	2	0	2	2

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	8
	news.nntp4.net	4
	2001:41d0:2:abb2::119	3
	news-out.aioe.org	2
	feed-out.gegeweb.org	1
	i2pn.org	1
	TOTAL: 6	19
TOTAL: 1		19

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	2752	11	2608	0	0	0%	23:56:13
2	i2pn.org	3181	8	1886	1	11	0%	23:47:49
3	news.tnetconsulting.net	4012	2	2665	1	18	0%	23:56:13
4	news.gegeweb.org	2383	2	2340	3	0	0%	23:56:13
5	news.aioe.org	1194	1	1104	1	0	0%	23:33:01
6	news.pasdenom.info	2450	1	2263	0	0	0%	23:56:13
7	nntp.terraraq.uk	1872	1	1670	16	0	0%	23:53:03
8	news.samoylyk.net	3071	0	2660	1	0	0%	23:56:13
TOTAL: 8		20915	26	17196	23	29	0%	190:54:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	860.0 KB	0.0 KB	860.0 KB	78.2 KB	23:56:13
2	i2pn.org	24.0 KB	3.7 KB	27.7 KB	3.1 KB	23:47:49
3	news.gegeweb.org	4.9 KB	12.5 KB	17.4 KB	3.5 KB	23:56:13
4	news.tnetconsulting.net	4.8 KB	3.7 KB	8.5 KB	2.8 KB	23:56:13
5	nntp.terraraq.uk	3.7 KB	1.4 MB	1.4 MB	84.3 KB	23:53:03
6	news.aioe.org	2.8 KB	3.7 KB	6.4 KB	3.2 KB	23:33:01
7	news.pasdenom.info	1.6 KB	0.0 KB	1.6 KB	1.6 KB	23:56:13
8	news.samoylyk.net	0.0 KB	3.7 KB	3.7 KB	3.7 KB	23:56:13
TOTAL: 8		901.7 KB	1.4 MB	2.3 MB	48.1 KB	190:54:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	2a0e:e701:1154:0:7570:6d01:199e:6d6b	2	7	15.8 KB	2	0	0	00:02:19
TOTAL: 1		2	7	15.8 KB	2	0	0	00:23:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	?	89	7	15.8 KB	2	00:03:53
2	unresolved	5	0	0.0 KB	0	00:00:21
3	*.gegeweb.eu	287	0	0.0 KB	0	00:00:33
4	*.nntp4.net	582	0	0.0 KB	0	00:00:58
5	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	00:00:01
6	*.org	578	0	0.0 KB	0	00:02:59
7	*.glou.org	578	0	0.0 KB	0	00:03:18
8	*.tnetconsulting.net	573	0	0.0 KB	0	00:05:55
9	*.samoylyk.net	1119	0	0.0 KB	0	00:03:06
10	*.terraraq.uk	582	0	0.0 KB	0	00:02:00
TOTAL: 10		4394	7	15.8 KB	2	00:23:11

NNRP auth users [Top 20]:

User	Conn
mailman	288
gerald	2
TOTAL: 2	290

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tncsrv06.tnetconsulting.net	15.546	12.928	0.000	00:05:55
news.glou.org	15.484	12.901	0.000	00:03:18
server.samoylyk.net	30.209	25.087	0.000	00:03:06
i2pn.org	15.123	12.949	0.000	00:02:59
2a0e:e701:1154:0:7570:6d01:199e:6d6b	0.121	0.056	0.012	00:02:19
nntp.terraraq.uk	15.671	13.385	0.000	00:02:00
2a03:f80:ed31:8c5a::1	0.745	0.689	0.000	00:01:27
news.nntp4.net	15.476	13.107	0.000	00:00:58
mail.gegeweb.eu	8.085	7.348	2.448	00:00:33
174.138.60.27	0.026	0.026	0.000	00:00:10
192.241.209.158	0.031	0.021	0.000	00:00:09
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	1.558	1.419	0.000	00:00:07
ecstatic.monitoring.internet-measurement.com	0.021	0.032	0.000	00:00:01
150.129.136.38	0.016	0.032	0.000	00:00:00
92.255.85.183	0.055	0.046	0.000	00:00:00
2001:41d0:2:abb2::25	0.019	0.039	0.006	00:00:00
TOTAL: 16	118.186	100.065	2.466	00:23:11

Curious NNRP server explorers [Top 20]:

System	Conn
server.samoylyk.net	1119
nntp.terraraq.uk	582
news.nntp4.net	582
i2pn.org	578
news.glou.org	578
tncsrv06.tnetconsulting.net	573
mail.gegeweb.eu	287
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	58
2a03:f80:ed31:8c5a::1	28
92.255.85.183	2
2001:41d0:2:abb2::25	1
150.129.136.38	1
ecstatic.monitoring.internet-measurement.com	1
174.138.60.27	1
192.241.209.158	1
TOTAL: 15	4392

NNRP unrecognized commands (by host):

System	Conn
92.255.85.183	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
#003	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
92.255.85.183	2	2
TOTAL: 1	2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	6	85.7%
2	grisbi	1	14.3%
TOTAL: 2		7	100.0%

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

Newsgroup		Count
1	fr.comp.sys.raspberry-pi	6
2	grisbi.test	1
TOTAL: 2		7

Daily Usenet report for news.niel.me

Dec 25 04:15:01 -- Dec 26 04:15:02