Daily Usenet report: Aug 10 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Aug 10 08:33:52 newsfeed nnrpd[22743]: SERVER perl filter not defined
Aug 10 10:45:05 newsfeed nnrpd[27227]: message repeated 25 times: [ 078088067110.sieradz.vectranet.pl group lodman.info 0]
Aug 10 14:43:14 newsfeed nnrpd[3111]: SERVER perl filter not defined
Aug 10 16:34:47 newsfeed nnrpd[6926]: SERVER perl filter not defined
Aug 10 17:54:20 newsfeed nnrpd[9663]: SERVER perl filter not defined
Aug 10 18:44:24 newsfeed nnrpd[11389]: SERVER perl filter not defined
Aug 10 20:53:01 newsfeed nnrpd[15852]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2885833	97.6%	335.5 MB	97.2%
innfeed	42015	1.4%	5.0 MB	1.5%
innd	23238	0.8%	4.0 MB	1.1%
nnrpd	5019	0.2%	538.8 KB	0.2%
TOTAL: 4	2956105	100.0%	345.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2879747	98.0%
Negative hits	33984	1.2%
Positive hits	19037	0.6%
Cache misses	6489	0.2%
TOTAL: 4	2939257	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.081	0.0%	245	0.000	8.494	193.000
article cleanup	00:00:40.826	0.0%	2885580	0.007	0.014	0.026
article logging	00:01:36.716	0.1%	2885983	0.018	0.034	0.499
article parse	01:06:45.871	4.6%	118229327	0.026	0.034	0.071
article write	00:36:47.479	2.6%	2881220	0.337	0.766	3.495
artlog/artcncl	00:00:00.006	0.0%	232	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:05.017	0.1%	135274008	0.001	0.001	0.004
hisgrep/artcncl	00:00:00.105	0.0%	200	0.000	0.525	11.000
hishave/artcncl	00:00:00.042	0.0%	245	0.000	0.171	7.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:01.081	1.7%	2941114	0.015	0.490	6.160
history sync	00:00:00.019	0.0%	290	0.000	0.066	1.000
history write	00:49:40.286	3.4%	2881724	0.245	1.034	13.827
hiswrite/artcncl	00:00:00.061	0.0%	45	0.000	1.356	16.000
idle	14:10:48.803	59.1%	82372678	0.368	0.620	2.753
nntp read	00:50:03.906	3.5%	115917155	0.020	0.026	0.040
overview write	00:00:19.157	0.0%	2881220	0.002	0.007	0.014
perl filter	05:13:51.511	21.8%	2881724	4.590	6.535	9.035
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:24.709	0.4%	5836793	0.011	0.066	0.108
TOTAL: 24:00:11.090	23:23:07.676	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.435	0.0%	142998	0.000	0.003	0.125
article prepare	00:00:00.041	0.0%	138938	0.000	0.000	0.083
article read	00:01:23.283	0.1%	34675	0.000	2.402	498.000
backlog stats	00:00:00.328	0.0%	408610	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:03.458	0.0%	225417	0.000	0.015	0.111
data write	00:00:22.917	0.0%	166768	0.000	0.137	3.533
idle	23:57:07.402	99.8%	408610	21.830	211.026	1265.208
status file	00:00:12.501	0.0%	14935	0.286	0.837	4.167
TOTAL: 23:59:27.283	23:59:10.365	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:18:57.401	99.9%	27036	0.000	841.005	900099.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.871	0.0%	55041	0.000	0.052	125.571
readart	00:00:04.402	0.0%	176	0.000	25.011	34.850
TOTAL: 06:19:19.261	06:19:04.674	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	1328	2886892	2875990	6911	3991	99%	364:45:49
2	news.nask.pl	7	19007	4669	14045	293	24%	23:59:39
3	news.icm.edu.pl	37	307	30	221	56	9%	17:45:48
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1379	2906212	2880695	21177	4340	99%	406:31:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	861.7 GB	20.5 MB	25.9 MB	861.8 GB	99%	313.8 KB
2	news.nask.pl	23.9 MB	740.2 KB	884.8 KB	25.5 MB	93%	5.3 KB
3	news.icm.edu.pl	539.5 KB	92.0 KB	3.2 MB	3.9 MB	13%	45.9 KB
4	localhost	8.8 KB	0.0 KB	0.0 KB	8.8 KB	100%	1.5 KB
TOTAL: 4		861.8 GB	21.4 MB	30.0 MB	861.8 GB	99%	313.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 10 04:15:02 - 04:59:59	90958	3.2%	33.71	27.5 GB	3.2%	10675.99
Aug 10 05:00:00 - 05:59:59	115746	4.0%	32.15	36.2 GB	4.2%	10543.18
Aug 10 06:00:00 - 06:59:59	118180	4.1%	32.83	37.2 GB	4.3%	10848.99
Aug 10 07:00:00 - 07:59:59	124756	4.3%	34.65	38.3 GB	4.4%	11144.21
Aug 10 08:00:00 - 08:59:59	111885	3.9%	31.08	32.8 GB	3.8%	9560.57
Aug 10 09:00:00 - 09:59:59	130001	4.5%	36.11	37.7 GB	4.4%	10985.20
Aug 10 10:00:00 - 10:59:59	133592	4.6%	37.11	37.8 GB	4.4%	11007.11
Aug 10 11:00:00 - 11:59:59	138662	4.8%	38.52	39.2 GB	4.6%	11425.95
Aug 10 12:00:00 - 12:59:59	123463	4.3%	34.30	38.0 GB	4.4%	11055.02
Aug 10 13:00:00 - 13:59:59	127170	4.4%	35.33	38.0 GB	4.4%	11066.20
Aug 10 14:00:00 - 14:59:59	130096	4.5%	36.14	38.8 GB	4.5%	11290.59
Aug 10 15:00:00 - 15:59:59	120297	4.2%	33.42	37.2 GB	4.3%	10831.02
Aug 10 16:00:00 - 16:59:59	126911	4.4%	35.25	36.6 GB	4.2%	10648.19
Aug 10 17:00:00 - 17:59:59	137030	4.8%	38.06	36.5 GB	4.2%	10630.10
Aug 10 18:00:00 - 18:59:59	124425	4.3%	34.56	37.3 GB	4.3%	10865.63
Aug 10 19:00:00 - 19:59:59	100249	3.5%	27.85	30.4 GB	3.5%	8862.29
Aug 10 20:00:00 - 20:59:59	126746	4.4%	35.21	37.8 GB	4.4%	10997.36
Aug 10 21:00:00 - 21:59:59	128599	4.5%	35.72	39.5 GB	4.6%	11514.33
Aug 10 22:00:00 - 22:59:59	116923	4.1%	32.48	37.2 GB	4.3%	10848.08
Aug 10 23:00:00 - 23:59:59	132203	4.6%	36.72	37.8 GB	4.4%	10998.60
Aug 11 00:00:00 - 00:59:59	90936	3.2%	25.26	27.8 GB	3.2%	8108.06
Aug 11 01:00:00 - 01:59:59	120858	4.2%	33.57	35.6 GB	4.1%	10365.06
Aug 11 02:00:00 - 02:59:59	83675	2.9%	23.24	26.5 GB	3.1%	7717.83
Aug 11 03:00:00 - 03:59:59	100445	3.5%	27.90	30.5 GB	3.5%	8885.69
Aug 11 04:00:00 - 04:15:02	27032	0.9%	29.97	8.3 GB	1.0%	9611.87
TOTAL: 24:00:00	2880838	100.0%	33.34	860.4 GB	100.0%	10442.03

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4335	246	3602	2	485
2	news.nask.pl	353	17	249	17	70
3	news.icm.edu.pl	75	0	5	0	70
TOTAL: 3		4763	263	3856	19	625

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	119
it.comp.os.win.windows10	49
comp.mobile.android	26
it.comp.os.win.windows7	19
de.comp.lang.vbclassic	9
comp.sys.raspberry-pi	7
it.tlc.cellulari.android	6
hr.rec.glazba.hifi	5
alt.sex.telephones	4
comp.mobile.ipad	4
de.comm.provider.telefonie	3
uk.rec.cycling.moderated	3
hr.ponuda.hardver.komponente	3
alt.windows7.general	2
it.comp.os.linux.ubuntu	2
comp.sys.mac.vintage	1
it.comp.os.win.windows8	1
TOTAL: 17	263

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

Groups	Count
pl.praca.oferowana	17
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 3	19

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	139
EMP (md5)	80
HTML post	3
TOTAL: 3	222

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	83280	1943	55692	878	19	84492	2%	24:01:52
2	icm	71821	316	26776	30593	0	65	0%	23:57:54
3	poznan	7162	96	4649	51	0	0	1%	24:00:44
4	nask	0	0	0	0	0	14910	0%	24:00:01
5	okapi	0	0	0	0	0	2679	0%	24:00:01
TOTAL: 5		162263	2355	87117	31522	19	102146	1%	120:00:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	13.3 MB	7.5 GB	7.5 GB	91.7 KB/s	255.8 KB	23:57:54
2	panorama	6.2 MB	3.9 MB	10.1 MB	0.1 KB/s	3.7 KB	24:01:52
3	poznan	2.9 MB	1.4 MB	4.4 MB	0.1 KB/s	30.5 KB	24:00:44
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		22.4 MB	7.5 GB	7.6 GB	18.3 KB/s	233.9 KB	120:00:32

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
okapi	488.3 KB
TOTAL: 2	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	9	164	355.1 KB	6	0	03:40:21
2	web.man.lodz.pl	288	8	9.5 KB	3	0	00:00:25
3	078088067110.sieradz.vectranet.pl	9	4	5.7 KB	4	0	00:53:13
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		312	176	370.3 KB	13	6	06:19:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	9	164	355.1 KB	6	0	03:40:21
2	*.man.lodz.pl	678	8	9.5 KB	3	6	00:00:29
3	*.sieradz.vectranet.pl	9	4	5.7 KB	4	0	00:53:13
4	*.toya.net.pl	24	0	0.0 KB	0	0	00:00:01
5	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:11
6	unresolved	6	0	0.0 KB	0	0	01:45:00
TOTAL: 6		728	176	370.3 KB	13	6	06:19:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.061	0.219	03:40:21
185.48.195.160	1.470	2.148	01:45:00
078088067110.sieradz.vectranet.pl	0.075	0.186	00:53:13
web.man.lodz.pl	1.051	5.588	00:00:25
host227.elsnet.pl	0.003	0.036	00:00:11
mon.man.lodz.pl	1.055	5.514	00:00:02
staticline41435.toya.net.pl	0.079	0.476	00:00:01
david.man.lodz.pl	0.378	1.825	00:00:00
isvcs01.man.lodz.pl	0.051	0.112	00:00:00
?	0.004	0.018	00:00:00
TOTAL: 10	4.227	16.122	06:19:18

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	24
185.48.195.160	6
host227.elsnet.pl	2
TOTAL: 5	416

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	4	0
185.48.195.160	1	0
078088067110.sieradz.vectranet.pl	1	0
TOTAL: 3	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	172	97.7%
2	lodman	4	2.3%
TOTAL: 2		176	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	105
2	pl.misc.kolej	59
3	pl.comp.pecet	8
4	lodman.info	4
TOTAL: 4		176

Daily Usenet report

Aug 10 04:15:02 -- Aug 11 04:15:02