Daily Usenet report for news.niel.me: Aug 5 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2345	28.5%	436.2 KB	33.0%
innd	2236	27.2%	384.7 KB	29.1%
innfeed	1828	22.3%	311.6 KB	23.5%
nnrpd	1804	22.0%	191.0 KB	14.4%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8215	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12273	58.5%
Negative hits	6375	30.4%
Do not exist	2329	11.1%
Cache misses	4	0.0%
TOTAL: 4	20981	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.004	0.0%	5	0.000	0.800	2.000
article cancel	00:00:00.002	0.0%	5	0.000	0.400	1.000
article cleanup	00:00:00.054	0.0%	2335	0.000	0.023	0.200
article logging	00:00:00.197	0.0%	2335	0.000	0.084	0.300
article parse	00:00:00.338	0.0%	5494	0.000	0.062	0.429
article write	00:00:01.100	0.0%	2023	0.083	0.544	1.429
artlog/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	5	0.000	0.400	1.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.116	0.0%	22080	0.000	0.005	0.033
hisgrep/artcncl	00:00:00.001	0.0%	5	0.000	0.200	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	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.326	0.0%	21000	0.000	0.016	0.064
history sync	00:00:00.052	0.0%	349	0.000	0.149	1.000
history write	00:00:00.422	0.0%	2333	0.000	0.181	1.400
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:44.044	100.0%	24500	1395.202	3528.328	13644.250
nntp read	00:00:00.660	0.0%	23727	0.000	0.028	0.077
overview write	00:00:00.749	0.0%	2023	0.000	0.370	1.586
perl filter	00:00:09.229	0.0%	2333	1.786	3.956	8.200
python filter	00:00:00.014	0.0%	2333	0.000	0.006	0.188
site send	00:00:00.882	0.0%	4120	0.000	0.214	0.600
TOTAL: 24:01:05.288	24:00:58.192	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.017	0.0%	2040	0.000	0.008	0.200
article prepare	00:00:00.003	0.0%	832	0.000	0.004	0.250
article read	00:00:00.035	0.0%	195	0.000	0.179	1.667
backlog stats	00:00:00.155	0.0%	30763	0.000	0.005	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.403	0.0%	16221	0.000	0.025	0.080
data write	00:00:00.872	0.0%	14486	0.000	0.060	0.133
idle	23:57:23.471	100.0%	30763	1251.581	2803.481	7625.241
status file	00:00:00.390	0.0%	218	0.000	1.789	6.000
TOTAL: 23:57:30.504	23:57:25.346	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:00.456	38.1%	1474	3.500	41.015	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.212	0.1%	3398	0.000	0.062	0.500
readart	00:00:00.547	0.3%	11037	0.050	0.050	0.050
TOTAL: 00:02:38.867	00:01:01.215	38.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	feed-out.gegeweb.org	2	1939	812	1127	0	41%	23:58:19
2	gegeweb-out.news.tnetconsulting.net	2	1744	351	1330	63	20%	22:44:55
3	news.nntp4.net	2	2494	334	2011	149	13%	26:51:36
4	news.samoylyk.net	2	2178	198	1974	6	9%	23:17:23
5	news.glou.org	3	2997	125	2723	149	4%	38:16:31
6	usenet.pasdenom.info	3	949	53	868	28	5%	21:45:02
7	i2pn.org	1	166	25	141	0	15%	02:16:56
8	nntp.terraraq.uk	1	336	9	327	0	2%	05:48:33
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		41	12803	1907	10501	395	14%	164:59:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	2.7 MB	0.0 KB	0.0 KB	2.7 MB	100%	3.4 KB
2	news.nntp4.net	1.8 MB	2.7 KB	1.2 MB	2.9 MB	60%	6.2 KB
3	gegeweb-out.news.tnetconsulting.net	1.3 MB	0.0 KB	257.2 KB	1.6 MB	84%	3.9 KB
4	news.samoylyk.net	1.3 MB	0.0 KB	33.3 KB	1.3 MB	97%	6.6 KB
5	news.glou.org	316.2 KB	0.0 KB	548.8 KB	865.0 KB	36%	3.2 KB
6	usenet.pasdenom.info	117.2 KB	0.0 KB	56.3 KB	173.4 KB	67%	2.1 KB
7	i2pn.org	99.4 KB	0.0 KB	0.0 KB	99.4 KB	100%	4.0 KB
8	nntp.terraraq.uk	28.8 KB	0.0 KB	0.0 KB	28.8 KB	100%	3.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		7.6 MB	2.7 KB	2.0 MB	9.6 MB	78%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 5 04:15:01 - 04:59:59	93	4.6%	0.03	294.8 KB	4.0%	0.11
Aug 5 05:00:00 - 05:59:59	66	3.3%	0.02	215.5 KB	2.9%	0.06
Aug 5 06:00:00 - 06:59:59	51	2.5%	0.01	185.1 KB	2.5%	0.05
Aug 5 07:00:00 - 07:59:59	59	2.9%	0.02	186.0 KB	2.5%	0.05
Aug 5 08:00:00 - 08:59:59	46	2.3%	0.01	226.3 KB	3.1%	0.06
Aug 5 09:00:00 - 09:59:59	69	3.4%	0.02	296.6 KB	4.0%	0.08
Aug 5 10:00:00 - 10:59:59	46	2.3%	0.01	161.1 KB	2.2%	0.04
Aug 5 11:00:00 - 11:59:59	71	3.5%	0.02	280.9 KB	3.8%	0.08
Aug 5 12:00:00 - 12:59:59	63	3.1%	0.02	180.0 KB	2.4%	0.05
Aug 5 13:00:00 - 13:59:59	88	4.3%	0.02	298.2 KB	4.0%	0.08
Aug 5 14:00:00 - 14:59:59	89	4.4%	0.02	361.5 KB	4.9%	0.10
Aug 5 15:00:00 - 15:59:59	97	4.8%	0.03	461.1 KB	6.2%	0.13
Aug 5 16:00:00 - 16:59:59	114	5.6%	0.03	328.4 KB	4.5%	0.09
Aug 5 17:00:00 - 17:59:59	137	6.8%	0.04	493.3 KB	6.7%	0.14
Aug 5 18:00:00 - 18:59:59	94	4.6%	0.03	286.8 KB	3.9%	0.08
Aug 5 19:00:00 - 19:59:59	98	4.8%	0.03	483.8 KB	6.6%	0.13
Aug 5 20:00:00 - 20:59:59	107	5.3%	0.03	470.7 KB	6.4%	0.13
Aug 5 21:00:00 - 21:59:59	113	5.6%	0.03	392.8 KB	5.3%	0.11
Aug 5 22:00:00 - 22:59:59	110	5.4%	0.03	360.5 KB	4.9%	0.10
Aug 5 23:00:00 - 23:59:59	95	4.7%	0.03	388.9 KB	5.3%	0.11
Aug 6 00:00:00 - 00:59:59	86	4.3%	0.02	314.3 KB	4.3%	0.09
Aug 6 01:00:00 - 01:59:59	78	3.9%	0.02	223.2 KB	3.0%	0.06
Aug 6 02:00:00 - 02:59:59	56	2.8%	0.02	192.6 KB	2.6%	0.05
Aug 6 03:00:00 - 03:59:59	75	3.7%	0.02	244.0 KB	3.3%	0.07
Aug 6 04:00:00 - 04:15:01	22	1.1%	0.02	53.5 KB	0.7%	0.06
TOTAL: 24:00:00	2023	100.0%	0.02	7.2 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.nntp4.net	141	1	140
2	gegeweb-out.news.tnetconsulting.net	72	1	71
3	news.glou.org	61	0	61
4	usenet.pasdenom.info	30	0	30
5	news.samoylyk.net	4	0	4
6	i2pn.org	3	0	3
7	nntp.terraraq.uk	1	0	1
TOTAL: 7		312	2	310

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	110
Too many newsgroups	87
Bad path (news.giganews.com.POSTED)	74
EMP (md5)	17
EMP (phl)	11
Cancel of <lhcob4FseqkU1@mid.individual.net> without Cancel-Key	1
Cancel of <3b7a651e6c135d74283f9a9b826814cbb1d1b932@i2pn2.org> without Cancel-Key	1
Cancel of <4bfd187b2a60d5d2be3f7a565f71a845a6e34f27@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <16582693610c54851620fc5b49cf7d921dd11ec5@i2pn2.org> without Cancel-Key	1
Cancel of <e7bd06e52d3ada15df6f96d160783c4e8671f5a9@i2pn2.org> without Cancel-Key	1
HTML Multipart	1
Cancel of <MPG.411b7c4fc2274e8298a4e3@news.individual.net> without Cancel-Key	1
Cancel of <7710c0c1e9b3ff2b36eaf54358f3bbb2a4680415@i2pn2.org> without Cancel-Key	1
Cancel of <ecf9c51711d25523a654aa83dc76e701b2e8fcf2@i2pn2.org> without Cancel-Key	1
Cancel of <b3db29fbf8337dd063f52754cc4b6525c1bc2415@i2pn2.org> without Cancel-Key	1
TOTAL: 16	310

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1276	69	1145	0	0	5%	23:56:21
2	i2pn.org	2950	54	1768	6	11	1%	23:59:57
3	news.samoylyk.net	2354	13	1772	54	7	0%	23:59:36
4	news.tnetconsulting.net	3159	11	2010	0	1	0%	23:59:27
5	nntp.terraraq.uk	1339	1	1319	0	0	0%	23:55:59
6	news.aioe.org	0	0	0	0	1649	0%	24:00:00
7	news.pasdenom.info	1763	0	1555	0	0	0%	23:58:00
8	news.nntp4.net	1815	0	1696	0	0	0%	23:59:08
TOTAL: 8		14656	148	11265	60	1668	1%	191:48:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	258.9 KB	0.0 KB	258.9 KB	3.8 KB	23:56:21
2	i2pn.org	179.8 KB	34.0 KB	213.8 KB	3.6 KB	23:59:57
3	news.samoylyk.net	60.1 KB	273.8 KB	333.9 KB	5.0 KB	23:59:36
4	news.tnetconsulting.net	27.2 KB	0.0 KB	27.2 KB	2.5 KB	23:59:27
5	nntp.terraraq.uk	2.5 KB	0.0 KB	2.5 KB	2.5 KB	23:55:59
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:58:00
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:08
TOTAL: 8		528.6 KB	307.8 KB	836.4 KB	4.0 KB	191:48:28

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	288	00:01:54
2	254.211.203.35.bc.googleusercontent.com	1	00:00:01
3	198.235.24.128	1	00:00:10
4	205.210.31.72	1	00:00:10
5	2607:ff10:c8:594::5	1	00:00:01
6	91.238.181.31	1	00:00:02
7	80.66.76.134	1	00:00:00
8	78.150.216.162.bc.googleusercontent.com	1	00:00:01
9	205.210.31.42	1	00:00:01
10	91.238.181.34	1	00:00:00
11	pool-100-38-239-226.nycmny.fios.verizon.net	1	00:00:12
12	azpdwgb2.stretchoid.com	1	00:00:00
TOTAL: 12		299	00:02:38

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.150.216.162.bc.googleusercontent.com	1	00:00:01
2	*.gegeweb.eu	288	00:01:54
3	*.stretchoid.com	1	00:00:00
4	unresolved	6	00:00:25
5	*.nycmny.fios.verizon.net	1	00:00:12
6	?	1	00:00:01
7	*.211.203.35.bc.googleusercontent.com	1	00:00:01
TOTAL: 7		299	00:02:38

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
mail.gegeweb.eu	5.056	4.364	1.696	00:01:54
pool-100-38-239-226.nycmny.fios.verizon.net	0.302	0.676	0.000	00:00:12
198.235.24.128	0.041	0.008	0.000	00:00:10
205.210.31.72	0.039	0.004	0.000	00:00:10
91.238.181.31	0.036	0.024	0.000	00:00:02
78.150.216.162.bc.googleusercontent.com	0.074	0.012	0.000	00:00:01
254.211.203.35.bc.googleusercontent.com	0.062	0.023	0.000	00:00:01
2607:ff10:c8:594::5	0.028	0.016	0.000	00:00:01
205.210.31.42	0.032	0.016	0.000	00:00:01
91.238.181.34	0.025	0.025	0.000	00:00:00
azpdwgb2.stretchoid.com	0.013	0.009	0.000	00:00:00
80.66.76.134	0.028	0.016	0.000	00:00:00
TOTAL: 12	5.736	5.193	1.696	00:02:38

NNRP unrecognized commands (by host):

System	Conn
254.211.203.35.bc.googleusercontent.com	4
78.150.216.162.bc.googleusercontent.com	4
80.66.76.134	1
91.238.181.34	1
azpdwgb2.stretchoid.com	1
TOTAL: 5	11

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.134	1	1
91.238.181.34	1	1
TOTAL: 2	2	3

Daily Usenet report for news.niel.me

Aug 5 04:15:01 -- Aug 6 04:15:01