Daily Usenet report: Apr 4 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Apr 4 11:20:57 newsfeed nnrpd[3479]: SERVER perl filter not defined
Apr 5 00:05:17 newsfeed nnrpd[29889]: SERVER perl filter not defined
Apr 5 00:05:44 newsfeed nnrpd[29891]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2753761	98.4%	324.2 MB	98.2%
innd	35642	1.3%	4.9 MB	1.5%
innfeed	4876	0.2%	695.7 KB	0.2%
nnrpd	4732	0.2%	493.4 KB	0.1%
TOTAL: 4	2799011	100.0%	330.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2748261	95.8%
Negative hits	97495	3.4%
Positive hits	13378	0.5%
Cache misses	8931	0.3%
TOTAL: 4	2868065	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.038	0.0%	245	0.000	12.400	1043.000
article cleanup	00:00:41.042	0.0%	2753281	0.007	0.015	0.028
article logging	00:01:36.719	0.1%	2753792	0.019	0.035	1.158
article parse	01:21:42.476	5.7%	231062080	0.011	0.021	0.048
article write	00:40:49.199	2.8%	2736551	0.377	0.895	6.536
artlog/artcncl	00:00:00.010	0.0%	220	0.000	0.045	1.000
artlog/artparse	00:00:00.013	0.0%	274	0.000	0.047	1.000
data move	00:03:08.982	0.2%	249439878	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.791	0.0%	218	0.000	3.628	52.000
hishave/artcncl	00:00:00.049	0.0%	245	0.000	0.200	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:03.300	2.1%	2870460	0.055	0.628	6.448
history sync	00:00:00.015	0.0%	292	0.000	0.051	1.000
history write	00:43:46.888	3.0%	2750695	0.219	0.955	9.285
hiswrite/artcncl	00:00:00.026	0.0%	27	0.000	0.963	16.000
idle	12:37:51.295	52.6%	157435514	0.199	0.289	0.521
nntp read	01:17:13.219	5.4%	228888096	0.012	0.020	0.038
overview write	00:00:16.098	0.0%	2736551	0.000	0.006	0.014
perl filter	06:03:45.065	25.3%	2750421	6.064	7.935	10.782
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:03.293	0.4%	5574055	0.034	0.054	0.102
TOTAL: 24:00:24.984	23:26:01.518	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.469	0.0%	113735	0.000	0.004	0.250
article prepare	00:00:00.081	0.0%	328288	0.000	0.000	0.036
article read	00:02:26.745	0.2%	82061	0.000	1.788	179.200
backlog stats	00:00:00.593	0.0%	544822	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:03.277	0.0%	244432	0.000	0.013	0.157
data write	00:00:47.089	0.1%	312388	0.000	0.151	0.480
idle	23:56:19.269	99.8%	544822	7.947	158.179	7957.250
status file	00:00:00.825	0.0%	972	0.000	0.849	11.500
TOTAL: 23:59:53.124	23:59:38.348	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.425	58.9%	3001	0.000	6.140	45.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.167	0.5%	6574	0.000	0.025	1.000
readart	00:00:00.000	0.0%	4	0.000	0.000	0.000
TOTAL: 00:00:31.292	00:00:18.592	59.4%	-	-	-	-

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	1252	2745132	2725686	16289	3157	99%	377:18:12
2	news.nask.pl	2	26728	10227	2645	13856	38%	24:00:45
3	news.icm.edu.pl	44	309	16	284	9	5%	17:43:17
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1302	2772172	2735932	19218	17022	98%	419:02:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	948.8 GB	23.4 MB	141.3 MB	948.9 GB	99%	364.6 KB
2	news.nask.pl	41.7 MB	1.0 MB	149.6 MB	192.3 MB	21%	8.2 KB
3	news.icm.edu.pl	341.3 KB	0.0 KB	548.9 KB	890.2 KB	38%	35.6 KB
4	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	1.4 KB
TOTAL: 4		948.8 GB	24.5 MB	291.4 MB	949.1 GB	99%	361.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 4 04:15:02 - 04:59:59	81592	3.0%	30.24	28.6 GB	3.0%	11111.69
Apr 4 05:00:00 - 05:59:59	100169	3.7%	27.82	34.4 GB	3.6%	10014.76
Apr 4 06:00:00 - 06:59:59	97797	3.6%	27.17	34.7 GB	3.7%	10113.36
Apr 4 07:00:00 - 07:59:59	114710	4.2%	31.86	41.8 GB	4.4%	12167.24
Apr 4 08:00:00 - 08:59:59	112322	4.1%	31.20	40.7 GB	4.3%	11852.35
Apr 4 09:00:00 - 09:59:59	113456	4.1%	31.52	40.0 GB	4.2%	11662.17
Apr 4 10:00:00 - 10:59:59	115979	4.2%	32.22	40.0 GB	4.2%	11644.00
Apr 4 11:00:00 - 11:59:59	120501	4.4%	33.47	41.7 GB	4.4%	12137.93
Apr 4 12:00:00 - 12:59:59	118718	4.3%	32.98	41.0 GB	4.3%	11942.95
Apr 4 13:00:00 - 13:59:59	125051	4.6%	34.74	40.9 GB	4.3%	11899.22
Apr 4 14:00:00 - 14:59:59	119652	4.4%	33.24	39.8 GB	4.2%	11598.50
Apr 4 15:00:00 - 15:59:59	111626	4.1%	31.01	38.3 GB	4.0%	11155.20
Apr 4 16:00:00 - 16:59:59	113562	4.2%	31.55	41.7 GB	4.4%	12137.69
Apr 4 17:00:00 - 17:59:59	112387	4.1%	31.22	40.6 GB	4.3%	11822.10
Apr 4 18:00:00 - 18:59:59	113615	4.2%	31.56	39.9 GB	4.2%	11628.33
Apr 4 19:00:00 - 19:59:59	123538	4.5%	34.32	42.0 GB	4.4%	12238.90
Apr 4 20:00:00 - 20:59:59	128628	4.7%	35.73	43.0 GB	4.5%	12528.64
Apr 4 21:00:00 - 21:59:59	126082	4.6%	35.02	41.5 GB	4.4%	12082.89
Apr 4 22:00:00 - 22:59:59	120523	4.4%	33.48	42.0 GB	4.4%	12231.73
Apr 4 23:00:00 - 23:59:59	114803	4.2%	31.89	40.1 GB	4.2%	11687.62
Apr 5 00:00:00 - 00:59:59	116271	4.2%	32.30	34.0 GB	3.6%	9917.41
Apr 5 01:00:00 - 01:59:59	122526	4.5%	34.03	39.0 GB	4.1%	11357.57
Apr 5 02:00:00 - 02:59:59	100237	3.7%	27.84	38.9 GB	4.1%	11318.56
Apr 5 03:00:00 - 03:59:59	87451	3.2%	24.29	35.0 GB	3.7%	10180.24
Apr 5 04:00:00 - 04:15:02	24805	0.9%	27.50	9.1 GB	1.0%	10573.08
TOTAL: 24:00:00	2736001	100.0%	31.67	948.6 GB	100.0%	11512.34

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14103	93	334	6	13670
2	newsfeed.pionier.net.pl	3418	197	2526	0	695
3	news.icm.edu.pl	19	0	0	0	19
TOTAL: 3		17540	290	2860	6	14384

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.sys.raspberry-pi	92
uk.railway	80
it.tlc.cellulari.android	44
it.comp.os.win.windows10	26
it.comp.os.win.windows7	11
comp.mobile.android	10
uk.rec.cycling.moderated	7
de.comp.lang.php	5
nl.comp.os.linux.techniek	4
hr.ponuda.hardver.komponente	3
alt.sex.telephones	2
hr.rec.glazba.hifi	2
uk.net.providers.gradwell	2
de.comp.lang.vbclassic	1
de.rec.sf.startrek	1
TOTAL: 15	290

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

Groups	Count
sci.med.aids	3
pl.praca.oferowana	2
bit.listserv.catholic	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13175
Binary in non-binary group	281
Scoring filter (8)	61
Scoring filter (9)	45
Scoring filter (10)	18
Excessively crossposted test article	11
HTML post	3
Scoring filter (15)	1
Scoring filter (13)	1
Scoring filter (16)	1
Scoring filter (17)	1
TOTAL: 11	13598

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	104022	70005	19683	8528	21	8796	67%	24:03:28
2	panorama	12487	624	10094	290	23	93917	4%	23:56:54
3	poznan	15930	185	10043	13	0	0	1%	24:00:29
4	nask	7787	3	4150	2221	0	0	0%	23:59:05
5	okapi	0	0	0	0	0	1950	0%	24:00:03
TOTAL: 5		140226	70817	43970	11052	44	104663	50%	119:59:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	12.5 GB	2.5 GB	15.0 GB	182.0 KB/s	200.7 KB	24:03:28
2	panorama	2.3 MB	1.2 MB	3.5 MB	0.0 KB/s	3.9 KB	23:56:54
3	poznan	637.9 KB	94.1 KB	732.0 KB	0.0 KB/s	3.7 KB	24:00:29
4	nask	8.5 KB	839.1 MB	839.1 MB	10.0 KB/s	386.4 KB	23:59:05
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		12.5 GB	3.3 GB	15.9 GB	38.5 KB/s	203.0 KB	119:59:59

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
TOTAL: 1	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	4	2.9 KB	2	0	00:00:25
2	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 2		291	4	2.9 KB	2	3	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	675	4	2.9 KB	2	3	0	00:00:31
TOTAL: 1		675	4	2.9 KB	2	3	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.105	3.679	00:00:25
mon.man.lodz.pl	0.892	2.981	00:00:03
david.man.lodz.pl	0.289	1.006	00:00:01
isvcs01.man.lodz.pl	0.023	0.043	00:00:00
TOTAL: 4	2.309	7.709	00:00:31

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	4	100.0%
TOTAL: 1		4	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	4
TOTAL: 1		4

Daily Usenet report

Apr 4 04:15:02 -- Apr 5 04:15:02