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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	5194	41.7%	885.0 KB	46.0%
innd	3732	29.9%	562.9 KB	29.3%
nnrpd	1915	15.4%	209.1 KB	10.9%
innfeed	1625	13.0%	264.7 KB	13.8%
nocem	2	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	12469	100.0%	1.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	27137	67.5%
Negative hits	7959	19.8%
Do not exist	5124	12.7%
Cache misses	3	0.0%
TOTAL: 4	40223	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.021	0.0%	16	0.000	1.312	7.000
article cancel	00:00:00.001	0.0%	19	0.000	0.053	1.000
article cleanup	00:00:00.102	0.0%	5140	0.000	0.020	0.119
article logging	00:00:00.476	0.0%	5157	0.000	0.092	0.375
article parse	00:00:01.110	0.0%	10070	0.000	0.110	0.269
article write	00:00:02.842	0.0%	4418	0.231	0.643	2.880
artlog/artcncl	00:00:00.001	0.0%	19	0.000	0.053	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	16	0.000	0.062	1.000
data move	00:00:00.108	0.0%	45898	0.000	0.002	0.014
hisgrep/artcncl	00:00:00.000	0.0%	17	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	16	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	19	0.000	0.053	1.000
hishave/artcncl	00:00:00.000	0.0%	19	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	16	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.560	0.0%	40341	0.000	0.014	0.039
history sync	00:00:00.075	0.0%	406	0.000	0.185	7.000
history write	00:00:01.147	0.0%	5140	0.000	0.223	0.862
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	24:00:19.392	99.9%	45993	755.582	1878.968	6985.523
nntp read	00:00:01.528	0.0%	45349	0.009	0.034	0.063
overview write	00:00:02.793	0.0%	4418	0.050	0.632	7.304
perl filter	00:00:25.578	0.0%	5140	3.342	4.976	9.250
python filter	00:00:00.018	0.0%	6472	0.000	0.003	0.050
site send	00:00:01.209	0.0%	8841	0.000	0.137	0.476
TOTAL: 24:01:15.347	24:00:56.963	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.053	0.0%	4431	0.000	0.012	0.111
article prepare	00:00:00.000	0.0%	904	0.000	0.000	0.000
article read	00:00:00.039	0.0%	193	0.000	0.202	19.000
backlog stats	00:00:00.132	0.0%	64541	0.000	0.002	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.939	0.0%	37050	0.000	0.025	0.051
data write	00:00:02.331	0.0%	32565	0.023	0.072	0.140
idle	23:50:29.598	100.0%	64541	647.597	1329.846	3949.257
status file	00:00:00.457	0.0%	189	1.000	2.418	8.000
TOTAL: 23:50:36.880	23:50:33.549	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:34.248	94.9%	1253	19.250	1655.425	900050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.090	0.0%	3112	0.000	0.029	0.222
readart	00:00:00.061	0.0%	58	0.769	1.052	16.000
TOTAL: 00:36:26.769	00:34:34.399	94.9%	-	-	-	-

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-out.aioe.org	1	4592	2192	2299	101	47%	24:56:30
2	i2pn.org	1	5154	1163	3884	107	22%	26:01:06
3	feed-out.gegeweb.org	291	3036	512	2509	15	16%	23:53:00
4	news.nntp4.net	3	6200	300	5616	284	4%	30:09:27
5	usenet.pasdenom.info	17	18120	192	17909	19	1%	53:04:09
6	nntp.terraraq.uk	1	3755	190	3314	251	5%	23:44:09
7	gegeweb-out.news.tnetconsulting.net	1	4255	20	4232	3	0%	23:57:19
8	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 8		341	45112	4569	39763	780	10%	205:45:40

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	6.8 MB	307.4 KB	7.1 MB	95%	3.2 KB
2	i2pn.org	6.4 MB	343.5 KB	6.7 MB	95%	5.4 KB
3	news.nntp4.net	4.9 MB	1.4 MB	6.3 MB	77%	11.1 KB
4	nntp.terraraq.uk	3.3 MB	1.4 MB	4.7 MB	69%	10.9 KB
5	usenet.pasdenom.info	2.4 MB	23.7 KB	2.4 MB	99%	11.6 KB
6	feed-out.gegeweb.org	2.0 MB	16.8 KB	2.0 MB	99%	3.8 KB
7	gegeweb-out.news.tnetconsulting.net	211.8 KB	23.3 KB	235.0 KB	90%	10.2 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		26.0 MB	3.6 MB	29.5 MB	87%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 04:15:01 - 04:59:59	88	2.0%	0.03	457.9 KB	1.9%	0.17
Oct 7 05:00:00 - 05:59:59	110	2.5%	0.03	364.7 KB	1.5%	0.10
Oct 7 06:00:00 - 06:59:59	95	2.2%	0.03	330.1 KB	1.4%	0.09
Oct 7 07:00:00 - 07:59:59	99	2.2%	0.03	296.8 KB	1.3%	0.08
Oct 7 08:00:00 - 08:59:59	138	3.1%	0.04	1.4 MB	6.1%	0.40
Oct 7 09:00:00 - 09:59:59	133	3.0%	0.04	360.6 KB	1.5%	0.10
Oct 7 10:00:00 - 10:59:59	149	3.4%	0.04	452.8 KB	1.9%	0.13
Oct 7 11:00:00 - 11:59:59	161	3.6%	0.04	509.6 KB	2.2%	0.14
Oct 7 12:00:00 - 12:59:59	209	4.7%	0.06	1.2 MB	5.1%	0.34
Oct 7 13:00:00 - 13:59:59	175	4.0%	0.05	2.1 MB	9.0%	0.59
Oct 7 14:00:00 - 14:59:59	166	3.8%	0.05	1004.8 KB	4.3%	0.28
Oct 7 15:00:00 - 15:59:59	229	5.2%	0.06	1.4 MB	5.9%	0.39
Oct 7 16:00:00 - 16:59:59	214	4.8%	0.06	1.1 MB	4.7%	0.31
Oct 7 17:00:00 - 17:59:59	230	5.2%	0.06	1.2 MB	5.1%	0.33
Oct 7 18:00:00 - 18:59:59	268	6.1%	0.07	1.6 MB	6.9%	0.45
Oct 7 19:00:00 - 19:59:59	289	6.5%	0.08	980.8 KB	4.2%	0.27
Oct 7 20:00:00 - 20:59:59	248	5.6%	0.07	957.9 KB	4.1%	0.27
Oct 7 21:00:00 - 21:59:59	241	5.5%	0.07	724.9 KB	3.1%	0.20
Oct 7 22:00:00 - 22:59:59	243	5.5%	0.07	779.2 KB	3.3%	0.22
Oct 7 23:00:00 - 23:59:59	262	5.9%	0.07	1.3 MB	5.7%	0.37
Oct 8 00:00:00 - 00:59:59	204	4.6%	0.06	1.3 MB	5.5%	0.36
Oct 8 01:00:00 - 01:59:59	152	3.4%	0.04	1.2 MB	5.3%	0.35
Oct 8 02:00:00 - 02:59:59	142	3.2%	0.04	1.3 MB	5.5%	0.36
Oct 8 03:00:00 - 03:59:59	132	3.0%	0.04	447.7 KB	1.9%	0.12
Oct 8 04:00:00 - 04:15:02	38	0.9%	0.04	608.5 KB	2.6%	0.67
TOTAL: 24:00:01	4415	100.0%	0.05	23.0 MB	100.0%	0.27

Sites sending bad articles:

Server		Total	Group	Other
1	nntp.terraraq.uk	300	0	300
2	news.nntp4.net	220	0	220
3	news-out.aioe.org	112	1	111
4	i2pn.org	84	0	84
5	feed-out.gegeweb.org	15	0	15
6	gegeweb-out.news.tnetconsulting.net	11	0	11
7	usenet.pasdenom.info	2	0	2
TOTAL: 7		744	1	743

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	227
EMP (phn nph)	225
Bad path (news.giganews.com.POSTED)	124
EMP (md5)	99
User-issued spam cancel	46
EMP (fsl)	3
Cancels abusifs	1
Cancel of non-existing ID <is8getFr1ghU1@mid.individual.net>	1
TOTAL: 8	726

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	16
	news.nntp4.net	1
	TOTAL: 2	17
TOTAL: 1		17

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	6288	31	3225	17	4	0%	23:59:58
2	news.tnetconsulting.net	7430	20	4382	9	1	0%	23:59:58
3	news.pasdenom.info	5232	15	3822	17	20	0%	23:59:58
4	news.gegeweb.org	4058	14	3768	89	0	0%	23:59:58
5	news.aioe.org	1841	9	1696	0	0	0%	23:55:42
6	news.nntp4.net	4843	4	4147	0	0	0%	23:59:04
7	nntp.terraraq.uk	2979	1	2867	0	0	0%	23:59:58
TOTAL: 7		32671	94	23907	132	25	0%	167:54:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	68.1 KB	36.5 KB	104.6 KB	2.2 KB	23:59:58
2	news.tnetconsulting.net	65.4 KB	31.0 KB	96.4 KB	3.3 KB	23:59:58
3	news.gegeweb.org	62.1 KB	340.6 KB	402.6 KB	3.9 KB	23:59:58
4	news.pasdenom.info	49.4 KB	1.2 MB	1.3 MB	40.5 KB	23:59:58
5	news.aioe.org	29.7 KB	0.0 KB	29.7 KB	3.3 KB	23:55:42
6	news.nntp4.net	6.9 KB	0.0 KB	6.9 KB	1.7 KB	23:59:04
7	nntp.terraraq.uk	1.6 KB	0.0 KB	1.6 KB	1.6 KB	23:59:58
TOTAL: 7		283.2 KB	1.6 MB	1.9 MB	8.6 KB	167:54:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	108b-gniel	2	23	35.8 KB	6	0	0	00:04:07
TOTAL: 1		2	23	35.8 KB	6	0	0	00:36:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	?	2	23	35.8 KB	6	00:04:07
2	*.gegeweb.eu	288	0	0.0 KB	0	00:02:01
3	unresolved	10	0	0.0 KB	0	00:30:18
TOTAL: 3		300	23	35.8 KB	6	00:36: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
223.71.167.165	0.062	0.062	0.000	00:30:10
108b-gniel	0.074	0.106	0.000	00:04:07
sys.gegeweb.eu	8.048	8.812	2.879	00:02:01
88.165.5.23	0.147	0.218	0.000	00:00:03
170.106.173.40	0.030	0.030	0.000	00:00:02
212.90.61.180	0.019	0.056	0.000	00:00:01
TOTAL: 6	8.380	9.284	2.879	00:36:26

Curious NNRP server explorers [Top 20]:

System	Conn
sys.gegeweb.eu	288
88.165.5.23	6
223.71.167.165	2
212.90.61.180	1
170.106.173.40	1
TOTAL: 5	298

NNRP unrecognized commands (by host):

System	Conn
223.71.167.165	10
170.106.173.40	1
TOTAL: 2	11

NNRP unrecognized commands (by command):

Command	Count
Host: 185.73.232.189:119	2
GET / HTTP/1.1	2
User-Agent: Mozilla/5.0 (Macintosh; Inte...	2
Accept: /	2
Connection: keep-alive	2
#026#003#001	1
TOTAL: 6	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
223.71.167.165	1	0
170.106.173.40	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	18	78.3%
2	comp	3	13.0%
3	grisbi	2	8.7%
TOTAL: 3		23	100.0%

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

Newsgroup		Count
1	fr.usenet.forums.evolution	14
2	comp.sys.raspberry-pi	3
3	fr.comp.usenet.serveurs	2
4	fr.rec.sport.cyclisme	2
5	grisbi.test	2
TOTAL: 5		23

Daily Usenet report for news.niel.me

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