Daily Usenet report for news.niel.me: Apr 12 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 12 13:46:06 rpi-studio innd: filter: No Cancel-Key[sha1:Hql4kBK4oNUvep6OW5OQpZOIrw0=] matches Cancel-Lock[sha1:aJ9PG42ASoSAb7D731S13kzQihU=] target=<t33o18$3via3$35@news.freedyn.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	5410	45.4%	949.1 KB	49.9%
innd	3184	26.7%	488.3 KB	25.7%
innfeed	1681	14.1%	304.9 KB	16.0%
nnrpd	1640	13.8%	158.2 KB	8.3%
nocem	7	0.1%	0.6 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	11923	100.0%	1.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	25801	61.2%
Negative hits	11042	26.2%
Do not exist	5340	12.7%
Cache misses	3	0.0%
TOTAL: 4	42186	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.026	0.0%	17	0.500	1.529	4.333
article cancel	00:00:00.005	0.0%	24	0.000	0.208	1.000
article cleanup	00:00:00.089	0.0%	5345	0.000	0.017	0.154
article logging	00:00:00.388	0.0%	5369	0.000	0.072	0.222
article parse	00:00:01.004	0.0%	12120	0.000	0.083	0.261
article write	00:00:04.066	0.0%	4097	0.091	0.992	146.222
artlog/artcncl	00:00:00.002	0.0%	23	0.000	0.087	0.500
artlog/artcncl/perl	00:00:00.002	0.0%	17	0.000	0.118	1.000
data move	00:00:00.132	0.0%	47919	0.000	0.003	0.016
hisgrep/artcncl	00:00:00.002	0.0%	24	0.000	0.083	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	17	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	30	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	24	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	17	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.563	0.0%	42195	0.000	0.013	0.049
history sync	00:00:00.052	0.0%	416	0.000	0.125	1.000
history write	00:00:01.785	0.0%	5345	0.000	0.334	3.711
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:16.318	99.9%	48506	259.347	1781.559	9244.508
nntp read	00:00:01.518	0.0%	47959	0.004	0.032	0.099
overview write	00:00:02.753	0.0%	4097	0.077	0.672	7.500
perl filter	00:00:25.387	0.0%	5345	1.830	4.750	9.762
python filter	00:00:00.010	0.0%	5345	0.000	0.002	0.045
site send	00:00:01.471	0.0%	8254	0.000	0.178	0.556
TOTAL: 24:01:13.165	24:00:55.573	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.053	0.0%	4345	0.000	0.012	0.143
article prepare	00:00:00.000	0.0%	148	0.000	0.000	0.000
article read	00:00:00.010	0.0%	35	0.000	0.286	4.000
backlog stats	00:00:00.137	0.0%	58926	0.000	0.002	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.913	0.0%	36467	0.000	0.025	0.071
data write	00:00:02.044	0.0%	32533	0.022	0.063	0.118
idle	23:58:47.963	100.0%	58926	504.393	1465.023	4145.263
status file	00:00:00.459	0.0%	169	1.000	2.716	15.000
TOTAL: 23:58:54.981	23:58:51.579	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:27.291	21.1%	1457	16.600	18.731	101.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.125	0.1%	3239	0.000	0.039	3.000
TOTAL: 00:02:09.509	00:00:27.416	21.2%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	4164	2244	1805	115	53%	24:17:21
2	i2pn.org	1	5827	925	4743	159	15%	32:22:29
3	feed-out.gegeweb.org	2	2893	483	2408	2	16%	23:59:27
4	usenet.pasdenom.info	1	2586	247	2256	83	9%	22:10:00
5	news.samoylyk.net	1	2202	57	2111	34	2%	10:47:55
6	nntp.terraraq.uk	1	3475	57	3373	45	1%	23:59:55
7	news.nntp4.net	1	4182	49	3196	937	1%	19:39:06
8	gegeweb-out.news.tnetconsulting.net	2	3903	17	3882	4	0%	23:59:54
9	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 9		38	29232	4079	23774	1379	13%	181:16:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	7.1 MB	0.0 KB	344.5 KB	7.4 MB	95%	3.2 KB
2	i2pn.org	3.6 MB	0.0 KB	571.6 KB	4.1 MB	86%	3.9 KB
3	feed-out.gegeweb.org	1.8 MB	0.0 KB	2.0 KB	1.8 MB	99%	3.9 KB
4	news.nntp4.net	1.7 MB	0.0 KB	3.7 MB	5.4 MB	31%	5.6 KB
5	news.samoylyk.net	823.6 KB	0.0 KB	84.1 KB	907.7 KB	90%	10.0 KB
6	gegeweb-out.news.tnetconsulting.net	715.1 KB	39.3 KB	48.2 KB	802.6 KB	89%	38.2 KB
7	usenet.pasdenom.info	623.0 KB	0.0 KB	202.1 KB	825.1 KB	75%	2.5 KB
8	nntp.terraraq.uk	230.4 KB	0.0 KB	116.3 KB	346.7 KB	66%	3.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		16.5 MB	39.3 KB	5.0 MB	21.6 MB	76%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 12 04:15:01 - 04:59:59	65	1.6%	0.02	318.3 KB	1.5%	0.12
Apr 12 05:00:00 - 05:59:59	105	2.6%	0.03	377.1 KB	1.8%	0.10
Apr 12 06:00:00 - 06:59:59	100	2.4%	0.03	353.2 KB	1.7%	0.10
Apr 12 07:00:00 - 07:59:59	83	2.0%	0.02	239.3 KB	1.2%	0.07
Apr 12 08:00:00 - 08:59:59	69	1.7%	0.02	198.8 KB	1.0%	0.06
Apr 12 09:00:00 - 09:59:59	103	2.5%	0.03	276.2 KB	1.3%	0.08
Apr 12 10:00:00 - 10:59:59	165	4.0%	0.05	513.2 KB	2.5%	0.14
Apr 12 11:00:00 - 11:59:59	159	3.9%	0.04	761.6 KB	3.7%	0.21
Apr 12 12:00:00 - 12:59:59	201	4.9%	0.06	1.3 MB	6.5%	0.38
Apr 12 13:00:00 - 13:59:59	224	5.5%	0.06	669.9 KB	3.2%	0.19
Apr 12 14:00:00 - 14:59:59	171	4.2%	0.05	621.3 KB	3.0%	0.17
Apr 12 15:00:00 - 15:59:59	167	4.1%	0.05	503.4 KB	2.4%	0.14
Apr 12 16:00:00 - 16:59:59	181	4.4%	0.05	598.7 KB	2.9%	0.17
Apr 12 17:00:00 - 17:59:59	168	4.1%	0.05	601.5 KB	2.9%	0.17
Apr 12 18:00:00 - 18:59:59	210	5.1%	0.06	687.1 KB	3.3%	0.19
Apr 12 19:00:00 - 19:59:59	187	4.6%	0.05	609.7 KB	2.9%	0.17
Apr 12 20:00:00 - 20:59:59	220	5.4%	0.06	838.5 KB	4.0%	0.23
Apr 12 21:00:00 - 21:59:59	417	10.2%	0.12	6.5 MB	32.2%	1.86
Apr 12 22:00:00 - 22:59:59	212	5.2%	0.06	1.4 MB	6.8%	0.39
Apr 12 23:00:00 - 23:59:59	233	5.7%	0.06	633.0 KB	3.0%	0.18
Apr 13 00:00:00 - 00:59:59	191	4.7%	0.05	761.3 KB	3.7%	0.21
Apr 13 01:00:00 - 01:59:59	187	4.6%	0.05	677.3 KB	3.3%	0.19
Apr 13 02:00:00 - 02:59:59	127	3.1%	0.04	610.4 KB	2.9%	0.17
Apr 13 03:00:00 - 03:59:59	117	2.9%	0.03	382.5 KB	1.8%	0.11
Apr 13 04:00:00 - 04:15:01	35	0.9%	0.04	101.7 KB	0.5%	0.11
TOTAL: 24:00:00	4097	100.0%	0.05	20.3 MB	100.0%	0.24

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	873	0	873
2	news-out.aioe.org	135	3	132
3	i2pn.org	101	0	101
4	usenet.pasdenom.info	69	0	69
5	nntp.terraraq.uk	64	0	64
6	news.samoylyk.net	27	0	27
7	feed-out.gegeweb.org	3	0	3
8	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 8		1273	3	1270

Unwanted newsgroups [Top 20]:

Newsgroup	Count
soc.men.alt.feminism	1
soc.culture.russia	1
news.test	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	694
EMP (md5)	193
Bad path (news.giganews.com.POSTED)	136
EMP (phn nph)	89
EMP (phn path)	70
EMP (phl)	24
Cancel for rejected article	18
User-issued spam cancel	9
Cancel of <mn.62b47e64e47e7be8.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.62b17e64a9b6ba18.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.52817e6417505aed.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.52807e648eb2d7c6.145333@zorro.live.invalid> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<t33o18$3via3$35@news.freedyn.de>	1
Cancel of <mn.62ae7e64cd8b414b.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.62b27e64242d4f25.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.62b17e6410271022.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.52827e6451010a5b.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.62b07e648d51166f.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.528b7e64c1a29d37.145333@zorro.live.invalid> without Cancel-Key	1
Cancel of <mn.5ae67e6423ae6239.145333@zorro.live.invalid> without Cancel-Key	1
TOTAL: 21	1246

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	22
	news.samoylyk.net	1
	feed-out.gegeweb.org	1
	TOTAL: 3	24
TOTAL: 1		24

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	6047	13	3254	0	125	0%	23:59:56
2	news.tnetconsulting.net	6850	12	4072	1	126	0%	23:59:33
3	news.nntp4.net	4250	6	3987	0	0	0%	23:59:56
4	news.pasdenom.info	3628	1	3510	1	0	0%	23:59:56
5	news.gegeweb.org	3681	1	3608	2	0	0%	23:59:56
6	news.aioe.org	1455	0	1373	0	1	0%	23:57:52
7	news.samoylyk.net	4170	0	3952	0	0	0%	23:59:26
8	nntp.terraraq.uk	2907	0	2864	0	0	0%	23:59:56
TOTAL: 8		32988	33	26620	4	252	0%	191:56:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	40.9 KB	8.4 KB	49.3 KB	3.8 KB	23:59:33
2	news.nntp4.net	28.4 KB	0.0 KB	28.4 KB	4.7 KB	23:59:56
3	i2pn.org	25.9 KB	0.0 KB	25.9 KB	2.0 KB	23:59:56
4	news.gegeweb.org	12.8 KB	7.2 KB	20.1 KB	6.7 KB	23:59:56
5	news.pasdenom.info	4.0 KB	12.1 KB	16.2 KB	8.1 KB	23:59:56
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:57:52
7	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:26
8	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		112.2 KB	27.8 KB	140.0 KB	3.8 KB	191:56:31

NNRP readership statistics:

System		Conn	Elapsed
1	88.165.5.23	5	00:00:02
2	185.220.101.73	2	00:00:00
3	tor-exit34.stormycloud.org	5	00:00:04
4	tor-exit.riverside.rocks	2	00:00:02
5	mail.gegeweb.eu	288	00:01:30
6	tor-exit-33.for-privacy.net	1	00:00:00
7	45.135.232.161	1	00:00:00
8	23.129.64.143	1	00:00:03
9	23.129.64.219	1	00:00:03
10	23.129.64.215	1	00:00:05
11	23.129.64.138	2	00:00:06
12	tor-exit-16.zbau.f3netze.de	1	00:00:03
13	208.100.26.237	10	00:00:03
TOTAL: 13		320	00:02:08

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:30
2	*.zbau.f3netze.de	1	00:00:03
3	*.riverside.rocks	2	00:00:02
4	*.for-privacy.net	1	00:00:00
5	unresolved	23	00:00:26
6	*.stormycloud.org	5	00:00:04
TOTAL: 6		320	00:02:08

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
mail.gegeweb.eu	7.597	8.349	2.403	00:01:30
23.129.64.138	0.044	0.033	0.000	00:00:06
23.129.64.215	0.061	0.020	0.000	00:00:05
tor-exit34.stormycloud.org	0.237	0.058	0.000	00:00:04
tor-exit-16.zbau.f3netze.de	0.012	0.025	0.000	00:00:03
208.100.26.237	0.241	0.087	0.000	00:00:03
23.129.64.143	0.019	0.019	0.000	00:00:03
23.129.64.219	0.038	0.000	0.000	00:00:03
tor-exit.riverside.rocks	0.129	0.020	0.000	00:00:02
88.165.5.23	0.147	0.133	0.000	00:00:02
185.220.101.73	0.087	0.025	0.000	00:00:00
tor-exit-33.for-privacy.net	0.150	0.011	0.000	00:00:00
45.135.232.161	0.024	0.024	0.000	00:00:00
TOTAL: 13	8.786	8.804	2.403	00:02:08

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	20
TOTAL: 1	20

NNRP unrecognized commands (by host):

System	Conn
45.135.232.161	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.135.232.161	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Apr 12 04:15:01 -- Apr 13 04:15:01