Daily Usenet report: Feb 10 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 10 10:22:25 newsfeed nnrpd[7027]: SERVER perl filter not defined
Feb 10 13:14:38 newsfeed nnrpd[12918]: unable to get certificate from '/etc/news/cert.pem'
Feb 10 13:14:38 newsfeed nnrpd[12918]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Feb 10 17:51:47 newsfeed nnrpd[22556]: SERVER perl filter not defined
Feb 10 18:57:30 newsfeed nnrpd[24730]: SERVER perl filter not defined
Feb 10 20:23:20 newsfeed nnrpd[27774]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3298466	98.6%	381.2 MB	98.3%
innd	35746	1.1%	5.2 MB	1.3%
nnrpd	5998	0.2%	648.7 KB	0.2%
innfeed	4810	0.1%	673.6 KB	0.2%
TOTAL: 4	3345020	100.0%	387.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3292716	97.1%
Negative hits	85651	2.5%
Positive hits	13690	0.4%
Cache misses	141	0.0%
TOTAL: 4	3392198	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.949	0.0%	173	0.000	5.486	219.000
article cleanup	00:00:46.044	0.1%	3298367	0.006	0.014	0.023
article logging	00:01:51.973	0.1%	3299005	0.018	0.034	0.676
article parse	01:27:51.071	6.1%	188683454	0.015	0.028	0.079
article write	00:27:52.278	1.9%	3283519	0.337	0.509	1.689
artlog/artcncl	00:00:00.004	0.0%	171	0.000	0.023	1.000
artlog/artparse	00:00:00.002	0.0%	78	0.000	0.026	1.000
data move	00:03:08.120	0.2%	208407729	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.116	0.0%	132	0.000	0.879	40.000
hishave/artcncl	00:00:00.035	0.0%	173	0.000	0.202	1.278
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:04.165	1.9%	3394367	0.060	0.478	7.444
history sync	00:00:00.015	0.0%	295	0.000	0.051	1.000
history write	00:44:58.896	3.1%	3294892	0.156	0.819	9.099
hiswrite/artcncl	00:00:00.010	0.0%	41	0.000	0.244	1.000
idle	11:45:00.261	49.0%	131261848	0.140	0.322	1.971
nntp read	01:12:07.662	5.0%	185997052	0.015	0.023	0.038
overview write	00:00:19.779	0.0%	3283519	0.002	0.006	0.014
perl filter	06:54:14.232	28.8%	3294814	5.737	7.543	10.667
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:55.215	0.4%	6590702	0.032	0.054	0.107
TOTAL: 24:00:14.935	23:11:10.827	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.124	0.0%	42504	0.000	0.003	0.167
article prepare	00:00:00.003	0.0%	9750	0.000	0.000	0.250
article read	00:00:19.194	0.0%	2407	0.000	7.974	923.000
backlog stats	00:00:00.147	0.0%	155643	0.000	0.001	0.049
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:01.183	0.0%	86792	0.000	0.014	0.130
data write	00:00:02.354	0.0%	64563	0.000	0.036	0.241
idle	23:59:35.794	100.0%	155643	33.221	554.961	4628.462
status file	00:00:00.889	0.0%	1112	0.000	0.799	4.500
TOTAL: 24:00:04.913	23:59:59.688	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	05:00:51.560	99.8%	151473	0.000	119.173	3306.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.107	0.0%	303909	0.000	0.020	0.737
readart	00:00:00.059	0.0%	66	0.000	0.894	9.500
TOTAL: 05:01:25.097	05:00:57.726	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1222	3283312	3272533	6444	4335	99%	370:19:30
2	news.nask.pl	2	24089	9898	3539	10652	41%	24:03:23
3	news.icm.edu.pl	48	206	12	190	4	5%	15:48:55
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1277	3307611	3282447	10173	14991	99%	410:11:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	968.7 GB	59.2 MB	243.4 MB	969.0 GB	99%	310.1 KB
2	news.nask.pl	49.3 MB	950.8 KB	142.0 MB	192.2 MB	25%	9.6 KB
3	news.icm.edu.pl	178.7 KB	0.0 KB	82.8 KB	261.5 KB	68%	16.3 KB
4	localhost	5.5 KB	0.0 KB	0.0 KB	5.5 KB	100%	1.4 KB
TOTAL: 4		968.8 GB	60.1 MB	385.5 MB	969.2 GB	99%	308.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 10 04:15:02 - 04:59:59	107848	3.3%	39.97	30.5 GB	3.1%	11838.28
Feb 10 05:00:00 - 05:59:59	150163	4.6%	41.71	42.6 GB	4.4%	12415.62
Feb 10 06:00:00 - 06:59:59	149871	4.6%	41.63	42.9 GB	4.4%	12494.31
Feb 10 07:00:00 - 07:59:59	154039	4.7%	42.79	43.1 GB	4.4%	12557.92
Feb 10 08:00:00 - 08:59:59	157090	4.8%	43.64	42.3 GB	4.4%	12324.33
Feb 10 09:00:00 - 09:59:59	158840	4.8%	44.12	42.7 GB	4.4%	12427.56
Feb 10 10:00:00 - 10:59:59	154573	4.7%	42.94	42.6 GB	4.4%	12398.56
Feb 10 11:00:00 - 11:59:59	156367	4.8%	43.44	43.2 GB	4.5%	12582.39
Feb 10 12:00:00 - 12:59:59	158042	4.8%	43.90	43.8 GB	4.5%	12743.27
Feb 10 13:00:00 - 13:59:59	162013	4.9%	45.00	43.8 GB	4.5%	12743.70
Feb 10 14:00:00 - 14:59:59	161036	4.9%	44.73	43.2 GB	4.5%	12589.97
Feb 10 15:00:00 - 15:59:59	154813	4.7%	43.00	42.8 GB	4.4%	12468.02
Feb 10 16:00:00 - 16:59:59	156169	4.8%	43.38	43.3 GB	4.5%	12614.91
Feb 10 17:00:00 - 17:59:59	152619	4.6%	42.39	41.9 GB	4.3%	12200.85
Feb 10 18:00:00 - 18:59:59	138730	4.2%	38.54	40.7 GB	4.2%	11847.39
Feb 10 19:00:00 - 19:59:59	134549	4.1%	37.37	41.9 GB	4.3%	12209.33
Feb 10 20:00:00 - 20:59:59	124361	3.8%	34.54	42.1 GB	4.3%	12249.74
Feb 10 21:00:00 - 21:59:59	123092	3.7%	34.19	42.0 GB	4.3%	12220.52
Feb 10 22:00:00 - 22:59:59	117190	3.6%	32.55	39.8 GB	4.1%	11597.98
Feb 10 23:00:00 - 23:59:59	106612	3.2%	29.61	37.7 GB	3.9%	10985.19
Feb 11 00:00:00 - 00:59:59	95746	2.9%	26.60	32.9 GB	3.4%	9593.91
Feb 11 01:00:00 - 01:59:59	103402	3.1%	28.72	34.5 GB	3.6%	10035.25
Feb 11 02:00:00 - 02:59:59	94387	2.9%	26.22	32.3 GB	3.3%	9395.57
Feb 11 03:00:00 - 03:59:59	87852	2.7%	24.40	29.2 GB	3.0%	8498.09
Feb 11 04:00:00 - 04:15:02	23329	0.7%	25.86	7.4 GB	0.8%	8618.21
TOTAL: 24:00:00	3282733	100.0%	37.99	968.9 GB	100.0%	11759.41

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10798	51	280	4	10463
2	newsfeed.pionier.net.pl	4760	199	3274	0	1287
3	news.icm.edu.pl	4	0	0	0	4
TOTAL: 3		15562	250	3554	4	11754

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	102
it.comp.os.win.windows10	43
comp.mobile.android	39
it.tlc.cellulari.android	20
comp.mobile.ipad	16
uk.rec.cycling.moderated	8
it.comp.os.win.windows7	5
nl.comp.os.linux.techniek	5
hr.ponuda.hardver.komponente	4
de.comp.office-pakete.ms-office.outlook	2
hr.alt.cellular.gsm.android	2
de.comp.lang.vbclassic	2
nl.comp.sys.android	1
de.comp.office-pakete.ms-office.word	1
TOTAL: 14	250

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

Groups	Count
pl.praca.oferowana	2
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9826
Binary in non-binary group	1038
EMP (phl)	57
Scoring filter (8)	48
Scoring filter (9)	30
Scoring filter (10)	18
Scoring filter (12)	5
Scoring filter (11)	5
EMP (fsl)	3
HTML post	3
Scoring filter (13)	2
Too many newsgroups	1
Scoring filter (17)	1
Poison newsgroup	1
TOTAL: 14	11038

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	23193	1196	19728	502	11	21198	5%	24:02:27
2	poznan	15198	163	9573	24	0	0	1%	23:53:00
3	icm	29349	63	22253	286	0	0	0%	24:04:52
4	nask	7143	34	4914	2	0	0	0%	24:07:22
5	okapi	0	0	0	0	0	1870	0%	24:00:01
TOTAL: 5		74883	1456	56468	814	11	23068	1%	120:07:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.2 MB	1.4 MB	5.5 MB	0.1 KB/s	3.3 KB	24:02:27
2	icm	2.7 MB	92.1 MB	94.8 MB	1.1 KB/s	278.0 KB	24:04:52
3	poznan	523.4 KB	81.3 KB	604.7 KB	0.0 KB/s	3.2 KB	23:53:00
4	nask	116.3 KB	5.8 KB	122.1 KB	0.0 KB/s	3.4 KB	24:07:22
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		7.4 MB	93.6 MB	101.0 MB	0.2 KB/s	45.6 KB	120:07:42

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	60	78.7 KB	25	0	00:00:23
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	60	78.7 KB	25	4	05:01:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	964	60	78.7 KB	25	4	00:00:32
2	unresolved	8	0	0.0 KB	0	0	05:00:50
3	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	00:00:01
TOTAL: 3		974	60	78.7 KB	25	4	05:01:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.57	8.604	13.679	05:00:42
web.man.lodz.pl	1.053	5.455	00:00:23
mon.man.lodz.pl	1.818	9.111	00:00:07
118.193.90.131	0.028	0.037	00:00:05
196.52.20.226	0.000	0.022	00:00:02
ip232.208-100-26.static.steadfastdns.net	0.009	0.032	00:00:01
david.man.lodz.pl	0.313	1.586	00:00:01
66.240.219.146	0.000	0.020	00:00:00
isvcs01.man.lodz.pl	0.022	0.067	00:00:00
TOTAL: 9	11.847	30.009	05:01:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	576
david.man.lodz.pl	96
118.193.90.131	3
89.38.41.57	3
ip232.208-100-26.static.steadfastdns.net	2
66.240.219.146	1
196.52.20.226	1
TOTAL: 7	682

NNRP unrecognized commands (by host):

System	Conn
118.193.90.131	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
#026#003#001#001"#001	1
À/À+À'À#À#023À#011	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.41.57	2	1
118.193.90.131	2	3
TOTAL: 2	4	4

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	50
2	pl.comp.os.ms-windows.winnt	10
TOTAL: 2		60

Daily Usenet report

Feb 10 04:15:02 -- Feb 11 04:15:02