Daily Usenet report: Aug 10 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Aug 10 11:20:02 newsfeed nnrpd[28195]: SERVER perl filter not defined
Aug 10 11:21:14 newsfeed nnrpd[28226]: SERVER perl filter not defined
Aug 10 11:32:32 newsfeed nnrpd[28722]: SERVER perl filter not defined
Aug 10 11:33:36 newsfeed nnrpd[28724]: SERVER perl filter not defined
Aug 10 11:40:30 newsfeed nnrpd[28897]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2325589	98.4%	269.4 MB	98.1%
innd	27394	1.2%	4.0 MB	1.5%
innfeed	4897	0.2%	668.2 KB	0.2%
nnrpd	4820	0.2%	491.4 KB	0.2%
TOTAL: 4	2362700	100.0%	274.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2318425	95.5%
Negative hits	93179	3.8%
Positive hits	15753	0.6%
Cache misses	104	0.0%
TOTAL: 4	2427461	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.732	0.0%	197	0.000	8.792	494.000
article cleanup	00:00:35.253	0.0%	2323764	0.005	0.015	0.031
article logging	00:01:08.820	0.1%	2324359	0.015	0.030	0.937
article parse	01:07:08.577	4.7%	218398955	0.009	0.018	0.040
article write	00:35:54.829	2.5%	2310535	0.359	0.933	3.618
artlog/artcncl	00:00:00.006	0.0%	178	0.000	0.034	1.000
data move	00:03:03.506	0.2%	233968857	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.164	0.0%	188	0.000	0.872	15.500
hishave/artcncl	00:00:00.050	0.0%	197	0.000	0.254	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:38.599	1.7%	2428437	0.173	0.609	9.291
history sync	00:00:00.009	0.0%	291	0.000	0.031	1.000
history write	00:32:27.198	2.3%	2319488	0.264	0.839	10.232
hiswrite/artcncl	00:00:00.013	0.0%	9	0.000	1.444	10.000
idle	14:09:51.638	59.1%	169431666	0.219	0.301	0.548
nntp read	01:14:17.539	5.2%	216637083	0.013	0.021	0.059
overview write	00:00:13.038	0.0%	2310535	0.000	0.006	0.016
perl filter	04:55:41.069	20.5%	2319488	4.898	7.649	11.112
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:57.546	0.3%	4759104	0.034	0.050	0.099
TOTAL: 23:59:10.091	23:08:59.586	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.520	0.0%	178121	0.000	0.003	0.133
article prepare	00:00:00.095	0.0%	393310	0.000	0.000	0.003
article read	00:02:34.881	0.2%	98134	0.000	1.578	281.500
backlog stats	00:00:00.524	0.0%	644972	0.000	0.001	0.031
callbacks	00:00:00.001	0.0%	6	0.000	0.167	1.000
data read	00:00:03.781	0.0%	319497	0.000	0.012	0.083
data write	00:00:45.092	0.1%	329968	0.000	0.137	0.333
idle	23:57:01.245	99.7%	644972	17.510	133.682	2733.818
status file	00:00:00.820	0.0%	1195	0.000	0.686	3.000
TOTAL: 24:00:41.487	24:00:26.959	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:08:05.529	97.6%	7589	0.000	63.978	10048.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.363	0.1%	15826	0.000	0.023	1.000
readart	00:00:00.002	0.0%	28	0.000	0.071	0.500
TOTAL: 00:08:17.262	00:08:05.894	97.7%	-	-	-	-

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	932	2312256	2300168	7516	4572	99%	277:02:37
2	news.nask.pl	2	23432	11236	3547	8649	47%	24:06:34
3	news.icm.edu.pl	36	441	20	366	55	4%	18:00:00
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		976	2336134	2311429	11429	13276	98%	319:09:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	770.6 GB	133.6 MB	243.5 MB	771.0 GB	99%	350.8 KB
2	news.nask.pl	59.7 MB	1.7 MB	192.5 MB	254.0 MB	23%	13.1 KB
3	news.icm.edu.pl	714.6 KB	0.0 KB	803.7 KB	1.5 MB	47%	20.2 KB
4	localhost	11.7 KB	0.0 KB	0.0 KB	11.7 KB	100%	2.3 KB
TOTAL: 4		770.7 GB	135.3 MB	436.8 MB	771.3 GB	99%	347.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 10 04:15:02 - 04:59:59	68449	3.0%	25.37	23.2 GB	3.0%	8998.16
Aug 10 05:00:00 - 05:59:59	92373	4.0%	25.66	32.3 GB	4.2%	9396.83
Aug 10 06:00:00 - 06:59:59	108515	4.7%	30.14	37.5 GB	4.9%	10933.21
Aug 10 07:00:00 - 07:59:59	132339	5.7%	36.76	38.8 GB	5.0%	11299.71
Aug 10 08:00:00 - 08:59:59	127420	5.5%	35.39	39.0 GB	5.1%	11361.92
Aug 10 09:00:00 - 09:59:59	125039	5.4%	34.73	38.0 GB	4.9%	11081.98
Aug 10 10:00:00 - 10:59:59	107040	4.6%	29.73	38.4 GB	5.0%	11171.53
Aug 10 11:00:00 - 11:59:59	99453	4.3%	27.63	38.6 GB	5.0%	11252.11
Aug 10 12:00:00 - 12:59:59	104618	4.5%	29.06	38.3 GB	5.0%	11150.38
Aug 10 13:00:00 - 13:59:59	90429	3.9%	25.12	35.6 GB	4.6%	10379.32
Aug 10 14:00:00 - 14:59:59	45312	2.0%	12.59	19.8 GB	2.6%	5760.33
Aug 10 15:00:00 - 15:59:59	53510	2.3%	14.86	22.1 GB	2.9%	6443.53
Aug 10 16:00:00 - 16:59:59	63265	2.7%	17.57	23.1 GB	3.0%	6730.12
Aug 10 17:00:00 - 17:59:59	60834	2.6%	16.90	23.0 GB	3.0%	6689.45
Aug 10 18:00:00 - 18:59:59	84963	3.7%	23.60	27.8 GB	3.6%	8090.32
Aug 10 19:00:00 - 19:59:59	116553	5.0%	32.38	32.9 GB	4.3%	9577.49
Aug 10 20:00:00 - 20:59:59	109577	4.7%	30.44	33.2 GB	4.3%	9668.28
Aug 10 21:00:00 - 21:59:59	129713	5.6%	36.03	35.8 GB	4.6%	10417.30
Aug 10 22:00:00 - 22:59:59	111296	4.8%	30.92	36.6 GB	4.7%	10656.86
Aug 10 23:00:00 - 23:59:59	118136	5.1%	32.82	36.0 GB	4.7%	10497.57
Aug 11 00:00:00 - 00:59:59	89177	3.9%	24.77	28.1 GB	3.6%	8194.66
Aug 11 01:00:00 - 01:59:59	94025	4.1%	26.12	29.5 GB	3.8%	8605.49
Aug 11 02:00:00 - 02:59:59	86466	3.7%	24.02	29.7 GB	3.8%	8642.15
Aug 11 03:00:00 - 03:59:59	73961	3.2%	20.54	26.7 GB	3.5%	7777.36
Aug 11 04:00:00 - 04:15:02	19122	0.8%	21.20	6.9 GB	0.9%	8021.88
TOTAL: 24:00:00	2311585	100.0%	26.75	770.9 GB	100.0%	9355.48

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	8853	14	391	2	8446
2	newsfeed.pionier.net.pl	4916	163	3885	2	866
3	news.icm.edu.pl	57	0	0	0	57
TOTAL: 3		13826	177	4276	4	9369

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	108
it.comp.os.win.windows10	31
it.tlc.cellulari.android	9
comp.mobile.android	8
comp.mobile.ipad	4
it.comp.os.win.windows7	4
hr.alt.cellular.gsm.android	3
it.comp.os.linux.ubuntu	2
uk.rec.cycling.moderated	2
de.comp.lang.vbclassic	1
hr.rec.sport.rukomet	1
rec.arts.tv.x-factor	1
it.comp.os.win.windows8	1
de.rec.sf.startrek	1
alt.sex.telephones	1
TOTAL: 15	177

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

Groups	Count
pl.sci.historia	3
pl.praca.oferowana	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7456
Binary in non-binary group	1055
EMP (phl)	128
Scoring filter (8)	67
Scoring filter (9)	25
Scoring filter (10)	15
Excessively crossposted test article	8
Scoring filter (11)	7
Scoring filter (13)	4
EMP (fsl)	3
Scoring filter (12)	3
PFM bot misc	1
Angle-bracket bot	1
Scoring filter (14)	1
TOTAL: 14	8774

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	121729	78694	20136	17256	4	15681	64%	24:00:15
2	panorama	41765	1482	37737	639	15	122651	3%	23:58:00
3	poznan	17206	85	10999	35	0	0	0%	23:55:39
4	nask	6826	30	4478	4	0	0	0%	23:57:34
5	okapi	0	0	0	0	0	2360	0%	24:00:00
TOTAL: 5		187526	80291	73350	17934	19	140692	42%	119:51:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.1 GB	3.6 GB	17.7 GB	214.7 KB/s	193.4 KB	24:00:15
2	panorama	8.6 MB	6.1 MB	14.7 MB	0.2 KB/s	7.1 KB	23:58:00
3	poznan	369.4 KB	91.6 KB	461.1 KB	0.0 KB/s	3.8 KB	23:55:39
4	nask	283.8 KB	1.3 MB	1.6 MB	0.0 KB/s	46.8 KB	23:57:34
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		14.1 GB	3.6 GB	17.7 GB	43.0 KB/s	189.1 KB	119:51:28

Backlog files shrunk by innfeed:

Server	Size
panorama	10.5 MB
TOTAL: 1	10.5 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	36.0 KB	12	0	00:00:20
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	28	36.0 KB	12	5	00:08:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	28	36.0 KB	12	5	00:00:24
2	*.shodan.io	1	0	0.0 KB	0	0	00:00:03
3	unresolved	9	0	0.0 KB	0	0	00:07:48
TOTAL: 3		687	28	36.0 KB	12	5	00:08:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.194.66	0.262	0.353	00:07:46
web.man.lodz.pl	1.006	5.355	00:00:20
census9.shodan.io	0.000	0.020	00:00:03
mon.man.lodz.pl	0.854	4.447	00:00:03
207.244.75.23	0.012	0.110	00:00:01
david.man.lodz.pl	0.281	1.488	00:00:01
isvcs01.man.lodz.pl	0.032	0.092	00:00:00
104.200.154.91	0.009	0.013	00:00:00
TOTAL: 8	2.456	11.878	00:08:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
207.244.75.23	6
185.48.194.66	2
104.200.154.91	1
census9.shodan.io	1
TOTAL: 6	394

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.194.66	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Aug 10 04:15:02 -- Aug 11 04:15:02