Daily Usenet report: Aug 17 04:15:04

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Aug 17 08:06:55 newsfeed nnrpd[28617]: SERVER perl filter not defined
Aug 17 09:29:02 newsfeed nnrpd[31687]: SERVER perl filter not defined
Aug 17 10:22:07 newsfeed nnrpd[1201]: SERVER perl filter not defined
Aug 17 11:51:40 newsfeed nnrpd[5309]: SERVER perl filter not defined
Aug 17 13:10:05 newsfeed pgpverify[8286]: pgpverify: verification failed
Aug 17 14:50:05 newsfeed overchan[19320]: input line too long, skipping
Aug 17 14:50:34 newsfeed overchan[19320]: input line too long, skipping
Aug 17 20:08:12 newsfeed overchan[19320]: input line too long, skipping
Aug 17 20:09:50 newsfeed overchan[19320]: input line too long, skipping
Aug 17 20:13:43 newsfeed overchan[19320]: input line too long, skipping
Aug 17 20:29:30 newsfeed nnrpd[24631]: SERVER perl filter not defined
Aug 17 23:58:19 newsfeed nnrpd[32413]: SERVER perl filter not defined
Aug 18 00:25:20 newsfeed nnrpd[922]: SERVER perl filter not defined
Aug 18 03:11:15 newsfeed overchan[19320]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2201943	96.9%	255.9 MB	96.6%
innfeed	45777	2.0%	5.4 MB	2.0%
innd	19076	0.8%	3.0 MB	1.2%
nnrpd	4802	0.2%	506.9 KB	0.2%
overchan	7	0.0%	1.1 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2271610	100.0%	264.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2189311	95.3%
Negative hits	93890	4.1%
Positive hits	13065	0.6%
Cache misses	639	0.0%
TOTAL: 4	2296905	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.264	0.0%	260	0.000	16.400	954.000
article cleanup	00:00:31.173	0.0%	2201718	0.011	0.014	0.020
article logging	00:01:22.988	0.1%	2202223	0.029	0.038	0.142
article parse	01:01:17.492	4.3%	181165280	0.013	0.020	0.030
article write	00:19:41.178	1.4%	2195783	0.404	0.538	0.976
artlog/artcncl	00:00:00.005	0.0%	251	0.000	0.020	0.250
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:04:35.727	0.3%	195927234	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.085	0.0%	236	0.000	8.835	501.000
hishave/artcncl	00:00:00.626	0.0%	260	0.000	2.408	452.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:43:56.818	3.1%	2308505	0.135	1.142	5.432
history sync	00:00:00.015	0.0%	290	0.000	0.052	0.500
history write	01:42:34.955	7.1%	2199336	0.242	2.799	8.567
hiswrite/artcncl	00:00:00.040	0.0%	24	0.000	1.667	9.000
idle	13:12:27.015	55.0%	135185098	0.205	0.352	0.835
nntp read	01:28:42.560	6.2%	179508553	0.017	0.030	0.048
overview write	00:00:11.962	0.0%	2195783	0.004	0.005	0.008
perl filter	04:40:14.042	19.5%	2199306	6.217	7.645	8.661
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:31.362	0.2%	4414287	0.038	0.048	0.067
TOTAL: 24:00:04.284	23:19:14.307	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.296	0.0%	124110	0.000	0.002	0.013
article prepare	00:00:00.012	0.0%	61610	0.000	0.000	0.050
article read	00:01:39.583	0.1%	15456	0.000	6.443	411.500
backlog stats	00:00:00.203	0.0%	258287	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	46	0.000	0.000	0.000
data read	00:00:02.470	0.0%	139200	0.007	0.018	0.050
data write	00:00:04.263	0.0%	116820	0.018	0.036	0.081
idle	23:51:52.274	99.8%	258287	8.399	332.623	953.481
status file	00:00:13.866	0.0%	15756	0.000	0.880	35.250
TOTAL: 23:54:07.470	23:53:52.967	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.131	61.6%	3073	0.000	5.900	30.222
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.173	0.6%	6782	0.000	0.026	0.667
readart	00:00:00.000	0.0%	46	0.000	0.000	0.000
TOTAL: 00:00:29.430	00:00:18.304	62.2%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1004	2195547	2184096	9021	2430	99%	371:55:20
2	news.nask.pl	3	17064	11699	1864	3501	68%	24:18:03
3	news.icm.edu.pl	38	245	13	229	3	5%	16:27:29
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1053	2212863	2195815	11114	5934	99%	412:40:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	742.5 GB	16.0 MB	16.6 MB	742.5 GB	99%	356.1 KB
2	news.nask.pl	56.0 MB	407.3 KB	18.7 MB	75.2 MB	74%	5.1 KB
3	news.icm.edu.pl	389.1 KB	0.0 KB	204.5 KB	593.6 KB	65%	37.1 KB
4	localhost	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	1.3 KB
TOTAL: 4		742.6 GB	16.4 MB	35.6 MB	742.6 GB	99%	353.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 17 04:15:04 - 04:59:59	65968	3.0%	24.47	24.0 GB	3.2%	9328.73
Aug 17 05:00:00 - 05:59:59	81104	3.7%	22.53	29.0 GB	3.9%	8459.53
Aug 17 06:00:00 - 06:59:59	93769	4.3%	26.05	29.1 GB	3.9%	8473.19
Aug 17 07:00:00 - 07:59:59	91614	4.2%	25.45	32.7 GB	4.4%	9516.14
Aug 17 08:00:00 - 08:59:59	74029	3.4%	20.56	24.8 GB	3.3%	7217.87
Aug 17 09:00:00 - 09:59:59	64014	2.9%	17.78	19.8 GB	2.7%	5771.87
Aug 17 10:00:00 - 10:59:59	90359	4.1%	25.10	27.4 GB	3.7%	7975.07
Aug 17 11:00:00 - 11:59:59	124281	5.7%	34.52	40.2 GB	5.4%	11699.80
Aug 17 12:00:00 - 12:59:59	114854	5.2%	31.90	37.7 GB	5.1%	10986.46
Aug 17 13:00:00 - 13:59:59	114364	5.2%	31.77	37.9 GB	5.1%	11041.31
Aug 17 14:00:00 - 14:59:59	124991	5.7%	34.72	43.8 GB	5.9%	12770.49
Aug 17 15:00:00 - 15:59:59	132071	6.0%	36.69	44.9 GB	6.0%	13073.75
Aug 17 16:00:00 - 16:59:59	136937	6.2%	38.04	44.9 GB	6.0%	13071.33
Aug 17 17:00:00 - 17:59:59	126015	5.7%	35.00	43.3 GB	5.8%	12622.48
Aug 17 18:00:00 - 18:59:59	134519	6.1%	37.37	43.5 GB	5.9%	12673.24
Aug 17 19:00:00 - 19:59:59	122148	5.6%	33.93	40.9 GB	5.5%	11905.53
Aug 17 20:00:00 - 20:59:59	78907	3.6%	21.92	29.2 GB	3.9%	8514.56
Aug 17 21:00:00 - 21:59:59	58915	2.7%	16.37	22.0 GB	3.0%	6401.61
Aug 17 22:00:00 - 22:59:59	61850	2.8%	17.18	22.3 GB	3.0%	6496.92
Aug 17 23:00:00 - 23:59:59	66153	3.0%	18.38	22.2 GB	3.0%	6473.06
Aug 18 00:00:00 - 00:59:59	49982	2.3%	13.88	16.4 GB	2.2%	4771.29
Aug 18 01:00:00 - 01:59:59	61899	2.8%	17.19	21.4 GB	2.9%	6239.95
Aug 18 02:00:00 - 02:59:59	57865	2.6%	16.07	21.1 GB	2.8%	6155.77
Aug 18 03:00:00 - 03:59:59	55257	2.5%	15.35	18.7 GB	2.5%	5445.72
Aug 18 04:00:00 - 04:15:02	13384	0.6%	14.84	5.1 GB	0.7%	5887.52
TOTAL: 23:59:58	2195249	100.0%	25.41	742.3 GB	100.0%	9009.30

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3684	44	147	4	3489
2	newsfeed.pionier.net.pl	2752	107	2237	4	404
3	news.icm.edu.pl	7	0	0	0	7
TOTAL: 3		6443	151	2384	8	3900

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	72
comp.mobile.ipad	24
comp.mobile.android	17
it.comp.os.win.windows10	14
comp.sys.raspberry-pi	8
nl.comp.os.linux.techniek	7
uk.rec.cycling.moderated	3
it.tlc.cellulari.android	2
nl.comp.sys.android	2
de.comp.lang.vbclassic	1
it.comp.virtualizzazione	1
TOTAL: 11	151

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3101
Binary in non-binary group	143
Scoring filter (8)	26
Scoring filter (9)	23
Scoring filter (10)	19
EMP (phl)	16
HTML post	9
Too many newsgroups	8
Scoring filter (11)	7
Poison newsgroup	6
EMP (fsl)	2
Scoring filter (13)	2
Scoring filter (12)	1
Scoring filter (17)	1
Too many newsgroups (low_xpost)	1
TOTAL: 15	3365

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	322
	news.nask.pl	183
	TOTAL: 2	505
TOTAL: 1		505

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	105496	4585	65092	9500	173	44632	4%	23:59:56
2	poznan	18930	546	11039	35	0	0	2%	23:59:56
3	icm	28766	130	20994	30	0	0	0%	23:59:53
4	nask	4392	7	3208	0	0	0	0%	23:59:53
5	okapi	0	0	0	0	0	2566	0%	23:57:49
TOTAL: 5		157584	5268	100333	9565	173	47198	3%	119:57:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	15.8 MB	48.2 MB	64.0 MB	0.8 KB/s	4.7 KB	23:59:56
2	icm	7.9 MB	8.0 MB	15.9 MB	0.2 KB/s	101.5 KB	23:59:53
3	poznan	2.5 MB	207.6 KB	2.7 MB	0.0 KB/s	4.7 KB	23:59:56
4	nask	12.1 KB	0.0 KB	12.1 KB	0.0 KB/s	1.7 KB	23:59:53
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		26.1 MB	56.4 MB	82.5 MB	0.2 KB/s	5.7 KB	119:57:27

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	46	55.6 KB	21	0	00:00:22
2	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:01
TOTAL: 2		295	46	55.6 KB	21	7	00:00:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	679	46	55.6 KB	21	7	0	00:00:28
TOTAL: 1		679	46	55.6 KB	21	7	0	00:00:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.312	2.896	00:00:22
mon.man.lodz.pl	1.180	2.872	00:00:03
david.man.lodz.pl	0.420	0.868	00:00:01
isvcs01.man.lodz.pl	0.056	0.088	00:00:01
TOTAL: 4	2.968	6.724	00:00:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Aug 17 04:15:04 -- Aug 18 04:15:02