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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 15 17:00:05 rpi-studio pgpverify[16757]: pgpverify: verification failed
Feb 15 17:00:06 rpi-studio pgpverify[16772]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3955	38.7%	764.4 KB	45.1%
innfeed	2250	22.0%	356.8 KB	21.1%
innd	2207	21.6%	380.3 KB	22.5%
nnrpd	1781	17.4%	189.6 KB	11.2%
controlchan	13	0.1%	2.2 KB	0.1%
nocem	5	0.0%	0.4 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 7	10213	100.0%	1.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	21111	44.1%
Negative hits	13849	28.9%
Cache misses	9020	18.8%
Do not exist	3873	8.1%
TOTAL: 4	47853	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.046	0.0%	23	1.000	2.000	9.000
article cancel	00:00:00.008	0.0%	27	0.000	0.296	1.000
article cleanup	00:00:00.094	0.0%	3892	0.000	0.024	0.176
article logging	00:00:00.313	0.0%	3907	0.000	0.080	0.308
article parse	00:00:00.777	0.0%	10210	0.000	0.076	0.217
article write	00:00:05.484	0.0%	3544	0.333	1.547	3.875
artlog/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.004	0.0%	23	0.000	0.174	1.000
data move	00:00:00.173	0.0%	52791	0.000	0.003	0.018
hisgrep/artcncl	00:00:00.002	0.0%	27	0.000	0.074	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hisgrep/perl	00:00:00.004	0.0%	27	0.000	0.148	1.000
hishave/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	23	0.000	0.043	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.640	0.0%	47901	0.000	0.013	0.039
history sync	00:00:00.042	0.0%	406	0.000	0.103	1.000
history write	00:00:00.816	0.0%	3882	0.000	0.210	0.556
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:35.680	99.9%	53801	126.857	1606.581	8113.662
nntp read	00:00:01.576	0.0%	53202	0.009	0.030	0.058
overview write	00:00:01.824	0.0%	3544	0.100	0.515	5.000
perl filter	00:00:17.035	0.0%	3882	2.647	4.388	10.783
python filter	00:00:00.004	0.0%	3882	0.000	0.001	0.040
site send	00:00:01.435	0.0%	7152	0.000	0.201	0.479
TOTAL: 24:01:19.200	24:01:05.958	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	16463	0.000	0.004	0.077
article prepare	00:00:00.000	0.0%	324	0.000	0.000	0.000
article read	00:00:00.017	0.0%	58	0.000	0.293	4.000
backlog stats	00:00:00.141	0.0%	49694	0.000	0.003	0.015
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.682	0.0%	30937	0.000	0.022	0.079
data write	00:00:01.402	0.0%	27631	0.017	0.051	0.104
idle	23:59:00.828	100.0%	49694	86.210	1737.450	5317.912
status file	00:00:00.811	0.0%	418	1.000	1.940	6.000
TOTAL: 23:59:06.990	23:59:03.940	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:36.540	44.1%	1450	11.000	66.579	10009.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.085	0.0%	3193	0.000	0.027	1.000
TOTAL: 00:03:38.968	00:01:36.625	44.1%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	2	2	0	2
TOTAL	0	0	2	2	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	i2pn.org	1	3502	1186	2176	140	33%	23:45:14
2	feed-out.gegeweb.org	2	1998	838	1160	0	41%	23:58:50
3	news.glou.org	1	3238	385	2806	47	11%	23:59:58
4	news.nntp4.net	1	2935	375	2487	73	12%	19:09:57
5	gegeweb-out.news.tnetconsulting.net	1	5405	275	5114	16	5%	36:20:59
6	nntp.terraraq.uk	2	3403	136	3243	24	3%	23:50:05
7	news.samoylyk.net	1	3445	104	3340	1	3%	23:59:47
8	usenet.pasdenom.info	4	9401	19	9380	2	0%	02:03:31
9	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 9		39	33327	3318	29706	303	9%	177:08:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	5.4 MB	0.0 KB	408.3 KB	5.8 MB	93%	13.8 KB
2	i2pn.org	4.2 MB	79.7 KB	452.9 KB	4.7 MB	88%	3.6 KB
3	feed-out.gegeweb.org	2.7 MB	0.0 KB	0.0 KB	2.7 MB	100%	3.3 KB
4	gegeweb-out.news.tnetconsulting.net	2.1 MB	79.7 KB	134.6 KB	2.3 MB	90%	8.1 KB
5	news.nntp4.net	1.8 MB	79.7 KB	342.9 KB	2.2 MB	80%	5.0 KB
6	news.samoylyk.net	1.4 MB	0.0 KB	7.0 KB	1.4 MB	99%	13.9 KB
7	nntp.terraraq.uk	766.4 KB	79.7 KB	137.5 KB	983.7 KB	77%	6.1 KB
8	usenet.pasdenom.info	39.0 KB	0.0 KB	7.1 KB	46.1 KB	84%	2.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		18.4 MB	318.9 KB	1.5 MB	20.2 MB	91%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 15 04:15:01 - 04:59:59	63	1.8%	0.02	263.1 KB	1.4%	0.10
Feb 15 05:00:00 - 05:59:59	70	2.0%	0.02	384.4 KB	2.0%	0.11
Feb 15 06:00:00 - 06:59:59	107	3.0%	0.03	386.1 KB	2.0%	0.11
Feb 15 07:00:00 - 07:59:59	104	2.9%	0.03	407.8 KB	2.1%	0.11
Feb 15 08:00:00 - 08:59:59	146	4.1%	0.04	796.9 KB	4.1%	0.22
Feb 15 09:00:00 - 09:59:59	149	4.2%	0.04	648.3 KB	3.3%	0.18
Feb 15 10:00:00 - 10:59:59	111	3.1%	0.03	621.5 KB	3.2%	0.17
Feb 15 11:00:00 - 11:59:59	117	3.3%	0.03	374.1 KB	1.9%	0.10
Feb 15 12:00:00 - 12:59:59	144	4.1%	0.04	485.2 KB	2.5%	0.13
Feb 15 13:00:00 - 13:59:59	162	4.6%	0.04	547.8 KB	2.8%	0.15
Feb 15 14:00:00 - 14:59:59	151	4.3%	0.04	606.5 KB	3.1%	0.17
Feb 15 15:00:00 - 15:59:59	141	4.0%	0.04	724.3 KB	3.7%	0.20
Feb 15 16:00:00 - 16:59:59	173	4.9%	0.05	1.8 MB	9.3%	0.50
Feb 15 17:00:00 - 17:59:59	233	6.6%	0.06	1.4 MB	7.2%	0.39
Feb 15 18:00:00 - 18:59:59	279	7.9%	0.08	928.6 KB	4.8%	0.26
Feb 15 19:00:00 - 19:59:59	196	5.5%	0.05	758.2 KB	3.9%	0.21
Feb 15 20:00:00 - 20:59:59	203	5.7%	0.06	991.9 KB	5.1%	0.28
Feb 15 21:00:00 - 21:59:59	157	4.4%	0.04	722.2 KB	3.7%	0.20
Feb 15 22:00:00 - 22:59:59	138	3.9%	0.04	1.7 MB	8.8%	0.47
Feb 15 23:00:00 - 23:59:59	172	4.9%	0.05	1.9 MB	10.1%	0.55
Feb 16 00:00:00 - 00:59:59	155	4.4%	0.04	1022.1 KB	5.2%	0.28
Feb 16 01:00:00 - 01:59:59	116	3.3%	0.03	654.7 KB	3.4%	0.18
Feb 16 02:00:00 - 02:59:59	129	3.6%	0.04	633.4 KB	3.3%	0.18
Feb 16 03:00:00 - 03:59:59	84	2.4%	0.02	448.5 KB	2.3%	0.12
Feb 16 04:00:00 - 04:15:02	41	1.2%	0.05	180.5 KB	0.9%	0.20
TOTAL: 24:00:01	3541	100.0%	0.04	19.0 MB	100.0%	0.23

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	160	0	2	158
2	news.nntp4.net	88	0	2	86
3	news.glou.org	47	0	0	47
4	nntp.terraraq.uk	24	0	2	22
5	usenet.pasdenom.info	16	0	0	16
6	gegeweb-out.news.tnetconsulting.net	11	1	2	8
7	news.samoylyk.net	9	0	2	7
8	feed-out.gegeweb.org	9	0	0	9
TOTAL: 8		364	1	10	353

Unwanted newsgroups [Top 20]:

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	121
EMP (phn nph)	79
EMP (phn path)	76
Bad path (news.giganews.com.POSTED)	49
Too many newsgroups	5
Cancels abusifs	2
Cancel of non-existing ID <ts8fqh$1f48s$1@dont-email.me>	1
Cancel of non-existing ID <83830858-e42f-4010-93f6-de1138ce23a4n@googlegroups.com>	1
HTML Multipart	1
Cancel of <dalton-2BA2C4.03583615022023@news.eternal-september.org> without Cancel-Key	1
Cancel of non-existing ID <251d8f17-2030-4cfa-92a8-dd9615c2710dn@googlegroups.com>	1
TOTAL: 11	337

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	4	0	4	4
TOTAL: 1	4	0	4	4

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	9
	news.samoylyk.net	6
	i2pn.org	1
	TOTAL: 3	16
TOTAL: 1		16

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3446	33	2256	1	9	0%	23:59:57
2	news.nntp4.net	3105	9	3028	1	0	0%	23:57:22
3	news.tnetconsulting.net	4922	7	3527	1	13	0%	23:59:57
4	nntp.terraraq.uk	2595	6	2406	6	0	0%	23:59:57
5	news.gegeweb.org	2781	6	2695	5	0	0%	23:59:57
6	news.samoylyk.net	3729	5	3413	0	0	0%	23:59:44
7	news.pasdenom.info	15170	1	14993	0	908	0%	23:57:48
8	news.aioe.org	0	0	0	0	2785	0%	23:59:57
TOTAL: 8		35748	67	32318	14	3715	0%	191:54:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.pasdenom.info	125.9 KB	0.0 KB	125.9 KB	125.9 KB	23:57:48
2	news.nntp4.net	122.8 KB	4.0 KB	126.8 KB	12.7 KB	23:57:22
3	i2pn.org	121.0 KB	9.6 KB	130.5 KB	3.8 KB	23:59:57
4	news.tnetconsulting.net	64.2 KB	9.6 KB	73.7 KB	9.2 KB	23:59:57
5	nntp.terraraq.uk	60.4 KB	566.2 KB	626.6 KB	52.2 KB	23:59:57
6	news.samoylyk.net	57.4 KB	0.0 KB	57.4 KB	11.5 KB	23:59:44
7	news.gegeweb.org	16.3 KB	38.0 KB	54.4 KB	4.9 KB	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
TOTAL: 8		567.9 KB	627.4 KB	1.2 MB	14.8 KB	191:54:39

NNRP readership statistics:

System		Conn	Elapsed
1	88.165.5.23	1	00:00:00
2	gracious.monitoring.internet-measurement.com	1	00:00:00
3	mail.gegeweb.eu	288	00:03:19
4	commended.monitoring.internet-measurement.com	1	00:00:00
5	147.78.47.8	1	00:00:00
6	192.241.220.10	1	00:00:00
7	explorer.eu.edgewatch.com	1	00:00:10
8	talented.monitoring.internet-measurement.com	1	00:00:00
9	85.14.32.53	1	00:00:01
10	brilliant.monitoring.internet-measurement.com	1	00:00:05
TOTAL: 10		297	00:03:38

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:03:19
2	*.eu.edgewatch.com	1	00:00:10
3	unresolved	4	00:00:03
4	*.monitoring.internet-measurement.com	4	00:00:05
TOTAL: 4		297	00:03: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.031	4.531	2.053	00:03:19
explorer.eu.edgewatch.com	0.010	0.041	0.000	00:00:10
brilliant.monitoring.internet-measurement.com	0.029	0.057	0.000	00:00:05
85.14.32.53	0.042	0.010	0.000	00:00:01
192.241.220.10	0.025	0.025	0.000	00:00:00
88.165.5.23	0.024	0.024	0.000	00:00:00
147.78.47.8	0.038	0.013	0.000	00:00:00
gracious.monitoring.internet-measurement.com	0.019	0.010	0.000	00:00:00
talented.monitoring.internet-measurement.com	0.019	0.010	0.000	00:00:00
commended.monitoring.internet-measurement.com	0.000	0.029	0.000	00:00:00
TOTAL: 10	5.237	4.750	2.053	00:03:38

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
192.241.220.10	1
147.78.47.8	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
#003	1
MGLNDD_45.13.105.36_119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.78.47.8	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Feb 15 04:15:01 -- Feb 16 04:15:02