Daily Usenet report: Apr 11 04:15:03

Unknown entries from news log file:

First 29 / 29 lines (100.0%)

Apr 11 07:43:44 newsfeed overchan[1409]: input line too long, skipping
Apr 11 10:09:14 newsfeed nnrpd[15155]: SERVER perl filter not defined
Apr 11 10:09:58 newsfeed nnrpd[15161]: SERVER perl filter not defined
Apr 11 10:10:44 newsfeed nnrpd[15205]: SERVER perl filter not defined
Apr 11 10:11:29 newsfeed nnrpd[15206]: SERVER perl filter not defined
Apr 11 10:30:17 newsfeed nnrpd[15921]: SERVER perl filter not defined
Apr 11 10:46:07 newsfeed nnrpd[16550]: SERVER perl filter not defined
Apr 11 12:28:23 newsfeed nnrpd[20248]: SERVER perl filter not defined
Apr 11 12:30:28 newsfeed nnrpd[20316]: SERVER perl filter not defined
Apr 11 12:31:29 newsfeed nnrpd[20316]: message repeated 2 times: [ pc-212-191-92-112.p.lodz.pl group lodman.test 0]
Apr 11 16:06:10 newsfeed nnrpd[28263]: SERVER perl filter not defined
Apr 11 17:58:33 newsfeed overchan[1409]: input line too long, skipping
Apr 11 19:49:39 newsfeed overchan[1409]: input line too long, skipping
Apr 11 19:50:44 newsfeed overchan[1409]: input line too long, skipping
Apr 11 19:52:00 newsfeed overchan[1409]: input line too long, skipping
Apr 11 19:53:27 newsfeed overchan[1409]: input line too long, skipping
Apr 11 20:00:57 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:44:00 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:44:20 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:44:41 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:45:08 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:45:31 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:46:10 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:46:29 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:46:51 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:47:09 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:52:06 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:52:25 newsfeed overchan[1409]: input line too long, skipping
Apr 12 02:52:42 newsfeed overchan[1409]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2577217	98.4%	301.5 MB	98.3%
innd	32927	1.3%	4.3 MB	1.4%
nnrpd	4857	0.2%	504.8 KB	0.2%
innfeed	3619	0.1%	493.5 KB	0.2%
overchan	20	0.0%	3.2 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2618641	100.0%	306.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2561814	80.3%
Negative hits	612957	19.2%
Positive hits	13671	0.4%
Cache misses	112	0.0%
TOTAL: 4	3188554	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.874	0.0%	194	0.000	14.814	1531.000
article cleanup	00:00:32.942	0.0%	2488235	0.011	0.013	0.016
article logging	00:01:37.654	0.1%	2488822	0.030	0.039	0.143
article parse	01:17:14.209	5.4%	243135630	0.013	0.019	0.028
article write	00:26:59.069	1.9%	2469831	0.479	0.656	0.943
artlog/artcncl	00:00:00.005	0.0%	184	0.000	0.027	0.250
artlog/artparse	00:00:04.140	0.0%	88014	0.000	0.047	0.200
data move	00:03:33.656	0.2%	260789666	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.826	0.0%	179	0.000	4.615	628.000
hishave/artcncl	00:00:00.023	0.0%	194	0.000	0.119	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:21.358	1.6%	3201551	0.021	0.419	4.506
history sync	00:00:00.011	0.0%	290	0.000	0.038	0.500
history write	00:55:22.819	3.8%	2572871	0.157	1.291	6.194
hiswrite/artcncl	00:00:00.005	0.0%	15	0.000	0.333	0.556
idle	13:33:05.506	56.5%	175441046	0.216	0.278	0.367
nntp read	01:28:41.276	6.2%	241291912	0.016	0.022	0.031
overview write	00:00:13.536	0.0%	2469831	0.004	0.005	0.008
perl filter	05:10:23.949	21.6%	2484857	6.687	7.495	8.192
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:02.989	0.3%	4968695	0.037	0.049	0.060
TOTAL: 24:00:02.726	23:24:16.847	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.139	0.0%	36556	0.000	0.004	0.021
article prepare	00:00:00.011	0.0%	34676	0.000	0.000	0.006
article read	00:00:11.205	0.0%	9296	0.028	1.205	43.143
backlog stats	00:00:00.167	0.0%	167924	0.000	0.001	0.013
callbacks	00:00:00.005	0.0%	635	0.000	0.008	0.077
data read	00:00:02.373	0.0%	95899	0.007	0.025	0.049
data write	00:00:06.897	0.0%	67475	0.034	0.102	0.205
idle	24:04:45.814	100.0%	167924	332.094	516.221	957.456
status file	00:00:00.711	0.0%	870	0.167	0.817	1.867
TOTAL: 24:05:12.896	24:05:07.322	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:47:49.507	98.9%	3134	0.000	915.605	263132.429
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.238	0.0%	6997	0.000	0.034	0.737
readart	00:00:17.287	0.6%	10489	0.000	1.648	2.400
TOTAL: 00:48:22.883	00:48:07.032	99.5%	-	-	-	-

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	1247	2557551	2459439	6621	91491	96%	362:03:36
2	news.nask.pl	2	28153	9897	3371	14885	35%	23:58:28
3	news.icm.edu.pl	46	378	21	284	73	5%	18:30:23
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1306	2586092	2469367	10276	106449	95%	404:32:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	843.3 GB	21.6 MB	23.1 GB	866.4 GB	97%	356.2 KB
2	news.nask.pl	40.3 MB	999.4 KB	153.1 MB	194.4 MB	20%	8.0 KB
3	news.icm.edu.pl	417.0 KB	8.4 KB	982.2 KB	1.4 MB	29%	15.0 KB
4	localhost	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	1.3 KB
TOTAL: 4		843.4 GB	22.6 MB	23.2 GB	866.6 GB	97%	352.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 11 04:15:03 - 04:59:59	75352	3.1%	27.94	27.4 GB	3.2%	10638.01
Apr 11 05:00:00 - 05:59:59	100179	4.1%	27.83	35.7 GB	4.2%	10404.12
Apr 11 06:00:00 - 06:59:59	114686	4.6%	31.86	36.2 GB	4.3%	10542.20
Apr 11 07:00:00 - 07:59:59	121800	4.9%	33.83	37.0 GB	4.4%	10783.84
Apr 11 08:00:00 - 08:59:59	123628	5.0%	34.34	37.8 GB	4.5%	11016.32
Apr 11 09:00:00 - 09:59:59	101515	4.1%	28.20	31.4 GB	3.7%	9135.86
Apr 11 10:00:00 - 10:59:59	109024	4.4%	30.28	36.5 GB	4.3%	10629.80
Apr 11 11:00:00 - 11:59:59	103355	4.2%	28.71	35.6 GB	4.2%	10381.09
Apr 11 12:00:00 - 12:59:59	102434	4.1%	28.45	35.9 GB	4.2%	10442.37
Apr 11 13:00:00 - 13:59:59	94286	3.8%	26.19	35.2 GB	4.2%	10245.15
Apr 11 14:00:00 - 14:59:59	111106	4.5%	30.86	37.7 GB	4.5%	10984.96
Apr 11 15:00:00 - 15:59:59	99406	4.0%	27.61	37.6 GB	4.5%	10958.86
Apr 11 16:00:00 - 16:59:59	98872	4.0%	27.46	37.4 GB	4.4%	10907.87
Apr 11 17:00:00 - 17:59:59	92317	3.7%	25.64	33.5 GB	4.0%	9764.37
Apr 11 18:00:00 - 18:59:59	97994	4.0%	27.22	34.1 GB	4.0%	9946.79
Apr 11 19:00:00 - 19:59:59	98473	4.0%	27.35	35.2 GB	4.2%	10240.00
Apr 11 20:00:00 - 20:59:59	95251	3.9%	26.46	35.0 GB	4.1%	10185.97
Apr 11 21:00:00 - 21:59:59	96697	3.9%	26.86	34.4 GB	4.1%	10011.76
Apr 11 22:00:00 - 22:59:59	107122	4.3%	29.76	35.7 GB	4.2%	10389.65
Apr 11 23:00:00 - 23:59:59	114859	4.7%	31.91	36.9 GB	4.4%	10754.89
Apr 12 00:00:00 - 00:59:59	87644	3.5%	24.35	29.3 GB	3.5%	8523.37
Apr 12 01:00:00 - 01:59:59	96278	3.9%	26.74	32.9 GB	3.9%	9583.21
Apr 12 02:00:00 - 02:59:59	105800	4.3%	29.39	35.1 GB	4.2%	10224.04
Apr 12 03:00:00 - 03:59:59	95027	3.8%	26.40	31.7 GB	3.8%	9243.11
Apr 12 04:00:00 - 04:15:03	26830	1.1%	29.71	8.7 GB	1.0%	10144.80
TOTAL: 24:00:00	2469935	100.0%	28.59	843.9 GB	100.0%	10242.24

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	91907	199	3076	1	88631
2	news.nask.pl	15118	53	310	8	14747
3	news.icm.edu.pl	73	0	1	0	72
TOTAL: 3		107098	252	3387	9	103450

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	143
it.tlc.cellulari.android	23
it.comp.os.win.windows10	22
comp.mobile.ipad	21
comp.mobile.android	18
comp.sys.raspberry-pi	14
it.comp.os.win.windows8	3
de.comm.software.mailreader	3
alt.sex.telephones	3
it.discussioni.energia	2
TOTAL: 10	252

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14205
Binary in non-binary group	308
EMP (phl)	122
Scoring filter (8)	56
Scoring filter (9)	33
Scoring filter (10)	22
HTML post	8
Excessively crossposted test article	6
Scoring filter (12)	4
Scoring filter (17)	2
Too many newsgroups	1
Scoring filter (11)	1
Scoring filter (18)	1
TOTAL: 13	14769

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	373
	news.nask.pl	213
	TOTAL: 2	586
TOTAL: 1		586

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	7516	809	3690	436	1256	30326	10%	23:59:58
2	poznan	15176	193	9670	13	0	5	1%	23:59:58
3	icm	33913	71	21324	6381	0	2	0%	23:59:58
4	nask	6813	7	4560	0	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	2215	0%	23:57:51
TOTAL: 5		63418	1080	39244	6830	1256	32548	1%	119:57:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	5.4 MB	1.2 GB	1.2 GB	14.5 KB/s	194.1 KB	23:59:58
2	panorama	2.2 MB	1.2 MB	3.4 MB	0.0 KB/s	2.8 KB	23:59:58
3	poznan	1.0 MB	199.7 KB	1.2 MB	0.0 KB/s	5.9 KB	23:59:58
4	nask	12.7 KB	0.0 KB	12.7 KB	0.0 KB/s	1.8 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		8.6 MB	1.2 GB	1.2 GB	2.9 KB/s	158.9 KB	119:57:43

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
TOTAL: 1	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	42	42.9 KB	20	0	00:00:29
2	radius2.p.lodz.pl	1	5	4.3 KB	3	1	00:10:14
3	pc-212-191-92-112.p.lodz.pl	2	3	2.4 KB	2	2	00:32:51
4	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 4		298	50	49.6 KB	25	10	00:48:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	679	42	42.9 KB	20	7	00:00:35
2	*.p.lodz.pl	3	8	6.7 KB	5	3	00:43:05
3	*.dyn.optonline.net	1	0	0.0 KB	0	0	00:00:28
4	*.centertel.pl	1	0	0.0 KB	0	0	00:04:12
TOTAL: 4		684	50	49.6 KB	25	10	00:48:21

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-191-92-112.p.lodz.pl	0.000	0.052	00:32:51
radius2.p.lodz.pl	0.000	0.028	00:10:14
public-gprs393833.centertel.pl	0.040	0.024	00:04:12
web.man.lodz.pl	1.656	4.708	00:00:29
ool-457809ae.dyn.optonline.net	0.176	0.780	00:00:28
mon.man.lodz.pl	1.052	2.816	00:00:04
david.man.lodz.pl	0.332	1.028	00:00:01
isvcs01.man.lodz.pl	0.052	0.084	00:00:00
TOTAL: 8	3.308	9.520	00:48:21

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ool-457809ae.dyn.optonline.net	1
public-gprs393833.centertel.pl	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-191-92-112.p.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	42	84.0%
2	lodz	8	16.0%
TOTAL: 2		50	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	30
2	pl.comp.pecet	12
3	lodz.test	8
TOTAL: 3		50

Daily Usenet report

Apr 11 04:15:03 -- Apr 12 04:15:03