Daily Usenet report: Oct 29 04:15:03

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Oct 29 08:32:22 newsfeed nnrpd[16007]: SERVER perl filter not defined
Oct 29 15:31:10 newsfeed overchan[5922]: input line too long, skipping
Oct 29 16:16:17 newsfeed overchan[5922]: input line too long, skipping
Oct 29 16:20:27 newsfeed overchan[5922]: input line too long, skipping
Oct 29 18:04:49 newsfeed overchan[5922]: input line too long, skipping
Oct 29 18:20:34 newsfeed overchan[5922]: input line too long, skipping
Oct 29 18:33:08 newsfeed overchan[5922]: input line too long, skipping
Oct 29 18:54:56 newsfeed overchan[5922]: input line too long, skipping
Oct 29 21:55:14 newsfeed nnrpd[13097]: unable to get certificate from '/etc/news/cert.pem'
Oct 29 21:55:14 newsfeed nnrpd[13097]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 29 21:55:14 newsfeed nnrpd[13098]: unable to get certificate from '/etc/news/cert.pem'
Oct 29 21:55:14 newsfeed nnrpd[13098]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 29 21:55:27 newsfeed nnrpd[13099]: unable to get certificate from '/etc/news/cert.pem'
Oct 29 21:55:27 newsfeed nnrpd[13099]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 29 22:35:24 newsfeed overchan[5922]: input line too long, skipping
Oct 30 02:21:51 newsfeed nnrpd[22945]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3535539	97.0%	407.6 MB	96.7%
innd	57917	1.6%	8.1 MB	1.9%
innfeed	45629	1.3%	5.3 MB	1.3%
nnrpd	4755	0.1%	495.2 KB	0.1%
overchan	9	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3643850	100.0%	421.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3498413	94.8%
Negative hits	161262	4.4%
Positive hits	30609	0.8%
Cache misses	1294	0.0%
TOTAL: 4	3691578	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.821	0.0%	198	0.000	14.247	207.600
article cleanup	00:00:49.061	0.1%	3556991	0.000	0.014	0.025
article logging	00:02:21.405	0.2%	3557688	0.024	0.040	0.131
article parse	01:07:03.171	4.7%	125057348	0.019	0.032	0.044
article write	00:24:22.623	1.7%	3503009	0.170	0.418	1.986
artlog/artcncl	00:00:00.002	0.0%	164	0.000	0.012	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:04:12.564	0.3%	143658953	0.001	0.002	0.006
hisgrep/artcncl	00:00:00.061	0.0%	166	0.000	0.367	14.000
hishave/artcncl	00:00:00.089	0.0%	198	0.000	0.449	20.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:10:26.376	4.9%	3731645	0.111	1.133	10.556
history sync	00:00:00.012	0.0%	292	0.000	0.041	1.000
history write	02:14:13.881	9.3%	3537864	0.193	2.276	40.741
hiswrite/artcncl	00:00:00.130	0.0%	32	0.000	4.062	6.444
idle	10:37:06.626	44.2%	87229968	0.205	0.438	1215.279
nntp read	01:09:27.035	4.8%	122191706	0.020	0.034	0.054
overview write	00:00:18.470	0.0%	3503009	0.000	0.005	0.011
perl filter	06:22:03.377	26.5%	3537862	4.903	6.479	33.407
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:12.280	0.4%	7024211	0.032	0.044	0.069
TOTAL: 24:00:07.234	23:17:39.984	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.163	0.0%	52740	0.000	0.003	0.016
article prepare	00:00:00.005	0.0%	11688	0.000	0.000	0.025
article read	00:01:29.074	0.1%	2919	0.000	30.515	726.900
backlog stats	00:00:00.149	0.0%	153360	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	11	0.000	0.000	0.000
data read	00:00:01.794	0.0%	80270	0.000	0.022	0.068
data write	00:00:04.489	0.0%	64323	0.000	0.070	0.280
idle	23:51:58.569	99.9%	153360	325.317	560.241	6087.408
status file	00:00:16.938	0.0%	15699	0.600	1.079	8.217
TOTAL: 23:54:06.688	23:53:51.181	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:22.858	6.3%	3006	0.000	7.604	295.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.216	0.1%	6642	0.000	0.033	1.333
readart	00:00:00.000	0.0%	34	0.000	0.000	0.000
TOTAL: 00:06:03.277	00:00:23.074	6.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	765	3510076	3470406	5562	34108	98%	403:47:25
2	news.nask.pl	13	37007	9201	6909	20897	24%	25:54:12
3	news.icm.edu.pl	50	320	6	312	2	1%	08:06:21
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		831	3547405	3479615	12783	55007	98%	437:47:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	892.1 GB	3.1 GB	6.0 GB	901.2 GB	98%	269.7 KB
2	news.nask.pl	54.7 MB	18.4 MB	3.4 GB	3.4 GB	1%	120.0 KB
3	news.icm.edu.pl	144.6 KB	18.3 KB	93.9 KB	256.8 KB	56%	32.1 KB
4	localhost	2.9 KB	0.0 KB	0.0 KB	2.9 KB	100%	1.5 KB
TOTAL: 4		892.2 GB	3.1 GB	9.4 GB	904.7 GB	98%	268.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 29 04:15:03 - 04:59:59	29448	0.8%	10.92	7.8 GB	0.9%	3042.75
Oct 29 05:00:00 - 05:59:59	115388	3.3%	32.05	36.3 GB	4.1%	10567.83
Oct 29 06:00:00 - 06:59:59	120662	3.5%	33.52	41.8 GB	4.7%	12182.90
Oct 29 07:00:00 - 07:59:59	124870	3.6%	34.69	42.9 GB	4.8%	12497.12
Oct 29 08:00:00 - 08:59:59	130962	3.8%	36.38	41.6 GB	4.7%	12115.76
Oct 29 09:00:00 - 09:59:59	100287	2.9%	27.86	31.4 GB	3.5%	9134.21
Oct 29 10:00:00 - 10:59:59	82844	2.4%	23.01	25.4 GB	2.9%	7408.48
Oct 29 11:00:00 - 11:59:59	136429	3.9%	37.90	43.2 GB	4.8%	12595.67
Oct 29 12:00:00 - 12:59:59	132524	3.8%	36.81	42.3 GB	4.7%	12309.01
Oct 29 13:00:00 - 13:59:59	152567	4.4%	42.38	42.0 GB	4.7%	12236.36
Oct 29 14:00:00 - 14:59:59	270912	7.8%	75.25	37.2 GB	4.2%	10839.22
Oct 29 15:00:00 - 15:59:59	155620	4.5%	43.23	43.5 GB	4.9%	12672.03
Oct 29 16:00:00 - 16:59:59	219329	6.3%	60.92	42.3 GB	4.7%	12333.94
Oct 29 17:00:00 - 17:59:59	199030	5.7%	55.29	35.2 GB	3.9%	10251.20
Oct 29 18:00:00 - 18:59:59	121055	3.5%	33.63	34.7 GB	3.9%	10095.35
Oct 29 19:00:00 - 19:59:59	177551	5.1%	49.32	38.8 GB	4.4%	11305.91
Oct 29 20:00:00 - 20:59:59	139392	4.0%	38.72	38.6 GB	4.3%	11230.90
Oct 29 21:00:00 - 21:59:59	192195	5.5%	53.39	41.1 GB	4.6%	11967.80
Oct 29 22:00:00 - 22:59:59	139841	4.0%	38.84	43.2 GB	4.8%	12588.92
Oct 29 23:00:00 - 23:59:59	196310	5.6%	54.53	39.6 GB	4.4%	11529.56
Oct 30 00:00:00 - 00:59:59	95856	2.8%	26.63	29.0 GB	3.3%	8446.73
Oct 30 01:00:00 - 01:59:59	142354	4.1%	39.54	37.4 GB	4.2%	10879.35
Oct 30 02:00:00 - 02:59:59	142273	4.1%	39.52	35.9 GB	4.0%	10469.61
Oct 30 03:00:00 - 03:59:59	127020	3.7%	35.28	31.6 GB	3.5%	9206.41
Oct 30 04:00:00 - 04:15:06	34881	1.0%	38.50	9.2 GB	1.0%	10670.31
TOTAL: 24:00:03	3479600	100.0%	40.27	892.1 GB	100.0%	10825.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	34601	100	19093	0	15408
2	news.nask.pl	21172	23	402	5	20742
3	news.icm.edu.pl	2	0	1	0	1
TOTAL: 3		55775	123	19496	5	36151

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	80
de.rec.sf.startrek	11
it.comp.os.win.windows10	9
comp.mobile.android	7
it.tlc.cellulari.android	7
comp.mobile.ipad	4
de.comm.provider.telefonie	2
comp.sys.raspberry-pi	1
hr.rec.glazba.hifi	1
it.comp.os.linux.ubuntu	1
TOTAL: 10	123

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
pl.sci.matematyka	4
bit.listserv.catholic	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	31560
EMP (md5)	3772
Scoring filter (8)	58
Scoring filter (9)	23
Poison newsgroup	19
Scoring filter (10)	10
HTML post	4
Scoring filter (12)	2
Scoring filter (13)	1
Too many newsgroups	1
TOTAL: 10	35450

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	493
	news.nask.pl	206
	TOTAL: 2	699
TOTAL: 1		699

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	38150	885	10964	174	34	40169	2%	23:59:57
2	poznan	14309	84	9004	27	0	106	0%	23:59:57
3	icm	22168	35	16621	1053	0	274	0%	23:59:57
4	nask	3096	3	2285	0	0	36	0%	20:24:56
5	okapi	0	0	0	0	0	1083	0%	23:57:50
TOTAL: 5		77723	1007	38874	1254	34	41668	1%	116:22:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	8.0 MB	308.6 MB	316.6 MB	3.8 KB/s	298.0 KB	23:59:57
2	panorama	3.0 MB	960.9 KB	3.9 MB	0.0 KB/s	3.8 KB	23:59:57
3	poznan	326.8 KB	184.1 KB	511.0 KB	0.0 KB/s	4.6 KB	23:59:57
4	nask	241.8 KB	0.0 KB	241.8 KB	0.0 KB/s	80.6 KB	20:24:56
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		11.5 MB	309.7 MB	321.2 MB	0.8 KB/s	145.5 KB	116:22:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	286	34	31.5 KB	15	0	00:00:32
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		288	34	31.5 KB	15	2	00:06:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	666	34	31.5 KB	15	2	00:00:38
2	unresolved	8	0	0.0 KB	0	0	00:05:21
3	*.dhcp.ftwo.tx.charter.com	4	0	0.0 KB	0	0	00:00:02
TOTAL: 3		678	34	31.5 KB	15	2	00:06:02

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.2.11	0.036	0.276	00:05:21
web.man.lodz.pl	1.388	13.292	00:00:32
mon.man.lodz.pl	1.108	12.256	00:00:04
47-37-112-59.dhcp.ftwo.tx.charter.com	0.028	0.160	00:00:02
david.man.lodz.pl	0.348	3.744	00:00:01
isvcs01.man.lodz.pl	0.028	0.116	00:00:00
TOTAL: 6	2.936	29.844	00:06:02

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	284
david.man.lodz.pl	94
212.191.2.11	8
47-37-112-59.dhcp.ftwo.tx.charter.com	4
TOTAL: 4	390

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	34	100.0%
TOTAL: 1		34	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	34
TOTAL: 1		34

Daily Usenet report

Oct 29 04:15:03 -- Oct 30 04:15:06