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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2445	28.8%	420.3 KB	32.3%
innd	2276	26.8%	400.1 KB	30.8%
nnrpd	1896	22.3%	202.4 KB	15.6%
innfeed	1873	22.1%	278.0 KB	21.4%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8492	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8233	51.5%
Negative hits	5312	33.2%
Do not exist	2439	15.3%
Cache misses	2	0.0%
TOTAL: 4	15986	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.003	0.0%	3	1.000	1.000	1.000
article cancel	00:00:00.000	0.0%	3	0.000	0.000	0.000
article cleanup	00:00:00.054	0.0%	2442	0.000	0.022	0.250
article logging	00:00:00.201	0.0%	2442	0.000	0.082	0.333
article parse	00:00:00.635	0.0%	8779	0.000	0.072	0.500
article write	00:00:00.999	0.0%	1799	0.000	0.555	1.500
artlog/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:00:00.117	0.0%	20977	0.000	0.006	0.067
hisgrep/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	3	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.272	0.0%	15998	0.000	0.017	0.250
history sync	00:00:00.060	0.0%	390	0.000	0.154	1.000
history write	00:00:00.506	0.0%	2442	0.000	0.207	0.857
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:56.623	100.0%	22778	459.392	3795.620	28605.286
nntp read	00:00:00.630	0.0%	21944	0.000	0.029	0.158
overview write	00:00:00.640	0.0%	1799	0.000	0.356	4.750
perl filter	00:00:08.023	0.0%	2442	1.333	3.285	6.600
python filter	00:00:00.012	0.0%	2442	0.000	0.005	0.125
site send	00:00:00.737	0.0%	3654	0.000	0.202	0.571
TOTAL: 24:01:15.848	24:01:09.512	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	1825	0.000	0.012	0.200
article prepare	00:00:00.001	0.0%	208	0.000	0.005	0.125
article read	00:00:00.023	0.0%	50	0.000	0.460	7.000
backlog stats	00:00:00.150	0.0%	23120	0.000	0.006	0.051
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.280	0.0%	11378	0.000	0.025	0.099
data write	00:00:00.580	0.0%	9619	0.000	0.060	0.167
idle	23:50:13.451	100.0%	23120	1457.705	3711.654	10295.033
status file	00:00:00.673	0.0%	317	1.000	2.123	7.000
TOTAL: 23:50:32.540	23:50:15.180	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:45.272	23.5%	1458	11.200	31.051	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.144	0.1%	3263	0.000	0.044	0.500
TOTAL: 00:03:13.047	00:00:45.416	23.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	news.nntp4.net	3	2406	797	984	625	33%	23:56:00
2	feed-out.gegeweb.org	3	1762	489	1273	0	27%	23:48:40
3	news.samoylyk.net	3	1818	298	1520	0	16%	23:20:58
4	gegeweb-out.news.tnetconsulting.net	7	2590	155	2434	1	5%	40:35:53
5	news.glou.org	6	1252	91	1146	15	7%	22:34:08
6	nntp.terraraq.uk	7	1211	31	1178	2	2%	23:20:31
7	usenet.pasdenom.info	13	834	0	834	0	0%	22:41:35
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		67	11873	1861	9369	643	15%	180:17:45

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	2.5 MB	12.6 MB	15.2 MB	16%	10.9 KB
2	feed-out.gegeweb.org	1.9 MB	0.0 KB	1.9 MB	100%	3.9 KB
3	news.samoylyk.net	1.6 MB	0.0 KB	1.6 MB	100%	5.6 KB
4	gegeweb-out.news.tnetconsulting.net	629.0 KB	2.2 KB	631.3 KB	99%	4.0 KB
5	news.glou.org	268.2 KB	44.4 KB	312.6 KB	85%	2.9 KB
6	nntp.terraraq.uk	203.1 KB	86.5 KB	289.7 KB	70%	8.8 KB
7	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		7.1 MB	12.8 MB	19.9 MB	35%	8.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 30 04:15:01 - 04:59:59	18	1.0%	0.01	60.8 KB	0.9%	0.02
Oct 30 05:00:00 - 05:59:59	29	1.6%	0.01	105.2 KB	1.5%	0.03
Oct 30 06:00:00 - 06:59:59	24	1.3%	0.01	106.8 KB	1.5%	0.03
Oct 30 07:00:00 - 07:59:59	64	3.6%	0.02	234.2 KB	3.3%	0.07
Oct 30 08:00:00 - 08:59:59	35	1.9%	0.01	210.2 KB	3.0%	0.06
Oct 30 09:00:00 - 09:59:59	53	3.0%	0.01	183.1 KB	2.6%	0.05
Oct 30 10:00:00 - 10:59:59	48	2.7%	0.01	213.8 KB	3.0%	0.06
Oct 30 11:00:00 - 11:59:59	99	5.5%	0.03	451.2 KB	6.4%	0.13
Oct 30 12:00:00 - 12:59:59	100	5.6%	0.03	472.1 KB	6.6%	0.13
Oct 30 13:00:00 - 13:59:59	78	4.3%	0.02	288.6 KB	4.1%	0.08
Oct 30 14:00:00 - 14:59:59	64	3.6%	0.02	246.8 KB	3.5%	0.07
Oct 30 15:00:00 - 15:59:59	46	2.6%	0.01	165.8 KB	2.3%	0.05
Oct 30 16:00:00 - 16:59:59	88	4.9%	0.02	567.2 KB	8.0%	0.16
Oct 30 17:00:00 - 17:59:59	85	4.7%	0.02	417.6 KB	5.9%	0.12
Oct 30 18:00:00 - 18:59:59	105	5.8%	0.03	324.2 KB	4.6%	0.09
Oct 30 19:00:00 - 19:59:59	96	5.3%	0.03	314.4 KB	4.4%	0.09
Oct 30 20:00:00 - 20:59:59	142	7.9%	0.04	504.9 KB	7.1%	0.14
Oct 30 21:00:00 - 21:59:59	106	5.9%	0.03	314.5 KB	4.4%	0.09
Oct 30 22:00:00 - 22:59:59	137	7.6%	0.04	425.9 KB	6.0%	0.12
Oct 30 23:00:00 - 23:59:59	127	7.1%	0.04	363.0 KB	5.1%	0.10
Oct 31 00:00:00 - 00:59:59	72	4.0%	0.02	290.0 KB	4.1%	0.08
Oct 31 01:00:00 - 01:59:59	73	4.1%	0.02	379.9 KB	5.4%	0.11
Oct 31 02:00:00 - 02:59:59	41	2.3%	0.01	156.3 KB	2.2%	0.04
Oct 31 03:00:00 - 03:59:59	51	2.8%	0.01	250.3 KB	3.5%	0.07
Oct 31 04:00:00 - 04:15:01	15	0.8%	0.02	53.4 KB	0.8%	0.06
TOTAL: 24:00:00	1796	100.0%	0.02	6.9 MB	100.0%	0.08

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	625	244	381
2	news.glou.org	15	0	15
3	nntp.terraraq.uk	2	0	2
4	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 4		643	244	399

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	194
linux.kernel.git	49
news.test	1
TOTAL: 3	244

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	349
Bad path (news.giganews.com.POSTED)	43
Too many newsgroups	5
HTML Multipart	2
TOTAL: 4	399

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	2762	20	1747	28	34	0%	23:54:16
2	news.samoylyk.net	1721	3	1435	0	0	0%	23:50:41
3	news.pasdenom.info	1567	1	1286	0	0	0%	23:48:08
4	nntp.terraraq.uk	1126	0	1080	0	0	0%	23:32:05
5	news.nntp4.net	1133	0	994	0	0	0%	23:29:39
6	i2pn.org	0	0	0	0	1796	0%	24:00:00
7	news.aioe.org	0	0	0	0	1398	0%	24:00:00
8	news.gegeweb.org	1388	0	1287	0	0	0%	23:35:09
TOTAL: 8		9697	24	7829	28	3228	0%	190:09:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	61.8 KB	114.6 KB	176.3 KB	3.7 KB	23:54:16
2	news.samoylyk.net	9.1 KB	0.0 KB	9.1 KB	3.0 KB	23:50:41
3	news.pasdenom.info	2.3 KB	0.0 KB	2.3 KB	2.3 KB	23:48:08
4	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:32:05
5	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:29:39
6	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:35:09
TOTAL: 8		73.2 KB	114.6 KB	187.7 KB	3.6 KB	190:09:58

NNRP readership statistics:

System		Conn	Elapsed
1	147.185.133.165	2	00:00:31
2	198.235.24.41	1	00:00:01
3	azpdws8kj2pq.stretchoid.com	2	00:00:10
4	176.65.134.34	1	00:00:00
5	mail.gegeweb.eu	289	00:02:03
6	58.48.55.50	5	00:00:08
7	o36.scanner.modat.io	1	00:00:00
8	o33.scanner.modat.io	4	00:00:00
9	205.210.31.16	1	00:00:02
10	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
11	radiant.monitoring.internet-measurement.com	1	00:00:01
12	165.210.203.35.bc.googleusercontent.com	2	00:00:23
13	147.211.203.35.bc.googleusercontent.com	1	00:00:00
14	2a06:4883:b000::be	1	00:00:01
15	162.150.216.162.bc.googleusercontent.com	1	00:00:01
16	206.168.34.209	1	00:00:01
TOTAL: 16		314	00:03:39

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.150.216.162.bc.googleusercontent.com	1	00:00:01
2	*.stretchoid.com	2	00:00:10
3	*.scanner.modat.io	5	00:00:00
4	unresolved	11	00:00:45
5	?	1	00:00:01
6	*.211.203.35.bc.googleusercontent.com	1	00:00:00
7	*.gegeweb.eu	289	00:02:03
8	*.w92-154.abo.wanadoo.fr	1	00:00:10
9	*.210.203.35.bc.googleusercontent.com	2	00:00:23
10	*.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 10		314	00:03:39

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	5.394	4.468	2.141	00:02:03
147.185.133.165	0.182	0.082	0.000	00:00:31
165.210.203.35.bc.googleusercontent.com	0.238	0.074	0.000	00:00:23
azpdws8kj2pq.stretchoid.com	0.017	0.027	0.000	00:00:10
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.009	0.013	0.000	00:00:10
58.48.55.50	0.191	0.117	0.000	00:00:08
205.210.31.16	0.028	0.020	0.000	00:00:02
radiant.monitoring.internet-measurement.com	0.020	0.028	0.000	00:00:01
2a06:4883:b000::be	0.021	0.029	0.000	00:00:01
198.235.24.41	0.040	0.009	0.000	00:00:01
162.150.216.162.bc.googleusercontent.com	0.074	0.013	0.000	00:00:01
206.168.34.209	0.040	0.018	0.000	00:00:01
o33.scanner.modat.io	0.128	0.045	0.000	00:00:00
147.211.203.35.bc.googleusercontent.com	0.068	0.020	0.000	00:00:00
o36.scanner.modat.io	0.035	0.013	0.000	00:00:00
176.65.134.34	0.043	0.019	0.000	00:00:00
TOTAL: 16	6.528	4.995	2.141	00:03:39

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
165.210.203.35.bc.googleusercontent.com	4
147.211.203.35.bc.googleusercontent.com	4
162.150.216.162.bc.googleusercontent.com	4
147.185.133.165	4
206.168.34.209	1
azpdws8kj2pq.stretchoid.com	1
TOTAL: 6	18

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	4
GET / HTTP/1.1	4
User-Agent: Hello from Palo Alto Network...	2
User-Agent: curl/7.68.0	2
Accept-Encoding: gzip	2
Accept: /	2
EHLO www.censys.io	1
MGLNDD_45.13.105.36_119	1
TOTAL: 8	18

NNRP client timeouts [Top 20]:

System	Conn	Peer
165.210.203.35.bc.googleusercontent.com	1	2
147.185.133.165	1	2
TOTAL: 2	2	6

Daily Usenet report for news.niel.me

Oct 30 04:15:01 -- Oct 31 04:15:01