Daily Usenet report: Feb 2 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Feb 2 19:39:15 newsfeed nnrpd[19257]: SERVER perl filter not defined
Feb 2 19:55:17 newsfeed nnrpd[19890]: SERVER perl filter not defined
Feb 3 00:41:35 newsfeed nnrpd[29641]: SERVER perl filter not defined
Feb 3 00:55:27 newsfeed nnrpd[30212]: SERVER perl filter not defined
Feb 3 04:15:02 newsfeed nnrpd[4783]: david.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3084653	98.6%	357.6 MB	98.4%
innd	33256	1.1%	4.7 MB	1.3%
innfeed	4901	0.2%	698.6 KB	0.2%
nnrpd	4800	0.2%	498.6 KB	0.1%
TOTAL: 4	3127610	100.0%	363.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3077980	96.6%
Negative hits	92978	2.9%
Positive hits	13971	0.4%
Cache misses	59	0.0%
TOTAL: 4	3184988	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.349	0.0%	424	0.000	12.616	570.500
article cleanup	00:00:43.061	0.0%	3083689	0.007	0.014	0.028
article logging	00:01:34.262	0.1%	3084323	0.016	0.031	0.818
article parse	01:20:39.062	5.6%	157788234	0.016	0.031	0.080
article write	00:42:43.467	3.0%	3066945	0.355	0.836	4.591
artlog/artcncl	00:00:00.010	0.0%	407	0.000	0.025	1.000
artlog/artparse	00:00:00.003	0.0%	103	0.000	0.029	1.000
data move	00:02:49.916	0.2%	175643169	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.365	0.0%	105	0.000	3.476	236.000
hishave/artcncl	00:00:00.357	0.0%	424	0.000	0.842	4.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:39:48.145	6.9%	3186818	0.177	1.879	12.347
history sync	00:00:00.016	0.0%	293	0.000	0.055	1.000
history write	02:29:35.054	10.4%	3079730	0.414	2.914	20.685
hiswrite/artcncl	00:00:01.264	0.0%	319	0.000	3.962	9.000
idle	09:29:14.063	39.5%	96902487	0.111	0.352	1.412
nntp read	01:05:01.357	4.5%	155263206	0.016	0.025	0.041
overview write	00:00:18.100	0.0%	3066945	0.001	0.006	0.017
perl filter	06:26:29.267	26.8%	3079627	5.792	7.530	10.781
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:35.523	0.4%	6310584	0.035	0.053	0.091
TOTAL: 23:59:56.878	23:24:38.641	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.790	0.0%	213736	0.000	0.004	0.143
article prepare	00:00:00.152	0.0%	640292	0.000	0.000	0.001
article read	00:07:41.661	0.5%	158779	0.000	2.908	182.000
backlog stats	00:00:00.989	0.0%	997753	0.000	0.001	0.036
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:06.345	0.0%	434636	0.000	0.015	0.129
data write	00:01:29.718	0.1%	580371	0.000	0.155	0.363
idle	23:50:51.982	99.3%	997753	8.458	86.045	4329.000
status file	00:00:01.020	0.0%	1221	0.000	0.835	4.500
TOTAL: 24:00:38.240	24:00:12.657	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:26:44.676	99.0%	13421	0.000	119.565	230.146
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.610	0.0%	27485	0.000	0.022	0.500
readart	00:00:00.032	0.0%	42	0.000	0.762	7.500
TOTAL: 00:27:01.573	00:26:45.318	99.0%	-	-	-	-

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	519	3067771	3056838	6212	4721	99%	494:16:51
2	news.nask.pl	2	25554	10149	3325	12080	39%	23:55:52
3	news.icm.edu.pl	39	389	26	333	30	6%	18:03:18
4	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 4		565	3093718	3067017	9870	16831	99%	536:16:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	895.8 GB	40.4 MB	276.2 MB	896.1 GB	99%	306.9 KB
2	news.nask.pl	45.7 MB	1.5 MB	148.9 MB	196.1 MB	23%	9.0 KB
3	news.icm.edu.pl	769.2 KB	36.8 KB	513.6 KB	1.3 MB	58%	23.6 KB
4	localhost	5.6 KB	0.0 KB	0.0 KB	5.6 KB	100%	1.4 KB
TOTAL: 4		895.9 GB	42.0 MB	425.6 MB	896.3 GB	99%	304.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 2 04:15:02 - 04:59:59	119391	3.9%	44.25	31.9 GB	3.6%	12401.47
Feb 2 05:00:00 - 05:59:59	159552	5.2%	44.32	43.3 GB	4.8%	12618.59
Feb 2 06:00:00 - 06:59:59	156762	5.1%	43.55	43.4 GB	4.8%	12629.01
Feb 2 07:00:00 - 07:59:59	153169	5.0%	42.55	42.5 GB	4.7%	12375.24
Feb 2 08:00:00 - 08:59:59	157853	5.1%	43.85	43.9 GB	4.9%	12775.66
Feb 2 09:00:00 - 09:59:59	166645	5.4%	46.29	45.3 GB	5.1%	13204.48
Feb 2 10:00:00 - 10:59:59	168251	5.5%	46.74	44.5 GB	5.0%	12947.60
Feb 2 11:00:00 - 11:59:59	164785	5.4%	45.77	43.4 GB	4.8%	12637.73
Feb 2 12:00:00 - 12:59:59	161535	5.3%	44.87	43.7 GB	4.9%	12717.10
Feb 2 13:00:00 - 13:59:59	145489	4.7%	40.41	43.3 GB	4.8%	12611.13
Feb 2 14:00:00 - 14:59:59	138364	4.5%	38.43	40.3 GB	4.5%	11751.38
Feb 2 15:00:00 - 15:59:59	59666	1.9%	16.57	20.8 GB	2.3%	6053.92
Feb 2 16:00:00 - 16:59:59	72374	2.4%	20.10	25.0 GB	2.8%	7281.53
Feb 2 17:00:00 - 17:59:59	79518	2.6%	22.09	26.7 GB	3.0%	7764.81
Feb 2 18:00:00 - 18:59:59	87604	2.9%	24.33	28.7 GB	3.2%	8350.39
Feb 2 19:00:00 - 19:59:59	84533	2.8%	23.48	27.9 GB	3.1%	8140.73
Feb 2 20:00:00 - 20:59:59	82115	2.7%	22.81	27.9 GB	3.1%	8113.20
Feb 2 21:00:00 - 21:59:59	119193	3.9%	33.11	32.2 GB	3.6%	9385.81
Feb 2 22:00:00 - 22:59:59	145567	4.7%	40.44	38.5 GB	4.3%	11202.43
Feb 2 23:00:00 - 23:59:59	151662	4.9%	42.13	41.9 GB	4.7%	12213.69
Feb 3 00:00:00 - 00:59:59	110603	3.6%	30.72	34.6 GB	3.9%	10063.90
Feb 3 01:00:00 - 01:59:59	137204	4.5%	38.11	43.5 GB	4.9%	12656.75
Feb 3 02:00:00 - 02:59:59	105318	3.4%	29.25	34.4 GB	3.8%	10008.51
Feb 3 03:00:00 - 03:59:59	112721	3.7%	31.31	38.1 GB	4.3%	11108.93
Feb 3 04:00:00 - 04:15:02	26891	0.9%	29.81	9.7 GB	1.1%	11255.05
TOTAL: 24:00:00	3066765	100.0%	35.49	895.1 GB	100.0%	10863.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12379	28	524	2	11825
2	newsfeed.pionier.net.pl	5072	160	3536	0	1376
3	news.icm.edu.pl	30	0	2	0	28
TOTAL: 3		17481	188	4062	2	13229

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	103
it.comp.os.win.windows10	27
comp.mobile.android	14
it.comp.os.linux.ubuntu	9
it.tlc.cellulari.android	9
de.comp.lang.php	8
comp.sys.raspberry-pi	8
nl.comp.sys.android	7
alt.sex.telephones	2
hr.rec.glazba.hifi	1
TOTAL: 10	188

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11136
Binary in non-binary group	1045
EMP (fsl)	106
EMP (phl)	72
Scoring filter (8)	50
Scoring filter (9)	46
Scoring filter (10)	13
Scoring filter (17)	7
Scoring filter (11)	5
Too many newsgroups	5
HTML post	3
Scoring filter (12)	3
Scoring filter (15)	1
TOTAL: 13	12492

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	174876	117672	21584	30297	1	29168	67%	24:01:57
2	panorama	28276	2935	19226	2881	12	159508	10%	24:03:18
3	poznan	15029	232	9835	43	1	0	1%	24:00:56
4	nask	12827	4	5078	5751	0	0	0%	24:05:20
5	okapi	0	0	0	0	0	2114	0%	24:00:00
TOTAL: 5		231008	120843	55723	38972	14	190790	52%	120:11:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.1 GB	7.1 GB	28.2 GB	341.6 KB/s	199.7 KB	24:01:57
2	panorama	12.5 MB	379.9 MB	392.3 MB	4.6 KB/s	69.1 KB	24:03:18
3	poznan	957.5 KB	343.0 KB	1.3 MB	0.0 KB/s	4.7 KB	24:00:56
4	nask	31.5 KB	2.0 GB	2.0 GB	24.1 KB/s	363.7 KB	24:05:20
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		21.1 GB	9.4 GB	30.6 GB	74.1 KB/s	200.5 KB	120:11:31

Backlog files shrunk by innfeed:

Server	Size
panorama	11.4 MB
TOTAL: 1	11.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	42	115.3 KB	21	0	00:00:22
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:02
TOTAL: 2		292	42	115.3 KB	21	4	00:27:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	42	115.3 KB	21	4	00:00:30
2	*.dyn.optonline.net	1	0	0.0 KB	0	0	00:00:00
3	unresolved	3	0	0.0 KB	0	0	00:26:30
TOTAL: 3		680	42	115.3 KB	21	4	00:27:01

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.52	0.411	0.760	00:15:59
185.48.193.226	0.149	0.303	00:10:30
web.man.lodz.pl	1.133	7.000	00:00:22
mon.man.lodz.pl	0.990	6.308	00:00:03
isvcs01.man.lodz.pl	0.042	0.077	00:00:02
david.man.lodz.pl	0.303	1.949	00:00:01
ool-ae2c99f9.dyn.optonline.net	0.000	0.024	00:00:00
TOTAL: 7	3.028	16.421	00:27:01

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.192.52	2
185.48.193.226	1
ool-ae2c99f9.dyn.optonline.net	1
TOTAL: 5	388

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.192.52	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	36
2	pl.comp.os.ms-windows.winnt	6
TOTAL: 2		42

Daily Usenet report

Feb 2 04:15:02 -- Feb 3 04:15:02