Daily Usenet report: Jun 14 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jun 14 20:25:08 newsfeed nnrpd[8448]: SERVER perl filter not defined
Jun 14 20:25:38 newsfeed nnrpd[8597]: SERVER perl filter not defined
Jun 14 21:03:07 newsfeed nnrpd[9806]: SERVER perl filter not defined
Jun 14 21:03:55 newsfeed nnrpd[9812]: SERVER perl filter not defined
Jun 14 21:04:51 newsfeed nnrpd[9813]: SERVER perl filter not defined
Jun 14 21:05:15 newsfeed nnrpd[9881]: SERVER perl filter not defined
Jun 14 21:05:35 newsfeed nnrpd[9887]: SERVER perl filter not defined
Jun 14 23:18:34 newsfeed nnrpd[14515]: SERVER perl filter not defined
Jun 15 00:14:27 newsfeed nnrpd[16341]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2231779	98.3%	255.3 MB	98.0%
innd	27721	1.2%	4.0 MB	1.5%
nnrpd	4952	0.2%	511.1 KB	0.2%
innfeed	4817	0.2%	664.9 KB	0.2%
TOTAL: 4	2269269	100.0%	260.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2220775	94.5%
Negative hits	107851	4.6%
Positive hits	20144	0.9%
Cache misses	1388	0.1%
TOTAL: 4	2350158	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:44.961	0.1%	1562	0.000	28.784	531.000
article cleanup	00:00:29.048	0.0%	2230809	0.004	0.013	0.029
article logging	00:01:01.422	0.1%	2231516	0.014	0.028	0.611
article parse	01:01:35.575	4.3%	209789951	0.007	0.018	0.070
article write	00:19:04.432	1.3%	2213894	0.220	0.517	4.020
artlog/artcncl	00:00:00.033	0.0%	1427	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:20.758	0.2%	224467063	0.000	0.001	0.004
hisgrep/artcncl	00:00:19.715	0.0%	1535	0.000	12.844	22.909
hishave/artcncl	00:00:09.102	0.0%	1562	0.000	5.827	11.427
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:21:43.693	1.5%	2352155	0.041	0.554	11.080
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	00:34:05.616	2.4%	2224153	0.161	0.920	27.259
hiswrite/artcncl	00:00:00.014	0.0%	27	0.000	0.519	10.000
idle	15:01:13.314	62.6%	167743516	0.105	0.322	0.695
nntp read	01:03:47.958	4.4%	208186207	0.010	0.018	0.033
overview write	00:00:11.842	0.0%	2213894	0.000	0.005	0.016
perl filter	04:43:03.070	19.7%	2224153	3.387	7.636	10.317
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:44.080	0.3%	4462666	0.034	0.050	0.099
TOTAL: 24:00:12.348	23:13:34.646	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.137	0.0%	36938	0.000	0.004	0.167
article prepare	00:00:00.012	0.0%	30838	0.000	0.000	0.062
article read	00:00:03.240	0.0%	7705	0.000	0.421	184.667
backlog stats	00:00:00.181	0.0%	200645	0.000	0.001	0.033
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.271	0.0%	110086	0.000	0.012	0.133
data write	00:00:06.076	0.0%	87141	0.000	0.070	0.270
idle	23:59:45.373	100.0%	200645	18.242	430.538	8312.500
status file	00:00:00.574	0.0%	840	0.000	0.683	3.000
TOTAL: 24:00:02.692	23:59:56.864	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	14:19:26.672	99.9%	287701	0.000	179.237	450026.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.152	0.0%	576234	0.000	0.018	2.000
readart	00:00:01.708	0.0%	134	0.000	12.746	44.846
TOTAL: 14:20:05.922	14:19:38.532	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	802	2216230	2199405	8664	8161	99%	250:45:05
2	news.nask.pl	2	26490	13424	4415	8651	50%	23:56:37
3	news.icm.edu.pl	44	342	30	253	59	8%	16:49:14
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		858	2243071	2212868	13332	16871	98%	291:30:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	697.2 GB	390.9 MB	591.3 MB	698.2 GB	99%	331.6 KB
2	news.nask.pl	60.6 MB	984.4 KB	144.2 MB	205.8 MB	29%	9.5 KB
3	news.icm.edu.pl	945.0 KB	0.0 KB	6.6 MB	7.5 MB	12%	86.4 KB
4	localhost	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	1.4 KB
TOTAL: 4		697.3 GB	391.9 MB	742.0 MB	698.4 GB	99%	328.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 14 04:15:02 - 04:59:59	97054	4.4%	35.97	27.3 GB	3.9%	10617.71
Jun 14 05:00:00 - 05:59:59	131113	5.9%	36.42	38.1 GB	5.5%	11084.25
Jun 14 06:00:00 - 06:59:59	133976	6.1%	37.22	39.0 GB	5.6%	11350.95
Jun 14 07:00:00 - 07:59:59	138880	6.3%	38.58	40.3 GB	5.8%	11725.79
Jun 14 08:00:00 - 08:59:59	140559	6.4%	39.04	40.3 GB	5.8%	11740.39
Jun 14 09:00:00 - 09:59:59	139842	6.3%	38.84	39.9 GB	5.7%	11624.44
Jun 14 10:00:00 - 10:59:59	140797	6.4%	39.11	39.3 GB	5.6%	11453.23
Jun 14 11:00:00 - 11:59:59	95633	4.3%	26.56	28.9 GB	4.1%	8418.04
Jun 14 12:00:00 - 12:59:59	131430	5.9%	36.51	39.0 GB	5.6%	11356.46
Jun 14 13:00:00 - 13:59:59	129089	5.8%	35.86	39.6 GB	5.7%	11529.82
Jun 14 14:00:00 - 14:59:59	83750	3.8%	23.26	28.0 GB	4.0%	8148.27
Jun 14 15:00:00 - 15:59:59	60386	2.7%	16.77	23.2 GB	3.3%	6750.83
Jun 14 16:00:00 - 16:59:59	65821	3.0%	18.28	24.2 GB	3.5%	7040.24
Jun 14 17:00:00 - 17:59:59	64650	2.9%	17.96	22.3 GB	3.2%	6496.94
Jun 14 18:00:00 - 18:59:59	67933	3.1%	18.87	23.4 GB	3.4%	6821.81
Jun 14 19:00:00 - 19:59:59	59220	2.7%	16.45	20.6 GB	3.0%	6006.32
Jun 14 20:00:00 - 20:59:59	73407	3.3%	20.39	25.1 GB	3.6%	7307.55
Jun 14 21:00:00 - 21:59:59	68811	3.1%	19.11	24.0 GB	3.4%	6977.70
Jun 14 22:00:00 - 22:59:59	71860	3.2%	19.96	23.8 GB	3.4%	6939.14
Jun 14 23:00:00 - 23:59:59	76963	3.5%	21.38	25.4 GB	3.6%	7394.15
Jun 15 00:00:00 - 00:59:59	54661	2.5%	15.18	18.7 GB	2.7%	5459.68
Jun 15 01:00:00 - 01:59:59	54593	2.5%	15.16	19.2 GB	2.8%	5602.09
Jun 15 02:00:00 - 02:59:59	68132	3.1%	18.93	23.3 GB	3.3%	6772.07
Jun 15 03:00:00 - 03:59:59	52113	2.4%	14.48	20.3 GB	2.9%	5901.95
Jun 15 04:00:00 - 04:15:01	12051	0.5%	13.38	4.9 GB	0.7%	5716.05
TOTAL: 23:59:59	2212724	100.0%	25.61	697.9 GB	100.0%	8470.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	8884	26	358	5	8495
2	newsfeed.pionier.net.pl	8685	238	6294	0	2153
3	news.icm.edu.pl	59	0	0	0	59
TOTAL: 3		17628	264	6652	5	10707

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	123
it.comp.os.win.windows10	63
uk.rec.cycling.moderated	27
comp.sys.raspberry-pi	15
it.comp.os.win.windows7	11
comp.mobile.ipad	9
comp.mobile.android	7
it.tlc.cellulari.android	7
alt.sex.telephones	1
it.comp.os.win.windows8	1
TOTAL: 10	264

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

Groups	Count
pl.praca.oferowana	2
bit.listserv.catholic	1
pl.sci.historia	1
soc.culture.estonia	1
TOTAL: 4	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7255
Binary in non-binary group	1859
PFM bot misc	289
Scoring filter (8)	165
Poison newsgroup	113
EMP (phl)	86
Scoring filter (9)	79
Scoring filter (10)	50
Scoring filter (11)	33
Scoring filter (12)	24
Scoring filter (13)	20
HTML post	6
Scoring filter (14)	4
Scoring filter (20)	3
Scoring filter (15)	3
Scoring filter (18)	3
Scoring filter (16)	2
Scoring filter (21)	2
Scoring filter (22)	2
Scoring filter (24)	1
TOTAL: 21	10000

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	43084	4985	26243	2488	0	0	11%	24:00:42
2	poznan	21198	99	13261	16	0	0	0%	24:00:03
3	panorama	2058	46	694	11	1	36049	2%	23:55:50
4	nask	7935	26	5252	0	0	0	0%	23:51:30
5	okapi	0	0	0	0	0	2413	0%	24:00:00
TOTAL: 5		74275	5156	45450	2515	1	38462	6%	119:48:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	794.8 MB	536.0 MB	1.3 GB	15.8 KB/s	182.4 KB	24:00:42
2	poznan	305.6 KB	60.2 KB	365.8 KB	0.0 KB/s	3.2 KB	24:00:03
3	panorama	116.4 KB	192.3 KB	308.7 KB	0.0 KB/s	5.4 KB	23:55:50
4	nask	101.7 KB	0.0 KB	101.7 KB	0.0 KB/s	3.9 KB	23:51:30
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		795.3 MB	536.3 MB	1.3 GB	3.2 KB/s	177.7 KB	119:48:05

Backlog files shrunk by innfeed:

Server	Size
panorama	2.9 MB
TOTAL: 1	2.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-191-92-103.p.lodz.pl	5	44	105.6 KB	3	0	01:48:34
2	lze200.lze.lodz.pl	7	38	68.3 KB	8	0	00:49:47
3	web.man.lodz.pl	288	38	37.2 KB	16	0	00:00:24
4	pc-212-51-218-225.p.lodz.pl	3	14	32.2 KB	2	0	01:10:38
5	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:00
TOTAL: 5		312	134	243.3 KB	29	9	14:20:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	8	58	137.8 KB	5	0	02:59:12
2	*.man.lodz.pl	681	38	37.2 KB	16	9	00:00:29
3	*.lze.lodz.pl	7	38	68.3 KB	8	0	00:49:47
4	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:04
5	unresolved	4	0	0.0 KB	0	0	10:30:31
TOTAL: 5		702	134	243.3 KB	29	9	14:20:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.146	13.988	19.955	10:29:42
pc-212-191-92-103.p.lodz.pl	0.022	0.093	01:48:34
pc-212-51-218-225.p.lodz.pl	0.022	0.036	01:10:38
lze200.lze.lodz.pl	0.037	0.112	00:49:47
212.51.203.199	0.004	0.027	00:00:48
web.man.lodz.pl	0.888	4.603	00:00:24
host227.elsnet.pl	0.000	0.031	00:00:04
mon.man.lodz.pl	0.854	3.723	00:00:03
david.man.lodz.pl	0.282	1.351	00:00:01
isvcs01.man.lodz.pl	0.042	0.156	00:00:00
TOTAL: 10	16.139	30.087	14:20:05

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	2
212.51.203.199	2
89.38.41.146	2
TOTAL: 5	390

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-191-92-103.p.lodz.pl	2	1
pc-212-51-218-225.p.lodz.pl	1	0
lze200.lze.lodz.pl	1	1
TOTAL: 3	4	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	54
2	pl.comp.os.ms-windows.winnt	51
3	pl.comp.pecet	13
4	pl.misc.telefonia.gsm	4
5	pl.misc.kolej	4
6	pl.regionalne.lodz	4
7	pl.rec.kuchnia	4
TOTAL: 7		134

Daily Usenet report

Jun 14 04:15:02 -- Jun 15 04:15:01