Daily Usenet report: Aug 25 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Aug 25 06:05:21 newsfeed nnrpd[25638]: SERVER perl filter not defined
Aug 25 12:44:51 newsfeed nnrpd[7314]: SERVER perl filter not defined
Aug 25 12:45:49 newsfeed nnrpd[7372]: SERVER perl filter not defined
Aug 25 17:30:40 newsfeed nnrpd[17087]: SERVER perl filter not defined
Aug 26 01:08:15 newsfeed nnrpd[555]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2509948	98.5%	293.3 MB	98.2%
innd	27489	1.1%	4.1 MB	1.4%
nnrpd	4892	0.2%	496.3 KB	0.2%
innfeed	4835	0.2%	676.7 KB	0.2%
TOTAL: 4	2547164	100.0%	298.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2505118	96.3%
Negative hits	81125	3.1%
Positive hits	14673	0.6%
Cache misses	126	0.0%
TOTAL: 4	2601042	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.506	0.0%	196	0.000	7.684	126.000
article cleanup	00:00:38.285	0.0%	2509483	0.006	0.015	0.027
article logging	00:01:09.106	0.1%	2510075	0.013	0.028	0.405
article parse	01:19:00.776	5.5%	224005172	0.010	0.021	0.053
article write	00:26:12.501	1.8%	2498048	0.411	0.629	5.293
artlog/artcncl	00:00:00.005	0.0%	184	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:58.808	0.2%	240821623	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.289	0.0%	178	0.000	1.624	21.429
hishave/artcncl	00:00:00.043	0.0%	196	0.000	0.219	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:15.233	1.5%	2601674	0.093	0.513	8.819
history sync	00:00:00.020	0.0%	290	0.000	0.069	3.000
history write	00:32:10.295	2.2%	2505863	0.180	0.770	9.635
hiswrite/artcncl	00:00:00.033	0.0%	18	0.000	1.833	15.000
idle	13:34:09.762	56.5%	165928994	0.216	0.294	0.548
nntp read	01:12:34.654	5.0%	222049552	0.012	0.020	0.038
overview write	00:00:14.030	0.0%	2498048	0.000	0.006	0.013
perl filter	05:30:35.933	23.0%	2505862	5.798	7.916	11.072
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:28.891	0.3%	5038383	0.040	0.053	0.117
TOTAL: 24:00:10.173	23:06:30.170	96.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.170	0.0%	53609	0.000	0.003	0.143
article prepare	00:00:00.022	0.0%	98716	0.000	0.000	0.062
article read	00:00:24.261	0.0%	24701	0.000	0.982	61.880
backlog stats	00:00:00.248	0.0%	276644	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	36	0.000	0.000	0.000
data read	00:00:01.745	0.0%	137969	0.000	0.013	0.159
data write	00:00:12.182	0.0%	136334	0.000	0.089	0.302
idle	23:59:01.321	99.9%	276644	21.201	312.103	2181.552
status file	00:00:00.831	0.0%	948	0.000	0.877	42.000
TOTAL: 23:59:48.433	23:59:40.780	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:04:59.299	99.6%	3259	0.000	1196.471	90894.571
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.171	0.0%	7478	0.000	0.023	0.667
readart	00:00:01.676	0.0%	199	0.000	8.422	25.233
TOTAL: 01:05:13.184	01:05:01.146	99.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1038	2498418	2486674	7356	4388	99%	274:34:32
2	news.nask.pl	2	21213	10960	3267	6986	51%	23:53:28
3	news.icm.edu.pl	33	335	9	311	15	2%	17:25:59
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1079	2519971	2497648	10934	11389	99%	315:53:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	833.8 GB	81.7 MB	299.8 MB	834.1 GB	99%	351.1 KB
2	news.nask.pl	44.4 MB	1.1 MB	133.6 MB	179.1 MB	24%	10.2 KB
3	news.icm.edu.pl	137.6 KB	8.5 KB	268.5 KB	414.7 KB	33%	17.3 KB
4	localhost	8.4 KB	0.0 KB	0.0 KB	8.4 KB	100%	1.7 KB
TOTAL: 4		833.8 GB	82.8 MB	433.7 MB	834.3 GB	99%	348.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 25 04:15:02 - 04:59:59	77336	3.1%	28.66	26.2 GB	3.1%	10175.75
Aug 25 05:00:00 - 05:59:59	79844	3.2%	22.18	26.6 GB	3.2%	7741.36
Aug 25 06:00:00 - 06:59:59	92128	3.7%	25.59	31.7 GB	3.8%	9236.95
Aug 25 07:00:00 - 07:59:59	102878	4.1%	28.58	34.5 GB	4.1%	10054.42
Aug 25 08:00:00 - 08:59:59	108791	4.4%	30.22	38.9 GB	4.7%	11334.69
Aug 25 09:00:00 - 09:59:59	129782	5.2%	36.05	40.2 GB	4.8%	11717.32
Aug 25 10:00:00 - 10:59:59	124095	5.0%	34.47	39.5 GB	4.7%	11497.54
Aug 25 11:00:00 - 11:59:59	118471	4.7%	32.91	37.0 GB	4.4%	10770.56
Aug 25 12:00:00 - 12:59:59	106226	4.3%	29.51	35.3 GB	4.2%	10296.37
Aug 25 13:00:00 - 13:59:59	102694	4.1%	28.53	34.5 GB	4.1%	10050.78
Aug 25 14:00:00 - 14:59:59	99309	4.0%	27.59	33.3 GB	4.0%	9699.75
Aug 25 15:00:00 - 15:59:59	103554	4.1%	28.77	37.3 GB	4.5%	10852.85
Aug 25 16:00:00 - 16:59:59	105124	4.2%	29.20	36.2 GB	4.3%	10547.51
Aug 25 17:00:00 - 17:59:59	104249	4.2%	28.96	36.7 GB	4.4%	10686.09
Aug 25 18:00:00 - 18:59:59	103024	4.1%	28.62	37.8 GB	4.5%	11016.99
Aug 25 19:00:00 - 19:59:59	105299	4.2%	29.25	34.5 GB	4.1%	10039.87
Aug 25 20:00:00 - 20:59:59	103367	4.1%	28.71	36.8 GB	4.4%	10723.31
Aug 25 21:00:00 - 21:59:59	124907	5.0%	34.70	39.1 GB	4.7%	11392.88
Aug 25 22:00:00 - 22:59:59	119265	4.8%	33.13	36.6 GB	4.4%	10663.80
Aug 25 23:00:00 - 23:59:59	127832	5.1%	35.51	37.7 GB	4.5%	10972.53
Aug 26 00:00:00 - 00:59:59	91410	3.7%	25.39	30.4 GB	3.6%	8844.38
Aug 26 01:00:00 - 01:59:59	89119	3.6%	24.76	29.3 GB	3.5%	8534.44
Aug 26 02:00:00 - 02:59:59	75407	3.0%	20.95	27.3 GB	3.3%	7961.30
Aug 26 03:00:00 - 03:59:59	82946	3.3%	23.04	29.1 GB	3.5%	8465.98
Aug 26 04:00:00 - 04:15:02	20683	0.8%	22.93	7.5 GB	0.9%	8740.23
TOTAL: 24:00:00	2497740	100.0%	28.91	834.0 GB	100.0%	10121.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	7241	42	274	1	6924
2	newsfeed.pionier.net.pl	4767	258	3346	1	1162
3	news.icm.edu.pl	17	0	1	0	16
TOTAL: 3		12025	300	3621	2	8102

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	153
it.comp.os.win.windows10	77
comp.sys.raspberry-pi	22
comp.mobile.android	17
it.tlc.cellulari.android	13
comp.mobile.ipad	7
hr.ponuda.hardver.komponente	2
de.rec.sf.startrek	2
uk.rec.cycling.moderated	2
nl.comp.3d-printen	1
it.comp.os.win.windows7	1
hr.alt.cellular.gsm.android	1
hr.alt.cellular.gsm.iphone	1
it.comp.os.win.windows8	1
TOTAL: 14	300

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6263
Binary in non-binary group	1003
EMP (phl)	126
Scoring filter (8)	60
Scoring filter (9)	21
Scoring filter (10)	12
Too many newsgroups	8
HTML post	3
Scoring filter (12)	3
Scoring filter (13)	2
Scoring filter (11)	2
Scoring filter (21)	2
Scoring filter (22)	1
Excessively crossposted test article	1
Scoring filter (25)	1
EMP (fsl)	1
TOTAL: 16	7509

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	14606	3194	8669	1194	74	40440	21%	23:57:17
2	icm	46971	387	21395	19323	0	1	0%	24:06:13
3	poznan	17265	287	10678	32	0	0	1%	23:58:56
4	nask	6628	20	4276	5	0	0	0%	23:59:34
5	okapi	0	0	0	0	0	2190	0%	24:00:02
TOTAL: 5		85470	3888	45018	20554	74	42631	4%	120:02:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	10.4 MB	5.2 MB	15.5 MB	0.2 KB/s	3.6 KB	23:57:17
2	icm	4.5 MB	4.2 GB	4.2 GB	51.2 KB/s	225.5 KB	24:06:13
3	poznan	1.4 MB	285.3 KB	1.7 MB	0.0 KB/s	5.5 KB	23:58:56
4	nask	62.5 KB	16.4 KB	78.9 KB	0.0 KB/s	3.2 KB	23:59:34
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		16.3 MB	4.2 GB	4.3 GB	10.3 KB/s	182.6 KB	120:02:02

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
okapi	488.4 KB
TOTAL: 2	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	7	111	217.9 KB	11	0	01:04:44
2	web.man.lodz.pl	288	88	93.1 KB	40	0	00:00:21
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 3		300	199	311.0 KB	51	5	01:05:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	7	111	217.9 KB	11	0	01:04:44
2	*.man.lodz.pl	677	88	93.1 KB	40	5	00:00:26
3	*.shodan.io	1	0	0.0 KB	0	0	00:00:00
4	unresolved	1	0	0.0 KB	0	0	00:00:01
TOTAL: 4		686	199	311.0 KB	51	5	01:05:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.042	0.113	01:04:44
web.man.lodz.pl	0.928	4.874	00:00:21
mon.man.lodz.pl	0.953	4.251	00:00:03
66.240.219.146	0.007	0.012	00:00:01
david.man.lodz.pl	0.300	1.335	00:00:01
isvcs01.man.lodz.pl	0.021	0.111	00:00:00
census7.shodan.io	0.006	0.011	00:00:00
TOTAL: 7	2.257	10.707	01:05:12

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
66.240.219.146	1
census7.shodan.io	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	60
2	pl.comp.os.ms-windows.winnt	58
3	pl.rec.kuchnia	39
4	pl.misc.telefonia.gsm	33
5	pl.comp.os.linux	7
6	pl.regionalne.lodz	1
7	pl.rec.dom	1
TOTAL: 7		199

Daily Usenet report

Aug 25 04:15:02 -- Aug 26 04:15:02