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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	39355	80.3%	7.8 MB	85.4%
innd	5838	11.9%	827.3 KB	8.9%
innfeed	1959	4.0%	333.3 KB	3.6%
nnrpd	1863	3.8%	201.1 KB	2.2%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	49017	100.0%	9.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	101386	51.0%
Negative hits	58191	29.3%
Do not exist	39152	19.7%
Cache misses	6	0.0%
TOTAL: 4	198735	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.045	0.0%	27	0.000	1.667	3.500
article cancel	00:00:00.002	0.0%	29	0.000	0.069	1.000
article cleanup	00:00:00.806	0.0%	39216	0.000	0.021	0.051
article logging	00:00:03.062	0.0%	39216	0.019	0.078	0.156
article parse	00:00:10.382	0.0%	192129	0.029	0.054	0.104
article write	00:00:28.438	0.0%	35560	0.540	0.800	2.532
artlog/artcncl	00:00:00.000	0.0%	29	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	27	0.000	0.074	1.000
data move	00:00:02.362	0.0%	413269	0.002	0.006	0.016
hisgrep/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	27	0.000	0.037	0.500
hisgrep/perl	00:00:00.010	0.0%	32	0.000	0.312	5.000
hishave/artcncl	00:00:00.000	0.0%	29	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	27	0.000	0.037	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.494	0.0%	199067	0.005	0.018	0.032
history sync	00:00:00.042	0.0%	383	0.000	0.110	0.667
history write	00:00:08.154	0.0%	39216	0.114	0.208	0.367
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	23:56:29.203	99.7%	350783	126.278	245.705	1129.714
nntp read	00:00:10.060	0.0%	350605	0.018	0.029	0.046
overview write	00:00:10.857	0.0%	35560	0.174	0.305	0.854
perl filter	00:02:06.302	0.1%	39216	2.687	3.221	5.167
python filter	00:00:00.185	0.0%	39216	0.000	0.005	0.023
site send	00:00:09.491	0.0%	73612	0.048	0.129	0.242
TOTAL: 24:01:02.974	24:00:02.899	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.320	0.0%	35877	0.000	0.009	0.040
article prepare	00:00:00.036	0.0%	10800	0.000	0.003	0.047
article read	00:00:00.265	0.0%	1611	0.000	0.164	0.750
backlog stats	00:00:02.519	0.0%	552240	0.001	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:08.878	0.0%	371398	0.012	0.024	0.042
data write	00:00:14.539	0.0%	295101	0.036	0.049	0.076
idle	23:51:51.273	99.9%	552240	78.704	155.569	757.847
status file	00:00:00.542	0.0%	220	1.000	2.464	9.000
TOTAL: 23:52:43.487	23:52:18.372	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:25.462	18.0%	1445	10.600	17.621	8989.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.087	0.1%	3202	0.000	0.027	0.273
TOTAL: 00:02:21.282	00:00:25.549	18.1%	-	-	-	-

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	6	36629	20941	13454	2234	57%	25:28:24
2	news.glou.org	4	10429	3588	5784	1057	34%	23:58:00
3	news.samoylyk.net	5	34220	3565	30655	0	10%	24:30:09
4	feed-out.gegeweb.org	4	4536	3526	1010	0	77%	25:09:38
5	gegeweb-out.news.tnetconsulting.net	5	32412	3058	29323	31	9%	23:07:12
6	nntp.terraraq.uk	2	11655	747	10565	343	6%	28:05:32
7	usenet.pasdenom.info	5	896	39	856	1	4%	07:00:26
8	i2pn.org	2	4391	12	4379	0	0%	23:59:45
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		58	135168	35476	96026	3666	26%	181:19:06

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	185.6 MB	19.5 MB	205.0 MB	90%	9.1 KB
2	news.samoylyk.net	33.5 MB	0.0 KB	33.5 MB	100%	9.6 KB
3	news.glou.org	27.1 MB	5.8 MB	32.9 MB	82%	7.2 KB
4	gegeweb-out.news.tnetconsulting.net	24.0 MB	208.4 KB	24.2 MB	99%	8.0 KB
5	feed-out.gegeweb.org	15.3 MB	0.0 KB	15.3 MB	100%	4.4 KB
6	nntp.terraraq.uk	5.6 MB	2.3 MB	7.8 MB	71%	7.4 KB
7	usenet.pasdenom.info	89.4 KB	1.8 KB	91.2 KB	97%	2.3 KB
8	i2pn.org	21.3 KB	0.0 KB	21.3 KB	100%	1.8 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		291.1 MB	27.7 MB	318.8 MB	91%	8.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 12 04:15:01 - 04:59:59	363	1.0%	0.13	2.7 MB	0.9%	1.04
Oct 12 05:00:00 - 05:59:59	883	2.5%	0.25	5.9 MB	2.0%	1.66
Oct 12 06:00:00 - 06:59:59	2501	7.0%	0.69	21.5 MB	7.4%	6.11
Oct 12 07:00:00 - 07:59:59	1959	5.5%	0.54	17.4 MB	5.9%	4.94
Oct 12 08:00:00 - 08:59:59	1178	3.3%	0.33	10.8 MB	3.7%	3.08
Oct 12 09:00:00 - 09:59:59	1240	3.5%	0.34	9.3 MB	3.2%	2.63
Oct 12 10:00:00 - 10:59:59	2620	7.4%	0.73	23.3 MB	8.0%	6.61
Oct 12 11:00:00 - 11:59:59	2216	6.2%	0.62	18.3 MB	6.3%	5.22
Oct 12 12:00:00 - 12:59:59	1198	3.4%	0.33	9.7 MB	3.3%	2.77
Oct 12 13:00:00 - 13:59:59	641	1.8%	0.18	5.1 MB	1.8%	1.45
Oct 12 14:00:00 - 14:59:59	673	1.9%	0.19	4.9 MB	1.7%	1.38
Oct 12 15:00:00 - 15:59:59	557	1.6%	0.15	3.2 MB	1.1%	0.92
Oct 12 16:00:00 - 16:59:59	535	1.5%	0.15	2.7 MB	0.9%	0.78
Oct 12 17:00:00 - 17:59:59	868	2.4%	0.24	6.0 MB	2.0%	1.70
Oct 12 18:00:00 - 18:59:59	2247	6.3%	0.62	19.1 MB	6.5%	5.43
Oct 12 19:00:00 - 19:59:59	2042	5.7%	0.57	17.7 MB	6.1%	5.03
Oct 12 20:00:00 - 20:59:59	1087	3.0%	0.30	8.0 MB	2.7%	2.28
Oct 12 21:00:00 - 21:59:59	973	2.7%	0.27	7.1 MB	2.4%	2.02
Oct 12 22:00:00 - 22:59:59	2366	6.6%	0.66	19.9 MB	6.8%	5.66
Oct 12 23:00:00 - 23:59:59	1923	5.4%	0.53	17.4 MB	5.9%	4.94
Oct 13 00:00:00 - 00:59:59	1454	4.1%	0.40	11.0 MB	3.8%	3.13
Oct 13 01:00:00 - 01:59:59	1281	3.6%	0.36	10.9 MB	3.7%	3.11
Oct 13 02:00:00 - 02:59:59	2506	7.0%	0.70	21.5 MB	7.4%	6.11
Oct 13 03:00:00 - 03:59:59	1925	5.4%	0.53	15.5 MB	5.3%	4.40
Oct 13 04:00:00 - 04:15:01	408	1.1%	0.45	3.1 MB	1.0%	3.48
TOTAL: 24:00:00	35644	100.0%	0.41	291.8 MB	100.0%	3.46

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	2223	0	2223
2	news.glou.org	1069	0	1069
3	nntp.terraraq.uk	315	0	315
4	gegeweb-out.news.tnetconsulting.net	33	2	31
5	usenet.pasdenom.info	14	0	14
6	i2pn.org	1	0	1
TOTAL: 6		3655	2	3653

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2720
EMP (phr nph)	611
Bad path (news.giganews.com.POSTED)	82
EMP (phn path)	66
Too many newsgroups	64
Too many newsgroups (meow)	40
EMP (phl)	34
hierarchy violation - crosspost outside sdnet	29
Cancel for rejected article	3
Cancel of <65283753$0$25959$426a74cc@news.free.fr> without Cancel-Key	2
HTML post	1
Cancel of non-existing ID <a984d0df-aa8b-49c7-a135-693e65ed947an@googlegroups.com>	1
TOTAL: 12	3653

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	16837	527	14115	0	0	3%	23:59:56
2	nntp.terraraq.uk	37545	467	33595	120	0	1%	23:59:56
3	news.tnetconsulting.net	62428	385	35173	85	33	0%	23:59:56
4	news.samoylyk.net	47903	194	31615	89	48	0%	23:59:56
5	i2pn.org	61867	104	34748	679	180	0%	23:59:45
6	news.pasdenom.info	36335	17	35211	26	108	0%	23:59:56
7	news.gegeweb.org	33949	4	32137	8	12	0%	23:59:56
8	news.aioe.org	0	0	0	0	5361	0%	23:59:55
TOTAL: 8		296864	1698	216594	1007	5742	0%	191:59:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	10.0 KB	23:59:56
2	nntp.terraraq.uk	4.6 MB	3.2 MB	7.7 MB	0.1 KB/s	13.5 KB	23:59:56
3	news.tnetconsulting.net	3.2 MB	425.3 KB	3.6 MB	0.0 KB/s	7.9 KB	23:59:56
4	news.samoylyk.net	1.8 MB	525.3 KB	2.3 MB	0.0 KB/s	8.2 KB	23:59:56
5	i2pn.org	422.8 KB	5.2 MB	5.6 MB	0.1 KB/s	7.3 KB	23:59:45
6	news.pasdenom.info	77.3 KB	300.3 KB	377.6 KB	0.0 KB/s	8.8 KB	23:59:56
7	news.gegeweb.org	21.5 KB	129.0 KB	150.5 KB	0.0 KB/s	12.5 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 8		15.2 MB	9.7 MB	24.9 MB	0.0 KB/s	9.4 KB	191:59:16

NNRP readership statistics:

System		Conn	Elapsed
1	45.227.254.48	1	00:00:00
2	mail.gegeweb.eu	288	00:01:56
3	classical.monitoring.internet-measurement.com	1	00:00:00
4	208.100.26.243	10	00:00:04
5	162.243.138.52	1	00:00:09
6	fabulous.monitoring.internet-measurement.com	1	00:00:06
7	ec2-54-153-120-66.us-west-1.compute.amazonaws.com	1	00:00:01
8	lucid.monitoring.internet-measurement.com	1	00:00:00
9	194.165.16.73	1	00:00:00
10	grand.monitoring.internet-measurement.com	1	00:00:00
11	230.149.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 11		307	00:02:20

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.149.216.162.bc.googleusercontent.com	1	00:00:00
2	*.gegeweb.eu	288	00:01:56
3	*.us-west-1.compute.amazonaws.com	1	00:00:01
4	unresolved	13	00:00:15
5	*.monitoring.internet-measurement.com	4	00:00:06
TOTAL: 5		307	00:02:20

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.394	4.022	1.593	00:01:56
162.243.138.52	0.012	0.012	0.000	00:00:09
fabulous.monitoring.internet-measurement.com	0.055	0.017	0.000	00:00:06
208.100.26.243	0.195	0.098	0.000	00:00:04
ec2-54-153-120-66.us-west-1.compute.amazonaws.com	0.058	0.018	0.000	00:00:01
230.149.216.162.bc.googleusercontent.com	0.015	0.008	0.000	00:00:00
194.165.16.73	0.035	0.038	0.000	00:00:00
45.227.254.48	0.007	0.015	0.000	00:00:00
lucid.monitoring.internet-measurement.com	0.034	0.030	0.000	00:00:00
classical.monitoring.internet-measurement.com	0.021	0.007	0.000	00:00:00
grand.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
TOTAL: 11	4.842	4.277	1.593	00:02:20

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
230.149.216.162.bc.googleusercontent.com	3
45.227.254.48	1
TOTAL: 2	4

NNRP unrecognized commands (by command):

Command	Count
#003	1
User-Agent: Expanse, a Palo Alto Network...	1
Host: 45.13.105.36:119	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.48	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Oct 12 04:15:01 -- Oct 13 04:15:01