Daily Usenet report: Jan 22 04:15:01

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 22 11:50:52 newsfeed nnrpd[5400]: SERVER perl filter not defined
Jan 22 12:38:39 newsfeed nnrpd[7001]: SERVER perl filter not defined
Jan 22 12:42:40 newsfeed nnrpd[7225]: SERVER perl filter not defined
Jan 22 13:16:06 newsfeed nnrpd[8301]: SERVER perl filter not defined
Jan 22 14:42:48 newsfeed nnrpd[11199]: SERVER perl filter not defined
Jan 22 19:22:27 newsfeed overchan[21570]: input line too long, skipping
Jan 22 19:46:27 newsfeed overchan[21570]: input line too long, skipping
Jan 22 20:00:32 newsfeed nnrpd[21909]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1890728	96.7%	223.2 MB	96.4%
innfeed	43798	2.2%	5.2 MB	2.2%
innd	15836	0.8%	2.5 MB	1.1%
nnrpd	4954	0.3%	527.3 KB	0.2%
overchan	4	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1955321	100.0%	231.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1882215	98.3%
Negative hits	20850	1.1%
Positive hits	10796	0.6%
Cache misses	463	0.0%
TOTAL: 4	1914324	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.501	0.0%	82	0.000	18.305	110.000
article cleanup	00:00:26.435	0.0%	1886101	0.000	0.014	0.042
article logging	00:01:25.136	0.1%	1886624	0.000	0.045	0.156
article parse	00:29:53.438	2.1%	70388808	0.000	0.025	0.075
article write	00:11:32.439	0.8%	1879750	0.174	0.368	2.046
artlog/artcncl	00:00:00.004	0.0%	80	0.000	0.050	1.000
data move	00:01:11.075	0.1%	80406039	0.000	0.001	0.054
hisgrep/artcncl	00:00:00.163	0.0%	80	0.000	2.038	34.000
hishave/artcncl	00:00:00.639	0.0%	82	0.000	7.793	68.222
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:12:39.011	0.9%	1914361	0.026	0.396	4.121
history sync	00:00:00.028	0.0%	299	0.000	0.094	1.000
history write	00:10:30.060	0.7%	1882244	0.113	0.335	1.295
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	18:31:52.864	77.2%	58335375	0.654	1.144	6447.656
nntp read	00:51:49.451	3.6%	68830479	0.021	0.045	0.070
overview write	00:00:10.458	0.0%	1879750	0.000	0.006	0.026
perl filter	03:00:02.446	12.5%	1882244	1.770	5.739	10.487
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:21.009	0.3%	3776327	0.010	0.069	0.093
TOTAL: 24:00:06.809	23:35:56.157	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.221	0.0%	114867	0.000	0.002	0.010
article prepare	00:00:00.004	0.0%	9098	0.000	0.000	0.019
article read	00:00:36.986	0.0%	2247	0.000	16.460	174.125
backlog stats	00:00:00.310	0.0%	524418	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:04.616	0.0%	266367	0.004	0.017	0.090
data write	00:00:06.545	0.0%	246004	0.011	0.027	0.306
idle	23:52:41.271	99.9%	524418	17.315	163.917	1173.328
status file	00:00:25.787	0.0%	15779	0.625	1.634	77.342
TOTAL: 23:54:12.668	23:53:55.740	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.075	0.0%	1099	0.068	0.068	0.068
idle	00:14:10.831	98.0%	4228	0.000	201.237	1219.600
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.795	0.1%	9184	0.000	0.087	8.526
readart	00:00:00.037	0.0%	83	0.000	0.446	31.000
TOTAL: 00:14:28.085	00:14:11.738	98.1%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	825	1883855	1881330	1115	1410	99%	206:33:57
2	news.nask.pl	2	13205	2308	5943	4954	17%	24:21:49
3	news.icm.edu.pl	38	377	67	288	22	17%	19:08:16
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		872	1897443	1883711	7346	6386	99%	250:04:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	426.2 GB	11.4 MB	12.1 MB	426.3 GB	99%	237.4 KB
2	news.nask.pl	15.1 MB	13.0 MB	25.3 MB	53.4 MB	28%	7.5 KB
3	news.icm.edu.pl	1.3 MB	16.5 KB	475.8 KB	1.8 MB	72%	20.3 KB
4	localhost	10.2 KB	0.0 KB	0.0 KB	10.2 KB	100%	1.7 KB
TOTAL: 4		426.2 GB	24.5 MB	37.9 MB	426.3 GB	99%	236.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 22 04:15:01 - 04:59:59	252	0.0%	0.09	1.1 MB	0.0%	0.40
Jan 22 05:00:00 - 05:59:59	260	0.0%	0.07	1.2 MB	0.0%	0.34
Jan 22 06:00:00 - 06:59:59	37180	2.0%	10.33	7.9 GB	1.9%	2308.76
Jan 22 07:00:00 - 07:59:59	62280	3.3%	17.30	17.8 GB	4.2%	5194.34
Jan 22 08:00:00 - 08:59:59	124833	6.6%	34.68	20.8 GB	4.9%	6060.84
Jan 22 09:00:00 - 09:59:59	59540	3.2%	16.54	17.3 GB	4.0%	5029.49
Jan 22 10:00:00 - 10:59:59	70110	3.7%	19.48	16.9 GB	4.0%	4930.26
Jan 22 11:00:00 - 11:59:59	100889	5.4%	28.02	19.6 GB	4.6%	5701.85
Jan 22 12:00:00 - 12:59:59	59394	3.2%	16.50	12.5 GB	2.9%	3642.62
Jan 22 13:00:00 - 13:59:59	79051	4.2%	21.96	20.2 GB	4.7%	5876.99
Jan 22 14:00:00 - 14:59:59	84483	4.5%	23.47	21.5 GB	5.0%	6275.62
Jan 22 15:00:00 - 15:59:59	93360	5.0%	25.93	15.5 GB	3.6%	4528.50
Jan 22 16:00:00 - 16:59:59	75639	4.0%	21.01	20.5 GB	4.8%	5982.95
Jan 22 17:00:00 - 17:59:59	117711	6.2%	32.70	24.9 GB	5.8%	7259.95
Jan 22 18:00:00 - 18:59:59	100873	5.4%	28.02	23.1 GB	5.4%	6726.47
Jan 22 19:00:00 - 19:59:59	94699	5.0%	26.31	23.3 GB	5.5%	6790.57
Jan 22 20:00:00 - 20:59:59	97512	5.2%	27.09	23.1 GB	5.4%	6732.82
Jan 22 21:00:00 - 21:59:59	134399	7.1%	37.33	24.9 GB	5.8%	7263.33
Jan 22 22:00:00 - 22:59:59	104406	5.5%	29.00	21.7 GB	5.1%	6330.51
Jan 22 23:00:00 - 23:59:59	91744	4.9%	25.48	21.1 GB	4.9%	6144.00
Jan 23 00:00:00 - 00:59:59	65842	3.5%	18.29	14.6 GB	3.4%	4247.60
Jan 23 01:00:00 - 01:59:59	91665	4.9%	25.46	23.7 GB	5.6%	6909.04
Jan 23 02:00:00 - 02:59:59	51804	2.8%	14.39	12.0 GB	2.8%	3493.44
Jan 23 03:00:00 - 03:59:59	65696	3.5%	18.25	18.0 GB	4.2%	5251.97
Jan 23 04:00:00 - 04:15:02	20150	1.1%	22.34	5.5 GB	1.3%	6415.19
TOTAL: 24:00:01	1883772	100.0%	21.80	426.7 GB	100.0%	5178.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4949	0	2646	1	2302
2	newsfeed.pionier.net.pl	1903	181	1211	1	510
3	news.icm.edu.pl	24	0	1	0	23
TOTAL: 3		6876	181	3858	2	2835

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	101
it.tlc.cellulari.android	25
it.comp.os.win.windows10	20
it.comp.os.win.windows7	10
comp.mobile.android	6
de.comm.software.mailreader	5
comp.sys.raspberry-pi	5
it.discussioni.energia	4
uk.rec.cycling.moderated	3
comp.mobile.ipad	2
TOTAL: 10	181

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2122
Binary in non-binary group	77
EMP (phl)	70
Scoring filter (9)	16
Scoring filter (18)	9
Scoring filter (10)	7
Scoring filter (8)	5
Too many newsgroups	1
Scoring filter (11)	1
TOTAL: 9	2308

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	493
	news.nask.pl	32
	news.icm.edu.pl	2
	TOTAL: 3	527
TOTAL: 1		527

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43243	900	15773	297	3	37133	2%	24:00:07
2	poznan	334068	143	2507	9	0	62595	0%	24:03:29
3	icm	24865	100	16631	7	0	0	0%	24:09:38
4	nask	9821	3	8631	3	0	0	0%	22:13:57
5	okapi	0	0	0	0	0	1262	0%	24:05:37
TOTAL: 5		411997	1146	43542	316	3	100990	0%	118:32:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	panorama	2.9 MB	1.2 MB	4.2 MB	3.6 KB	24:00:07
2	poznan	421.8 KB	32.9 KB	454.7 KB	3.0 KB	24:03:29
3	icm	344.0 KB	39.1 KB	383.1 KB	3.6 KB	24:09:38
4	nask	11.0 KB	5.9 KB	16.9 KB	2.8 KB	22:13:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	24:05:37
TOTAL: 5		3.7 MB	1.3 MB	5.0 MB	3.5 KB	118:32:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	289	82	112.2 KB	35	0	00:00:28
2	az1-ss13.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 3		297	83	112.9 KB	36	6	00:14:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	583	82	112.2 KB	35	6	00:00:33
2	*.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	*.consolidated.net	1	0	0.0 KB	0	0	00:13:39
4	unresolved	97	0	0.0 KB	0	0	00:00:01
TOTAL: 4		683	83	112.9 KB	36	6	00:14:27

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-14-174.consolidated.net	0.076	0.200	00:13:39
web.man.lodz.pl	1.744	5.084	00:00:28
az1-ss13.a2hosting.com	0.044	0.024	00:00:12
mon.man.lodz.pl	1.192	3.660	00:00:03
isvcs01.man.lodz.pl	0.048	0.100	00:00:01
212.51.192.185	0.408	1.256	00:00:01
122.228.19.80	0.008	0.012	00:00:00
TOTAL: 7	3.520	10.336	00:14:27

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
client-66-116-14-174.consolidated.net	1
122.228.19.80	1
TOTAL: 4	386

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Connection: keep-alive	1
Host: 212.191.2.21:119	1
Accept: /	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 5	5

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	82	98.8%
2	lodz	1	1.2%
TOTAL: 2		83	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	58
2	pl.comp.pecet	22
3	pl.internet.polip	2
4	lodz.test	1
TOTAL: 4		83

Daily Usenet report

Jan 22 04:15:01 -- Jan 23 04:15:02