Daily Usenet report: Dec 19 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 19 09:51:05 newsfeed nnrpd[3187]: SERVER perl filter not defined
Dec 19 12:07:20 newsfeed nnrpd[8419]: SERVER perl filter not defined
Dec 19 12:13:26 newsfeed nnrpd[8531]: SERVER perl filter not defined
Dec 19 12:24:20 newsfeed nnrpd[8912]: SERVER perl filter not defined
Dec 19 15:30:18 newsfeed overchan[22529]: input line too long, skipping
Dec 19 18:56:43 newsfeed overchan[22529]: input line too long, skipping
Dec 19 19:45:11 newsfeed overchan[22529]: input line too long, skipping
Dec 19 20:00:56 newsfeed overchan[22529]: input line too long, skipping
Dec 19 20:24:00 newsfeed overchan[22529]: input line too long, skipping
Dec 20 00:18:29 newsfeed nnrpd[2630]: SERVER perl filter not defined
Dec 20 01:55:44 newsfeed overchan[22529]: input line too long, skipping
Dec 20 01:56:08 newsfeed overchan[22529]: input line too long, skipping
Dec 20 01:59:34 newsfeed overchan[22529]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3074545	98.3%	351.0 MB	98.0%
innfeed	25588	0.8%	3.0 MB	0.9%
innd	22135	0.7%	3.5 MB	1.0%
nnrpd	4783	0.2%	499.6 KB	0.1%
overchan	9	0.0%	1.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3127061	100.0%	358.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3051300	96.3%
Negative hits	106844	3.4%
Positive hits	10461	0.3%
Cache misses	97	0.0%
TOTAL: 4	3168702	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.894	0.0%	188	0.000	20.713	203.200
article cleanup	00:00:34.895	0.0%	3073088	0.008	0.011	0.016
article logging	00:01:46.071	0.1%	3073823	0.024	0.035	0.066
article parse	00:36:25.687	2.5%	77525030	0.018	0.028	0.045
article write	00:14:42.597	1.0%	3066764	0.194	0.288	0.434
artlog/artcncl	00:00:00.003	0.0%	171	0.000	0.018	0.111
data move	00:02:41.103	0.2%	91910565	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.369	0.0%	179	0.000	2.061	54.000
hishave/artcncl	00:00:00.080	0.0%	188	0.000	0.426	4.857
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:46:25.819	7.4%	3189859	0.284	2.002	7.966
history sync	00:00:00.010	0.0%	290	0.000	0.034	0.500
history write	05:24:36.531	22.5%	3070548	1.575	6.343	14.008
hiswrite/artcncl	00:00:00.299	0.0%	9	17.000	33.222	41.333
idle	10:30:40.806	43.8%	56310862	0.367	0.672	1.398
nntp read	00:39:41.999	2.8%	75150949	0.021	0.032	0.052
overview write	00:00:13.990	0.0%	3066764	0.003	0.005	0.007
perl filter	04:20:13.752	18.1%	3070523	3.490	5.085	7.280
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:28.076	0.3%	6152859	0.034	0.044	0.059
TOTAL: 24:00:05.768	23:42:35.981	98.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.099	0.0%	45271	0.000	0.002	0.025
article prepare	00:00:00.002	0.0%	8902	0.000	0.000	0.007
article read	00:01:07.424	0.1%	2243	2.917	30.060	329.143
backlog stats	00:00:00.096	0.0%	142142	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	22	0.000	0.000	0.000
data read	00:00:01.017	0.0%	77915	0.002	0.013	0.029
data write	00:00:02.611	0.0%	58661	0.019	0.045	0.086
idle	24:03:53.284	99.9%	142142	343.758	609.484	1178.690
status file	00:00:12.648	0.0%	8848	0.417	1.429	28.935
TOTAL: 24:05:26.592	24:05:17.181	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:36.793	74.3%	3052	0.000	12.055	10002.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.188	0.4%	6724	0.000	0.028	0.632
readart	00:00:00.000	0.0%	36	0.000	0.000	0.000
TOTAL: 00:00:49.487	00:00:36.981	74.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	671	3066146	3057989	5571	2586	99%	527:03:10
2	news.nask.pl	2	14543	8742	2066	3735	60%	24:08:36
3	news.icm.edu.pl	41	278	12	259	7	4%	17:22:37
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		720	3080972	3066748	7896	6328	99%	568:34:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	628.8 GB	17.9 MB	19.0 MB	628.8 GB	99%	215.4 KB
2	news.nask.pl	52.7 MB	761.4 KB	16.9 MB	70.3 MB	74%	5.8 KB
3	news.icm.edu.pl	668.2 KB	42.1 KB	523.4 KB	1.2 MB	54%	64.9 KB
4	localhost	8.1 KB	0.0 KB	0.0 KB	8.1 KB	100%	1.6 KB
TOTAL: 4		628.8 GB	18.7 MB	36.4 MB	628.9 GB	99%	214.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 19 04:15:02 - 04:59:59	80174	2.6%	29.72	16.7 GB	2.7%	6508.69
Dec 19 05:00:00 - 05:59:59	118661	3.9%	32.96	22.7 GB	3.6%	6610.83
Dec 19 06:00:00 - 06:59:59	112988	3.7%	31.39	23.0 GB	3.6%	6694.08
Dec 19 07:00:00 - 07:59:59	115107	3.8%	31.97	23.9 GB	3.8%	6951.44
Dec 19 08:00:00 - 08:59:59	108420	3.5%	30.12	22.9 GB	3.6%	6679.04
Dec 19 09:00:00 - 09:59:59	99123	3.2%	27.53	20.1 GB	3.2%	5854.66
Dec 19 10:00:00 - 10:59:59	92189	3.0%	25.61	20.0 GB	3.2%	5832.38
Dec 19 11:00:00 - 11:59:59	106125	3.5%	29.48	21.8 GB	3.5%	6346.28
Dec 19 12:00:00 - 12:59:59	124064	4.0%	34.46	21.2 GB	3.4%	6179.45
Dec 19 13:00:00 - 13:59:59	108815	3.5%	30.23	22.5 GB	3.6%	6553.48
Dec 19 14:00:00 - 14:59:59	100898	3.3%	28.03	19.1 GB	3.0%	5562.29
Dec 19 15:00:00 - 15:59:59	107309	3.5%	29.81	19.9 GB	3.2%	5789.64
Dec 19 16:00:00 - 16:59:59	100610	3.3%	27.95	23.5 GB	3.7%	6838.61
Dec 19 17:00:00 - 17:59:59	113051	3.7%	31.40	23.4 GB	3.7%	6808.63
Dec 19 18:00:00 - 18:59:59	105521	3.4%	29.31	23.0 GB	3.6%	6686.94
Dec 19 19:00:00 - 19:59:59	91101	3.0%	25.31	19.8 GB	3.1%	5768.36
Dec 19 20:00:00 - 20:59:59	143839	4.7%	39.96	27.4 GB	4.3%	7976.35
Dec 19 21:00:00 - 21:59:59	151392	4.9%	42.05	31.6 GB	5.0%	9209.28
Dec 19 22:00:00 - 22:59:59	146679	4.8%	40.74	32.2 GB	5.1%	9365.03
Dec 19 23:00:00 - 23:59:59	195056	6.4%	54.18	38.8 GB	6.2%	11289.28
Dec 20 00:00:00 - 00:59:59	174127	5.7%	48.37	32.9 GB	5.2%	9573.41
Dec 20 01:00:00 - 01:59:59	172347	5.6%	47.87	36.7 GB	5.8%	10683.53
Dec 20 02:00:00 - 02:59:59	188739	6.2%	52.43	37.9 GB	6.0%	11033.20
Dec 20 03:00:00 - 03:59:59	166514	5.4%	46.25	38.5 GB	6.1%	11211.85
Dec 20 04:00:00 - 04:15:03	44466	1.4%	49.24	10.4 GB	1.7%	12128.34
TOTAL: 24:00:01	3067315	100.0%	35.50	629.7 GB	100.0%	7642.33

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3852	24	257	5	3566
2	newsfeed.pionier.net.pl	3200	234	2283	2	681
3	news.icm.edu.pl	7	0	1	0	6
TOTAL: 3		7059	258	2541	7	4253

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	170
it.comp.os.win.windows10	28
it.tlc.cellulari.android	20
comp.sys.raspberry-pi	15
de.comp.lang.vbclassic	10
it.comp.os.win.windows7	5
uk.rec.cycling.moderated	2
comp.mobile.android	2
alt.sex.telephones	2
nl.comp.os.linux.techniek	2
de.comm.software.mailreader	1
hr.ponuda.hardver.komponente	1
TOTAL: 12	258

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3273
Binary in non-binary group	139
Scoring filter (9)	35
Scoring filter (8)	31
Scoring filter (10)	8
Too many newsgroups	3
Scoring filter (12)	2
HTML post	2
Angle-bracket bot	1
TOTAL: 9	3494

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	609
	news.nask.pl	125
	TOTAL: 2	734
TOTAL: 1		734

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	27175	1105	11224	282	54	32406	4%	23:59:59
2	poznan	13444	143	8532	14	0	2	1%	23:59:59
3	icm	24546	40	19042	5	0	0	0%	23:59:59
4	nask	4849	5	3876	0	0	0	0%	23:57:57
5	okapi	0	0	0	0	0	1232	0%	23:57:51
TOTAL: 5		70014	1293	42674	301	54	33640	1%	119:55:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	7.0 MB	796.6 KB	7.7 MB	0.1 KB/s	5.7 KB	23:59:59
2	icm	821.8 KB	16.9 KB	838.7 KB	0.0 KB/s	18.6 KB	23:59:59
3	poznan	484.2 KB	38.8 KB	523.0 KB	0.0 KB/s	3.3 KB	23:59:59
4	nask	11.5 KB	0.0 KB	11.5 KB	0.0 KB/s	2.3 KB	23:57:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		8.2 MB	852.4 KB	9.1 MB	0.0 KB/s	5.8 KB	119:55:45

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	36	30.3 KB	16	0	00:00:31
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:02
TOTAL: 2		293	36	30.3 KB	16	5	00:00:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	36	30.3 KB	16	5	00:00:38
2	unresolved	2	0	0.0 KB	0	0	00:00:10
TOTAL: 2		679	36	30.3 KB	16	5	00:00:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.152	4.764	00:00:31
185.48.192.177	0.000	0.012	00:00:10
mon.man.lodz.pl	0.936	4.580	00:00:03
isvcs01.man.lodz.pl	0.032	0.108	00:00:02
david.man.lodz.pl	0.312	1.564	00:00:01
TOTAL: 5	2.432	11.028	00:00:49

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	24
2	pl.comp.pecet	12
TOTAL: 2		36

Daily Usenet report

Dec 19 04:15:02 -- Dec 20 04:15:03