Daily Usenet report for news.niel.me: Oct 13 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 14 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:PslO5hxb0OjueLqILK5KPMjjrcg=] matches Cancel-Lock[sha1:ghGjSnYmE7qxTaB/h4nGOoxPyjY=] target=<de-gruppen-finden-07.10.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45107	76.6%	8.9 MB	83.1%
innd	6934	11.8%	953.8 KB	8.7%
nnrpd	4819	8.2%	552.7 KB	5.1%
innfeed	1997	3.4%	338.5 KB	3.1%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	58859	100.0%	10.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	115321	50.9%
Negative hits	66465	29.3%
Do not exist	44858	19.8%
Cache misses	5	0.0%
TOTAL: 4	226649	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.019	0.0%	13	0.500	1.462	3.000
article cancel	00:00:00.001	0.0%	14	0.000	0.071	0.500
article cleanup	00:00:00.913	0.0%	45130	0.003	0.020	0.049
article logging	00:00:03.476	0.0%	45130	0.034	0.077	0.131
article parse	00:00:11.359	0.0%	213408	0.029	0.053	0.087
article write	00:00:34.454	0.0%	40271	0.550	0.856	1.730
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	13	0.000	0.077	1.000
data move	00:00:05.974	0.0%	467655	0.006	0.013	0.020
hisgrep/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	13	0.000	0.077	0.333
hisgrep/perl	00:00:00.005	0.0%	14	0.000	0.357	2.500
hishave/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	13	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:03.913	0.0%	227615	0.007	0.017	0.027
history sync	00:00:00.058	0.0%	373	0.000	0.155	0.667
history write	00:00:08.950	0.0%	45047	0.109	0.199	0.298
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:55:44.994	99.6%	394696	132.238	218.257	412.160
nntp read	00:00:11.617	0.0%	394604	0.019	0.029	0.046
overview write	00:00:11.566	0.0%	40271	0.174	0.287	0.620
perl filter	00:02:22.942	0.2%	45047	2.754	3.173	3.991
python filter	00:00:00.192	0.0%	45047	0.000	0.004	0.016
site send	00:00:10.270	0.0%	85000	0.039	0.121	0.203
TOTAL: 24:00:58.369	23:59:50.705	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.340	0.0%	40465	0.000	0.008	0.036
article prepare	00:00:00.104	0.0%	24572	0.000	0.004	0.023
article read	00:00:00.552	0.0%	3051	0.000	0.181	0.750
backlog stats	00:00:02.757	0.0%	622164	0.001	0.004	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:09.330	0.0%	420369	0.013	0.022	0.034
data write	00:00:16.443	0.0%	335270	0.029	0.049	0.063
idle	23:50:46.533	99.9%	622164	83.442	137.981	262.401
status file	00:00:00.487	0.0%	221	1.000	2.204	8.000
TOTAL: 23:51:44.377	23:51:16.546	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.592	0.0%	110123	0.000	0.051	1.000
idle	00:00:16.597	0.0%	1437	10.400	11.550	25.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.682	0.0%	224887	0.000	0.047	1.000
TOTAL: 77:41:22.149	00:00:32.871	0.0%	-	-	-	-

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.nntp4.net	4	39153	21802	14197	3154	55%	24:20:48
2	news.glou.org	3	12995	4912	6906	1177	37%	24:19:09
3	news.samoylyk.net	4	39679	4547	35131	1	11%	24:00:46
4	feed-out.gegeweb.org	4	6215	4394	1809	12	70%	23:04:35
5	gegeweb-out.news.tnetconsulting.net	2	40904	3622	37235	47	8%	24:48:09
6	nntp.terraraq.uk	1	10630	701	9531	398	6%	21:49:48
7	usenet.pasdenom.info	2	8216	300	7870	46	3%	38:43:36
8	i2pn.org	1	5233	11	5221	1	0%	23:59:57
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		46	163025	40289	117900	4836	24%	205:06:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	197.4 MB	7.0 KB	22.6 MB	219.9 MB	89%	9.0 KB
2	news.samoylyk.net	44.5 MB	4.0 KB	4.0 KB	44.5 MB	99%	10.0 KB
3	gegeweb-out.news.tnetconsulting.net	29.4 MB	2.4 KB	253.3 KB	29.6 MB	99%	8.3 KB
4	news.glou.org	26.1 MB	71.0 KB	6.5 MB	32.6 MB	79%	5.5 KB
5	feed-out.gegeweb.org	17.5 MB	110.6 KB	110.6 KB	17.7 MB	98%	4.1 KB
6	nntp.terraraq.uk	5.8 MB	60.3 KB	2.8 MB	8.7 MB	66%	8.1 KB
7	usenet.pasdenom.info	701.9 KB	55.5 KB	93.1 KB	850.6 KB	82%	2.5 KB
8	i2pn.org	36.1 KB	0.0 KB	1.2 KB	37.4 KB	96%	3.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		321.4 MB	310.8 KB	32.3 MB	354.0 MB	90%	8.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:01 - 04:59:59	776	1.9%	0.29	6.3 MB	2.0%	2.39
Oct 13 05:00:00 - 05:59:59	1195	3.0%	0.33	9.8 MB	3.1%	2.80
Oct 13 06:00:00 - 06:59:59	2453	6.1%	0.68	21.1 MB	6.6%	6.01
Oct 13 07:00:00 - 07:59:59	2004	5.0%	0.56	17.4 MB	5.4%	4.95
Oct 13 08:00:00 - 08:59:59	1760	4.4%	0.49	16.5 MB	5.1%	4.70
Oct 13 09:00:00 - 09:59:59	1649	4.1%	0.46	13.7 MB	4.3%	3.88
Oct 13 10:00:00 - 10:59:59	1636	4.1%	0.45	12.7 MB	4.0%	3.62
Oct 13 11:00:00 - 11:59:59	2239	5.6%	0.62	17.4 MB	5.4%	4.96
Oct 13 12:00:00 - 12:59:59	2027	5.0%	0.56	15.7 MB	4.9%	4.45
Oct 13 13:00:00 - 13:59:59	1772	4.4%	0.49	13.1 MB	4.1%	3.73
Oct 13 14:00:00 - 14:59:59	1519	3.8%	0.42	11.1 MB	3.4%	3.15
Oct 13 15:00:00 - 15:59:59	2257	5.6%	0.63	19.3 MB	6.0%	5.48
Oct 13 16:00:00 - 16:59:59	1790	4.5%	0.50	14.1 MB	4.4%	4.02
Oct 13 17:00:00 - 17:59:59	1730	4.3%	0.48	13.2 MB	4.1%	3.74
Oct 13 18:00:00 - 18:59:59	1703	4.2%	0.47	13.0 MB	4.0%	3.69
Oct 13 19:00:00 - 19:59:59	1644	4.1%	0.46	13.3 MB	4.1%	3.78
Oct 13 20:00:00 - 20:59:59	1854	4.6%	0.52	15.1 MB	4.7%	4.28
Oct 13 21:00:00 - 21:59:59	1647	4.1%	0.46	12.6 MB	3.9%	3.59
Oct 13 22:00:00 - 22:59:59	1712	4.3%	0.48	13.3 MB	4.1%	3.78
Oct 13 23:00:00 - 23:59:59	1593	4.0%	0.44	12.7 MB	4.0%	3.63
Oct 14 00:00:00 - 00:59:59	1326	3.3%	0.37	10.1 MB	3.2%	2.88
Oct 14 01:00:00 - 01:59:59	1246	3.1%	0.35	9.8 MB	3.1%	2.79
Oct 14 02:00:00 - 02:59:59	1318	3.3%	0.37	9.9 MB	3.1%	2.80
Oct 14 03:00:00 - 03:59:59	1136	2.8%	0.32	8.5 MB	2.6%	2.41
Oct 14 04:00:00 - 04:15:02	230	0.6%	0.25	1.5 MB	0.5%	1.69
TOTAL: 24:00:01	40216	100.0%	0.47	321.2 MB	100.0%	3.81

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	3154	1	3	3150
2	news.glou.org	1188	2	32	1154
3	nntp.terraraq.uk	427	0	9	418
4	gegeweb-out.news.tnetconsulting.net	46	3	2	41
5	usenet.pasdenom.info	34	0	24	10
6	feed-out.gegeweb.org	12	0	12	0
7	i2pn.org	2	0	0	2
8	news.samoylyk.net	1	0	1	0
TOTAL: 8		4864	6	83	4775

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	4
comp.lang.go	2
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3525
EMP (phr nph)	998
EMP (phn path)	76
Bad path (news.giganews.com.POSTED)	74
EMP (phl)	53
hierarchy violation - crosspost outside sdnet	14
Too many newsgroups	12
EMP (phn nph)	12
Too many newsgroups (meow)	9
Cancel for rejected article	2
TOTAL: 10	4775

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	20304	1444	16853	2	0	7%	23:59:57
2	nntp.terraraq.uk	41086	1416	36879	307	0	3%	23:59:54
3	news.tnetconsulting.net	69011	798	39287	118	61	1%	23:59:57
4	news.samoylyk.net	54309	351	35354	103	19	0%	23:59:54
5	i2pn.org	69515	320	38534	1256	226	0%	23:59:57
6	news.pasdenom.info	41280	21	39811	38	61	0%	23:59:57
7	news.gegeweb.org	37885	4	35730	10	0	0%	23:59:56
8	news.aioe.org	0	0	0	0	7543	0%	23:59:56
TOTAL: 8		333390	4354	242448	1834	7910	1%	191:59:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	9.0 MB	12.2 KB	9.0 MB	0.1 KB/s	6.4 KB	23:59:57
2	nntp.terraraq.uk	7.0 MB	3.8 MB	10.7 MB	0.1 KB/s	6.4 KB	23:59:54
3	news.tnetconsulting.net	5.3 MB	528.4 KB	5.9 MB	0.1 KB/s	6.5 KB	23:59:57
4	news.samoylyk.net	3.0 MB	488.6 KB	3.5 MB	0.0 KB/s	7.9 KB	23:59:54
5	i2pn.org	1.1 MB	9.0 MB	10.1 MB	0.1 KB/s	6.6 KB	23:59:57
6	news.pasdenom.info	59.9 KB	371.3 KB	431.2 KB	0.0 KB/s	7.3 KB	23:59:57
7	news.gegeweb.org	8.8 KB	88.9 KB	97.7 KB	0.0 KB/s	7.0 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 8		25.5 MB	14.2 MB	39.8 MB	0.1 KB/s	6.6 KB	191:59:28

NNRP readership statistics:

System		Conn	Elapsed
1	193.239.84.94	8	37:37:53
2	193.239.84.67	410	11:04:28
3	80.66.88.215	1	00:00:00
4	194.165.16.37	1	00:00:00
5	212.102.40.218	3	00:00:01
6	mail.gegeweb.eu	287	00:01:47
7	45.133.172.76	72	17:58:47
8	45.133.172.225	10	10:58:18
9	bern.scan.bufferover.run	1	00:00:01
10	69-164-217-74.ip.linodeusercontent.com	1	00:00:00
TOTAL: 10		794	77:41:20

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	1	00:00:00
2	*.scan.bufferover.run	1	00:00:01
3	unresolved	505	77:39:31
4	*.gegeweb.eu	287	00:01:47
TOTAL: 4		794	77:41:20

NNRP auth users [Top 20]:

User	Conn
mailman	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
193.239.84.94	7.631	9.909	0.000	37:37:53
45.133.172.76	6.594	6.810	0.000	17:58:47
193.239.84.67	18.434	9.682	0.000	11:04:28
45.133.172.225	1.399	1.653	0.000	10:58:18
mail.gegeweb.eu	4.575	4.048	1.607	00:01:47
212.102.40.218	0.053	0.032	0.000	00:00:01
bern.scan.bufferover.run	0.057	0.013	0.000	00:00:01
69-164-217-74.ip.linodeusercontent.com	0.048	0.008	0.000	00:00:00
80.66.88.215	0.044	0.012	0.000	00:00:00
194.165.16.37	0.007	0.017	0.000	00:00:00
TOTAL: 10	38.842	32.184	1.607	77:41:20

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
194.165.16.37	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
193.239.84.94	16	0
45.133.172.76	16	0
45.133.172.225	16	0
194.165.16.37	1	1
TOTAL: 4	49	1

Daily Usenet report for news.niel.me

Oct 13 04:15:01 -- Oct 14 04:15:02