Daily Usenet report: Sep 28 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Sep 28 10:12:07 newsfeed nnrpd[17727]: SERVER perl filter not defined
Sep 28 10:16:12 newsfeed nnrpd[17828]: SERVER perl filter not defined
Sep 28 10:19:08 newsfeed nnrpd[17852]: SERVER perl filter not defined
Sep 28 10:31:06 newsfeed nnrpd[18383]: SERVER perl filter not defined
Sep 28 11:10:07 newsfeed nnrpd[19743]: SERVER perl filter not defined
Sep 28 11:21:13 newsfeed nnrpd[20163]: SERVER perl filter not defined
Sep 28 12:15:13 newsfeed nnrpd[22095]: SERVER perl filter not defined
Sep 28 14:37:13 newsfeed nnrpd[26908]: SERVER perl filter not defined
Sep 28 15:34:17 newsfeed nnrpd[28885]: SERVER perl filter not defined
Sep 28 16:19:07 newsfeed nnrpd[30365]: SERVER perl filter not defined
Sep 28 19:36:11 newsfeed nnrpd[4785]: SERVER perl filter not defined
Sep 28 20:17:48 newsfeed nnrpd[6173]: SERVER perl filter not defined
Sep 28 21:20:03 newsfeed nnrpd[8307]: SERVER perl filter not defined
Sep 28 22:17:42 newsfeed nnrpd[10300]: SERVER perl filter not defined
Sep 28 22:18:17 newsfeed nnrpd[10301]: SERVER perl filter not defined
Sep 28 22:26:41 newsfeed nnrpd[10641]: SERVER perl filter not defined
Sep 28 22:36:05 newsfeed nnrpd[10981]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2014636	98.1%	235.6 MB	97.8%
innd	30234	1.5%	4.1 MB	1.7%
nnrpd	4945	0.2%	516.5 KB	0.2%
innfeed	4645	0.2%	629.0 KB	0.3%
TOTAL: 4	2054460	100.0%	240.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2008129	95.2%
Negative hits	84712	4.0%
Positive hits	16529	0.8%
Cache misses	101	0.0%
TOTAL: 4	2109471	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.947	0.0%	157	0.000	31.510	299.000
article cleanup	00:00:31.068	0.0%	2013540	0.004	0.015	0.033
article logging	00:01:06.245	0.1%	2014216	0.013	0.033	1.329
article parse	00:53:26.779	3.7%	174707811	0.009	0.018	0.056
article write	00:20:29.737	1.4%	1995920	0.312	0.616	3.592
artlog/artcncl	00:00:00.004	0.0%	155	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:06.254	0.1%	187767298	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.430	0.0%	143	0.000	10.000	44.172
hishave/artcncl	00:00:00.454	0.0%	157	0.000	2.892	273.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:31:34.210	2.2%	2109768	0.141	0.898	12.380
history sync	00:00:00.017	0.0%	293	0.000	0.058	1.000
history write	00:39:47.474	2.8%	2008513	0.269	1.189	16.291
hiswrite/artcncl	00:00:00.095	0.0%	14	0.000	6.786	38.000
idle	15:30:47.853	64.6%	143060020	0.234	0.390	0.888
nntp read	00:58:34.709	4.1%	173241679	0.012	0.020	0.046
overview write	00:00:11.335	0.0%	1995920	0.000	0.006	0.024
perl filter	04:06:48.204	17.1%	2008513	5.179	7.373	10.689
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:41.342	0.3%	4029095	0.034	0.055	0.105
TOTAL: 24:00:29.489	23:09:12.157	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.266	0.0%	122997	0.000	0.002	0.143
article prepare	00:00:00.026	0.0%	134456	0.000	0.000	0.036
article read	00:01:20.236	0.1%	33585	0.000	2.389	80.000
backlog stats	00:00:00.244	0.0%	356156	0.000	0.001	0.029
callbacks	00:00:00.000	0.0%	85	0.000	0.000	0.000
data read	00:00:02.253	0.0%	185414	0.000	0.012	0.131
data write	00:00:11.310	0.0%	170113	0.000	0.066	0.409
idle	23:57:22.794	99.9%	356156	4.511	242.149	3686.722
status file	00:00:00.941	0.0%	1250	0.000	0.753	2.000
TOTAL: 23:59:05.711	23:58:58.070	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:12:28.104	99.8%	32319	0.000	245.927	430508.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.277	0.0%	65259	0.000	0.020	0.500
readart	00:00:00.069	0.0%	94	0.000	0.734	4.000
TOTAL: 02:12:41.676	02:12:29.450	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	1115	1996268	1985806	5607	4855	99%	276:38:53
2	news.nask.pl	2	28193	9907	5575	12711	35%	24:19:48
3	news.icm.edu.pl	41	399	38	354	7	9%	17:56:23
4	localhost	18	17	17	0	0	100%	00:00:00
TOTAL: 4		1176	2024877	1995768	11536	17573	98%	318:55:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	611.4 GB	108.3 MB	158.0 MB	611.6 GB	99%	322.2 KB
2	news.nask.pl	76.3 MB	3.4 MB	204.8 MB	284.5 MB	26%	12.9 KB
3	news.icm.edu.pl	2.1 MB	0.0 KB	141.0 KB	2.2 MB	93%	50.5 KB
4	localhost	28.1 KB	0.0 KB	0.0 KB	28.1 KB	100%	1.7 KB
TOTAL: 4		611.4 GB	111.7 MB	362.9 MB	611.9 GB	99%	318.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 28 04:15:02 - 04:59:59	21996	1.1%	8.15	7.9 GB	1.3%	3052.80
Sep 28 05:00:00 - 05:59:59	32074	1.6%	8.91	11.2 GB	1.8%	3249.27
Sep 28 06:00:00 - 06:59:59	53634	2.7%	14.90	18.0 GB	2.9%	5231.81
Sep 28 07:00:00 - 07:59:59	50548	2.5%	14.04	18.1 GB	3.0%	5263.52
Sep 28 08:00:00 - 08:59:59	56269	2.8%	15.63	20.2 GB	3.3%	5894.70
Sep 28 09:00:00 - 09:59:59	67328	3.4%	18.70	22.4 GB	3.7%	6524.43
Sep 28 10:00:00 - 10:59:59	51725	2.6%	14.37	17.5 GB	2.9%	5088.20
Sep 28 11:00:00 - 11:59:59	62439	3.1%	17.34	21.2 GB	3.5%	6181.89
Sep 28 12:00:00 - 12:59:59	72017	3.6%	20.00	24.5 GB	4.0%	7138.97
Sep 28 13:00:00 - 13:59:59	65160	3.3%	18.10	22.4 GB	3.7%	6517.75
Sep 28 14:00:00 - 14:59:59	78346	3.9%	21.76	25.9 GB	4.2%	7546.21
Sep 28 15:00:00 - 15:59:59	78904	4.0%	21.92	26.2 GB	4.3%	7616.79
Sep 28 16:00:00 - 16:59:59	81918	4.1%	22.75	27.0 GB	4.4%	7860.18
Sep 28 17:00:00 - 17:59:59	85600	4.3%	23.78	28.0 GB	4.6%	8158.49
Sep 28 18:00:00 - 18:59:59	95803	4.8%	26.61	29.5 GB	4.8%	8602.54
Sep 28 19:00:00 - 19:59:59	92672	4.6%	25.74	29.0 GB	4.7%	8452.13
Sep 28 20:00:00 - 20:59:59	132155	6.6%	36.71	32.4 GB	5.3%	9445.51
Sep 28 21:00:00 - 21:59:59	129367	6.5%	35.94	31.4 GB	5.1%	9160.29
Sep 28 22:00:00 - 22:59:59	112409	5.6%	31.22	30.5 GB	5.0%	8889.25
Sep 28 23:00:00 - 23:59:59	95760	4.8%	26.60	28.7 GB	4.7%	8362.62
Sep 29 00:00:00 - 00:59:59	95769	4.8%	26.60	28.0 GB	4.6%	8149.52
Sep 29 01:00:00 - 01:59:59	129083	6.5%	35.86	35.7 GB	5.8%	10401.55
Sep 29 02:00:00 - 02:59:59	113470	5.7%	31.52	34.4 GB	5.6%	10010.21
Sep 29 03:00:00 - 03:59:59	111489	5.6%	30.97	32.3 GB	5.3%	9409.17
Sep 29 04:00:00 - 04:15:02	30252	1.5%	33.54	8.7 GB	1.4%	10118.72
TOTAL: 24:00:00	1996187	100.0%	23.10	611.0 GB	100.0%	7415.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12986	10	704	2	12270
2	newsfeed.pionier.net.pl	5289	192	4321	2	774
3	news.icm.edu.pl	19	0	0	0	19
TOTAL: 3		18294	202	5025	4	13063

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	112
it.comp.os.win.windows10	50
it.tlc.cellulari.android	16
comp.mobile.android	11
hr.alt.cellular.gsm.android	5
de.comp.office-pakete.ms-office.word	3
hr.ponuda.hardver.komponente	2
it.comp.os.win.windows7	2
comp.mobile.ipad	1
TOTAL: 9	202

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11398
Binary in non-binary group	724
Scoring filter (8)	92
EMP (phl)	78
Scoring filter (9)	41
Scoring filter (10)	15
HTML post	14
Scoring filter (11)	10
Scoring filter (13)	4
Scoring filter (20)	4
Scoring filter (18)	2
EMP (fsl)	1
Scoring filter (17)	1
Scoring filter (14)	1
Scoring filter (12)	1
Scoring filter (19)	1
TOTAL: 16	12387

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	98532	15300	74773	6238	188	26355	15%	23:53:14
2	icm	46727	483	24437	11044	0	0	1%	23:59:04
3	poznan	14691	108	9599	28	0	0	0%	23:57:18
4	nask	10969	30	7177	1	0	0	0%	23:58:27
5	okapi	0	0	0	0	0	2159	0%	24:00:03
TOTAL: 5		170919	15921	115986	17311	188	28514	9%	119:48:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	64.3 MB	36.5 MB	100.8 MB	1.2 KB/s	4.8 KB	23:53:14
2	icm	3.2 MB	2.5 GB	2.5 GB	30.1 KB/s	225.8 KB	23:59:04
3	poznan	1.1 MB	95.1 KB	1.1 MB	0.0 KB/s	8.6 KB	23:57:18
4	nask	268.9 KB	1.8 KB	270.7 KB	0.0 KB/s	8.7 KB	23:58:27
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		68.9 MB	2.5 GB	2.6 GB	6.3 KB/s	81.5 KB	119:48:06

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	74	67.0 KB	34	0	00:00:20
2	adme253.neoplus.adsl.tpnet.pl	3	1	2.0 KB	1	0	00:21:37
3	isvcs01.man.lodz.pl	17	0	0.0 KB	0	17	00:00:01
TOTAL: 3		308	75	69.0 KB	35	17	02:12:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	689	74	67.0 KB	34	17	00:00:25
2	*.neoplus.adsl.tpnet.pl	3	1	2.0 KB	1	0	00:21:37
3	unresolved	3	0	0.0 KB	0	0	01:50:37
TOTAL: 3		695	75	69.0 KB	35	17	02:12:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.142	1.705	2.094	01:50:35
adme253.neoplus.adsl.tpnet.pl	0.013	0.052	00:21:37
web.man.lodz.pl	0.889	5.189	00:00:20
mon.man.lodz.pl	1.033	4.492	00:00:03
196.52.18.50	0.000	0.012	00:00:02
isvcs01.man.lodz.pl	0.088	0.291	00:00:01
david.man.lodz.pl	0.250	1.528	00:00:01
TOTAL: 7	3.978	13.658	02:12:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.142	2
196.52.18.50	1
TOTAL: 4	387

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	74	98.7%
2	lodz	1	1.3%
TOTAL: 2		75	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	40
2	pl.comp.os.ms-windows.winnt	28
3	pl.internet.polip	6
4	lodz.ogloszenia	1
TOTAL: 4		75

Daily Usenet report

Sep 28 04:15:02 -- Sep 29 04:15:02