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

Unknown entries from news log file:

First 50 / 19202 lines (0.3%)

May 31 04:15:02 rpi-studio nnrpd[20225]: server.samoylyk.net noperm ihave without permission
May 31 04:15:03 rpi-studio nnrpd[20227]: news.glou.org noperm ihave without permission
May 31 04:15:10 rpi-studio nnrpd[20228]: news.nntp4.net noperm ihave without permission
May 31 04:15:14 rpi-studio nnrpd[20293]: nntp.terraraq.uk noperm ihave without permission
May 31 04:15:16 rpi-studio nnrpd[20355]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:15:20 rpi-studio nnrpd[20356]: server.samoylyk.net noperm ihave without permission
May 31 04:15:23 rpi-studio nnrpd[20359]: i2pn.org noperm ihave without permission
May 31 04:15:33 rpi-studio nnrpd[20389]: news.glou.org noperm ihave without permission
May 31 04:15:35 rpi-studio nnrpd[20390]: server.samoylyk.net noperm ihave without permission
May 31 04:15:40 rpi-studio nnrpd[20393]: news.nntp4.net noperm ihave without permission
May 31 04:15:44 rpi-studio nnrpd[20394]: nntp.terraraq.uk noperm ihave without permission
May 31 04:15:46 rpi-studio nnrpd[20396]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:15:50 rpi-studio nnrpd[20399]: server.samoylyk.net noperm ihave without permission
May 31 04:15:53 rpi-studio nnrpd[20402]: i2pn.org noperm ihave without permission
May 31 04:16:03 rpi-studio nnrpd[20415]: news.glou.org noperm ihave without permission
May 31 04:16:06 rpi-studio nnrpd[20416]: server.samoylyk.net noperm ihave without permission
May 31 04:16:11 rpi-studio nnrpd[20479]: news.nntp4.net noperm ihave without permission
May 31 04:16:14 rpi-studio nnrpd[20487]: nntp.terraraq.uk noperm ihave without permission
May 31 04:16:17 rpi-studio nnrpd[20488]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:16:21 rpi-studio nnrpd[20491]: server.samoylyk.net noperm ihave without permission
May 31 04:16:23 rpi-studio nnrpd[20492]: i2pn.org noperm ihave without permission
May 31 04:16:33 rpi-studio nnrpd[20501]: usenet.pasdenom.info noperm ihave without permission
May 31 04:16:33 rpi-studio nnrpd[20502]: news.glou.org noperm ihave without permission
May 31 04:16:37 rpi-studio nnrpd[20503]: server.samoylyk.net noperm ihave without permission
May 31 04:16:41 rpi-studio nnrpd[20506]: news.nntp4.net noperm ihave without permission
May 31 04:16:44 rpi-studio nnrpd[20507]: nntp.terraraq.uk noperm ihave without permission
May 31 04:16:48 rpi-studio nnrpd[20514]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:16:52 rpi-studio nnrpd[20517]: server.samoylyk.net noperm ihave without permission
May 31 04:16:53 rpi-studio nnrpd[20518]: i2pn.org noperm ihave without permission
May 31 04:17:03 rpi-studio nnrpd[20534]: news.glou.org noperm ihave without permission
May 31 04:17:07 rpi-studio nnrpd[20535]: server.samoylyk.net noperm ihave without permission
May 31 04:17:11 rpi-studio nnrpd[20538]: news.nntp4.net noperm ihave without permission
May 31 04:17:14 rpi-studio nnrpd[20539]: nntp.terraraq.uk noperm ihave without permission
May 31 04:17:19 rpi-studio nnrpd[20545]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:17:22 rpi-studio nnrpd[20548]: server.samoylyk.net noperm ihave without permission
May 31 04:17:24 rpi-studio nnrpd[20549]: i2pn.org noperm ihave without permission
May 31 04:17:33 rpi-studio nnrpd[20553]: news.glou.org noperm ihave without permission
May 31 04:17:37 rpi-studio nnrpd[20554]: server.samoylyk.net noperm ihave without permission
May 31 04:17:41 rpi-studio nnrpd[20557]: news.nntp4.net noperm ihave without permission
May 31 04:17:45 rpi-studio nnrpd[20558]: nntp.terraraq.uk noperm ihave without permission
May 31 04:17:49 rpi-studio nnrpd[20564]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:17:52 rpi-studio nnrpd[20567]: server.samoylyk.net noperm ihave without permission
May 31 04:17:55 rpi-studio nnrpd[20568]: i2pn.org noperm ihave without permission
May 31 04:18:03 rpi-studio nnrpd[20573]: news.glou.org noperm ihave without permission
May 31 04:18:07 rpi-studio nnrpd[20574]: server.samoylyk.net noperm ihave without permission
May 31 04:18:11 rpi-studio nnrpd[20577]: news.nntp4.net noperm ihave without permission
May 31 04:18:15 rpi-studio nnrpd[20578]: nntp.terraraq.uk noperm ihave without permission
May 31 04:18:19 rpi-studio nnrpd[20584]: tncsrv06.tnetconsulting.net noperm ihave without permission
May 31 04:18:23 rpi-studio nnrpd[20587]: server.samoylyk.net noperm ihave without permission
May 31 04:18:25 rpi-studio nnrpd[20588]: i2pn.org noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	89488	94.1%	9.3 MB	91.1%
inn	2821	3.0%	507.2 KB	4.9%
innfeed	1595	1.7%	236.5 KB	2.3%
innd	1164	1.2%	186.5 KB	1.8%
controlchan	6	0.0%	1.1 KB	0.0%
nocem	1	0.0%	0.0 KB	0.0%
TOTAL: 6	95075	100.0%	10.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22452	68.0%
Cache misses	6943	21.0%
Do not exist	2799	8.5%
Negative hits	846	2.6%
TOTAL: 4	33040	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.009	0.0%	8	0.000	1.125	3.000
article cancel	00:00:00.003	0.0%	9	0.000	0.333	1.000
article cleanup	00:00:00.088	0.0%	2802	0.000	0.031	0.333
article logging	00:00:00.182	0.0%	2803	0.000	0.065	0.250
article parse	00:00:00.276	0.0%	5698	0.000	0.048	0.182
article write	00:00:01.300	0.0%	2609	0.083	0.498	1.000
artlog/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
data move	00:00:00.058	0.0%	11994	0.000	0.005	0.071
hisgrep/artcncl	00:00:00.001	0.0%	9	0.000	0.111	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	12	0.000	0.083	0.333
hishave/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.379	0.0%	33033	0.000	0.011	0.214
history sync	00:00:00.051	0.0%	368	0.000	0.139	1.000
history write	00:00:00.443	0.0%	2802	0.000	0.158	0.450
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:23.662	99.9%	52138	87.345	1656.444	11544.327
nntp read	00:00:00.348	0.0%	12673	0.000	0.027	0.161
overview write	00:00:00.882	0.0%	2609	0.000	0.338	2.286
perl filter	00:00:14.069	0.0%	2802	1.833	5.021	10.286
python filter	00:00:00.014	0.0%	2802	0.000	0.005	0.125
site send	00:00:01.018	0.0%	5234	0.042	0.194	0.500
TOTAL: 24:00:59.704	23:59:42.785	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	3751	0.000	0.006	0.143
article prepare	00:00:00.003	0.0%	928	0.000	0.003	0.250
article read	00:00:00.038	0.0%	197	0.000	0.193	1.000
backlog stats	00:00:00.190	0.0%	38987	0.000	0.005	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.477	0.0%	21066	0.000	0.023	0.058
data write	00:00:00.956	0.0%	18688	0.000	0.051	0.162
idle	23:58:50.843	100.0%	38987	919.008	2214.349	8109.203
status file	00:00:00.393	0.0%	203	0.000	1.936	7.000
TOTAL: 23:58:57.412	23:58:52.922	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:47:18.583	77.7%	59241	9.333	47.916	10049.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.250	0.1%	119155	0.000	0.036	0.500
readart	00:00:00.042	0.0%	185	0.178	0.227	4.000
TOTAL: 01:00:53.708	00:47:22.875	77.8%	-	-	-	-

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
us-control@lists.killfile.org	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	188.165.224.178	1	2427	2419	0	8	99%	22:09:56
2	2001:41d0:2:abb2::119	2	127	127	0	0	100%	01:19:58
3	news.glou.org	2	4346	1	4344	1	0%	01:15:40
4	nntp.terraraq.uk	2	0	0	0	0	0%	00:03:00
5	usenet.pasdenom.info	2	3179	0	3179	0	0%	00:51:52
6	i2pn.org	2	1217	0	1217	0	0%	00:01:00
7	localhost	25	0	0	0	0	0%	00:00:00
8	news.nntp4.net	2	0	0	0	0	0%	00:03:00
9	gegeweb-out.news.tnetconsulting.net	2	185	0	185	0	0%	00:03:00
10	news.samoylyk.net	2	0	0	0	0	0%	00:01:00
TOTAL: 10		42	11481	2547	8925	9	22%	25:48:26

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	188.165.224.178	7.8 MB	16.4 KB	7.8 MB	99%	3.3 KB
2	2001:41d0:2:abb2::119	424.1 KB	0.0 KB	424.1 KB	100%	3.3 KB
3	news.glou.org	1.2 KB	1.7 KB	2.9 KB	41%	1.4 KB
4	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0%	0
5	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
6	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0%	0
7	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0%	0
9	gegeweb-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
10	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		8.2 MB	18.1 KB	8.3 MB	99%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 31 04:15:02 - 04:59:59	39	1.5%	0.01	126.1 KB	1.5%	0.05
May 31 05:00:00 - 05:59:59	63	2.4%	0.02	188.0 KB	2.2%	0.05
May 31 06:00:00 - 06:59:59	138	5.3%	0.04	445.8 KB	5.2%	0.12
May 31 07:00:00 - 07:59:59	60	2.3%	0.02	175.8 KB	2.0%	0.05
May 31 08:00:00 - 08:59:59	51	2.0%	0.01	166.1 KB	1.9%	0.05
May 31 09:00:00 - 09:59:59	75	2.9%	0.02	264.4 KB	3.1%	0.07
May 31 10:00:00 - 10:59:59	91	3.5%	0.03	234.3 KB	2.7%	0.07
May 31 11:00:00 - 11:59:59	82	3.1%	0.02	249.1 KB	2.9%	0.07
May 31 12:00:00 - 12:59:59	144	5.5%	0.04	428.8 KB	5.0%	0.12
May 31 13:00:00 - 13:59:59	104	4.0%	0.03	345.3 KB	4.0%	0.10
May 31 14:00:00 - 14:59:59	93	3.6%	0.03	259.4 KB	3.0%	0.07
May 31 15:00:00 - 15:59:59	137	5.3%	0.04	414.4 KB	4.8%	0.12
May 31 16:00:00 - 16:59:59	153	5.9%	0.04	466.0 KB	5.4%	0.13
May 31 17:00:00 - 17:59:59	182	7.0%	0.05	840.3 KB	9.8%	0.23
May 31 18:00:00 - 18:59:59	109	4.2%	0.03	497.8 KB	5.8%	0.14
May 31 19:00:00 - 19:59:59	118	4.5%	0.03	324.3 KB	3.8%	0.09
May 31 20:00:00 - 20:59:59	144	5.5%	0.04	411.2 KB	4.8%	0.11
May 31 21:00:00 - 21:59:59	155	5.9%	0.04	429.4 KB	5.0%	0.12
May 31 22:00:00 - 22:59:59	154	5.9%	0.04	659.7 KB	7.7%	0.18
May 31 23:00:00 - 23:59:59	103	3.9%	0.03	283.1 KB	3.3%	0.08
Jun 1 00:00:00 - 00:59:59	117	4.5%	0.03	433.7 KB	5.0%	0.12
Jun 1 01:00:00 - 01:59:59	99	3.8%	0.03	350.3 KB	4.1%	0.10
Jun 1 02:00:00 - 02:59:59	93	3.6%	0.03	309.5 KB	3.6%	0.09
Jun 1 03:00:00 - 03:59:59	96	3.7%	0.03	257.6 KB	3.0%	0.07
Jun 1 04:00:00 - 04:15:01	9	0.3%	0.01	39.0 KB	0.5%	0.04
TOTAL: 23:59:59	2609	100.0%	0.03	8.4 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	81	81
2	nntp.terraraq.uk	58	58
3	news.samoylyk.net	29	29
4	i2pn.org	13	13
5	188.165.224.178	7	7
6	gegeweb-out.news.tnetconsulting.net	6	6
7	news.glou.org	1	1
TOTAL: 7		195	195

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	80
Too many newsgroups	68
EMP (phn path)	32
Cancels abusifs	8
hierarchy violation - crosspost outside sdnet	1
HTML Multipart	1
Too many newsgroups (meow)	1
Cancel of non-existing ID <66572b0b$0$2581$426a74cc@news.free.fr>	1
No Cancel-Key matches Cancel-Lock. target=<v39oh2$f6r$1@rasp.pasdenom.info>	1
Cancel of non-existing ID <v3aq2f$1rq76$1@dont-email.me>	1
TOTAL: 10	194

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	188.165.224.178	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4566	118	2683	4	3	2%	23:55:57
2	news.tnetconsulting.net	4448	55	2501	49	1	1%	23:59:56
3	news.samoylyk.net	2977	2	2564	0	0	0%	23:59:56
4	news.gegeweb.org	73	2	70	0	0	2%	01:22:53
5	news.nntp4.net	2890	1	2758	0	0	0%	23:59:57
6	news.pasdenom.info	2848	1	2475	0	1	0%	23:59:56
7	news.aioe.org	0	0	0	0	2091	0%	23:59:59
8	nntp.terraraq.uk	1778	0	1714	0	1	0%	23:58:21
TOTAL: 8		19580	179	14765	53	2097	0%	169:16:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	271.9 KB	33.6 KB	305.5 KB	2.5 KB	23:55:57
2	news.tnetconsulting.net	109.4 KB	110.6 KB	220.0 KB	2.1 KB	23:59:56
3	news.gegeweb.org	11.9 KB	0.0 KB	11.9 KB	5.9 KB	01:22:53
4	news.samoylyk.net	7.8 KB	0.0 KB	7.8 KB	3.9 KB	23:59:56
5	news.nntp4.net	0.7 KB	0.0 KB	0.7 KB	0.7 KB	23:59:57
6	news.pasdenom.info	0.7 KB	0.0 KB	0.7 KB	0.7 KB	23:59:56
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
8	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:58:21
TOTAL: 8		402.3 KB	144.2 KB	546.5 KB	2.4 KB	169:16:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	69.10.48.174	3	185	938.0 KB	4	0	0	00:00:48
TOTAL: 1		3	185	938.0 KB	4	0	0	01:00:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	9	185	938.0 KB	4	00:01:07
2	*.samoylyk.net	5381	0	0.0 KB	0	00:14:59
3	*.glou.org	2720	0	0.0 KB	0	00:04:00
4	*.terraraq.uk	2720	0	0.0 KB	0	00:04:24
5	*.monitoring.internet-measurement.com	10	0	0.0 KB	0	00:00:24
6	*.gegeweb.eu	288	0	0.0 KB	0	00:00:46
7	*.org	2700	0	0.0 KB	0	00:12:01
8	*.pasdenom.info	274	0	0.0 KB	0	00:00:23
9	*.tnetconsulting.net	2685	0	0.0 KB	0	00:17:26
10	*.nntp4.net	2720	0	0.0 KB	0	00:04:52
TOTAL: 10		19507	185	938.0 KB	4	01:00:26

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
tncsrv06.tnetconsulting.net	54.910	49.586	0.000	00:17:26
server.samoylyk.net	109.418	98.582	0.000	00:14:59
i2pn.org	54.745	48.824	0.000	00:12:01
news.nntp4.net	53.190	48.069	0.000	00:04:52
nntp.terraraq.uk	53.013	48.617	0.000	00:04:24
news.glou.org	53.253	47.394	0.000	00:04:00
69.10.48.174	0.110	0.117	0.000	00:00:48
mail.gegeweb.eu	4.089	4.294	1.479	00:00:46
usenet.pasdenom.info	5.273	4.817	0.000	00:00:23
165.154.12.82	0.033	0.038	0.000	00:00:11
cute.monitoring.internet-measurement.com	0.018	0.012	0.000	00:00:10
194.165.16.76	0.011	0.014	0.000	00:00:06
evocative.monitoring.internet-measurement.com	0.049	0.036	0.000	00:00:06
adventuresome.monitoring.internet-measurement.com	0.051	0.011	0.000	00:00:05
impressive.monitoring.internet-measurement.com	0.042	0.021	0.000	00:00:00
198.199.101.120	0.025	0.016	0.000	00:00:00
sunny.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
motivating.monitoring.internet-measurement.com	0.036	0.028	0.000	00:00:00
pleasurable.monitoring.internet-measurement.com	0.052	0.013	0.000	00:00:00
adored.monitoring.internet-measurement.com	0.021	0.008	0.000	00:00:00
TOTAL: 21	388.367	350.525	1.479	01:00:26

Curious NNRP server explorers [Top 20]:

System	Conn
server.samoylyk.net	5381
nntp.terraraq.uk	2720
news.glou.org	2720
news.nntp4.net	2720
i2pn.org	2700
tncsrv06.tnetconsulting.net	2685
mail.gegeweb.eu	288
usenet.pasdenom.info	274
165.154.12.82	3
198.199.101.120	1
adored.monitoring.internet-measurement.com	1
motivating.monitoring.internet-measurement.com	1
pleasurable.monitoring.internet-measurement.com	1
impressive.monitoring.internet-measurement.com	1
194.165.16.76	1
admired.monitoring.internet-measurement.com	1
cute.monitoring.internet-measurement.com	1
evocative.monitoring.internet-measurement.com	1
gutsy.monitoring.internet-measurement.com	1
45.227.254.8	1
TOTAL: 22	19504

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
165.154.12.82	2
198.199.101.120	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

Command	Count
#026#003#001#001#027#001	1
MGLNDD_45.13.105.36_119	1
À#024	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
69.10.48.174	1	1
TOTAL: 1	1	2

Newsgroup request counts (by category):

Category		Count	Pct
1	grisbi	185	100.0%
TOTAL: 1		185	100.0%

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

Newsgroup		Count
1	grisbi.bugsreports	179
2	grisbi.admin	6
TOTAL: 2		185

Daily Usenet report for news.niel.me

May 31 04:15:02 -- Jun 1 04:15:01