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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 26 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:yZ9Jq36jaMl7Hi3GutSlF546c6o=] matches Cancel-Lock[sha1:TGljJjxj8xTMBqaXDcampOcj2vQ=] target=<de-gruppen-finden-19.10.2024@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2495	29.0%	487.9 KB	34.9%
innd	2306	26.8%	396.4 KB	28.4%
nnrpd	2024	23.5%	222.3 KB	15.9%
innfeed	1772	20.6%	291.4 KB	20.8%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8599	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12746	54.5%
Negative hits	8143	34.8%
Do not exist	2477	10.6%
Cache misses	2	0.0%
TOTAL: 4	23368	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	8	0.000	1.000	2.000
article cancel	00:00:00.001	0.0%	9	0.000	0.111	1.000
article cleanup	00:00:00.049	0.0%	2482	0.000	0.020	0.182
article logging	00:00:00.195	0.0%	2482	0.000	0.079	0.467
article parse	00:00:00.438	0.0%	5772	0.000	0.076	0.500
article write	00:00:01.101	0.0%	2121	0.150	0.519	1.200
artlog/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	0.500
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.154	0.0%	25273	0.000	0.006	0.021
hisgrep/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	11	0.000	0.091	0.333
hishave/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.395	0.0%	23402	0.000	0.017	0.060
history sync	00:00:00.053	0.0%	363	0.000	0.146	1.000
history write	00:00:00.448	0.0%	2482	0.000	0.180	0.455
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:41.508	100.0%	27324	1083.034	3163.574	27295.000
nntp read	00:00:00.765	0.0%	26452	0.000	0.029	0.095
overview write	00:00:00.729	0.0%	2121	0.000	0.344	4.857
perl filter	00:00:10.474	0.0%	2482	2.389	4.220	14.000
python filter	00:00:00.014	0.0%	2482	0.000	0.006	0.091
site send	00:00:00.870	0.0%	4279	0.000	0.203	0.615
TOTAL: 24:01:05.421	24:00:57.204	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	2150	0.000	0.011	0.200
article prepare	00:00:00.008	0.0%	1260	0.000	0.006	0.125
article read	00:00:00.060	0.0%	289	0.000	0.208	3.000
backlog stats	00:00:00.183	0.0%	30672	0.000	0.006	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.480	0.0%	17118	0.000	0.028	0.143
data write	00:00:00.880	0.0%	15326	0.000	0.057	0.156
idle	23:49:56.825	100.0%	30672	1402.249	2797.236	9556.825
status file	00:00:00.453	0.0%	234	0.000	1.936	6.000
TOTAL: 23:50:04.361	23:49:58.912	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:17:49.295	98.4%	14446	8.000	572.428	488213.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.785	0.0%	54230	0.000	0.033	1.000
readart	00:00:01.940	0.0%	12506	0.119	0.155	0.262
TOTAL: 02:20:00.974	02:17:53.020	98.5%	-	-	-	-

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	gegeweb-out.news.tnetconsulting.net	2	1931	1141	732	58	59%	25:09:52
2	feed-out.gegeweb.org	3	2018	461	1557	0	22%	23:59:14
3	i2pn.org	2	3941	284	3655	2	7%	43:57:51
4	news.nntp4.net	3	2584	204	2242	138	7%	28:39:46
5	usenet.pasdenom.info	5	2281	156	2098	27	6%	42:34:17
6	news.glou.org	3	1651	62	1549	40	3%	22:45:56
7	nntp.terraraq.uk	2	1794	47	1698	49	2%	21:58:06
8	news.samoylyk.net	2	2134	22	2112	0	1%	24:17:19
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		47	18334	2377	15643	314	12%	233:22:21

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	gegeweb-out.news.tnetconsulting.net	4.1 MB	165.7 KB	4.3 MB	96%	3.7 KB
2	feed-out.gegeweb.org	1.7 MB	0.0 KB	1.7 MB	100%	3.8 KB
3	news.nntp4.net	1.2 MB	464.4 KB	1.6 MB	72%	4.9 KB
4	i2pn.org	747.3 KB	3.7 KB	751.0 KB	99%	2.6 KB
5	usenet.pasdenom.info	393.4 KB	36.8 KB	430.2 KB	91%	2.4 KB
6	nntp.terraraq.uk	385.5 KB	1.3 MB	1.7 MB	21%	18.3 KB
7	news.glou.org	221.0 KB	139.6 KB	360.6 KB	61%	3.5 KB
8	news.samoylyk.net	121.5 KB	0.0 KB	121.5 KB	100%	5.5 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.9 MB	2.1 MB	11.0 MB	80%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 25 04:15:01 - 04:59:59	47	2.2%	0.02	148.8 KB	1.7%	0.06
Oct 25 05:00:00 - 05:59:59	51	2.4%	0.01	150.5 KB	1.7%	0.04
Oct 25 06:00:00 - 06:59:59	59	2.8%	0.02	211.5 KB	2.4%	0.06
Oct 25 07:00:00 - 07:59:59	35	1.7%	0.01	89.4 KB	1.0%	0.02
Oct 25 08:00:00 - 08:59:59	37	1.7%	0.01	147.1 KB	1.7%	0.04
Oct 25 09:00:00 - 09:59:59	44	2.1%	0.01	167.8 KB	1.9%	0.05
Oct 25 10:00:00 - 10:59:59	85	4.0%	0.02	286.1 KB	3.2%	0.08
Oct 25 11:00:00 - 11:59:59	85	4.0%	0.02	274.3 KB	3.1%	0.08
Oct 25 12:00:00 - 12:59:59	108	5.1%	0.03	439.8 KB	5.0%	0.12
Oct 25 13:00:00 - 13:59:59	72	3.4%	0.02	194.1 KB	2.2%	0.05
Oct 25 14:00:00 - 14:59:59	84	4.0%	0.02	352.2 KB	4.0%	0.10
Oct 25 15:00:00 - 15:59:59	114	5.4%	0.03	487.3 KB	5.5%	0.14
Oct 25 16:00:00 - 16:59:59	74	3.5%	0.02	271.0 KB	3.1%	0.08
Oct 25 17:00:00 - 17:59:59	108	5.1%	0.03	367.5 KB	4.2%	0.10
Oct 25 18:00:00 - 18:59:59	132	6.2%	0.04	486.3 KB	5.5%	0.14
Oct 25 19:00:00 - 19:59:59	112	5.3%	0.03	439.7 KB	5.0%	0.12
Oct 25 20:00:00 - 20:59:59	144	6.8%	0.04	560.6 KB	6.4%	0.16
Oct 25 21:00:00 - 21:59:59	134	6.3%	0.04	498.2 KB	5.7%	0.14
Oct 25 22:00:00 - 22:59:59	112	5.3%	0.03	563.2 KB	6.4%	0.16
Oct 25 23:00:00 - 23:59:59	152	7.2%	0.04	813.5 KB	9.2%	0.23
Oct 26 00:00:00 - 00:59:59	88	4.2%	0.02	457.8 KB	5.2%	0.13
Oct 26 01:00:00 - 01:59:59	95	4.5%	0.03	353.4 KB	4.0%	0.10
Oct 26 02:00:00 - 02:59:59	54	2.5%	0.01	149.7 KB	1.7%	0.04
Oct 26 03:00:00 - 03:59:59	78	3.7%	0.02	845.5 KB	9.6%	0.23
Oct 26 04:00:00 - 04:15:01	15	0.7%	0.02	51.7 KB	0.6%	0.06
TOTAL: 24:00:00	2119	100.0%	0.02	8.6 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	133	1	132
2	nntp.terraraq.uk	112	0	112
3	gegeweb-out.news.tnetconsulting.net	56	0	56
4	news.glou.org	47	0	47
5	usenet.pasdenom.info	10	0	10
6	i2pn.org	1	0	1
TOTAL: 6		359	1	358

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.misc.enfants.bebes	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	119
Binary: misplaced binary	112
EMP (phn path)	63
Bad path (news.giganews.com.POSTED)	59
Too many newsgroups (meow)	2
Cancel for rejected article	1
Cancel of <62b25dec06bb639b1411ac72297fb64a12b31b7c@i2pn2.org> without Cancel-Key	1
Cancel of <6f816803f231aeaefb66bee3a552435b3c091208@i2pn2.org> without Cancel-Key	1
TOTAL: 8	358

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2614	88	1865	7	12	3%	23:57:25
2	news.gegeweb.org	1784	64	1585	0	0	3%	23:56:29
3	news.samoylyk.net	2961	35	1943	112	21	1%	23:57:52
4	news.tnetconsulting.net	2716	5	2108	3	5	0%	23:57:47
5	news.nntp4.net	2073	1	1925	0	0	0%	23:57:11
6	news.pasdenom.info	1855	0	1696	0	0	0%	23:54:12
7	nntp.terraraq.uk	1416	0	1390	0	0	0%	23:54:00
8	news.aioe.org	0	0	0	0	1665	0%	24:00:00
TOTAL: 8		15419	193	12512	122	1703	1%	191:34:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	340.3 KB	30.4 KB	370.7 KB	3.9 KB	23:57:25
2	news.gegeweb.org	216.5 KB	0.0 KB	216.5 KB	3.4 KB	23:56:29
3	news.samoylyk.net	178.3 KB	474.3 KB	652.6 KB	4.4 KB	23:57:52
4	news.tnetconsulting.net	11.5 KB	12.9 KB	24.4 KB	3.0 KB	23:57:47
5	news.nntp4.net	1.7 KB	0.0 KB	1.7 KB	1.7 KB	23:57:11
6	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:54:12
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:54:00
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		748.3 KB	517.6 KB	1.2 MB	4.0 KB	191:34:56

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	162.250.122.68	10	11353	18.2 MB	45	00:34:49
2	69.10.48.174	10	1153	2.1 MB	24	00:03:41
TOTAL: 2		20	12506	20.3 MB	69	02:20:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	24	12506	20.3 MB	69	00:38:53
2	*.149.216.162.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:00
3	*.gegeweb.eu	288	0	0.0 KB	0	00:01:56
4	*.210.203.35.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:01
5	*.stretchoid.com	2	0	0.0 KB	0	00:00:11
6	*.rev.sfr.net	4	0	0.0 KB	0	01:38:43
7	?	1	0	0.0 KB	0	00:00:01
8	*.survey.inspici.com	1	0	0.0 KB	0	00:00:04
9	*.monitoring.internet-measurement.com	6	0	0.0 KB	0	00:00:07
TOTAL: 9		328	12506	20.3 MB	69	02:20:00

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
2a02-842a-00fb-c801-571e-1ebb-5f03-d132.rev.sfr.net	0.097	0.056	0.000	01:38:43
162.250.122.68	1.961	4.416	0.000	00:34:49
69.10.48.174	0.473	0.607	0.000	00:03:41
mail.gegeweb.eu	5.066	4.393	1.803	00:01:56
205.210.31.169	0.022	0.027	0.000	00:00:11
198.235.24.83	0.019	0.029	0.000	00:00:10
azpdcsb86.stretchoid.com	0.029	0.022	0.000	00:00:09
equitable.monitoring.internet-measurement.com	0.031	0.009	0.000	00:00:05
server-0-2.survey.inspici.com	0.029	0.020	0.000	00:00:04
dignified.monitoring.internet-measurement.com	0.034	0.017	0.000	00:00:01
azpdegc64.stretchoid.com	0.028	0.020	0.000	00:00:01
2607:ff10:c8:594::6	0.030	0.021	0.000	00:00:01
147.45.112.8	0.023	0.023	0.000	00:00:01
182.210.203.35.bc.googleusercontent.com	0.046	0.033	0.000	00:00:01
136.149.216.162.bc.googleusercontent.com	0.079	0.009	0.000	00:00:00
198.235.24.215	0.009	0.013	0.000	00:00:00
engaging.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
cozy.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
refined.monitoring.internet-measurement.com	0.024	0.005	0.000	00:00:00
TOTAL: 19	8.036	9.740	1.803	02:20:00

Curious NNRP server explorers [Top 20]:

System	Conn
mail.gegeweb.eu	288
2a02-842a-00fb-c801-571e-1ebb-5f03-d132.rev.sfr.net	4
205.210.31.169	1
198.235.24.83	1
136.149.216.162.bc.googleusercontent.com	1
refined.monitoring.internet-measurement.com	1
azpdegc64.stretchoid.com	1
equitable.monitoring.internet-measurement.com	1
cozy.monitoring.internet-measurement.com	1
azpdcsb86.stretchoid.com	1
2607:ff10:c8:594::6	1
server-0-2.survey.inspici.com	1
dignified.monitoring.internet-measurement.com	1
vibrant.monitoring.internet-measurement.com	1
182.210.203.35.bc.googleusercontent.com	1
147.45.112.8	1
engaging.monitoring.internet-measurement.com	1
198.235.24.215	1
TOTAL: 18	308

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
136.149.216.162.bc.googleusercontent.com	4
182.210.203.35.bc.googleusercontent.com	4
147.45.112.8	1
azpdegc64.stretchoid.com	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

Command	Count
Accept-Encoding: gzip	2
GET / HTTP/1.1	2
Host: 45.13.105.36:563	2
User-Agent: Expanse, a Palo Alto Network...	2
MGLNDD_45.13.105.36_119	1
#003	1
TOTAL: 6	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
2a02-842a-00fb-c801-571e-1ebb-5f03-d132.rev.sfr.net	2	0
147.45.112.8	1	1
TOTAL: 2	3	2

Newsgroup request counts (by category):

Category		Count	Pct
1	grisbi	11696	93.5%
2	local	810	6.5%
TOTAL: 2		12506	100.0%

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

Newsgroup		Count
1	grisbi.bugsreports	4871
2	grisbi.test	3569
3	grisbi.user.french	1867
4	grisbi.devel	1192
5	local.test	728
6	grisbi.cvs	116
7	local.gegeweb.support	82
8	grisbi.user	71
9	grisbi.admin	10
TOTAL: 9		12506

Daily Usenet report for news.niel.me

Oct 25 04:15:01 -- Oct 26 04:15:01