Daily Usenet report for news.niel.me: Feb 16 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 17 01:30:03 rpi-studio innd: filter: No Cancel-Key[sha1:pjLQsny7+QuQbtX3enY9eNSl/jg=] matches Cancel-Lock[sha1:sA5eNduXzTb/17eYn1MpsaNo9xw=] target=<de-gruppen-finden-10.02.2024@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21783	75.1%	4.4 MB	80.8%
innd	3241	11.2%	507.4 KB	9.1%
nnrpd	2023	7.0%	222.9 KB	4.0%
innfeed	1965	6.8%	334.8 KB	6.0%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	29014	100.0%	5.4 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	27658	37.1%
Positive hits	25300	33.9%
Do not exist	21561	28.9%
Cache misses	9	0.0%
TOTAL: 4	74528	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	16	0.000	0.688	2.000
article cancel	00:00:00.002	0.0%	18	0.000	0.111	1.000
article cleanup	00:00:00.434	0.0%	21775	0.000	0.020	0.062
article logging	00:00:01.736	0.0%	21775	0.012	0.080	0.165
article parse	00:00:07.950	0.0%	133410	0.033	0.060	0.113
article write	00:00:17.174	0.0%	20490	0.463	0.838	11.512
artlog/artcncl	00:00:00.001	0.0%	18	0.000	0.056	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	16	0.000	0.062	1.000
data move	00:00:01.194	0.0%	216242	0.000	0.006	0.012
hisgrep/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	16	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	20	0.000	0.050	0.500
hishave/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	16	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:01.392	0.0%	74929	0.005	0.019	0.041
history sync	00:00:00.063	0.0%	414	0.000	0.152	1.000
history write	00:00:03.946	0.0%	21712	0.068	0.182	0.292
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:58:32.053	99.8%	186406	141.752	463.033	1319.574
nntp read	00:00:05.462	0.0%	185793	0.012	0.029	0.054
overview write	00:00:07.378	0.0%	20490	0.173	0.360	4.169
perl filter	00:01:12.810	0.1%	21712	2.626	3.353	4.804
python filter	00:00:00.114	0.0%	21712	0.000	0.005	0.020
site send	00:00:05.136	0.0%	42192	0.029	0.122	0.242
TOTAL: 24:01:06.798	24:00:36.858	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.177	0.0%	20855	0.000	0.008	0.039
article prepare	00:00:00.044	0.0%	9760	0.000	0.005	0.167
article read	00:00:00.359	0.0%	2248	0.000	0.160	1.000
backlog stats	00:00:01.401	0.0%	292949	0.001	0.005	0.010
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.717	0.0%	196618	0.012	0.024	0.052
data write	00:00:08.236	0.0%	159331	0.032	0.052	0.076
idle	23:54:42.044	100.0%	292949	185.436	293.847	1150.482
status file	00:00:00.447	0.0%	209	0.000	2.139	8.000
TOTAL: 23:55:11.297	23:54:57.425	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:43.599	8.2%	1451	14.000	30.048	9497.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.127	0.0%	3735	0.000	0.034	0.364
readart	00:00:00.065	0.0%	118	0.405	0.551	0.632
TOTAL: 00:08:51.643	00:00:43.791	8.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.nntp4.net	3	21230	16061	4167	1002	75%	24:10:24
2	feed-out.gegeweb.org	2	3701	2047	1653	1	55%	23:59:48
3	news.glou.org	1	4229	1102	2950	177	26%	23:59:57
4	i2pn.org	1	7016	403	6608	5	5%	43:34:58
5	gegeweb-out.news.tnetconsulting.net	2	5051	360	4610	81	7%	35:55:14
6	usenet.pasdenom.info	2	1719	271	1439	9	15%	16:38:48
7	nntp.terraraq.uk	2	2359	229	2124	6	9%	15:00:40
8	localhost	25	0	0	0	0	0%	00:00:01
9	news.samoylyk.net	1	20	0	20	0	0%	00:08:25
TOTAL: 9		39	45325	20473	23571	1281	45%	183:28:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	215.5 MB	332.8 KB	9.7 MB	225.5 MB	95%	13.5 KB
2	feed-out.gegeweb.org	9.0 MB	10.6 KB	10.6 KB	9.0 MB	99%	4.5 KB
3	news.glou.org	8.9 MB	1.6 KB	656.9 KB	9.6 MB	93%	7.7 KB
4	i2pn.org	3.7 MB	0.0 KB	9.9 KB	3.7 MB	99%	9.3 KB
5	gegeweb-out.news.tnetconsulting.net	1.2 MB	0.0 KB	370.4 KB	1.6 MB	77%	3.7 KB
6	usenet.pasdenom.info	1.2 MB	0.0 KB	10.7 KB	1.2 MB	99%	4.3 KB
7	nntp.terraraq.uk	845.0 KB	2.0 KB	23.1 KB	870.1 KB	97%	3.7 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
9	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		240.3 MB	346.9 KB	10.7 MB	251.4 MB	95%	11.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 16 04:15:01 - 04:59:59	834	4.1%	0.31	9.9 MB	4.1%	3.74
Feb 16 05:00:00 - 05:59:59	900	4.4%	0.25	12.1 MB	5.0%	3.44
Feb 16 06:00:00 - 06:59:59	976	4.8%	0.27	12.1 MB	5.0%	3.45
Feb 16 07:00:00 - 07:59:59	922	4.5%	0.26	12.2 MB	5.1%	3.48
Feb 16 08:00:00 - 08:59:59	1210	5.9%	0.34	14.0 MB	5.8%	3.99
Feb 16 09:00:00 - 09:59:59	1240	6.1%	0.34	14.6 MB	6.1%	4.16
Feb 16 10:00:00 - 10:59:59	1143	5.6%	0.32	13.3 MB	5.5%	3.78
Feb 16 11:00:00 - 11:59:59	902	4.4%	0.25	9.4 MB	3.9%	2.68
Feb 16 12:00:00 - 12:59:59	441	2.2%	0.12	3.3 MB	1.4%	0.95
Feb 16 13:00:00 - 13:59:59	545	2.7%	0.15	4.3 MB	1.8%	1.24
Feb 16 14:00:00 - 14:59:59	481	2.4%	0.13	3.6 MB	1.5%	1.03
Feb 16 15:00:00 - 15:59:59	487	2.4%	0.14	3.9 MB	1.6%	1.12
Feb 16 16:00:00 - 16:59:59	561	2.7%	0.16	4.0 MB	1.7%	1.15
Feb 16 17:00:00 - 17:59:59	857	4.2%	0.24	8.8 MB	3.6%	2.49
Feb 16 18:00:00 - 18:59:59	561	2.7%	0.16	4.3 MB	1.8%	1.22
Feb 16 19:00:00 - 19:59:59	1026	5.0%	0.28	11.4 MB	4.7%	3.24
Feb 16 20:00:00 - 20:59:59	990	4.8%	0.28	11.4 MB	4.7%	3.23
Feb 16 21:00:00 - 21:59:59	939	4.6%	0.26	11.8 MB	4.9%	3.35
Feb 16 22:00:00 - 22:59:59	953	4.7%	0.26	11.2 MB	4.7%	3.20
Feb 16 23:00:00 - 23:59:59	918	4.5%	0.26	11.0 MB	4.6%	3.14
Feb 17 00:00:00 - 00:59:59	763	3.7%	0.21	9.6 MB	4.0%	2.73
Feb 17 01:00:00 - 01:59:59	702	3.4%	0.20	8.3 MB	3.5%	2.37
Feb 17 02:00:00 - 02:59:59	861	4.2%	0.24	12.5 MB	5.2%	3.54
Feb 17 03:00:00 - 03:59:59	987	4.8%	0.27	19.9 MB	8.3%	5.67
Feb 17 04:00:00 - 04:15:01	262	1.3%	0.29	3.3 MB	1.4%	3.73
TOTAL: 24:00:00	20461	100.0%	0.24	240.4 MB	100.0%	2.85

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	1010	0	50	960
2	news.glou.org	177	0	1	176
3	gegeweb-out.news.tnetconsulting.net	63	0	1	62
4	usenet.pasdenom.info	17	0	0	17
5	feed-out.gegeweb.org	11	0	11	0
6	nntp.terraraq.uk	7	1	1	5
7	i2pn.org	3	0	0	3
TOTAL: 7		1288	1	64	1223

Unwanted newsgroups [Top 20]:

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	740
Too many newsgroups	274
EMP (phn path)	117
Bad path (news.giganews.com.POSTED)	83
Cancels abusifs	5
Cancel of <17b442829c0a91b4$148522$19034$6ad9128e@news.vipernews.com> without Cancel-Key	1
HTML post	1
Cancel of non-existing ID <l38d45FdqdaU1@mid.individual.net>	1
User-issued spam cancel	1
TOTAL: 9	1223

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	35488	153	17950	1870	434	0%	23:59:49
2	news.nntp4.net	4717	79	4100	1	0	1%	23:59:32
3	nntp.terraraq.uk	19838	75	18962	46	0	0%	23:59:51
4	news.gegeweb.org	24603	56	18384	2	0	0%	23:59:57
5	news.samoylyk.net	26416	18	20352	48	9	0%	23:59:52
6	news.pasdenom.info	22275	8	20032	7	0	0%	23:59:57
7	news.tnetconsulting.net	27628	6	20381	71	14	0%	23:59:55
8	news.aioe.org	0	0	0	0	10108	0%	23:59:57
TOTAL: 8		160965	395	120161	2045	10565	0%	191:58:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	1.3 MB	2.4 KB	1.3 MB	0.0 KB/s	17.2 KB	23:59:32
2	nntp.terraraq.uk	1.2 MB	970.0 KB	2.2 MB	0.0 KB/s	18.4 KB	23:59:51
3	i2pn.org	612.7 KB	24.0 MB	24.6 MB	0.3 KB/s	12.5 KB	23:59:49
4	news.gegeweb.org	559.3 KB	95.6 KB	654.9 KB	0.0 KB/s	11.3 KB	23:59:57
5	news.samoylyk.net	57.3 KB	704.2 KB	761.5 KB	0.0 KB/s	11.5 KB	23:59:52
6	news.pasdenom.info	49.4 KB	42.6 KB	91.9 KB	0.0 KB/s	6.1 KB	23:59:57
7	news.tnetconsulting.net	22.5 KB	910.1 KB	932.6 KB	0.0 KB/s	12.1 KB	23:59:55
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 8		3.8 MB	26.7 MB	30.5 MB	0.0 KB/s	12.8 KB	191:58:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	2a0e:e701:1154:0:b9b3:7328:7a8:7510	2	51	247.1 KB	5	0	0	00:06:29
TOTAL: 1		2	51	247.1 KB	5	0	0	00:08:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	?	4	51	247.1 KB	5	00:06:36
2	*.211.203.35.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:01
3	*.do.binaryedge.ninja	1	0	0.0 KB	0	00:00:02
4	*.gegeweb.eu	288	0	0.0 KB	0	00:01:33
5	*.monitoring.internet-measurement.com	5	0	0.0 KB	0	00:00:09
6	unresolved	16	0	0.0 KB	0	00:00:27
TOTAL: 6		315	51	247.1 KB	5	00:08:51

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
2a0e:e701:1154:0:b9b3:7328:7a8:7510	0.161	0.094	0.014	00:06:29
mail.gegeweb.eu	4.298	4.364	1.565	00:01:33
50.31.21.11	0.119	0.055	0.000	00:00:12
192.241.212.36	0.008	0.016	0.000	00:00:09
2607:ff10:c8:594::e	0.053	0.042	0.000	00:00:07
thorough.monitoring.internet-measurement.com	0.049	0.025	0.000	00:00:06
208.100.26.235	0.215	0.143	0.000	00:00:03
sage.monitoring.internet-measurement.com	0.035	0.015	0.000	00:00:02
prod-beryllium-nyc1-48.do.binaryedge.ninja	0.030	0.021	0.000	00:00:02
17.211.203.35.bc.googleusercontent.com	0.050	0.033	0.000	00:00:01
80.66.88.215	0.016	0.008	0.000	00:00:00
192.241.219.57	0.010	0.015	0.000	00:00:00
elegant.monitoring.internet-measurement.com	0.042	0.021	0.000	00:00:00
jovial.monitoring.internet-measurement.com	0.042	0.021	0.000	00:00:00
popular.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
TOTAL: 15	5.148	4.881	1.579	00:08:51

Curious NNRP server explorers [Top 20]:

System	Conn
mail.gegeweb.eu	288
208.100.26.235	10
50.31.21.11	3
2607:ff10:c8:594::e	2
sage.monitoring.internet-measurement.com	1
192.241.212.36	1
prod-beryllium-nyc1-48.do.binaryedge.ninja	1
elegant.monitoring.internet-measurement.com	1
192.241.219.57	1
jovial.monitoring.internet-measurement.com	1
17.211.203.35.bc.googleusercontent.com	1
thorough.monitoring.internet-measurement.com	1
popular.monitoring.internet-measurement.com	1
80.66.88.215	1
TOTAL: 14	313

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
17.211.203.35.bc.googleusercontent.com	3
80.66.88.215	1
192.241.219.57	1
TOTAL: 3	5

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
Host: 45.13.105.36:563	1
User-Agent: Expanse, a Palo Alto Network...	1
#003	1
GET / HTTP/1.1	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.215	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	49	96.1%
2	news	2	3.9%
TOTAL: 2		51	100.0%

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

Newsgroup		Count
1	fr.usenet.distribution	26
2	fr.usenet.forums.evolution	19
3	fr.usenet.documents	2
4	fr.misc.bavardages.linux	2
5	news.announce.newgroups	2
TOTAL: 5		51

Daily Usenet report for news.niel.me

Feb 16 04:15:01 -- Feb 17 04:15:01