Daily Usenet report: Jul 12 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jul 12 07:29:21 newsfeed nnrpd[2411]: SERVER perl filter not defined
Jul 12 11:38:34 newsfeed nnrpd[11032]: SERVER perl filter not defined
Jul 12 18:53:27 newsfeed nnrpd[25920]: SERVER perl filter not defined
Jul 12 21:09:02 newsfeed nnrpd[30642]: SERVER perl filter not defined
Jul 12 23:55:36 newsfeed nnrpd[3847]: SERVER perl filter not defined
Jul 13 02:21:09 newsfeed nnrpd[8928]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2255708	97.4%	256.3 MB	97.3%
innd	50104	2.2%	6.0 MB	2.3%
nnrpd	5115	0.2%	526.0 KB	0.2%
innfeed	4862	0.2%	647.5 KB	0.2%
TOTAL: 4	2315789	100.0%	263.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2249537	93.8%
Negative hits	131043	5.5%
Positive hits	16863	0.7%
Cache misses	499	0.0%
TOTAL: 4	2397942	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.293	0.0%	620	0.000	5.311	376.000
article cleanup	00:00:32.348	0.0%	2253601	0.000	0.014	0.030
article logging	00:01:03.887	0.1%	2254285	0.014	0.028	0.455
article parse	01:04:45.108	4.5%	204948890	0.000	0.019	0.038
article write	00:26:07.262	1.8%	2217555	0.381	0.707	3.649
artlog/artcncl	00:00:00.009	0.0%	211	0.000	0.043	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:43.618	0.2%	219835096	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.398	0.0%	591	0.000	0.673	14.222
hishave/artcncl	00:00:00.213	0.0%	620	0.000	0.344	22.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:06.075	2.4%	2397595	0.125	0.878	12.292
history sync	00:00:00.026	0.0%	300	0.000	0.087	2.000
history write	00:46:17.958	3.2%	2249691	0.217	1.235	28.568
hiswrite/artcncl	00:00:00.085	0.0%	29	0.000	2.931	54.000
idle	14:22:05.753	59.9%	161036936	0.189	0.321	60060.000
nntp read	01:06:20.155	4.6%	203299148	0.000	0.020	0.029
overview write	00:00:12.432	0.0%	2217555	0.000	0.006	0.013
perl filter	04:41:57.088	19.6%	2249691	0.800	7.520	9.300
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:54.759	0.3%	4488543	0.036	0.052	0.092
TOTAL: 23:59:18.755	23:11:10.467	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.204	0.0%	65963	0.000	0.003	0.125
article prepare	00:00:00.025	0.0%	122696	0.000	0.000	0.042
article read	00:00:29.447	0.0%	30710	0.000	0.959	143.364
backlog stats	00:00:00.320	0.0%	321803	0.000	0.001	0.018
callbacks	00:00:00.001	0.0%	40	0.000	0.025	1.000
data read	00:00:02.206	0.0%	169505	0.000	0.013	0.080
data write	00:00:17.817	0.0%	153577	0.000	0.116	0.395
idle	23:58:49.473	99.9%	321803	21.248	268.268	23081.667
status file	00:00:00.646	0.0%	885	0.000	0.730	3.500
TOTAL: 23:59:48.211	23:59:40.139	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:37:28.036	99.9%	3534	0.000	6748.171	348389.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.177	0.0%	8360	0.000	0.021	0.593
readart	00:00:04.748	0.0%	346	0.000	13.723	183.500
TOTAL: 06:37:46.598	06:37:32.961	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	900	2220048	2205441	8933	5674	99%	284:09:29
2	news.nask.pl	2	47149	13059	3772	30318	27%	23:42:54
3	news.icm.edu.pl	52	150	8	139	3	5%	12:48:35
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		961	2267353	2218514	12844	35995	97%	320:40:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	716.2 GB	86.2 MB	430.1 MB	716.7 GB	99%	339.9 KB
2	news.nask.pl	77.1 MB	1.3 MB	167.1 MB	245.4 MB	31%	5.8 KB
3	news.icm.edu.pl	492.7 KB	9.4 KB	169.9 KB	672.1 KB	73%	61.1 KB
4	localhost	9.8 KB	0.0 KB	0.0 KB	9.8 KB	100%	1.6 KB
TOTAL: 4		716.3 GB	87.5 MB	597.3 MB	717.0 GB	99%	333.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 12 04:15:03 - 04:59:59	43730	2.0%	16.21	16.9 GB	2.4%	6574.56
Jul 12 05:00:00 - 05:59:59	57108	2.6%	15.86	21.0 GB	2.9%	6128.66
Jul 12 06:00:00 - 06:59:59	69907	3.2%	19.42	24.1 GB	3.4%	7025.72
Jul 12 07:00:00 - 07:59:59	78420	3.5%	21.78	26.8 GB	3.7%	7816.52
Jul 12 08:00:00 - 08:59:59	77407	3.5%	21.50	26.6 GB	3.7%	7746.03
Jul 12 09:00:00 - 09:59:59	101802	4.6%	28.28	30.4 GB	4.2%	8841.73
Jul 12 10:00:00 - 10:59:59	81201	3.7%	22.56	26.2 GB	3.7%	7618.88
Jul 12 11:00:00 - 11:59:59	79021	3.6%	21.95	25.1 GB	3.5%	7298.96
Jul 12 12:00:00 - 12:59:59	75109	3.4%	20.86	25.0 GB	3.5%	7272.69
Jul 12 13:00:00 - 13:59:59	71376	3.2%	19.83	25.0 GB	3.5%	7272.76
Jul 12 14:00:00 - 14:59:59	67339	3.0%	18.71	24.1 GB	3.4%	7018.45
Jul 12 15:00:00 - 15:59:59	69168	3.1%	19.21	23.9 GB	3.3%	6959.86
Jul 12 16:00:00 - 16:59:59	81102	3.7%	22.53	26.7 GB	3.7%	7786.89
Jul 12 17:00:00 - 17:59:59	78556	3.5%	21.82	25.2 GB	3.5%	7353.74
Jul 12 18:00:00 - 18:59:59	106825	4.8%	29.67	31.3 GB	4.4%	9104.92
Jul 12 19:00:00 - 19:59:59	123941	5.6%	34.43	38.3 GB	5.3%	11149.66
Jul 12 20:00:00 - 20:59:59	125345	5.6%	34.82	39.0 GB	5.4%	11348.41
Jul 12 21:00:00 - 21:59:59	128729	5.8%	35.76	40.4 GB	5.6%	11764.22
Jul 12 22:00:00 - 22:59:59	116579	5.3%	32.38	36.4 GB	5.1%	10590.27
Jul 12 23:00:00 - 23:59:59	138323	6.2%	38.42	39.6 GB	5.5%	11534.19
Jul 13 00:00:00 - 00:59:59	98408	4.4%	27.34	30.6 GB	4.3%	8905.04
Jul 13 01:00:00 - 01:59:59	113446	5.1%	31.51	35.0 GB	4.9%	10198.69
Jul 13 02:00:00 - 02:59:59	106876	4.8%	29.69	34.9 GB	4.9%	10163.83
Jul 13 03:00:00 - 03:59:59	104099	4.7%	28.92	35.6 GB	5.0%	10382.73
Jul 13 04:00:00 - 04:15:02	24947	1.1%	27.66	8.5 GB	1.2%	9936.05
TOTAL: 23:59:59	2218764	100.0%	25.68	716.5 GB	100.0%	8695.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	30607	33	267	2	30305
2	newsfeed.pionier.net.pl	6123	222	3649	0	2252
3	news.icm.edu.pl	3	0	1	0	2
TOTAL: 3		36733	255	3917	2	32559

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
it.tlc.cellulari.android	50
it.comp.os.win.windows10	26
uk.rec.cycling.moderated	17
comp.mobile.ipad	9
comp.mobile.android	7
comp.sys.raspberry-pi	7
de.rec.sf.startrek	5
hr.alt.cellular.gsm.android	4
de.comp.lang.vbclassic	4
alt.sex.telephones	1
nl.comp.os.linux.techniek	1
de.comp.lang.perl	1
de.comm.software.mailreader	1
de.comp.lang.php	1
hr.ponuda.hardver.komponente	1
TOTAL: 16	255

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

Groups	Count
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	28460
Binary in non-binary group	2502
Poison newsgroup	228
EMP (fsl)	188
Scoring filter (8)	124
PFM bot misc	107
EMP (phl)	101
Scoring filter (9)	54
Scoring filter (12)	47
Scoring filter (10)	18
HTML post	15
Scoring filter (11)	12
Scoring filter (13)	7
Scoring filter (21)	4
Scoring filter (16)	3
Scoring filter (23)	2
Scoring filter (22)	2
TOTAL: 17	31874

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	13191	2094	8736	901	81	53475	15%	24:00:04
2	icm	59425	383	25648	26780	0	620	0%	24:05:00
3	poznan	20788	197	12820	31	0	0	0%	23:52:29
4	nask	7539	30	4860	1	0	0	0%	24:02:29
5	okapi	0	0	0	0	0	2561	0%	24:00:00
TOTAL: 5		100943	2704	52064	27713	81	56656	2%	120:00:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.5 MB	5.6 MB	11.1 MB	0.1 KB/s	3.8 KB	24:00:04
2	icm	3.9 MB	6.1 GB	6.1 GB	73.7 KB/s	235.3 KB	24:05:00
3	poznan	1.0 MB	134.9 KB	1.2 MB	0.0 KB/s	5.2 KB	23:52:29
4	nask	168.7 KB	2.4 KB	171.1 KB	0.0 KB/s	5.5 KB	24:02:29
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		10.6 MB	6.1 GB	6.1 GB	14.8 KB/s	210.5 KB	120:00:02

Backlog files shrunk by innfeed:

Server	Size
panorama	4.3 MB
okapi	488.3 KB
TOTAL: 2	4.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	23	166	329.1 KB	19	0	03:34:17
2	lze200.lze.lodz.pl	19	114	223.6 KB	17	0	03:02:15
3	web.man.lodz.pl	287	66	61.3 KB	30	0	00:00:25
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		335	346	613.9 KB	66	6	06:37:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	23	166	329.1 KB	19	0	03:34:17
2	*.lze.lodz.pl	19	114	223.6 KB	17	0	03:02:15
3	*.man.lodz.pl	677	66	61.3 KB	30	6	00:00:30
4	unresolved	2	0	0.0 KB	0	0	00:00:12
5	*.elsnet.pl	6	0	0.0 KB	0	0	00:00:30
TOTAL: 5		727	346	613.9 KB	66	6	06:37:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.083	0.336	03:34:17
lze200.lze.lodz.pl	0.063	0.277	03:02:15
host227.elsnet.pl	0.026	0.087	00:00:30
web.man.lodz.pl	1.015	4.046	00:00:25
212.51.203.199	0.006	0.026	00:00:12
mon.man.lodz.pl	0.879	3.622	00:00:03
david.man.lodz.pl	0.282	1.159	00:00:01
isvcs01.man.lodz.pl	0.038	0.084	00:00:00
TOTAL: 8	2.392	9.637	06:37:46

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	6
212.51.203.199	2
TOTAL: 4	392

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	0
lze200.lze.lodz.pl	2	0
TOTAL: 2	5	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	120
2	pl.comp.pecet	75
3	pl.misc.kolej	46
4	pl.comp.os.ms-windows.winnt	41
5	pl.misc.telefonia.gsm	26
6	pl.rec.kuchnia	24
7	pl.comp.os.linux	10
8	pl.internet.polip	4
TOTAL: 8		346

Daily Usenet report

Jul 12 04:15:03 -- Jul 13 04:15:02