Daily Usenet report: Aug 1 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Aug 1 09:50:19 newsfeed nnrpd[21210]: SERVER perl filter not defined
Aug 1 09:51:49 newsfeed nnrpd[21245]: SERVER perl filter not defined
Aug 1 11:33:38 newsfeed nnrpd[24930]: SERVER perl filter not defined
Aug 1 12:02:32 newsfeed nnrpd[26051]: SERVER perl filter not defined
Aug 1 15:13:09 newsfeed nnrpd[929]: SERVER perl filter not defined
Aug 1 21:19:53 newsfeed nnrpd[14566]: SERVER perl filter not defined
Aug 2 00:32:41 newsfeed nnrpd[21552]: SERVER perl filter not defined
Aug 2 00:34:05 newsfeed nnrpd[21574]: SERVER perl filter not defined
Aug 2 01:23:40 newsfeed nnrpd[23393]: SERVER perl filter not defined
Aug 2 01:36:08 newsfeed nnrpd[23810]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2832216	97.6%	333.7 MB	97.4%
innfeed	40054	1.4%	4.7 MB	1.4%
innd	23755	0.8%	3.7 MB	1.1%
nnrpd	5571	0.2%	606.4 KB	0.2%
overchan	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2901598	100.0%	342.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2809235	96.8%
Negative hits	80710	2.8%
Positive hits	12600	0.4%
Cache misses	131	0.0%
TOTAL: 4	2902676	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.278	0.0%	200	0.000	21.390	932.000
article cleanup	00:00:43.032	0.0%	2831551	0.012	0.015	0.020
article logging	00:01:51.822	0.1%	2832073	0.029	0.039	0.124
article parse	01:16:11.273	5.3%	174725525	0.014	0.026	0.043
article write	00:23:06.469	1.6%	2824036	0.390	0.491	0.780
artlog/artcncl	00:00:00.005	0.0%	199	0.000	0.025	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:13.980	0.2%	192419301	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.419	0.0%	196	0.000	7.240	636.000
hishave/artcncl	00:00:00.089	0.0%	200	0.000	0.445	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:49.825	2.0%	2923657	0.026	0.592	4.688
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	01:31:07.988	6.3%	2827910	0.146	1.934	8.944
hiswrite/artcncl	00:00:00.001	0.0%	4	0.000	0.250	1.000
idle	11:45:33.879	49.0%	117977257	0.224	0.359	3.174
nntp read	01:28:02.574	6.1%	172467528	0.015	0.031	0.043
overview write	00:00:16.538	0.0%	2824036	0.004	0.006	0.008
perl filter	06:05:06.280	25.4%	2827907	6.867	7.746	9.078
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:58.325	0.3%	5669379	0.042	0.053	0.069
TOTAL: 24:00:03.404	23:09:07.790	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.188	0.0%	61865	0.000	0.003	0.011
article prepare	00:00:00.002	0.0%	9260	0.000	0.000	0.017
article read	00:01:33.448	0.1%	2315	0.000	40.366	389.000
backlog stats	00:00:00.164	0.0%	177371	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	6	0.000	0.000	0.000
data read	00:00:02.444	0.0%	95019	0.000	0.026	0.052
data write	00:00:03.717	0.0%	74135	0.000	0.050	0.184
idle	23:52:28.932	99.9%	177371	280.404	484.572	10027.933
status file	00:00:12.640	0.0%	13793	0.556	0.916	1.267
TOTAL: 23:54:37.602	23:54:21.535	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:06.405	94.7%	3420	0.000	598.364	228369.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.010	0.1%	7664	0.000	0.262	27.523
readart	00:00:00.203	0.0%	85	0.000	2.388	29.250
TOTAL: 00:36:01.073	00:34:08.618	94.8%	-	-	-	-

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	1237	2823574	2814180	5513	3881	99%	427:52:50
2	news.nask.pl	4	16412	9629	3185	3598	58%	25:36:53
3	news.icm.edu.pl	44	277	26	247	4	9%	18:36:39
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1296	2840273	2823845	8945	7483	99%	472:06:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	867.6 GB	15.6 MB	74.6 MB	867.7 GB	99%	322.9 KB
2	news.nask.pl	37.7 MB	629.3 KB	17.8 MB	56.2 MB	67%	4.3 KB
3	news.icm.edu.pl	633.7 KB	0.0 KB	96.2 KB	729.8 KB	86%	24.3 KB
4	localhost	16.9 KB	0.0 KB	0.0 KB	16.9 KB	100%	1.7 KB
TOTAL: 4		867.6 GB	16.2 MB	92.5 MB	867.7 GB	99%	321.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 1 04:15:02 - 04:59:59	91235	3.2%	33.82	28.9 GB	3.3%	11247.46
Aug 1 05:00:00 - 05:59:59	112533	4.0%	31.26	35.7 GB	4.1%	10399.57
Aug 1 06:00:00 - 06:59:59	117421	4.2%	32.62	37.2 GB	4.3%	10826.28
Aug 1 07:00:00 - 07:59:59	114477	4.1%	31.80	33.2 GB	3.8%	9677.00
Aug 1 08:00:00 - 08:59:59	114817	4.1%	31.89	35.5 GB	4.1%	10345.62
Aug 1 09:00:00 - 09:59:59	117300	4.2%	32.58	36.0 GB	4.2%	10484.23
Aug 1 10:00:00 - 10:59:59	130193	4.6%	36.16	39.0 GB	4.5%	11373.24
Aug 1 11:00:00 - 11:59:59	127934	4.5%	35.54	41.4 GB	4.8%	12055.36
Aug 1 12:00:00 - 12:59:59	126790	4.5%	35.22	40.1 GB	4.6%	11683.07
Aug 1 13:00:00 - 13:59:59	121780	4.3%	33.83	39.0 GB	4.5%	11346.68
Aug 1 14:00:00 - 14:59:59	110750	3.9%	30.76	33.9 GB	3.9%	9879.01
Aug 1 15:00:00 - 15:59:59	113365	4.0%	31.49	35.3 GB	4.1%	10293.98
Aug 1 16:00:00 - 16:59:59	118875	4.2%	33.02	37.4 GB	4.3%	10886.13
Aug 1 17:00:00 - 17:59:59	112775	4.0%	31.33	35.0 GB	4.0%	10185.60
Aug 1 18:00:00 - 18:59:59	125370	4.4%	34.83	38.1 GB	4.4%	11085.70
Aug 1 19:00:00 - 19:59:59	124515	4.4%	34.59	38.3 GB	4.4%	11165.11
Aug 1 20:00:00 - 20:59:59	123719	4.4%	34.37	37.8 GB	4.4%	11022.37
Aug 1 21:00:00 - 21:59:59	98399	3.5%	27.33	29.6 GB	3.4%	8629.63
Aug 1 22:00:00 - 22:59:59	127449	4.5%	35.40	37.4 GB	4.3%	10881.24
Aug 1 23:00:00 - 23:59:59	127503	4.5%	35.42	37.0 GB	4.3%	10783.21
Aug 2 00:00:00 - 00:59:59	99808	3.5%	27.72	30.3 GB	3.5%	8817.42
Aug 2 01:00:00 - 01:59:59	117028	4.1%	32.51	37.0 GB	4.3%	10789.23
Aug 2 02:00:00 - 02:59:59	115525	4.1%	32.09	35.0 GB	4.0%	10183.96
Aug 2 03:00:00 - 03:59:59	107531	3.8%	29.87	30.3 GB	3.5%	8814.46
Aug 2 04:00:00 - 04:15:03	26880	1.0%	29.77	8.0 GB	0.9%	9283.34
TOTAL: 24:00:01	2823972	100.0%	32.68	866.4 GB	100.0%	10515.13

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4222	154	3408	2	658
2	news.nask.pl	3819	27	238	5	3549
3	news.icm.edu.pl	4	0	0	0	4
TOTAL: 3		8045	181	3646	7	4211

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	113
comp.mobile.android	32
it.tlc.cellulari.android	14
it.comp.os.win.windows10	5
comp.sys.raspberry-pi	4
uk.rec.cycling.moderated	4
comp.mobile.ipad	4
it.comp.os.linux.ubuntu	1
it.comp.os.win.windows7	1
alt.sex.telephones	1
de.comm.software.mailreader	1
it.politica.m5s	1
TOTAL: 12	181

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3465
Binary in non-binary group	129
Scoring filter (8)	24
EMP (phl)	23
Scoring filter (9)	19
HTML post	15
Scoring filter (10)	5
EMP (fsl)	2
Scoring filter (11)	2
Scoring filter (12)	1
Too many newsgroups (low_xpost)	1
Angle-bracket bot	1
TOTAL: 12	3687

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	341
	news.nask.pl	180
	TOTAL: 2	521
TOTAL: 1		521

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44384	761	20363	340	22	40509	1%	23:59:59
2	poznan	15176	395	9215	32	0	0	2%	23:55:10
3	icm	28403	83	20454	125	0	0	0%	23:55:12
4	nask	6651	11	4476	0	0	0	0%	23:54:05
5	okapi	0	0	0	0	0	2327	0%	23:57:51
TOTAL: 5		94614	1250	54508	497	22	42836	1%	119:42:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.3 MB	44.2 MB	46.5 MB	0.6 KB/s	229.1 KB	23:55:12
2	panorama	1.9 MB	1.1 MB	3.0 MB	0.0 KB/s	2.8 KB	23:59:59
3	poznan	1.6 MB	85.5 KB	1.7 MB	0.0 KB/s	4.1 KB	23:55:10
4	nask	18.2 KB	0.0 KB	18.2 KB	0.0 KB/s	1.7 KB	23:54:05
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		5.9 MB	45.4 MB	51.3 MB	0.1 KB/s	30.1 KB	119:42:17

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	286	76	68.2 KB	31	0	0	00:00:36
2	user202-99.satfilm.com.pl	3	9	11.9 KB	5	0	1	00:33:35
3	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	0	00:00:03
TOTAL: 3		299	85	80.0 KB	36	10	1	00:36:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	676	76	68.2 KB	31	10	0	00:00:45
2	*.satfilm.com.pl	3	9	11.9 KB	5	0	1	00:33:35
3	*.snarked.org	2	0	0.0 KB	0	0	0	00:00:02
4	*.census.shodan.io	1	0	0.0 KB	0	0	0	00:00:03
5	*.146.060.pools.vodafone-ip.de	176	0	0.0 KB	0	0	0	00:00:12
6	unresolved	2	0	0.0 KB	0	0	0	00:01:20
TOTAL: 6		860	85	80.0 KB	36	10	1	00:36:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user202-99.satfilm.com.pl	0.100	0.132	00:33:35
212.191.2.11	0.012	0.016	00:01:20
web.man.lodz.pl	1.404	4.448	00:00:36
dslb-146-060-210-027.146.060.pools.vodafone-ip.de	0.684	2.736	00:00:12
mon.man.lodz.pl	1.268	4.592	00:00:04
ninja.census.shodan.io	0.004	0.004	00:00:03
isvcs01.man.lodz.pl	0.072	0.172	00:00:03
news.snarked.org	0.048	0.052	00:00:02
david.man.lodz.pl	0.400	1.584	00:00:01
TOTAL: 9	3.992	13.736	00:36:00

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	285
dslb-146-060-210-027.146.060.pools.vodafone-ip.de	176
david.man.lodz.pl	95
212.191.2.11	2
news.snarked.org	2
ninja.census.shodan.io	1
TOTAL: 6	561

NNRP client timeouts [Top 20]:

System	Conn	Peer
user202-99.satfilm.com.pl	1	0
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	76	89.4%
2	lodzman	4	4.7%
3	lodman	3	3.5%
4	lodz	2	2.4%
TOTAL: 4		85	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	68
2	pl.comp.pecet	8
3	lodzman.test	4
4	lodz.ogloszenia	2
5	lodman.info	2
6	lodman.test	1
TOTAL: 6		85

Daily Usenet report

Aug 1 04:15:02 -- Aug 2 04:15:03