Daily Usenet report: Dec 15 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Dec 15 08:16:08 newsfeed nnrpd[31659]: SERVER perl filter not defined
Dec 15 08:57:37 newsfeed nnrpd[576]: SERVER perl filter not defined
Dec 15 09:20:30 newsfeed nnrpd[1374]: SERVER perl filter not defined
Dec 15 09:21:24 newsfeed nnrpd[1385]: SERVER perl filter not defined
Dec 15 09:23:33 newsfeed nnrpd[1537]: SERVER perl filter not defined
Dec 15 17:07:26 newsfeed nnrpd[17579]: SERVER perl filter not defined
Dec 15 17:30:31 newsfeed nnrpd[18347]: SERVER perl filter not defined
Dec 15 20:29:12 newsfeed nnrpd[24502]: SERVER perl filter not defined
Dec 15 21:08:42 newsfeed nnrpd[25868]: SERVER perl filter not defined
Dec 15 22:03:56 newsfeed nnrpd[27849]: SERVER perl filter not defined
Dec 15 22:22:59 newsfeed nnrpd[28525]: SERVER perl filter not defined
Dec 15 22:25:40 newsfeed nnrpd[28613]: SERVER perl filter not defined
Dec 15 22:30:31 newsfeed nnrpd[28706]: SERVER perl filter not defined
Dec 15 22:54:34 newsfeed nnrpd[29574]: SERVER perl filter not defined
Dec 16 00:00:04 newsfeed nnrpd[31827]: SERVER perl filter not defined
Dec 16 00:29:56 newsfeed nnrpd[342]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2978033	98.5%	342.3 MB	98.2%
innd	36570	1.2%	5.2 MB	1.5%
nnrpd	4970	0.2%	511.1 KB	0.1%
innfeed	4815	0.2%	683.2 KB	0.2%
TOTAL: 4	3024388	100.0%	348.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2971650	96.7%
Negative hits	88504	2.9%
Positive hits	14109	0.5%
Cache misses	124	0.0%
TOTAL: 4	3074387	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.905	0.0%	208	0.000	9.159	318.000
article cleanup	00:00:44.628	0.1%	2977453	0.008	0.015	0.027
article logging	00:01:26.885	0.1%	2978062	0.015	0.029	0.682
article parse	01:21:24.658	5.7%	198179246	0.013	0.025	0.074
article write	00:30:25.759	2.1%	2960984	0.338	0.617	2.042
artlog/artcncl	00:00:00.002	0.0%	194	0.000	0.010	1.000
artlog/artparse	00:00:00.000	0.0%	9	0.000	0.000	0.000
data move	00:05:13.752	0.4%	216619783	0.001	0.001	0.007
hisgrep/artcncl	00:00:00.181	0.0%	185	0.000	0.978	21.000
hishave/artcncl	00:00:00.078	0.0%	208	0.000	0.375	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:29.280	1.9%	3076547	0.140	0.536	7.970
history sync	00:00:00.018	0.0%	291	0.000	0.062	1.000
history write	00:40:41.798	2.8%	2973875	0.211	0.821	8.987
hiswrite/artcncl	00:00:00.295	0.0%	23	0.000	12.826	270.000
idle	12:17:39.220	51.2%	136240877	0.179	0.325	1.211
nntp read	01:31:35.912	6.4%	195759185	0.018	0.028	0.047
overview write	00:00:17.103	0.0%	2960984	0.001	0.006	0.013
perl filter	06:13:12.205	25.9%	2973866	5.417	7.530	10.286
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:56.410	0.3%	5955624	0.030	0.050	0.082
TOTAL: 24:00:14.254	23:15:10.089	96.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.155	0.0%	43374	0.000	0.004	0.250
article prepare	00:00:00.018	0.0%	56130	0.000	0.000	0.042
article read	00:00:42.746	0.0%	14274	0.000	2.995	187.812
backlog stats	00:00:00.225	0.0%	227933	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	152	0.000	0.000	0.000
data read	00:00:01.601	0.0%	111375	0.000	0.014	0.162
data write	00:00:10.730	0.0%	113164	0.000	0.095	0.783
idle	23:58:06.605	99.9%	227933	59.799	378.561	2734.500
status file	00:00:00.614	0.0%	839	0.000	0.732	3.500
TOTAL: 23:59:08.573	23:59:02.694	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:39:13.981	99.7%	3832	0.000	1553.753	237910.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.226	0.0%	8436	0.000	0.027	0.500
readart	00:00:01.975	0.0%	130	0.000	15.192	54.143
TOTAL: 01:39:29.159	01:39:16.182	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1416	2960790	2951921	5350	3519	99%	369:33:56
2	news.nask.pl	1	26698	8844	4904	12950	33%	23:59:51
3	localhost	17	16	16	0	0	100%	00:00:00
4	news.icm.edu.pl	52	231	15	214	2	6%	18:13:26
TOTAL: 4		1486	2987735	2960796	10468	16471	99%	411:47:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	908.1 GB	17.8 MB	18.5 MB	908.1 GB	99%	322.2 KB
2	news.nask.pl	39.9 MB	1.0 MB	135.1 MB	176.1 MB	22%	8.3 KB
3	news.icm.edu.pl	233.2 KB	8.5 KB	17.5 KB	259.2 KB	89%	15.2 KB
4	localhost	25.0 KB	0.0 KB	0.0 KB	25.0 KB	100%	1.6 KB
TOTAL: 4		908.1 GB	18.8 MB	153.6 MB	908.3 GB	99%	319.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 15 04:15:02 - 04:59:59	82182	2.8%	30.46	29.1 GB	3.2%	11307.85
Dec 15 05:00:00 - 05:59:59	92942	3.1%	25.82	32.9 GB	3.6%	9580.71
Dec 15 06:00:00 - 06:59:59	92449	3.1%	25.68	32.9 GB	3.6%	9573.69
Dec 15 07:00:00 - 07:59:59	89745	3.0%	24.93	30.0 GB	3.3%	8726.81
Dec 15 08:00:00 - 08:59:59	109099	3.7%	30.31	35.3 GB	3.9%	10275.45
Dec 15 09:00:00 - 09:59:59	120301	4.1%	33.42	39.1 GB	4.3%	11387.75
Dec 15 10:00:00 - 10:59:59	125305	4.2%	34.81	39.7 GB	4.4%	11564.90
Dec 15 11:00:00 - 11:59:59	116262	3.9%	32.30	37.3 GB	4.1%	10854.35
Dec 15 12:00:00 - 12:59:59	123923	4.2%	34.42	39.8 GB	4.4%	11594.58
Dec 15 13:00:00 - 13:59:59	125697	4.2%	34.92	40.4 GB	4.5%	11763.35
Dec 15 14:00:00 - 14:59:59	123725	4.2%	34.37	39.7 GB	4.4%	11558.71
Dec 15 15:00:00 - 15:59:59	117715	4.0%	32.70	37.5 GB	4.1%	10910.89
Dec 15 16:00:00 - 16:59:59	135490	4.6%	37.64	41.1 GB	4.5%	11970.45
Dec 15 17:00:00 - 17:59:59	154128	5.2%	42.81	43.0 GB	4.8%	12526.66
Dec 15 18:00:00 - 18:59:59	156057	5.3%	43.35	43.3 GB	4.8%	12625.62
Dec 15 19:00:00 - 19:59:59	159165	5.4%	44.21	43.2 GB	4.8%	12589.91
Dec 15 20:00:00 - 20:59:59	160777	5.4%	44.66	43.6 GB	4.8%	12711.57
Dec 15 21:00:00 - 21:59:59	160915	5.4%	44.70	43.6 GB	4.8%	12693.28
Dec 15 22:00:00 - 22:59:59	161847	5.5%	44.96	43.9 GB	4.8%	12774.90
Dec 15 23:00:00 - 23:59:59	126212	4.3%	35.06	37.3 GB	4.1%	10877.64
Dec 16 00:00:00 - 00:59:59	99232	3.4%	27.56	31.9 GB	3.5%	9302.64
Dec 16 01:00:00 - 01:59:59	94565	3.2%	26.27	31.5 GB	3.5%	9162.53
Dec 16 02:00:00 - 02:59:59	109131	3.7%	30.31	33.1 GB	3.7%	9647.17
Dec 16 03:00:00 - 03:59:59	99149	3.3%	27.54	28.6 GB	3.2%	8316.08
Dec 16 04:00:00 - 04:15:02	24739	0.8%	27.43	7.0 GB	0.8%	8114.64
TOTAL: 24:00:00	2960752	100.0%	34.27	904.6 GB	100.0%	10979.06

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13152	21	297	3	12831
2	newsfeed.pionier.net.pl	3932	155	3293	0	484
3	news.icm.edu.pl	3	0	1	0	2
TOTAL: 3		17087	176	3591	3	13317

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	79
it.tlc.cellulari.android	34
it.comp.os.win.windows10	15
comp.mobile.android	13
comp.sys.raspberry-pi	10
nl.comp.os.linux.techniek	8
hr.alt.cellular.gsm.android	4
hr.ponuda.hardver.komponente	4
it.comp.os.linux.ubuntu	3
de.comp.lang.vbclassic	2
it.comp.os.win.windows7	2
comp.sys.mac.vintage	2
TOTAL: 12	176

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12376
Binary in non-binary group	111
Scoring filter (8)	82
EMP (phl)	59
Scoring filter (9)	34
Scoring filter (10)	14
Scoring filter (12)	11
EMP (fsl)	3
HTML post	3
Scoring filter (17)	2
Scoring filter (11)	2
Too many newsgroups	2
TOTAL: 12	12699

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	9758	1948	4891	1128	485	34813	19%	24:00:35
2	icm	39023	64	22130	10395	0	0	0%	24:03:29
3	nask	9298	32	6087	1	0	0	0%	23:52:48
4	poznan	13498	28	8785	16	0	0	0%	24:00:05
5	okapi	0	0	0	0	0	2225	0%	24:00:01
TOTAL: 5		71577	2072	41893	11540	485	37038	2%	119:56:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.1 MB	5.7 MB	10.7 MB	0.1 KB/s	3.6 KB	24:00:35
2	icm	2.9 MB	3.4 GB	3.4 GB	41.4 KB/s	343.0 KB	24:03:29
3	poznan	146.5 KB	59.3 KB	205.8 KB	0.0 KB/s	4.7 KB	24:00:05
4	nask	95.4 KB	217.8 KB	313.1 KB	0.0 KB/s	9.5 KB	23:52:48
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		8.2 MB	3.4 GB	3.4 GB	8.3 KB/s	264.4 KB	119:56:58

Backlog files shrunk by innfeed:

Server	Size
panorama	2.9 MB
okapi	488.3 KB
TOTAL: 2	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	74	77.2 KB	31	0	00:00:21
2	lze200.lze.lodz.pl	9	56	109.7 KB	6	0	01:36:55
3	isvcs01.man.lodz.pl	16	0	0.0 KB	0	16	00:00:01
TOTAL: 3		313	130	186.9 KB	37	16	01:39:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	688	74	77.2 KB	31	16	00:00:28
2	*.lze.lodz.pl	9	56	109.7 KB	6	0	01:36:55
3	unresolved	2	0	0.0 KB	0	0	00:02:05
TOTAL: 3		699	130	186.9 KB	37	16	01:39:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.035	0.166	01:36:55
185.48.195.200	0.032	0.086	00:02:05
web.man.lodz.pl	0.980	5.072	00:00:21
mon.man.lodz.pl	0.871	4.965	00:00:03
isvcs01.man.lodz.pl	0.109	0.277	00:00:01
david.man.lodz.pl	0.301	1.680	00:00:01
TOTAL: 6	2.328	12.246	01:39:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.200	2
TOTAL: 3	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	7	1
TOTAL: 1	7	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	56
2	pl.comp.os.ms-windows.winnt	36
3	pl.comp.os.linux	19
4	pl.rec.kuchnia	11
5	pl.misc.telefonia.gsm	8
TOTAL: 5		130

Daily Usenet report

Dec 15 04:15:02 -- Dec 16 04:15:02