Daily Usenet report for news.niel.me: Jan 15 04:15:02

Unknown entries from news log file:

First 50 / 173 lines (28.9%)

Jan 15 12:24:11 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 15 12:24:49 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 15 12:25:20 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 15 12:25:20 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 15 12:25:23 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 15 12:25:26 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 15 12:27:26 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 15 12:30:13 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 15 12:30:50 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 15 12:31:23 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 15 12:31:23 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 15 12:31:23 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 15 12:39:26 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 15 12:54:16 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 15 12:54:51 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 15 12:55:26 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 15 12:55:27 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 15 12:55:27 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 15 12:55:27 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 15 14:26:17 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 15 17:00:05 rpi-studio pgpverify[4309]: pgpverify: verification failed
Jan 15 17:00:06 rpi-studio pgpverify[4322]: pgpverify: verification failed
Jan 16 02:12:18 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 16 02:12:18 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 16 02:12:18 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 16 02:12:18 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 16 02:12:18 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 16 02:12:22 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 16 02:14:22 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 16 02:18:18 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 16 02:18:18 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 16 02:18:18 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 16 02:18:18 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 16 02:18:18 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 16 02:26:22 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 16 02:42:18 rpi-studio innfeed[18205]: news.gegeweb.org:0 connect: Network is unreachable
Jan 16 02:42:18 rpi-studio innfeed[18205]: news.nntp4.net:0 connect: Network is unreachable
Jan 16 02:42:18 rpi-studio innfeed[18205]: news.pasdenom.info:0 connect: Network is unreachable
Jan 16 02:42:18 rpi-studio innfeed[18205]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 16 02:42:18 rpi-studio innfeed[18205]: i2pn.org:0 connect: Network is unreachable
Jan 16 02:42:22 rpi-studio innfeed[18205]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 16 03:13:21 rpi-studio nnrpd[19284]: news.nntp4.net noperm ihave without permission
Jan 16 03:13:51 rpi-studio nnrpd[19287]: news.nntp4.net noperm ihave without permission
Jan 16 03:14:21 rpi-studio nnrpd[19432]: news.nntp4.net noperm ihave without permission
Jan 16 03:14:51 rpi-studio nnrpd[19443]: news.nntp4.net noperm ihave without permission
Jan 16 03:15:22 rpi-studio nnrpd[19460]: news.nntp4.net noperm ihave without permission
Jan 16 03:15:52 rpi-studio nnrpd[19464]: news.nntp4.net noperm ihave without permission
Jan 16 03:16:22 rpi-studio nnrpd[19469]: news.nntp4.net noperm ihave without permission
Jan 16 03:16:52 rpi-studio nnrpd[19473]: news.nntp4.net noperm ihave without permission
Jan 16 03:17:22 rpi-studio nnrpd[19487]: news.nntp4.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	5254	38.4%	916.8 KB	44.7%
innd	3887	28.4%	562.8 KB	27.4%
nnrpd	2483	18.2%	266.9 KB	13.0%
innfeed	2017	14.8%	301.5 KB	14.7%
controlchan	13	0.1%	2.2 KB	0.1%
nocem	11	0.1%	1.1 KB	0.1%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 7	13667	100.0%	2.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22786	54.6%
Negative hits	13774	33.0%
Do not exist	5116	12.3%
Cache misses	52	0.1%
TOTAL: 4	41728	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.055	0.0%	20	0.000	2.750	14.000
article cancel	00:00:00.007	0.0%	30	0.000	0.233	1.000
article cleanup	00:00:00.105	0.0%	5165	0.000	0.020	0.118
article logging	00:00:00.396	0.0%	5209	0.000	0.076	0.200
article parse	00:00:01.032	0.0%	12217	0.000	0.084	0.235
article write	00:00:03.301	0.0%	4501	0.222	0.733	3.960
artlog/artcncl	00:00:00.002	0.0%	30	0.000	0.067	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	20	0.000	0.050	0.500
data move	00:00:00.133	0.0%	42456	0.000	0.003	0.017
hisgrep/artcncl	00:00:00.001	0.0%	22	0.000	0.045	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	39	0.000	0.026	1.000
hishave/artcncl	00:00:00.000	0.0%	30	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	20	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.590	0.0%	41839	0.000	0.014	0.062
history sync	00:00:00.048	0.0%	404	0.000	0.119	1.000
history write	00:00:01.125	0.0%	5124	0.000	0.220	0.500
hiswrite/artcncl	00:00:00.002	0.0%	8	0.000	0.250	1.000
idle	23:59:19.465	99.9%	45919	621.165	1880.691	300335.500
nntp read	00:00:01.421	0.0%	44874	0.000	0.032	0.154
overview write	00:00:02.504	0.0%	4501	0.000	0.556	4.462
perl filter	00:00:24.585	0.0%	5124	2.437	4.798	9.857
python filter	00:00:00.015	0.0%	5124	0.000	0.003	0.118
site send	00:00:01.890	0.0%	9150	0.000	0.207	0.476
TOTAL: 24:01:21.150	23:59:56.679	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.069	0.0%	7460	0.000	0.009	0.133
article prepare	00:00:00.000	0.0%	656	0.000	0.000	0.000
article read	00:00:00.037	0.0%	161	0.000	0.230	8.000
backlog stats	00:00:00.114	0.0%	51108	0.000	0.002	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.713	0.0%	31603	0.000	0.023	0.070
data write	00:00:01.897	0.0%	27367	0.000	0.069	0.500
idle	23:59:04.238	100.0%	51108	542.782	1689.447	21437.714
status file	00:00:00.939	0.0%	357	1.000	2.630	9.000
TOTAL: 23:59:11.359	23:59:08.007	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:05:06.215	88.9%	1529	6.500	200.271	26935.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.122	0.0%	3669	0.000	0.033	0.444
readart	00:00:00.003	0.0%	7	0.000	0.429	1.500
TOTAL: 00:05:44.498	00:05:06.340	88.9%	-	-	-	-

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

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	news-out.aioe.org	7	3874	2251	1558	65	58%	26:40:50
2	gegeweb-out.news.tnetconsulting.net	8	4146	543	3540	63	13%	27:45:12
3	feed-out.gegeweb.org	253	2974	444	2524	6	14%	26:59:22
4	nntp.terraraq.uk	7	4341	327	3784	230	7%	27:31:47
5	i2pn.org	7	3884	226	3651	7	5%	27:38:41
6	usenet.pasdenom.info	8	3105	196	2908	1	6%	26:54:35
7	188.165.224.178	18	220	191	29	0	86%	01:52:37
8	news.nntp4.net	6	4576	151	4263	162	3%	25:45:46
9	46.165.242.75	0	52	33	19	0	63%	01:39:01
10	37.187.2.128	0	104	9	92	3	8%	01:42:31
11	localhost	27	0	0	0	0	0%	00:00:00
TOTAL: 11		341	27276	4371	22368	537	16%	194:30:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	6.3 MB	8.5 KB	176.2 KB	6.5 MB	97%	2.9 KB
2	nntp.terraraq.uk	3.5 MB	358.7 KB	1.8 MB	5.7 MB	61%	10.5 KB
3	gegeweb-out.news.tnetconsulting.net	3.2 MB	310.4 KB	579.4 KB	4.0 MB	78%	6.8 KB
4	news.nntp4.net	2.7 MB	221.6 KB	755.3 KB	3.7 MB	74%	12.0 KB
5	feed-out.gegeweb.org	1.9 MB	160.3 KB	161.8 KB	2.2 MB	85%	5.1 KB
6	188.165.224.178	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	6.1 KB
7	i2pn.org	814.2 KB	48.5 KB	70.4 KB	933.1 KB	87%	4.0 KB
8	usenet.pasdenom.info	435.4 KB	1.4 KB	1.4 KB	438.3 KB	99%	2.2 KB
9	37.187.2.128	155.1 KB	0.0 KB	3.5 KB	158.6 KB	97%	13.2 KB
10	46.165.242.75	96.1 KB	0.0 KB	0.0 KB	96.1 KB	100%	2.9 KB
11	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 11		20.2 MB	1.1 MB	3.6 MB	24.8 MB	81%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:02 - 04:59:59	62	1.4%	0.02	376.0 KB	0.0%	0.14
Jan 15 05:00:00 - 05:59:59	112	2.5%	0.03	400.7 KB	3.5%	0.11
Jan 15 06:00:00 - 06:59:59	107	2.4%	0.03	295.5 KB	2.6%	0.08
Jan 15 07:00:00 - 07:59:59	92	2.0%	0.03	376.0 KB	3.3%	0.10
Jan 15 08:00:00 - 08:59:59	109	2.4%	0.03	302.8 KB	2.6%	0.08
Jan 15 09:00:00 - 09:59:59	121	2.7%	0.03	437.5 KB	3.8%	0.12
Jan 15 10:00:00 - 10:59:59	147	3.3%	0.04	427.7 KB	3.7%	0.12
Jan 15 11:00:00 - 11:59:59	128	2.8%	0.04	838.2 KB	7.3%	0.23
Jan 15 12:00:00 - 12:59:59	172	3.8%	0.05	1.1 MB	10.3%	0.33
Jan 15 13:00:00 - 13:59:59	141	3.1%	0.04	411.7 KB	3.6%	0.11
Jan 15 14:00:00 - 14:59:59	155	3.4%	0.04	434.5 KB	3.8%	0.12
Jan 15 15:00:00 - 15:59:59	231	5.1%	0.06	938.0 KB	8.2%	0.26
Jan 15 16:00:00 - 16:59:59	260	5.8%	0.07	771.2 KB	6.7%	0.21
Jan 15 17:00:00 - 17:59:59	211	4.7%	0.06	1.1 MB	10.1%	0.32
Jan 15 18:00:00 - 18:59:59	387	8.6%	0.11	1.3 MB	11.6%	0.37
Jan 15 19:00:00 - 19:59:59	320	7.1%	0.09	1.1 MB	9.5%	0.30
Jan 15 20:00:00 - 20:59:59	299	6.7%	0.08	2.5 MB	22.4%	0.71
Jan 15 21:00:00 - 21:59:59	238	5.3%	0.07	913.6 KB	8.0%	0.25
Jan 15 22:00:00 - 22:59:59	213	4.7%	0.06	589.7 KB	5.2%	0.16
Jan 15 23:00:00 - 23:59:59	192	4.3%	0.05	704.0 KB	6.2%	0.20
Jan 16 00:00:00 - 00:59:59	207	4.6%	0.06	1.5 MB	13.6%	0.43
Jan 16 01:00:00 - 01:59:59	131	2.9%	0.04	1.8 MB	16.1%	0.51
Jan 16 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 16 03:00:00 - 03:59:59	439	9.8%	0.12	2.0 MB	17.9%	0.57
Jan 16 04:00:00 - 04:15:02	21	0.5%	0.02	54.3 KB	0.5%	0.06
TOTAL: 24:00:00	4495	100.0%	0.05	11.2 MB	100.0%	0.13

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	nntp.terraraq.uk	344	0	6	338
2	news.nntp4.net	167	1	5	161
3	news-out.aioe.org	95	1	4	90
4	gegeweb-out.news.tnetconsulting.net	71	0	17	54
5	i2pn.org	17	0	1	16
6	feed-out.gegeweb.org	10	0	5	5
7	188.165.224.178	4	0	2	2
8	usenet.pasdenom.info	1	0	1	0
TOTAL: 8		709	2	41	666

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.immobilier	1
news.test	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	210
Too many newsgroups	167
Bad path (news.giganews.com.POSTED)	109
EMP (md5)	103
User-issued spam cancel	8
EMP (phn path)	6
Cancel of <srukrm$h4e$1@dont-email.me> without Cancel-Key	1
Cancel of <sru9en$8ht$1@dont-email.me> without Cancel-Key	1
Cancel of <srukl6$fvk$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <b3496a6b-4cb7-4aa6-8223-2104c23381aan@googlegroups.com>	1
Cancel of <srtsqu$51n$1@dont-email.me> without Cancel-Key	1
Cancel of <srtk7l$vat$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <7NLEJ.4975$9O.4472@fx12.iad>	1
Cancel of <sruc34$o8u$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <kr05ugtod1v76vfqag9eoc32vrrj6n7m7d@4ax.com>	1
Cancel of non-existing ID <pDLEJ.660$VK4.562@fx08.iad>	1
Cancel of <srtjv4$uac$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <8HLEJ.663$VK4.288@fx08.iad>	1
Cancel of <srur5j$t0e$1@dont-email.me> without Cancel-Key	1
Cancel of non-existing ID <NFLEJ.662$VK4.6@fx08.iad>	1
TOTAL: 25	622

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	32
	feed-out.gegeweb.org	4
	nntp.terraraq.uk	4
	news.nntp4.net	2
	i2pn.org	1
	188.165.224.178	1
	TOTAL: 6	44
TOTAL: 1		44

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	6153	124	3992	18	430	2%	23:59:57
2	news.tnetconsulting.net	6476	10	4477	5	612	0%	23:59:57
3	news.gegeweb.org	3659	2	3650	0	225	0%	23:59:57
4	news.aioe.org	1470	1	1389	3	8	0%	22:37:23
5	nntp.terraraq.uk	2983	0	2926	0	558	0%	23:59:57
6	news.nntp4.net	4578	0	4326	0	581	0%	23:59:57
7	news.pasdenom.info	4160	0	4083	1	551	0%	23:59:57
TOTAL: 7		29479	137	24843	27	2965	0%	166:37:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	291.4 KB	111.0 KB	402.5 KB	2.8 KB	23:59:57
2	news.tnetconsulting.net	26.3 KB	84.1 KB	110.4 KB	7.4 KB	23:59:57
3	news.gegeweb.org	4.0 KB	0.0 KB	4.0 KB	2.0 KB	23:59:57
4	news.aioe.org	1.1 KB	6.4 KB	7.5 KB	1.9 KB	22:37:23
5	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
7	news.pasdenom.info	0.0 KB	1.3 KB	1.3 KB	1.3 KB	23:59:57
TOTAL: 7		322.8 KB	202.7 KB	525.6 KB	3.2 KB	166:37:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	192.168.1.90	3	5	42.1 KB	4	0	0	00:00:45
TOTAL: 1		3	5	42.1 KB	4	0	0	00:05:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	21	5	42.1 KB	4	00:04:44
2	*.nntp4.net	123	0	0.0 KB	0	00:00:23
3	*.gegeweb.eu	262	0	0.0 KB	0	00:00:35
TOTAL: 3		406	5	42.1 KB	4	00:05:43

NNRP auth users [Top 20]:

User	Conn
mailman	262
TOTAL: 1	262

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
46.165.242.75	0.249	0.290	0.000	00:03:52
192.168.1.90	0.106	0.133	0.000	00:00:45
sys.gegeweb.eu	7.573	8.326	2.957	00:00:35
news.nntp4.net	3.631	3.527	0.000	00:00:23
88.165.5.23	0.180	0.230	0.000	00:00:05
79.124.62.106	0.027	0.036	0.000	00:00:01
185.193.88.166	0.023	0.045	0.000	00:00:00
TOTAL: 7	11.789	12.587	2.957	00:05:43

Curious NNRP server explorers [Top 20]:

System	Conn
sys.gegeweb.eu	262
news.nntp4.net	123
46.165.242.75	9
88.165.5.23	7
185.193.88.166	1
79.124.62.106	1
TOTAL: 6	403

NNRP unrecognized commands (by host):

System	Conn
79.124.62.106	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
79.124.62.106	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	2	40.0%
2	grisbi	2	40.0%
3	news	1	20.0%
TOTAL: 3		5	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	fr.usenet.forums.evolution	2
2	grisbi.user.french	1
3	news.admin.hierarchies	1
4	grisbi.test	1
TOTAL: 4		5

Daily Usenet report for news.niel.me

Jan 15 04:15:02 -- Jan 16 04:15:02