Daily Usenet report: Apr 27 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Apr 27 09:03:13 newsfeed nnrpd[27449]: SERVER perl filter not defined
Apr 27 11:23:50 newsfeed nnrpd[32437]: SERVER perl filter not defined
Apr 27 14:23:46 newsfeed nnrpd[6532]: SERVER perl filter not defined
Apr 27 18:28:27 newsfeed nnrpd[15164]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2261161	96.8%	252.7 MB	96.2%
innfeed	48162	2.1%	5.8 MB	2.2%
innd	22251	1.0%	3.8 MB	1.4%
nnrpd	5232	0.2%	558.0 KB	0.2%
TOTAL: 4	2336806	100.0%	262.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2207121	80.0%
Negative hits	377927	13.7%
Positive hits	173321	6.3%
Cache misses	278	0.0%
TOTAL: 4	2758647	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.089	0.0%	226	0.000	22.518	383.000
article cleanup	00:00:30.249	0.0%	2259344	0.005	0.013	0.027
article logging	00:01:13.141	0.1%	2259939	0.017	0.032	0.942
article parse	01:12:03.632	5.0%	172886914	0.012	0.025	0.070
article write	00:27:15.190	1.9%	2203741	0.381	0.742	4.579
artlog/artcncl	00:00:00.007	0.0%	225	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:23.102	0.2%	188809621	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.092	0.0%	206	0.000	5.301	74.455
hishave/artcncl	00:00:00.139	0.0%	226	0.000	0.615	10.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:38:08.508	2.7%	2759227	0.114	0.829	24.745
history sync	00:00:00.026	0.0%	291	0.000	0.089	3.000
history write	00:47:45.133	3.3%	2207536	0.239	1.298	54.146
hiswrite/artcncl	00:00:00.067	0.0%	20	0.000	3.350	17.000
idle	13:05:58.903	54.6%	130515090	0.119	0.361	0.822
nntp read	01:04:27.246	4.5%	171367160	0.014	0.023	0.041
overview write	00:00:13.714	0.0%	2203741	0.000	0.006	0.019
perl filter	05:44:59.371	24.0%	2207536	5.849	9.377	13.925
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:19.283	0.3%	4647471	0.033	0.056	0.100
TOTAL: 23:59:12.228	23:09:23.892	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:02.954	0.0%	1779618	0.000	0.002	0.167
article prepare	00:00:00.212	0.0%	725778	0.000	0.000	0.125
article read	00:45:33.070	3.2%	181395	0.000	15.067	538.000
backlog stats	00:00:01.296	0.0%	1923086	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:12.636	0.0%	1304198	0.000	0.010	0.091
data write	00:03:10.093	0.2%	1785959	0.000	0.106	0.694
idle	23:09:33.460	96.5%	1923086	0.439	43.354	2201.294
status file	00:00:14.513	0.0%	16589	0.000	0.875	36.500
TOTAL: 23:59:50.323	23:58:48.234	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:37:21.553	99.9%	3419	0.000	6973.253	600060.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.341	0.0%	68259	0.000	0.005	0.579
readart	00:00:00.326	0.0%	73	0.000	4.466	30.000
TOTAL: 06:37:43.562	06:37:22.220	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	996	2204508	2147806	6742	49960	97%	299:23:40
2	news.nask.pl	33	177211	56890	114618	5703	32%	27:37:49
3	news.icm.edu.pl	37	293	25	268	0	8%	16:16:24
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1071	2382016	2204725	121628	55663	92%	343:17:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	816.4 GB	17.2 GB	17.2 GB	850.9 GB	95%	406.0 KB
2	news.nask.pl	19.4 GB	564.9 MB	588.9 MB	20.6 GB	94%	344.5 KB
3	news.icm.edu.pl	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	44.4 KB
4	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.4 KB
TOTAL: 4		835.9 GB	17.8 GB	17.8 GB	871.5 GB	95%	404.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 27 04:15:02 - 04:59:59	43027	2.0%	15.95	21.4 GB	2.6%	8317.70
Apr 27 05:00:00 - 05:59:59	57958	2.6%	16.10	26.0 GB	3.1%	7573.99
Apr 27 06:00:00 - 06:59:59	74564	3.4%	20.71	36.2 GB	4.3%	10547.26
Apr 27 07:00:00 - 07:59:59	92123	4.2%	25.59	37.3 GB	4.5%	10878.84
Apr 27 08:00:00 - 08:59:59	92463	4.2%	25.68	35.4 GB	4.2%	10320.43
Apr 27 09:00:00 - 09:59:59	80872	3.7%	22.46	32.6 GB	3.9%	9489.43
Apr 27 10:00:00 - 10:59:59	87765	4.0%	24.38	34.7 GB	4.1%	10094.10
Apr 27 11:00:00 - 11:59:59	87268	4.0%	24.24	30.4 GB	3.6%	8864.53
Apr 27 12:00:00 - 12:59:59	87110	4.0%	24.20	30.4 GB	3.6%	8856.39
Apr 27 13:00:00 - 13:59:59	93496	4.2%	25.97	32.0 GB	3.8%	9309.63
Apr 27 14:00:00 - 14:59:59	114629	5.2%	31.84	37.3 GB	4.5%	10853.82
Apr 27 15:00:00 - 15:59:59	104311	4.7%	28.98	35.4 GB	4.2%	10324.55
Apr 27 16:00:00 - 16:59:59	113834	5.2%	31.62	38.1 GB	4.5%	11083.99
Apr 27 17:00:00 - 17:59:59	119149	5.4%	33.10	38.0 GB	4.5%	11054.44
Apr 27 18:00:00 - 18:59:59	127591	5.8%	35.44	39.2 GB	4.7%	11430.71
Apr 27 19:00:00 - 19:59:59	112305	5.1%	31.20	36.4 GB	4.4%	10616.08
Apr 27 20:00:00 - 20:59:59	108063	4.9%	30.02	36.2 GB	4.3%	10536.11
Apr 27 21:00:00 - 21:59:59	106050	4.8%	29.46	40.1 GB	4.8%	11682.19
Apr 27 22:00:00 - 22:59:59	102334	4.6%	28.43	40.6 GB	4.9%	11832.34
Apr 27 23:00:00 - 23:59:59	87425	4.0%	24.28	36.8 GB	4.4%	10714.53
Apr 28 00:00:00 - 00:59:59	69978	3.2%	19.44	30.0 GB	3.6%	8745.49
Apr 28 01:00:00 - 01:59:59	82159	3.7%	22.82	38.8 GB	4.6%	11287.14
Apr 28 02:00:00 - 02:59:59	82615	3.7%	22.95	37.5 GB	4.5%	10933.71
Apr 28 03:00:00 - 03:59:59	60645	2.8%	16.85	27.3 GB	3.3%	7950.54
Apr 28 04:00:00 - 04:15:02	17003	0.8%	18.85	8.3 GB	1.0%	9608.16
TOTAL: 24:00:00	2204737	100.0%	25.52	836.4 GB	100.0%	10150.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	50491	173	49703	4	611
2	news.nask.pl	5708	5	2105	40	3558
TOTAL: 2		56199	178	51808	44	4169

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	81
it.tlc.cellulari.android	32
uk.rec.cycling.moderated	14
it.comp.os.win.windows10	12
comp.mobile.android	10
it.comp.os.win.windows7	7
de.rec.sf.startrek	5
hr.ponuda.hardver.komponente	5
comp.sys.raspberry-pi	5
comp.mobile.ipad	3
it.comp.os.linux.ubuntu	2
hr.ponuda.hardver.periferije	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 13	178

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

Groups	Count
pl.praca.oferowana	35
pl.sci.matematyka	5
pl.sci.historia	2
bit.listserv.catholic	1
christnet.philosophy	1
TOTAL: 5	44

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2423
PFM bot misc	321
EMP (phl)	239
Scoring filter (8)	225
Binary in non-binary group	181
Scoring filter (11)	52
Scoring filter (9)	31
Scoring filter (10)	29
Scoring filter (12)	22
HTML post	18
Scoring filter (13)	13
Scoring filter (15)	5
Scoring filter (16)	5
Too many newsgroups	2
Poison newsgroup	2
EMP (fsl)	2
Scoring filter (19)	1
Scoring filter (14)	1
Scoring filter (20)	1
Scoring filter (18)	1
TOTAL: 20	3574

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	1406917	3903	62186	170450	0	1299815	0%	23:55:01
2	panorama	253666	3239	220934	3032	13	252487	1%	23:51:38
3	poznan	7141	77	4466	27	0	0	1%	24:00:52
4	okapi	0	0	0	0	0	2747	0%	24:00:02
5	nask	0	0	0	0	0	163012	0%	24:00:02
TOTAL: 5		1667724	7219	287586	173509	13	1718061	0%	119:47:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	417.7 MB	62.7 GB	63.1 GB	768.9 KB/s	379.7 KB	23:55:01
2	panorama	38.0 MB	41.0 MB	79.0 MB	0.9 KB/s	12.9 KB	23:51:38
3	poznan	254.7 KB	92.1 KB	346.9 KB	0.0 KB/s	3.3 KB	24:00:52
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		456.0 MB	62.8 GB	63.2 GB	153.7 KB/s	366.8 KB	119:47:35

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	dmcs2.p.lodz.pl	5	31	53.6 KB	3	0	0	02:03:45
2	web.man.lodz.pl	288	22	17.1 KB	10	0	0	00:00:35
3	pc-212-51-218-225.p.lodz.pl	7	17	37.4 KB	11	0	0	03:30:58
4	radius2.p.lodz.pl	3	3	2.5 KB	2	1	0	00:22:13
5	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	0	00:00:00
6	staticline-31-183-111-211.toya.net.pl	5	0	0.0 KB	0	0	3	00:39:21
TOTAL: 6		311	73	110.5 KB	26	4	3	06:37:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.p.lodz.pl	15	51	93.5 KB	16	1	0	05:56:57
2	*.man.lodz.pl	675	22	17.1 KB	10	3	0	00:00:40
3	*.toya.net.pl	86	0	0.0 KB	0	0	3	00:39:29
4	*.elsnet.pl	3	0	0.0 KB	0	0	0	00:00:35
TOTAL: 4		779	73	110.5 KB	26	4	3	06:37:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.021	0.139	03:30:58
dmcs2.p.lodz.pl	0.012	0.107	02:03:45
staticline-31-183-111-211.toya.net.pl	0.092	0.124	00:39:21
radius2.p.lodz.pl	0.136	0.084	00:22:13
web.man.lodz.pl	0.995	5.005	00:00:35
host227.elsnet.pl	0.010	0.066	00:00:35
staticline41435.toya.net.pl	0.273	1.353	00:00:07
mon.man.lodz.pl	0.909	4.931	00:00:03
david.man.lodz.pl	0.326	1.611	00:00:00
isvcs01.man.lodz.pl	0.015	0.042	00:00:00
TOTAL: 10	2.789	13.462	06:37:43

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	81
host227.elsnet.pl	3
TOTAL: 4	468

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	6	0
dmcs2.p.lodz.pl	4	1
staticline-31-183-111-211.toya.net.pl	1	0
TOTAL: 3	11	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	70	95.9%
2	alt	3	4.1%
TOTAL: 2		73	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	31
2	pl.misc.kolej	17
3	pl.comp.os.ms-windows.winnt	12
4	pl.comp.pecet	10
5	alt.test.pl	3
TOTAL: 5		73

Daily Usenet report

Apr 27 04:15:02 -- Apr 28 04:15:02