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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2490	28.8%	454.2 KB	33.1%
innd	2273	26.3%	393.4 KB	28.7%
nnrpd	2122	24.6%	237.6 KB	17.3%
innfeed	1753	20.3%	286.2 KB	20.9%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8640	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12635	57.7%
Negative hits	6778	31.0%
Do not exist	2466	11.3%
Cache misses	1	0.0%
TOTAL: 4	21880	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.001	0.0%	1	1.000	1.000	1.000
article cancel	00:00:00.000	0.0%	1	0.000	0.000	0.000
article cleanup	00:00:00.065	0.0%	2488	0.000	0.026	0.182
article logging	00:00:00.178	0.0%	2488	0.000	0.072	0.400
article parse	00:00:00.346	0.0%	6562	0.000	0.053	0.200
article write	00:00:01.127	0.0%	2137	0.000	0.527	1.400
artlog/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	1	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.141	0.0%	23767	0.000	0.006	0.038
hisgrep/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	1	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%	1	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	1	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.344	0.0%	22080	0.000	0.016	0.083
history sync	00:00:00.052	0.0%	333	0.000	0.156	1.000
history write	00:00:00.539	0.0%	2487	0.000	0.217	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:42.360	100.0%	26412	1342.787	3272.844	18195.667
nntp read	00:00:00.718	0.0%	25547	0.000	0.028	0.092
overview write	00:00:00.713	0.0%	2137	0.000	0.334	2.545
perl filter	00:00:10.076	0.0%	2487	2.154	4.051	7.000
python filter	00:00:00.008	0.0%	2487	0.000	0.003	0.083
site send	00:00:00.796	0.0%	4359	0.000	0.183	0.515
TOTAL: 24:01:04.810	24:00:57.464	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	2167	0.000	0.011	0.100
article prepare	00:00:00.003	0.0%	552	0.000	0.005	0.250
article read	00:00:00.026	0.0%	134	0.000	0.194	1.500
backlog stats	00:00:00.205	0.0%	31890	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.385	0.0%	17026	0.000	0.023	0.092
data write	00:00:00.854	0.0%	15045	0.000	0.057	0.182
idle	23:59:49.170	100.0%	31890	1396.012	2708.974	7439.111
status file	00:00:00.429	0.0%	203	0.000	2.113	7.000
TOTAL: 23:59:56.838	23:59:51.095	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:08.356	33.3%	1556	11.000	43.931	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.140	0.1%	3525	0.000	0.040	0.667
TOTAL: 00:03:25.294	00:01:08.496	33.4%	-	-	-	-

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	2088	773	1315	0	37%	23:59:00
2	news.samoylyk.net	1	1847	421	1418	8	22%	19:42:15
3	gegeweb-out.news.tnetconsulting.net	1	2016	290	1665	61	14%	24:20:58
4	news.glou.org	3	3285	196	2932	157	5%	41:54:43
5	news.nntp4.net	1	1754	172	1510	72	9%	19:22:57
6	i2pn.org	1	2399	168	2217	14	7%	23:59:57
7	usenet.pasdenom.info	5	1113	74	1034	5	6%	24:39:04
8	nntp.terraraq.uk	1	115	3	112	0	2%	02:17:57
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	14617	2097	12203	317	14%	180:16:51

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	2.6 MB	0.0 KB	2.6 MB	100%	3.4 KB
2	news.samoylyk.net	2.4 MB	26.3 KB	2.4 MB	98%	5.8 KB
3	gegeweb-out.news.tnetconsulting.net	913.5 KB	212.7 KB	1.1 MB	81%	3.2 KB
4	news.glou.org	707.6 KB	544.0 KB	1.2 MB	56%	3.5 KB
5	news.nntp4.net	624.6 KB	683.6 KB	1.3 MB	47%	5.4 KB
6	i2pn.org	529.8 KB	150.9 KB	680.7 KB	77%	3.7 KB
7	usenet.pasdenom.info	173.8 KB	6.3 KB	180.1 KB	96%	2.3 KB
8	nntp.terraraq.uk	6.3 KB	0.0 KB	6.3 KB	100%	2.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		7.9 MB	1.6 MB	9.4 MB	83%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 30 04:15:01 - 04:59:59	51	2.4%	0.02	169.8 KB	2.0%	0.06
Jul 30 05:00:00 - 05:59:59	46	2.2%	0.01	135.6 KB	1.6%	0.04
Jul 30 06:00:00 - 06:59:59	69	3.2%	0.02	232.8 KB	2.8%	0.06
Jul 30 07:00:00 - 07:59:59	40	1.9%	0.01	314.7 KB	3.8%	0.09
Jul 30 08:00:00 - 08:59:59	60	2.8%	0.02	220.6 KB	2.6%	0.06
Jul 30 09:00:00 - 09:59:59	81	3.8%	0.02	290.1 KB	3.5%	0.08
Jul 30 10:00:00 - 10:59:59	79	3.7%	0.02	510.0 KB	6.1%	0.14
Jul 30 11:00:00 - 11:59:59	110	5.1%	0.03	577.9 KB	6.9%	0.16
Jul 30 12:00:00 - 12:59:59	75	3.5%	0.02	255.8 KB	3.1%	0.07
Jul 30 13:00:00 - 13:59:59	92	4.3%	0.03	329.9 KB	4.0%	0.09
Jul 30 14:00:00 - 14:59:59	82	3.8%	0.02	348.2 KB	4.2%	0.10
Jul 30 15:00:00 - 15:59:59	107	5.0%	0.03	351.4 KB	4.2%	0.10
Jul 30 16:00:00 - 16:59:59	117	5.5%	0.03	315.1 KB	3.8%	0.09
Jul 30 17:00:00 - 17:59:59	125	5.9%	0.03	417.2 KB	5.0%	0.12
Jul 30 18:00:00 - 18:59:59	106	5.0%	0.03	419.1 KB	5.0%	0.12
Jul 30 19:00:00 - 19:59:59	127	5.9%	0.04	508.9 KB	6.1%	0.14
Jul 30 20:00:00 - 20:59:59	120	5.6%	0.03	474.6 KB	5.7%	0.13
Jul 30 21:00:00 - 21:59:59	118	5.5%	0.03	417.4 KB	5.0%	0.12
Jul 30 22:00:00 - 22:59:59	109	5.1%	0.03	398.8 KB	4.8%	0.11
Jul 30 23:00:00 - 23:59:59	129	6.0%	0.04	489.6 KB	5.9%	0.14
Jul 31 00:00:00 - 00:59:59	77	3.6%	0.02	329.7 KB	4.0%	0.09
Jul 31 01:00:00 - 01:59:59	81	3.8%	0.02	344.6 KB	4.1%	0.10
Jul 31 02:00:00 - 02:59:59	49	2.3%	0.01	175.1 KB	2.1%	0.05
Jul 31 03:00:00 - 03:59:59	64	3.0%	0.02	240.3 KB	2.9%	0.07
Jul 31 04:00:00 - 04:15:01	22	1.0%	0.02	66.5 KB	0.8%	0.07
TOTAL: 24:00:00	2136	100.0%	0.02	8.1 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.nntp4.net	152	1	151
2	news.glou.org	98	0	98
3	gegeweb-out.news.tnetconsulting.net	68	0	68
4	i2pn.org	19	0	19
5	news.samoylyk.net	10	0	10
6	usenet.pasdenom.info	5	0	5
TOTAL: 6		352	1	351

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	112
EMP (phn path)	112
Bad path (news.giganews.com.POSTED)	74
EMP (md5)	50
Cancel for rejected article	1
Cancel of <a03878e63cfeed53b6b92ca665bda46fc168ce4d@i2pn2.org> without Cancel-Key	1
Cancel of <0275fae9e274b2afd7a6340c61c39e41dbbb3278@i2pn2.org> without Cancel-Key	1
TOTAL: 7	351

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1461	62	1313	0	0	4%	23:59:57
2	i2pn.org	3201	58	1905	5	16	1%	23:55:09
3	news.tnetconsulting.net	3525	8	2119	5	11	0%	23:59:57
4	news.samoylyk.net	1876	0	1648	0	4	0%	23:59:34
5	news.pasdenom.info	1778	0	1590	0	0	0%	23:59:34
6	news.aioe.org	0	0	0	0	1740	0%	24:00:00
7	news.nntp4.net	2028	0	1910	0	0	0%	23:54:36
8	nntp.terraraq.uk	1413	0	1299	0	0	0%	23:59:34
TOTAL: 8		15282	128	11784	10	1771	0%	191:48:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	244.9 KB	13.6 KB	258.4 KB	4.1 KB	23:55:09
2	news.gegeweb.org	204.3 KB	0.0 KB	204.3 KB	3.3 KB	23:59:57
3	news.tnetconsulting.net	21.2 KB	41.7 KB	62.9 KB	4.8 KB	23:59:57
4	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:34
5	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:34
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:54:36
8	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:34
TOTAL: 8		470.3 KB	55.3 KB	525.6 KB	3.8 KB	191:48:21

NNRP readership statistics:

System		Conn	Elapsed
1	172-105-246-139.ip.linodeusercontent.com	104	00:00:20
2	250.149.216.162.bc.googleusercontent.com	1	00:00:01
3	224.211.203.35.bc.googleusercontent.com	1	00:00:00
4	fruitful.monitoring.internet-measurement.com	1	00:00:02
5	172-232-194-101.ip.linodeusercontent.com	1	00:00:01
6	mail.gegeweb.eu	288	00:02:21
7	172-232-194-40.ip.linodeusercontent.com	1	00:00:00
8	205.210.31.47	1	00:00:00
9	194.165.16.10	1	00:00:00
10	147.185.132.51	1	00:00:11
11	205.210.31.229	1	00:00:10
12	lputeaux-658-1-30-140.w80-13.abo.wanadoo.fr	1	00:00:10
13	91.238.181.24	1	00:00:00
14	80.66.76.134	1	00:00:00
15	194.165.16.73	1	00:00:00
TOTAL: 15		405	00:03:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	7	00:00:24
2	*.149.216.162.bc.googleusercontent.com	1	00:00:01
3	*.gegeweb.eu	288	00:02:21
4	*.monitoring.internet-measurement.com	1	00:00:02
5	*.211.203.35.bc.googleusercontent.com	1	00:00:00
6	*.ip.linodeusercontent.com	106	00:00:22
7	*.w80-13.abo.wanadoo.fr	1	00:00:10
TOTAL: 7		405	00:03:24

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.115	4.117	1.654	00:02:21
172-105-246-139.ip.linodeusercontent.com	2.686	1.263	0.000	00:00:20
147.185.132.51	0.033	0.017	0.000	00:00:11
lputeaux-658-1-30-140.w80-13.abo.wanadoo.fr	0.028	0.016	0.000	00:00:10
205.210.31.229	0.029	0.022	0.000	00:00:10
fruitful.monitoring.internet-measurement.com	0.029	0.016	0.000	00:00:02
172-232-194-101.ip.linodeusercontent.com	0.023	0.015	0.000	00:00:01
250.149.216.162.bc.googleusercontent.com	0.065	0.024	0.000	00:00:01
224.211.203.35.bc.googleusercontent.com	0.054	0.023	0.000	00:00:00
205.210.31.47	0.026	0.013	0.000	00:00:00
194.165.16.73	0.039	0.024	0.000	00:00:00
194.165.16.10	0.028	0.020	0.000	00:00:00
80.66.76.134	0.013	0.009	0.000	00:00:00
91.238.181.24	0.041	0.020	0.000	00:00:00
172-232-194-40.ip.linodeusercontent.com	0.009	0.013	0.000	00:00:00
?	0.035	0.022	0.000	00:00:00
TOTAL: 16	8.253	5.634	1.654	00:03:24

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
250.149.216.162.bc.googleusercontent.com	4
224.211.203.35.bc.googleusercontent.com	4
194.165.16.10	1
80.66.76.134	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

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

Daily Usenet report for news.niel.me

Jul 30 04:15:01 -- Jul 31 04:15:01