Daily Usenet report: May 3 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

May 3 14:44:18 newsfeed nnrpd[27518]: message repeated 2 times: [ user-164-126-163-13.play-internet.pl group lodzman.test 0]
May 3 15:55:25 newsfeed nnrpd[30077]: SERVER perl filter not defined
May 3 16:12:54 newsfeed nnrpd[30570]: SERVER perl filter not defined
May 3 18:08:50 newsfeed nnrpd[2284]: SERVER perl filter not defined
May 3 18:12:36 newsfeed nnrpd[2378]: SERVER perl filter not defined
May 3 21:47:42 newsfeed nnrpd[10123]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2335772	97.8%	263.2 MB	97.3%
innfeed	24105	1.0%	2.9 MB	1.1%
innd	23037	1.0%	3.8 MB	1.4%
nnrpd	5064	0.2%	536.8 KB	0.2%
TOTAL: 4	2387978	100.0%	270.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2330012	98.3%
Negative hits	24838	1.0%
Positive hits	14625	0.6%
Cache misses	191	0.0%
TOTAL: 4	2369666	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.251	0.0%	224	0.000	23.442	491.250
article cleanup	00:00:33.508	0.0%	2335795	0.000	0.014	0.027
article logging	00:01:12.508	0.1%	2336372	0.017	0.031	0.983
article parse	01:13:55.016	5.1%	189195368	0.010	0.023	0.081
article write	00:28:11.921	2.0%	2329253	0.380	0.726	4.889
artlog/artcncl	00:00:00.004	0.0%	210	0.000	0.019	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:45.719	0.2%	204632392	0.000	0.001	0.007
hisgrep/artcncl	00:00:00.411	0.0%	171	0.000	2.404	245.000
hishave/artcncl	00:00:00.280	0.0%	224	0.000	1.250	26.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:01.334	2.2%	2371938	0.116	0.810	33.637
history sync	00:00:00.027	0.0%	292	0.000	0.092	3.000
history write	00:44:22.193	3.1%	2332314	0.197	1.141	44.694
hiswrite/artcncl	00:00:00.209	0.0%	53	0.000	3.943	16.000
idle	13:28:58.368	56.2%	141925432	0.124	0.342	2.552
nntp read	01:13:33.165	5.1%	187379313	0.014	0.024	0.040
overview write	00:00:13.912	0.0%	2329253	0.000	0.006	0.017
perl filter	05:12:17.092	21.7%	2332314	5.820	8.034	11.651
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:12.688	0.3%	4703858	0.031	0.054	0.098
TOTAL: 24:00:19.565	23:02:23.606	96.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.285	0.0%	98394	0.000	0.003	0.091
article prepare	00:00:00.022	0.0%	77018	0.000	0.000	0.125
article read	00:01:01.185	0.1%	19250	0.000	3.178	409.000
backlog stats	00:00:00.214	0.0%	301165	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	5	0.000	0.000	0.000
data read	00:00:01.955	0.0%	155016	0.000	0.013	0.077
data write	00:00:13.087	0.0%	139631	0.000	0.094	0.727
idle	23:58:19.254	99.9%	301165	18.512	286.551	3756.188
newart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
prepart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
readart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
status file	00:00:07.443	0.0%	7374	0.000	1.009	167.500
TOTAL: 23:59:53.248	23:59:43.445	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:37:48.705	99.7%	28484	0.000	206.035	127861.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.254	0.0%	57788	0.000	0.022	0.667
readart	00:00:01.055	0.0%	77	0.000	13.701	423.500
TOTAL: 01:38:04.988	01:37:51.014	99.8%	-	-	-	-

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	1273	2328267	2324594	227	3446	99%	313:18:51
2	news.nask.pl	2	17287	3644	10590	3053	21%	23:39:43
3	news.icm.edu.pl	45	354	42	280	32	11%	17:22:53
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1326	2345913	2328285	11097	6531	99%	354:21:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	769.9 GB	25.1 MB	27.1 MB	770.0 GB	99%	346.8 KB
2	news.nask.pl	25.7 MB	781.9 KB	27.7 MB	54.1 MB	47%	8.3 KB
3	news.icm.edu.pl	1.2 MB	10.9 KB	706.2 KB	1.9 MB	62%	25.8 KB
4	localhost	6.7 KB	0.0 KB	0.0 KB	6.7 KB	100%	1.3 KB
TOTAL: 4		770.0 GB	25.9 MB	55.5 MB	770.0 GB	99%	345.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 3 04:15:02 - 04:59:59	75394	3.2%	27.94	24.4 GB	3.2%	9495.31
May 3 05:00:00 - 05:59:59	99622	4.3%	27.67	33.3 GB	4.3%	9694.74
May 3 06:00:00 - 06:59:59	101543	4.4%	28.21	34.2 GB	4.4%	9952.39
May 3 07:00:00 - 07:59:59	81927	3.5%	22.76	28.1 GB	3.6%	8187.13
May 3 08:00:00 - 08:59:59	98207	4.2%	27.28	32.4 GB	4.2%	9438.36
May 3 09:00:00 - 09:59:59	109025	4.7%	30.28	34.6 GB	4.5%	10087.80
May 3 10:00:00 - 10:59:59	113650	4.9%	31.57	34.1 GB	4.4%	9933.89
May 3 11:00:00 - 11:59:59	114130	4.9%	31.70	34.5 GB	4.5%	10036.03
May 3 12:00:00 - 12:59:59	121878	5.2%	33.85	36.3 GB	4.7%	10566.72
May 3 13:00:00 - 13:59:59	120458	5.2%	33.46	36.8 GB	4.8%	10722.57
May 3 14:00:00 - 14:59:59	115309	5.0%	32.03	35.7 GB	4.6%	10411.20
May 3 15:00:00 - 15:59:59	101652	4.4%	28.24	32.0 GB	4.2%	9320.72
May 3 16:00:00 - 16:59:59	95182	4.1%	26.44	32.1 GB	4.2%	9353.74
May 3 17:00:00 - 17:59:59	99531	4.3%	27.65	32.7 GB	4.2%	9536.60
May 3 18:00:00 - 18:59:59	99528	4.3%	27.65	31.6 GB	4.1%	9199.14
May 3 19:00:00 - 19:59:59	94377	4.1%	26.22	30.4 GB	3.9%	8850.64
May 3 20:00:00 - 20:59:59	97669	4.2%	27.13	32.6 GB	4.2%	9502.29
May 3 21:00:00 - 21:59:59	110330	4.7%	30.65	34.0 GB	4.4%	9893.35
May 3 22:00:00 - 22:59:59	87148	3.7%	24.21	29.8 GB	3.9%	8672.19
May 3 23:00:00 - 23:59:59	89977	3.9%	24.99	30.6 GB	4.0%	8905.22
May 4 00:00:00 - 00:59:59	74525	3.2%	20.70	27.6 GB	3.6%	8043.31
May 4 01:00:00 - 01:59:59	78563	3.4%	21.82	33.1 GB	4.3%	9644.66
May 4 02:00:00 - 02:59:59	66179	2.8%	18.38	26.2 GB	3.4%	7643.50
May 4 03:00:00 - 03:59:59	66924	2.9%	18.59	26.7 GB	3.5%	7765.92
May 4 04:00:00 - 04:15:02	15718	0.7%	17.43	6.8 GB	0.9%	7869.49
TOTAL: 24:00:00	2328446	100.0%	26.95	770.6 GB	100.0%	9352.09

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3938	122	3245	0	571
2	news.nask.pl	3148	1	232	3	2912
3	news.icm.edu.pl	30	0	1	0	29
TOTAL: 3		7116	123	3478	3	3512

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.tlc.cellulari.android	45
uk.railway	45
it.comp.os.win.windows10	16
comp.mobile.android	10
hr.alt.cellular.gsm.android	2
de.rec.sf.startrek	2
uk.rec.cycling.moderated	1
comp.sys.raspberry-pi	1
nl.comp.os.linux.techniek	1
TOTAL: 9	123

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

Groups	Count
pl.sci.historia	1
christnet.philosophy	1
bit.listserv.catholic	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2102
PFM bot misc	214
Scoring filter (8)	199
EMP (phl)	153
Binary in non-binary group	153
Poison newsgroup	32
Scoring filter (9)	27
Scoring filter (11)	16
Scoring filter (10)	14
Scoring filter (12)	7
HTML post	4
EMP (fsl)	4
Scoring filter (13)	3
Scoring filter (14)	2
Scoring filter (16)	2
Scoring filter (17)	1
Attached HTML file	1
Scoring filter (15)	1
TOTAL: 18	2935

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	48273	10446	21088	5849	0	0	21%	24:05:46
2	panorama	53210	1790	38727	516	11	57148	3%	24:00:07
3	nask	20300	117	11139	0	0	0	0%	23:56:57
4	poznan	5842	64	3601	18	0	0	1%	24:00:56
5	okapi	0	0	0	0	0	2115	0%	24:00:01
TOTAL: 5		127625	12417	74555	6383	11	59263	9%	120:03:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.5 GB	1.5 GB	3.0 GB	36.8 KB/s	196.1 KB	24:05:46
2	panorama	141.8 MB	3.1 MB	144.9 MB	1.7 KB/s	64.4 KB	24:00:07
3	nask	352.5 KB	0.0 KB	352.5 KB	0.0 KB/s	3.0 KB	23:56:57
4	poznan	241.6 KB	256.9 KB	498.5 KB	0.0 KB/s	6.1 KB	24:00:56
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		1.7 GB	1.5 GB	3.2 GB	7.7 KB/s	177.9 KB	120:03:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	66	58.6 KB	30	0	0	00:00:33
2	user-164-126-163-13.play-internet.pl	17	6	5.2 KB	2	0	5	00:20:20
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:00
4	staticline-31-183-6-141.toya.net.pl	3	0	0.0 KB	0	0	1	00:10:11
TOTAL: 4		313	72	63.8 KB	32	5	6	01:38:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	677	66	58.6 KB	30	5	0	00:00:38
2	*.play-internet.pl	17	6	5.2 KB	2	0	5	00:20:20
3	unresolved	3	0	0.0 KB	0	0	0	01:06:52
4	*.toya.net.pl	31	0	0.0 KB	0	0	1	00:10:13
TOTAL: 4		728	72	63.8 KB	32	5	6	01:38:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.40.162	1.563	1.848	01:06:51
user-164-126-163-13.play-internet.pl	0.143	0.339	00:20:20
staticline-31-183-6-141.toya.net.pl	0.064	0.065	00:10:11
web.man.lodz.pl	0.879	5.422	00:00:33
mon.man.lodz.pl	0.972	5.181	00:00:03
staticline41435.toya.net.pl	0.098	0.487	00:00:01
196.52.18.10	0.007	0.027	00:00:01
david.man.lodz.pl	0.282	1.576	00:00:01
isvcs01.man.lodz.pl	0.031	0.102	00:00:00
TOTAL: 9	4.039	15.047	01:38:04

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	28
89.38.40.162	2
196.52.18.10	1
TOTAL: 5	415

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.40.162	1	1
TOTAL: 1	1	5

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	66	91.7%
2	lodzman	6	8.3%
TOTAL: 2		72	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	42
2	pl.comp.os.ms-windows.winnt	24
3	lodzman.test	6
TOTAL: 3		72

Daily Usenet report

May 3 04:15:02 -- May 4 04:15:02