Daily Usenet report for news.niel.me: Jul 4 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 4 11:42:29 rpi-studio innd: filter: No Cancel-Key[sha1:GwvWKzxVq23WIZL9VyEhKLgAPwg= sha256:6EsliIo9U4NDX6UeYuVIqK+xLDYIK6SooqebyCLCL1Q=#015#012#011sha1:mRDkPj/V+nSwfj1SDi8eXBNxGDw= sha256:RE9FuxFjAPFWva8vCrG1G1u18xN7DmZXtX8sllhiqNE=] matches Cancel-Lock[sha1:9BGgB6aGeHzo8ZT2lU97y+m0HDo= sha256:AWwvVXxUOiBiCUsWr8PEzjYJ+QawDk89qUuZcOmLBs0=] target=<v65qlg$rpu$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3003	33.5%	544.7 KB	37.8%
innd	2359	26.3%	407.7 KB	28.3%
nnrpd	1815	20.2%	216.0 KB	15.0%
innfeed	1788	19.9%	271.4 KB	18.8%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8967	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	16122	58.9%
Negative hits	8256	30.2%
Do not exist	2989	10.9%
Cache misses	2	0.0%
TOTAL: 4	27369	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	6	0.500	1.833	7.000
article cancel	00:00:00.003	0.0%	7	0.000	0.429	1.000
article cleanup	00:00:00.074	0.0%	2993	0.000	0.025	0.200
article logging	00:00:00.193	0.0%	2993	0.000	0.064	0.429
article parse	00:00:00.387	0.0%	8206	0.000	0.047	0.225
article write	00:00:01.285	0.0%	2543	0.000	0.505	1.400
artlog/artcncl	00:00:00.001	0.0%	7	0.000	0.143	0.500
artlog/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	1.000
data move	00:00:00.152	0.0%	30249	0.000	0.005	0.022
hisgrep/artcncl	00:00:00.001	0.0%	7	0.000	0.143	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.005	0.0%	29	0.000	0.172	1.000
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	6	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.411	0.0%	27396	0.000	0.015	0.056
history sync	00:00:00.050	0.0%	373	0.000	0.134	1.000
history write	00:00:00.521	0.0%	2993	0.000	0.174	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:35.421	100.0%	32526	916.887	2657.425	20702.759
nntp read	00:00:00.797	0.0%	31915	0.000	0.025	0.081
overview write	00:00:00.758	0.0%	2543	0.000	0.298	2.250
perl filter	00:00:12.097	0.0%	2993	1.938	4.042	7.674
python filter	00:00:00.019	0.0%	2993	0.000	0.006	0.100
site send	00:00:00.976	0.0%	5150	0.000	0.190	0.500
TOTAL: 24:01:01.908	24:00:53.163	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	2654	0.000	0.008	0.125
article prepare	00:00:00.006	0.0%	740	0.000	0.008	0.125
article read	00:00:00.035	0.0%	180	0.000	0.194	4.000
backlog stats	00:00:00.185	0.0%	36893	0.000	0.005	0.032
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.511	0.0%	20193	0.000	0.025	0.081
data write	00:00:00.992	0.0%	17890	0.000	0.055	0.125
idle	23:58:25.246	100.0%	36893	965.100	2339.339	6537.435
status file	00:00:00.377	0.0%	219	0.000	1.721	11.000
TOTAL: 23:58:32.606	23:58:27.372	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:11.068	41.2%	1468	19.600	48.411	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.167	0.1%	3402	0.000	0.049	0.500
readart	00:00:00.569	0.3%	10994	0.052	0.052	0.052
TOTAL: 00:02:52.545	00:01:11.804	41.6%	-	-	-	-

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	2482	939	1541	2	37%	23:59:41
2	news.samoylyk.net	1	2272	386	1872	14	16%	19:14:48
3	news.nntp4.net	2	2966	349	2407	210	11%	24:37:08
4	gegeweb-out.news.tnetconsulting.net	1	1849	249	1538	62	13%	19:47:40
5	i2pn.org	1	2283	224	2058	1	9%	20:17:35
6	news.glou.org	2	2122	65	1940	117	3%	22:53:31
7	nntp.terraraq.uk	1	168	4	164	0	2%	03:17:18
8	usenet.pasdenom.info	2	140	0	138	2	0%	03:17:10
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		37	14282	2216	11658	408	15%	137:24:51

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.2 MB	51.4 KB	3.3 MB	98%	8.4 KB
2	feed-out.gegeweb.org	3.2 MB	3.5 KB	3.2 MB	99%	3.4 KB
3	news.nntp4.net	1.3 MB	749.8 KB	2.1 MB	64%	3.8 KB
4	gegeweb-out.news.tnetconsulting.net	782.1 KB	208.6 KB	990.7 KB	78%	3.2 KB
5	i2pn.org	741.5 KB	2.1 KB	743.6 KB	99%	3.3 KB
6	news.glou.org	208.0 KB	283.1 KB	491.1 KB	42%	2.7 KB
7	nntp.terraraq.uk	9.3 KB	0.0 KB	9.3 KB	100%	2.3 KB
8	usenet.pasdenom.info	0.0 KB	6.4 KB	6.4 KB	0%	3.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		9.4 MB	1.3 MB	10.7 MB	88%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 4 04:15:01 - 04:59:59	46	1.8%	0.02	164.9 KB	1.5%	0.06
Jul 4 05:00:00 - 05:59:59	48	1.9%	0.01	160.1 KB	1.4%	0.04
Jul 4 06:00:00 - 06:59:59	42	1.7%	0.01	118.7 KB	1.1%	0.03
Jul 4 07:00:00 - 07:59:59	116	4.6%	0.03	922.9 KB	8.3%	0.26
Jul 4 08:00:00 - 08:59:59	61	2.4%	0.02	165.8 KB	1.5%	0.05
Jul 4 09:00:00 - 09:59:59	76	3.0%	0.02	281.5 KB	2.5%	0.08
Jul 4 10:00:00 - 10:59:59	88	3.5%	0.02	523.6 KB	4.7%	0.15
Jul 4 11:00:00 - 11:59:59	128	5.0%	0.04	453.4 KB	4.1%	0.13
Jul 4 12:00:00 - 12:59:59	101	4.0%	0.03	347.7 KB	3.1%	0.10
Jul 4 13:00:00 - 13:59:59	112	4.4%	0.03	751.6 KB	6.7%	0.21
Jul 4 14:00:00 - 14:59:59	109	4.3%	0.03	390.0 KB	3.5%	0.11
Jul 4 15:00:00 - 15:59:59	127	5.0%	0.04	387.3 KB	3.5%	0.11
Jul 4 16:00:00 - 16:59:59	138	5.4%	0.04	395.8 KB	3.6%	0.11
Jul 4 17:00:00 - 17:59:59	192	7.6%	0.05	982.6 KB	8.8%	0.27
Jul 4 18:00:00 - 18:59:59	145	5.7%	0.04	656.7 KB	5.9%	0.18
Jul 4 19:00:00 - 19:59:59	155	6.1%	0.04	539.2 KB	4.8%	0.15
Jul 4 20:00:00 - 20:59:59	125	4.9%	0.03	472.7 KB	4.2%	0.13
Jul 4 21:00:00 - 21:59:59	140	5.5%	0.04	715.2 KB	6.4%	0.20
Jul 4 22:00:00 - 22:59:59	142	5.6%	0.04	492.9 KB	4.4%	0.14
Jul 4 23:00:00 - 23:59:59	139	5.5%	0.04	759.5 KB	6.8%	0.21
Jul 5 00:00:00 - 00:59:59	101	4.0%	0.03	756.0 KB	6.8%	0.21
Jul 5 01:00:00 - 01:59:59	92	3.6%	0.03	265.5 KB	2.4%	0.07
Jul 5 02:00:00 - 02:59:59	46	1.8%	0.01	202.1 KB	1.8%	0.06
Jul 5 03:00:00 - 03:59:59	55	2.2%	0.02	190.3 KB	1.7%	0.05
Jul 5 04:00:00 - 04:15:01	16	0.6%	0.02	49.3 KB	0.4%	0.05
TOTAL: 24:00:00	2540	100.0%	0.03	10.9 MB	100.0%	0.13

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	206	206
2	news.glou.org	118	118
3	gegeweb-out.news.tnetconsulting.net	73	73
4	usenet.pasdenom.info	35	35
5	news.samoylyk.net	16	16
6	feed-out.gegeweb.org	2	2
TOTAL: 6		450	450

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	180
EMP (phn path)	164
Bad path (news.giganews.com.POSTED)	81
Cancel for rejected article	2
Cancel of <8f7f48005241791ead7f18fa5bdbc6f95d091225@i2pn2.org> without Cancel-Key	1
Cancel of <0ce0e617312145cfabfe8973e28846c9cc561cdc@i2pn2.org> without Cancel-Key	1
Cancel of <b3429043605bd2f04f181b9b1e6c57c5bc19b7df@i2pn2.org> without Cancel-Key	1
Cancel of <d442d97cac455b2d3ab8c2af3bff899e06bb14a1@i2pn2.org> without Cancel-Key	1
Cancel of <ad4d1d178eafdf890b025a235d59095c3e20352a@i2pn2.org> without Cancel-Key	1
HTML Multipart	1
Cancel of <248f09213838570bb4787617bc3efaa8ca0e203d@i2pn2.org> without Cancel-Key	1
Cancel of <accbc8fbb58c1f3c9af94f454ec12ca4523f62ce@i2pn2.org> without Cancel-Key	1
Cancel of <bda3a2a333d1cced20c7c93d18e08f734153cd5f@i2pn2.org> without Cancel-Key	1
Cancel of <MPG.40f14199397e206898a46e@news.individual.net> without Cancel-Key	1
Cancel of <ee9d3a917828d18fb2b380b0022c867d2d969092@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <6686c2b3$0$3301$426a74cc@news.free.fr>	1
Cancel of <242165dabec9c8ab81b2c57e409db945cab85b7d@i2pn2.org> without Cancel-Key	1
Cancel of <80f34ba0e48d4ffca3c0b0776f0cde8f8d9c4acd@i2pn2.org> without Cancel-Key	1
Cancel of <0a6c1257f365efa119ff588dc44d829d6f530ac6@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <66865f28$0$3674$426a34cc@news.free.fr>	1
TOTAL: 27	450

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1696	80	1521	0	0	4%	23:59:31
2	i2pn.org	3732	68	2156	19	48	1%	23:58:11
3	news.tnetconsulting.net	4178	9	2523	3	37	0%	23:59:45
4	news.nntp4.net	2345	4	2177	0	29	0%	23:59:57
5	news.samoylyk.net	2421	1	2090	0	0	0%	23:59:31
6	news.aioe.org	0	0	0	0	2035	0%	24:00:00
7	nntp.terraraq.uk	1559	0	1427	0	0	0%	23:57:56
8	news.pasdenom.info	2236	0	1963	1	0	0%	23:58:09
TOTAL: 8		18167	162	13857	23	2149	0%	191:53:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	337.6 KB	209.8 KB	547.4 KB	6.3 KB	23:58:11
2	news.gegeweb.org	286.3 KB	0.0 KB	286.3 KB	3.6 KB	23:59:31
3	news.tnetconsulting.net	21.9 KB	8.2 KB	30.1 KB	2.5 KB	23:59:45
4	news.nntp4.net	16.7 KB	0.0 KB	16.7 KB	4.2 KB	23:59:57
5	news.samoylyk.net	3.1 KB	0.0 KB	3.1 KB	3.1 KB	23:59:31
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:57:56
8	news.pasdenom.info	0.0 KB	2.7 KB	2.7 KB	2.7 KB	23:58:09
TOTAL: 8		665.6 KB	220.7 KB	886.3 KB	4.8 KB	191:53:00

NNRP readership statistics:

System		Conn	Elapsed
1	113.211.203.35.bc.googleusercontent.com	1	00:00:00
2	225.210.203.35.bc.googleusercontent.com	1	00:00:00
3	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
4	thrilling.monitoring.internet-measurement.com	2	00:00:00
5	wondrous.monitoring.internet-measurement.com	1	00:00:00
6	radiant.monitoring.internet-measurement.com	1	00:00:00
7	supportive.monitoring.internet-measurement.com	1	00:00:00
8	azpdsgc50.stretchoid.com	1	00:00:00
9	mail.gegeweb.eu	288	00:01:57
10	27.210.203.35.bc.googleusercontent.com	1	00:00:00
11	104.152.52.234	1	00:00:10
12	azpdcs75.stretchoid.com	1	00:00:09
13	buoyant.monitoring.internet-measurement.com	1	00:00:06
14	pool-100-38-239-226.nycmny.fios.verizon.net	1	00:00:13
TOTAL: 14		302	00:02:52

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.w92-154.abo.wanadoo.fr	1	00:00:10
2	*.gegeweb.eu	288	00:01:57
3	*.stretchoid.com	2	00:00:10
4	*.211.203.35.bc.googleusercontent.com	1	00:00:00
5	*.monitoring.internet-measurement.com	6	00:00:06
6	*.nycmny.fios.verizon.net	1	00:00:13
7	unresolved	1	00:00:10
8	*.210.203.35.bc.googleusercontent.com	2	00:00:01
TOTAL: 8		302	00:02:52

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	4.131	4.209	1.502	00:01:57
pool-100-38-239-226.nycmny.fios.verizon.net	0.290	0.762	0.000	00:00:13
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.015	0.010	0.000	00:00:10
104.152.52.234	0.019	0.026	0.000	00:00:10
azpdcs75.stretchoid.com	0.014	0.010	0.000	00:00:09
buoyant.monitoring.internet-measurement.com	0.064	0.019	0.000	00:00:06
azpdsgc50.stretchoid.com	0.041	0.012	0.000	00:00:00
225.210.203.35.bc.googleusercontent.com	0.031	0.009	0.000	00:00:00
27.210.203.35.bc.googleusercontent.com	0.060	0.019	0.000	00:00:00
113.211.203.35.bc.googleusercontent.com	0.063	0.019	0.000	00:00:00
wondrous.monitoring.internet-measurement.com	0.031	0.024	0.000	00:00:00
thrilling.monitoring.internet-measurement.com	0.054	0.037	0.000	00:00:00
TOTAL: 12	4.813	5.156	1.502	00:02:52

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
27.210.203.35.bc.googleusercontent.com	4
225.210.203.35.bc.googleusercontent.com	4
113.211.203.35.bc.googleusercontent.com	4
azpdsgc50.stretchoid.com	1
TOTAL: 4	13

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	3
GET / HTTP/1.1	3
User-Agent: Expanse, a Palo Alto Network...	3
Accept-Encoding: gzip	3
MGLNDD_45.13.105.36_119	1
TOTAL: 5	13

Daily Usenet report for news.niel.me

Jul 4 04:15:01 -- Jul 5 04:15:01