Daily Usenet report for news.niel.me: Feb 7 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 7 10:30:47 rpi-studio innd: filter: No Cancel-Key[sha1:RgWqPBDw76ewZRe5dHzr2QUA5cI= sha256:5G67OjXQOQHqb+l7xOBi0TOIQjgyaxPm9akDVeU84tU=#015#012#011sha1:w7Y2ZyJbYqMsooI1Zi3SUZTDG4k= sha256:+IKkcapr0Y1+a0nFaxppjJnxqnRBce2iO/dnil7i674=] matches Cancel-Lock[sha1:3FfWfasQPJng4xRf7tThpTfOky8= sha256:ENdnsrrj/DpjuuOHCqY/cKwPNi/M52ZK7yjS5uN2k/8=] target=<upvihl$h2b$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15552	67.2%	3.1 MB	74.2%
innd	3239	14.0%	507.6 KB	11.9%
nnrpd	2418	10.4%	270.4 KB	6.3%
innfeed	1928	8.3%	323.7 KB	7.6%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	23139	100.0%	4.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	23635	38.7%
Negative hits	22079	36.1%
Do not exist	15403	25.2%
Cache misses	9	0.0%
TOTAL: 4	61126	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.034	0.0%	27	0.000	1.259	3.500
article cancel	00:00:00.004	0.0%	28	0.000	0.143	1.000
article cleanup	00:00:00.302	0.0%	15497	0.000	0.019	0.065
article logging	00:00:01.188	0.0%	15497	0.016	0.077	0.178
article parse	00:00:04.508	0.0%	80057	0.022	0.056	0.102
article write	00:00:09.465	0.0%	14236	0.382	0.665	1.330
artlog/artcncl	00:00:00.000	0.0%	28	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.004	0.0%	27	0.000	0.148	1.000
data move	00:00:00.716	0.0%	143740	0.001	0.005	0.014
hisgrep/artcncl	00:00:00.001	0.0%	28	0.000	0.036	1.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	27	0.000	0.037	1.000
hisgrep/perl	00:00:00.000	0.0%	30	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	28	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	27	0.000	0.037	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.132	0.0%	61334	0.003	0.018	0.081
history sync	00:00:00.044	0.0%	406	0.000	0.108	0.667
history write	00:00:02.757	0.0%	15497	0.027	0.178	0.327
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:12.811	99.9%	126166	458.737	684.438	1087.549
nntp read	00:00:03.722	0.0%	125147	0.014	0.030	0.065
overview write	00:00:04.841	0.0%	14236	0.118	0.340	2.351
perl filter	00:00:52.575	0.1%	15497	2.537	3.393	4.880
python filter	00:00:00.060	0.0%	15497	0.000	0.004	0.024
site send	00:00:03.605	0.0%	29712	0.030	0.121	0.291
TOTAL: 24:01:01.659	24:00:37.771	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.118	0.0%	14248	0.000	0.008	0.042
article prepare	00:00:00.013	0.0%	5304	0.000	0.002	0.036
article read	00:00:00.146	0.0%	943	0.000	0.155	1.667
backlog stats	00:00:00.988	0.0%	214022	0.001	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.482	0.0%	143655	0.008	0.024	0.041
data write	00:00:05.868	0.0%	111723	0.032	0.053	0.075
idle	23:54:57.864	100.0%	214022	285.966	402.285	792.360
status file	00:00:00.346	0.0%	203	0.000	1.704	8.000
TOTAL: 23:55:18.907	23:55:08.825	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:04.173	36.1%	1659	13.500	38.682	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.152	0.1%	3812	0.000	0.040	3.667
TOTAL: 00:02:57.816	00:01:04.325	36.2%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	2	15063	10260	3740	1063	68%	24:07:06
2	feed-out.gegeweb.org	2	3447	1578	1869	0	45%	23:57:30
3	news.glou.org	1	4010	1215	2661	134	30%	23:59:58
4	nntp.terraraq.uk	1	4665	567	4094	4	12%	29:10:34
5	gegeweb-out.news.tnetconsulting.net	2	5199	376	4765	58	7%	39:25:52
6	usenet.pasdenom.info	1	4088	292	3777	19	7%	36:38:20
7	i2pn.org	1	3258	213	3044	1	6%	21:15:47
8	news.samoylyk.net	1	200	1	199	0	0%	01:45:10
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		36	39930	14502	24149	1279	36%	200:20:17

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	110.4 MB	12.0 MB	122.3 MB	90%	11.1 KB
2	news.glou.org	10.5 MB	397.0 KB	10.9 MB	96%	8.2 KB
3	feed-out.gegeweb.org	6.1 MB	0.0 KB	6.1 MB	100%	4.0 KB
4	nntp.terraraq.uk	2.4 MB	10.3 KB	2.4 MB	99%	4.2 KB
5	i2pn.org	1.4 MB	8.6 KB	1.4 MB	99%	6.9 KB
6	gegeweb-out.news.tnetconsulting.net	1.0 MB	205.7 KB	1.2 MB	83%	2.9 KB
7	usenet.pasdenom.info	929.2 KB	26.8 KB	956.0 KB	97%	3.1 KB
8	news.samoylyk.net	2.6 KB	0.0 KB	2.6 KB	100%	2.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		132.6 MB	12.6 MB	145.2 MB	91%	9.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 7 04:15:01 - 04:59:59	358	2.5%	0.13	3.5 MB	2.7%	1.34
Feb 7 05:00:00 - 05:59:59	532	3.7%	0.15	5.4 MB	4.1%	1.53
Feb 7 06:00:00 - 06:59:59	636	4.5%	0.18	7.1 MB	5.4%	2.01
Feb 7 07:00:00 - 07:59:59	563	4.0%	0.16	5.6 MB	4.3%	1.61
Feb 7 08:00:00 - 08:59:59	609	4.3%	0.17	6.1 MB	4.6%	1.74
Feb 7 09:00:00 - 09:59:59	598	4.2%	0.17	6.4 MB	4.8%	1.81
Feb 7 10:00:00 - 10:59:59	615	4.3%	0.17	6.4 MB	4.9%	1.82
Feb 7 11:00:00 - 11:59:59	542	3.8%	0.15	5.4 MB	4.1%	1.53
Feb 7 12:00:00 - 12:59:59	561	3.9%	0.16	5.7 MB	4.3%	1.61
Feb 7 13:00:00 - 13:59:59	658	4.6%	0.18	6.5 MB	4.9%	1.86
Feb 7 14:00:00 - 14:59:59	660	4.6%	0.18	6.7 MB	5.1%	1.90
Feb 7 15:00:00 - 15:59:59	566	4.0%	0.16	5.3 MB	4.0%	1.50
Feb 7 16:00:00 - 16:59:59	570	4.0%	0.16	5.2 MB	3.9%	1.48
Feb 7 17:00:00 - 17:59:59	562	3.9%	0.16	4.6 MB	3.5%	1.31
Feb 7 18:00:00 - 18:59:59	523	3.7%	0.15	4.6 MB	3.5%	1.31
Feb 7 19:00:00 - 19:59:59	554	3.9%	0.15	4.2 MB	3.1%	1.18
Feb 7 20:00:00 - 20:59:59	629	4.4%	0.17	5.0 MB	3.8%	1.42
Feb 7 21:00:00 - 21:59:59	706	5.0%	0.20	5.8 MB	4.4%	1.65
Feb 7 22:00:00 - 22:59:59	709	5.0%	0.20	6.1 MB	4.6%	1.74
Feb 7 23:00:00 - 23:59:59	612	4.3%	0.17	5.2 MB	4.0%	1.49
Feb 8 00:00:00 - 00:59:59	621	4.4%	0.17	5.3 MB	4.0%	1.52
Feb 8 01:00:00 - 01:59:59	613	4.3%	0.17	5.6 MB	4.3%	1.61
Feb 8 02:00:00 - 02:59:59	548	3.9%	0.15	4.4 MB	3.3%	1.24
Feb 8 03:00:00 - 03:59:59	564	4.0%	0.16	4.7 MB	3.6%	1.34
Feb 8 04:00:00 - 04:15:02	124	0.9%	0.14	1.2 MB	0.9%	1.32
TOTAL: 24:00:01	14233	100.0%	0.16	132.0 MB	100.0%	1.56

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	1073	1073
2	news.glou.org	134	134
3	gegeweb-out.news.tnetconsulting.net	42	42
4	usenet.pasdenom.info	11	11
5	nntp.terraraq.uk	3	3
6	i2pn.org	1	1
TOTAL: 6		1264	1264

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	926
Too many newsgroups	163
EMP (phn path)	93
Bad path (news.giganews.com.POSTED)	65
Binary: misplaced binary	7
Cancels abusifs	3
Cancel for rejected article	2
User-issued spam cancel	2
HTML Multipart	1
Cancel of <uq18p5$1fqu6$2@solani.org> without Cancel-Key	1
Cancel of <17b178497604578d$38301$23896$64d91c8e@news.vipernews.com> without Cancel-Key	1
TOTAL: 11	1264

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	23160	176	13258	465	24	0%	23:59:58
2	nntp.terraraq.uk	13564	148	12658	63	0	1%	23:59:58
3	news.nntp4.net	4465	133	3769	0	0	2%	23:59:58
4	news.gegeweb.org	14878	29	12603	0	0	0%	23:59:50
5	news.samoylyk.net	18889	11	14045	120	16	0%	23:59:51
6	news.tnetconsulting.net	22031	6	14052	172	6	0%	23:59:57
7	news.pasdenom.info	14634	1	13839	2	0	0%	23:59:53
8	news.aioe.org	0	0	0	0	7443	0%	23:59:58
TOTAL: 8		111621	504	84224	822	7489	0%	191:59:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	2.2 MB	0.0 KB	2.2 MB	0.0 KB/s	16.6 KB	23:59:58
2	nntp.terraraq.uk	2.0 MB	599.2 KB	2.6 MB	0.0 KB/s	12.8 KB	23:59:58
3	i2pn.org	757.0 KB	4.3 MB	5.0 MB	0.1 KB/s	8.0 KB	23:59:58
4	news.gegeweb.org	214.2 KB	0.0 KB	214.2 KB	0.0 KB/s	7.4 KB	23:59:50
5	news.samoylyk.net	30.8 KB	1.8 MB	1.8 MB	0.0 KB/s	14.1 KB	23:59:51
6	news.tnetconsulting.net	17.2 KB	2.0 MB	2.0 MB	0.0 KB/s	11.8 KB	23:59:57
7	news.pasdenom.info	2.7 KB	11.2 KB	14.0 KB	0.0 KB/s	4.7 KB	23:59:53
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:58
TOTAL: 8		5.2 MB	8.7 MB	13.9 MB	0.0 KB/s	10.7 KB	191:59:23

NNRP readership statistics:

System		Conn	Elapsed
1	dependable.monitoring.internet-measurement.com	1	00:00:00
2	vivacious.monitoring.internet-measurement.com	2	00:00:02
3	80.66.88.211	1	00:00:00
4	marvellous.monitoring.internet-measurement.com	1	00:00:05
5	106.75.99.41	1	00:00:02
6	194.165.16.10	1	00:00:01
7	35-157-n1.p04.customer.vsm.sh	1	00:00:01
8	172-104-137-47.ip.linodeusercontent.com	210	00:00:44
9	exalted.monitoring.internet-measurement.com	1	00:00:00
10	courageous.monitoring.internet-measurement.com	1	00:00:02
11	mail.gegeweb.eu	288	00:01:44
12	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:11
TOTAL: 12		509	00:02:57

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	6	00:00:10
2	*.w92-154.abo.wanadoo.fr	1	00:00:11
3	*.p04.customer.vsm.sh	1	00:00:01
4	*.ip.linodeusercontent.com	210	00:00:44
5	*.gegeweb.eu	288	00:01:44
6	unresolved	3	00:00:03
TOTAL: 6		509	00:02:57

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.328	4.459	1.536	00:01:44
172-104-137-47.ip.linodeusercontent.com	5.262	3.248	0.000	00:00:44
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.010	0.014	0.000	00:00:11
marvellous.monitoring.internet-measurement.com	0.071	0.024	0.000	00:00:05
courageous.monitoring.internet-measurement.com	0.011	0.016	0.000	00:00:02
vivacious.monitoring.internet-measurement.com	0.060	0.021	0.000	00:00:02
106.75.99.41	0.022	0.029	0.000	00:00:02
35-157-n1.p04.customer.vsm.sh	0.032	0.024	0.000	00:00:01
194.165.16.10	0.044	0.020	0.000	00:00:01
exalted.monitoring.internet-measurement.com	0.029	0.029	0.000	00:00:00
80.66.88.211	0.008	0.016	0.000	00:00:00
dependable.monitoring.internet-measurement.com	0.043	0.020	0.000	00:00:00
TOTAL: 12	9.920	7.920	1.536	00:02:57

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
80.66.88.211	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.211	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Feb 7 04:15:01 -- Feb 8 04:15:02