Daily Usenet report: Apr 25 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Apr 25 11:03:05 newsfeed nnrpd[20758]: SERVER perl filter not defined
Apr 25 11:04:04 newsfeed nnrpd[20765]: SERVER perl filter not defined
Apr 25 11:04:25 newsfeed nnrpd[20782]: SERVER perl filter not defined
Apr 25 11:04:36 newsfeed nnrpd[20788]: SERVER perl filter not defined
Apr 25 11:05:45 newsfeed nnrpd[20851]: SERVER perl filter not defined
Apr 25 11:30:16 newsfeed nnrpd[21798]: SERVER perl filter not defined
Apr 25 16:19:51 newsfeed nnrpd[32102]: unable to get certificate from '/etc/news/cert.pem'
Apr 25 16:19:51 newsfeed nnrpd[32102]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Apr 25 17:11:58 newsfeed nnrpd[1494]: SERVER perl filter not defined
Apr 25 17:14:01 newsfeed nnrpd[1510]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2006485	97.5%	227.3 MB	97.0%
innfeed	29765	1.4%	3.6 MB	1.5%
innd	17250	0.8%	3.0 MB	1.3%
nnrpd	5080	0.2%	537.6 KB	0.2%
TOTAL: 4	2058580	100.0%	234.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2005131	99.3%
Negative hits	13188	0.7%
Positive hits	320	0.0%
Cache misses	90	0.0%
TOTAL: 4	2018729	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.655	0.0%	175	0.000	15.171	476.000
article cleanup	00:00:27.473	0.0%	2004675	0.002	0.014	0.027
article logging	00:01:05.558	0.1%	2005308	0.014	0.033	0.340
article parse	01:04:44.539	4.5%	156739772	0.014	0.025	0.078
article write	00:26:05.520	1.8%	2004226	0.303	0.781	3.285
artlog/artcncl	00:00:00.004	0.0%	173	0.000	0.023	1.000
artlog/artparse	00:00:00.036	0.0%	1183	0.000	0.030	0.188
data move	00:02:10.057	0.2%	170668582	0.000	0.001	0.007
hisgrep/artcncl	00:00:00.318	0.0%	158	0.000	2.013	23.000
hishave/artcncl	00:00:00.098	0.0%	175	0.000	0.560	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:37:04.789	2.6%	2019273	0.027	1.102	20.432
history sync	00:00:00.024	0.0%	291	0.000	0.082	2.000
history write	00:48:11.866	3.3%	2005797	0.224	1.442	57.249
hiswrite/artcncl	00:00:00.039	0.0%	17	0.000	2.294	14.000
idle	13:54:10.470	57.9%	122633787	0.106	0.408	1.187
nntp read	01:01:09.542	4.2%	155173724	0.014	0.024	0.040
overview write	00:00:12.067	0.0%	2004226	0.001	0.006	0.019
perl filter	05:13:35.012	21.8%	2004614	4.858	9.386	13.874
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:53.848	0.3%	4270364	0.033	0.055	0.093
TOTAL: 24:00:12.196	23:12:53.915	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.362	0.0%	549511	0.000	0.002	0.056
article prepare	00:00:00.098	0.0%	360592	0.000	0.000	0.050
article read	00:01:52.663	0.1%	89848	0.000	1.254	914.500
backlog stats	00:00:01.281	0.0%	1554645	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:09.271	0.0%	702428	0.000	0.013	0.079
data write	00:01:46.813	0.1%	857157	0.000	0.125	1.000
idle	23:55:27.560	99.7%	1554645	10.002	55.400	4396.786
status file	00:00:08.164	0.0%	9363	0.000	0.872	33.833
TOTAL: 24:00:05.107	23:59:27.212	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:40:39.816	99.8%	3372	0.000	3926.399	600069.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.207	0.0%	7681	0.000	0.027	1.000
readart	00:00:02.479	0.0%	148	0.000	16.750	334.000
TOTAL: 03:41:00.767	03:40:42.502	99.9%	-	-	-	-

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	951	2005353	2003745	0	1608	99%	275:44:39
2	news.icm.edu.pl	40	271	54	193	24	19%	16:51:06
3	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 3		1000	2005632	2003807	193	1632	99%	292:35:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	751.4 GB	5.7 MB	338.3 MB	751.7 GB	99%	393.1 KB
2	news.icm.edu.pl	2.6 MB	29.9 KB	878.3 KB	3.5 MB	74%	45.5 KB
3	localhost	12.5 KB	0.0 KB	0.0 KB	12.5 KB	100%	1.6 KB
TOTAL: 3		751.4 GB	5.7 MB	339.2 MB	751.7 GB	99%	393.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 25 04:15:02 - 04:59:59	50661	2.5%	18.78	19.1 GB	2.5%	7414.44
Apr 25 05:00:00 - 05:59:59	59006	2.9%	16.39	23.1 GB	3.1%	6722.77
Apr 25 06:00:00 - 06:59:59	64251	3.2%	17.85	26.9 GB	3.6%	7842.97
Apr 25 07:00:00 - 07:59:59	69675	3.5%	19.35	29.0 GB	3.9%	8447.54
Apr 25 08:00:00 - 08:59:59	76460	3.8%	21.24	30.3 GB	4.0%	8836.90
Apr 25 09:00:00 - 09:59:59	83942	4.2%	23.32	32.0 GB	4.3%	9333.92
Apr 25 10:00:00 - 10:59:59	81976	4.1%	22.77	33.8 GB	4.5%	9836.01
Apr 25 11:00:00 - 11:59:59	74942	3.7%	20.82	33.0 GB	4.4%	9615.80
Apr 25 12:00:00 - 12:59:59	68193	3.4%	18.94	27.5 GB	3.7%	8014.90
Apr 25 13:00:00 - 13:59:59	72843	3.6%	20.23	28.3 GB	3.8%	8246.86
Apr 25 14:00:00 - 14:59:59	76407	3.8%	21.22	28.9 GB	3.8%	8407.05
Apr 25 15:00:00 - 15:59:59	74917	3.7%	20.81	28.0 GB	3.7%	8145.85
Apr 25 16:00:00 - 16:59:59	80727	4.0%	22.42	28.9 GB	3.8%	8411.39
Apr 25 17:00:00 - 17:59:59	94186	4.7%	26.16	31.2 GB	4.2%	9098.36
Apr 25 18:00:00 - 18:59:59	87791	4.4%	24.39	35.2 GB	4.7%	10249.41
Apr 25 19:00:00 - 19:59:59	98534	4.9%	27.37	34.6 GB	4.6%	10089.82
Apr 25 20:00:00 - 20:59:59	96967	4.8%	26.94	34.4 GB	4.6%	10005.96
Apr 25 21:00:00 - 21:59:59	114795	5.7%	31.89	37.1 GB	4.9%	10814.02
Apr 25 22:00:00 - 22:59:59	103391	5.2%	28.72	32.3 GB	4.3%	9411.30
Apr 25 23:00:00 - 23:59:59	94249	4.7%	26.18	34.3 GB	4.6%	9998.53
Apr 26 00:00:00 - 00:59:59	84740	4.2%	23.54	29.0 GB	3.9%	8437.04
Apr 26 01:00:00 - 01:59:59	94157	4.7%	26.15	35.8 GB	4.8%	10420.11
Apr 26 02:00:00 - 02:59:59	97337	4.9%	27.04	35.9 GB	4.8%	10443.46
Apr 26 03:00:00 - 03:59:59	82816	4.1%	23.00	34.8 GB	4.6%	10132.20
Apr 26 04:00:00 - 04:15:02	21084	1.1%	23.37	8.6 GB	1.1%	10021.29
TOTAL: 24:00:00	2004047	100.0%	23.19	752.0 GB	100.0%	9126.24

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2239	250	59	4	1926
2	news.icm.edu.pl	26	0	2	1	23
TOTAL: 2		2265	250	61	5	1949

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	131
it.comp.os.win.windows10	53
it.tlc.cellulari.android	15
de.comp.lang.php	14
comp.mobile.android	13
it.comp.os.win.windows7	12
it.comp.os.linux.ubuntu	4
hr.alt.cellular.gsm.android	4
uk.rec.cycling.moderated	2
hr.ponuda.hardver.komponente	2
TOTAL: 10	250

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

Groups	Count
pl.praca.oferowana	2
pl.sci.matematyka	1
pl.sci.historia	1
christnet.philosophy	1
TOTAL: 4	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	113
EMP (md5)	19
EMP (fsl)	1
TOTAL: 3	133

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	370694	5953	169889	81075	2	21661	1%	24:00:00
2	panorama	270605	2220	252097	872	2	271772	0%	23:59:22
3	nask	0	0	0	0	0	239534	0%	24:00:00
4	poznan	78	0	54	0	0	0	0%	04:36:42
5	okapi	0	0	0	0	0	2800	0%	24:00:00
TOTAL: 5		641377	8173	422040	81947	4	535767	1%	100:36:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	948.0 MB	29.6 GB	30.5 GB	370.2 KB/s	367.5 KB	24:00:00
2	panorama	231.2 MB	46.3 MB	277.6 MB	3.3 KB/s	91.9 KB	23:59:22
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
4	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:36:42
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		1.2 GB	29.6 GB	30.8 GB	89.1 KB/s	358.1 KB	100:36:04

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
TOTAL: 1	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	15	130	280.6 KB	11	0	03:39:47
2	web.man.lodz.pl	288	18	14.2 KB	9	0	00:00:45
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:02
TOTAL: 3		311	148	294.8 KB	20	8	03:41:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	15	130	280.6 KB	11	0	03:39:47
2	*.man.lodz.pl	680	18	14.2 KB	9	8	00:00:51
3	unresolved	5	0	0.0 KB	0	0	00:00:16
4	*.toya.net.pl	39	0	0.0 KB	0	0	00:00:05
TOTAL: 4		739	148	294.8 KB	20	8	03:41:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.091	0.253	03:39:47
web.man.lodz.pl	1.066	4.617	00:00:45
212.51.203.199	0.004	0.027	00:00:08
118.193.27.8	0.010	0.046	00:00:08
mon.man.lodz.pl	0.958	4.861	00:00:03
staticline41435.toya.net.pl	0.148	0.631	00:00:02
staticline-31-183-53-151.toya.net.pl	0.004	0.018	00:00:02
isvcs01.man.lodz.pl	0.049	0.159	00:00:02
david.man.lodz.pl	0.301	1.545	00:00:01
TOTAL: 9	2.631	12.157	03:41:00

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	38
118.193.27.8	3
212.51.203.199	2
staticline-31-183-53-151.toya.net.pl	1
TOTAL: 6	428

NNRP unrecognized commands (by host):

System	Conn
118.193.27.8	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
#026#003#001#0018#001	1
À/À+À'À#À#023À#011À#037À#036	1
À"À!	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	5	1
118.193.27.8	1	1
TOTAL: 2	6	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	110
2	pl.misc.kolej	20
3	pl.comp.os.ms-windows.winnt	12
4	pl.comp.pecet	6
TOTAL: 4		148

Daily Usenet report

Apr 25 04:15:02 -- Apr 26 04:15:02