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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 10 11:56:01 rpi-studio innd: filter: No Cancel-Key[sha1:AJoOpZVC2JHqK09f17hjq8pSu50=] matches Cancel-Lock[sha1:aD/23/7+KnsaxxlvAZZ27EBr2TU=] target=<654e0c00$0$2979$426a74cc@news.free.fr>
Nov 11 01:30:03 rpi-studio innd: filter: No Cancel-Key[sha1:9PimkGiMwq+ZztnGK3KOidW2y8I=] matches Cancel-Lock[sha1:OZjOW9GO2R7eCCRw6iG4bA1rNyM=] target=<de-gruppen-finden-04.11.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	29021	68.4%	5.5 MB	75.7%
innd	8729	20.6%	1.1 MB	15.6%
nnrpd	2659	6.3%	295.5 KB	4.0%
innfeed	2030	4.8%	349.7 KB	4.7%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	42441	100.0%	7.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	52524	40.7%
Negative hits	47559	36.9%
Do not exist	28850	22.4%
Cache misses	128	0.1%
TOTAL: 4	129061	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.007	0.0%	9	0.000	0.778	1.000
article cancel	00:00:00.004	0.0%	10	0.000	0.400	3.000
article cleanup	00:00:00.658	0.0%	29030	0.000	0.023	0.066
article logging	00:00:02.257	0.0%	29030	0.034	0.078	0.130
article parse	00:00:08.611	0.0%	150638	0.026	0.057	0.088
article write	00:00:15.780	0.0%	22348	0.448	0.706	0.949
artlog/artcncl	00:00:00.001	0.0%	10	0.000	0.100	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	9	0.000	0.000	0.000
data move	00:00:03.748	0.0%	287665	0.004	0.013	0.023
hisgrep/artcncl	00:00:00.001	0.0%	10	0.000	0.100	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	13	0.000	0.000	0.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%	9	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.866	0.0%	129857	0.005	0.022	0.734
history sync	00:00:00.050	0.0%	383	0.000	0.131	0.667
history write	00:00:06.590	0.0%	29029	0.099	0.227	0.363
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:57:17.439	99.7%	250612	174.180	344.107	786.562
nntp read	00:00:07.761	0.0%	250160	0.018	0.031	0.053
overview write	00:00:09.962	0.0%	22348	0.154	0.446	28.088
perl filter	00:01:52.181	0.1%	29029	3.059	3.864	5.408
python filter	00:00:00.116	0.0%	29029	0.000	0.004	0.035
site send	00:00:06.200	0.0%	46073	0.049	0.135	0.287
TOTAL: 24:00:56.668	24:00:14.232	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.206	0.0%	22318	0.000	0.009	0.035
article prepare	00:00:00.018	0.0%	5164	0.000	0.003	0.050
article read	00:00:00.169	0.0%	965	0.000	0.175	3.000
backlog stats	00:00:01.481	0.0%	331459	0.001	0.004	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:05.292	0.0%	229456	0.010	0.023	0.042
data write	00:00:09.176	0.0%	179820	0.032	0.051	0.077
idle	23:54:26.031	100.0%	331459	122.919	259.658	637.001
status file	00:00:00.478	0.0%	218	1.000	2.193	9.000
TOTAL: 23:54:59.077	23:54:42.851	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.598	0.0%	35188	0.000	0.045	0.500
idle	00:01:05.218	0.1%	1444	30.800	45.165	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.589	0.0%	73981	0.000	0.049	0.500
TOTAL: 29:25:23.995	00:01:10.405	0.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.nntp4.net	5	28243	11335	11349	5559	40%	24:22:47
2	gegeweb-out.news.tnetconsulting.net	2	22437	5542	16776	119	24%	24:36:50
3	news.glou.org	2	10818	3368	6618	832	31%	23:39:26
4	feed-out.gegeweb.org	3	3609	1354	2255	0	37%	23:59:39
5	nntp.terraraq.uk	1	6266	316	5854	96	5%	24:15:02
6	usenet.pasdenom.info	1	2468	209	2252	7	8%	22:01:25
7	i2pn.org	2	4012	202	3808	2	5%	24:21:19
8	news.samoylyk.net	1	142	0	142	0	0%	01:10:06
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		42	77995	22326	49054	6615	28%	168:26:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	128.0 MB	0.0 KB	49.6 MB	177.5 MB	72%	10.8 KB
2	gegeweb-out.news.tnetconsulting.net	47.7 MB	6.0 KB	536.8 KB	48.2 MB	98%	8.7 KB
3	news.glou.org	20.9 MB	0.0 KB	4.9 MB	25.7 MB	81%	6.3 KB
4	feed-out.gegeweb.org	13.8 MB	0.0 KB	0.0 KB	13.8 MB	100%	10.4 KB
5	i2pn.org	2.4 MB	0.0 KB	3.9 KB	2.4 MB	99%	11.9 KB
6	nntp.terraraq.uk	1.4 MB	0.0 KB	925.6 KB	2.3 MB	61%	5.8 KB
7	usenet.pasdenom.info	694.5 KB	0.0 KB	11.0 KB	705.5 KB	98%	3.3 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		214.8 MB	6.0 KB	55.9 MB	270.7 MB	79%	9.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 10 04:15:01 - 04:59:59	639	2.9%	0.24	6.2 MB	2.9%	2.36
Nov 10 05:00:00 - 05:59:59	923	4.1%	0.26	10.6 MB	5.0%	3.03
Nov 10 06:00:00 - 06:59:59	1240	5.6%	0.34	16.1 MB	7.5%	4.59
Nov 10 07:00:00 - 07:59:59	1396	6.2%	0.39	16.0 MB	7.5%	4.56
Nov 10 08:00:00 - 08:59:59	1389	6.2%	0.39	15.4 MB	7.2%	4.37
Nov 10 09:00:00 - 09:59:59	1621	7.3%	0.45	16.3 MB	7.6%	4.63
Nov 10 10:00:00 - 10:59:59	1550	6.9%	0.43	16.4 MB	7.6%	4.67
Nov 10 11:00:00 - 11:59:59	1163	5.2%	0.32	12.9 MB	6.0%	3.67
Nov 10 12:00:00 - 12:59:59	1181	5.3%	0.33	11.1 MB	5.2%	3.17
Nov 10 13:00:00 - 13:59:59	1075	4.8%	0.30	10.8 MB	5.0%	3.07
Nov 10 14:00:00 - 14:59:59	1158	5.2%	0.32	10.2 MB	4.7%	2.90
Nov 10 15:00:00 - 15:59:59	999	4.5%	0.28	9.2 MB	4.3%	2.61
Nov 10 16:00:00 - 16:59:59	984	4.4%	0.27	9.7 MB	4.5%	2.77
Nov 10 17:00:00 - 17:59:59	683	3.1%	0.19	6.8 MB	3.2%	1.95
Nov 10 18:00:00 - 18:59:59	727	3.3%	0.20	5.7 MB	2.7%	1.63
Nov 10 19:00:00 - 19:59:59	703	3.1%	0.20	5.4 MB	2.5%	1.53
Nov 10 20:00:00 - 20:59:59	601	2.7%	0.17	6.1 MB	2.8%	1.72
Nov 10 21:00:00 - 21:59:59	618	2.8%	0.17	3.9 MB	1.8%	1.11
Nov 10 22:00:00 - 22:59:59	675	3.0%	0.19	3.9 MB	1.8%	1.10
Nov 10 23:00:00 - 23:59:59	525	2.4%	0.15	3.3 MB	1.5%	0.94
Nov 11 00:00:00 - 00:59:59	547	2.4%	0.15	3.3 MB	1.5%	0.93
Nov 11 01:00:00 - 01:59:59	586	2.6%	0.16	4.2 MB	2.0%	1.19
Nov 11 02:00:00 - 02:59:59	570	2.6%	0.16	3.6 MB	1.7%	1.03
Nov 11 03:00:00 - 03:59:59	590	2.6%	0.16	5.6 MB	2.6%	1.58
Nov 11 04:00:00 - 04:15:01	194	0.9%	0.22	2.1 MB	1.0%	2.34
TOTAL: 24:00:00	22337	100.0%	0.26	214.7 MB	100.0%	2.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	5583	0	0	5583
2	news.glou.org	847	1	0	846
3	gegeweb-out.news.tnetconsulting.net	115	0	1	114
4	nntp.terraraq.uk	110	0	0	110
5	usenet.pasdenom.info	8	0	0	8
6	i2pn.org	2	1	0	1
TOTAL: 6		6665	2	1	6662

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.lang.go	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6417
Bad path (news.giganews.com.POSTED)	133
EMP (phl)	56
EMP (phn path)	29
Too many newsgroups	20
Cancels abusifs	2
Too many newsgroups (meow)	1
Cancel of non-existing ID <kr6453FbscrU1@mid.individual.net>	1
Cancel for rejected article	1
Cancel of <1796431bbf3bf2bc$766888$821165$a92e2ab9@news.vipernews.com> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<654e0c00$0$2979$426a74cc@news.free.fr>	1
TOTAL: 11	6662

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	12741	197	10790	1	0	1%	23:59:52
2	i2pn.org	33245	140	21249	445	33	0%	23:59:55
3	nntp.terraraq.uk	23781	95	20991	152	0	0%	23:59:55
4	news.tnetconsulting.net	34674	68	22246	21	1	0%	23:59:51
5	news.samoylyk.net	31323	15	22189	123	12	0%	23:59:51
6	news.gegeweb.org	21032	3	20917	29	0	0%	23:59:45
7	news.pasdenom.info	23245	0	21925	5	0	0%	23:59:53
8	news.aioe.org	0	0	0	0	5276	0%	23:59:55
TOTAL: 8		180041	518	140307	776	5322	0%	191:58:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	1.0 MB	5.4 KB	1.0 MB	0.0 KB/s	5.4 KB	23:59:52
2	i2pn.org	1.0 MB	4.0 MB	5.0 MB	0.1 KB/s	8.8 KB	23:59:55
3	nntp.terraraq.uk	525.4 KB	1.1 MB	1.6 MB	0.0 KB/s	6.5 KB	23:59:55
4	news.tnetconsulting.net	382.8 KB	72.7 KB	455.5 KB	0.0 KB/s	5.1 KB	23:59:51
5	news.samoylyk.net	69.9 KB	776.6 KB	846.5 KB	0.0 KB/s	6.1 KB	23:59:51
6	news.gegeweb.org	24.5 KB	310.0 KB	334.5 KB	0.0 KB/s	10.5 KB	23:59:45
7	news.pasdenom.info	0.0 KB	29.0 KB	29.0 KB	0.0 KB/s	5.8 KB	23:59:53
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 8		3.1 MB	6.2 MB	9.3 MB	0.0 KB/s	7.3 KB	191:58:57

NNRP readership statistics:

System		Conn	Elapsed
1	dignified.monitoring.internet-measurement.com	1	00:00:05
2	192.241.234.45	1	00:00:09
3	mail.gegeweb.eu	288	00:01:59
4	robust.monitoring.internet-measurement.com	1	00:00:00
5	talented.monitoring.internet-measurement.com	1	00:00:00
6	kind.monitoring.internet-measurement.com	1	00:00:00
7	63.135.72.148	8	00:21:11
8	splendid.monitoring.internet-measurement.com	1	00:00:00
9	107.170.251.41	1	00:00:01
10	193.56.252.243	112	16:30:36
11	181.215.176.7	24	14:54:39
12	104.152.52.189	1	00:00:10
TOTAL: 12		440	31:48:55

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:59
2	unresolved	147	31:46:49
3	*.monitoring.internet-measurement.com	5	00:00:06
TOTAL: 3		440	31:48:55

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
193.56.252.243	7.298	5.368	0.000	16:30:36
181.215.176.7	3.445	3.575	0.000	14:54:39
63.135.72.148	0.467	0.262	0.000	00:21:11
mail.gegeweb.eu	4.645	4.329	1.669	00:01:59
104.152.52.189	0.019	0.030	0.000	00:00:10
192.241.234.45	0.037	0.016	0.000	00:00:09
dignified.monitoring.internet-measurement.com	0.044	0.028	0.000	00:00:05
107.170.251.41	0.025	0.029	0.000	00:00:01
kind.monitoring.internet-measurement.com	0.036	0.028	0.000	00:00:00
robust.monitoring.internet-measurement.com	0.047	0.016	0.000	00:00:00
splendid.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
TOTAL: 11	16.083	13.689	1.669	31:48:55

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
107.170.251.41	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
181.215.176.7	16	0
TOTAL: 1	16	0

Daily Usenet report for news.niel.me

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