Daily Usenet report: Sep 5 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 5 09:54:10 newsfeed nnrpd[24238]: SERVER perl filter not defined
Sep 5 10:01:40 newsfeed nnrpd[24575]: SERVER perl filter not defined
Sep 5 12:01:17 newsfeed nnrpd[28691]: SERVER perl filter not defined
Sep 5 14:36:39 newsfeed nnrpd[1658]: SERVER perl filter not defined
Sep 5 14:37:12 newsfeed nnrpd[1662]: SERVER perl filter not defined
Sep 5 19:27:46 newsfeed overchan[2616]: input line too long, skipping
Sep 6 00:20:20 newsfeed nnrpd[17367]: SERVER perl filter not defined
Sep 6 00:20:47 newsfeed nnrpd[17377]: SERVER perl filter not defined
Sep 6 00:21:06 newsfeed nnrpd[17383]: SERVER perl filter not defined
Sep 6 00:21:33 newsfeed nnrpd[17384]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2700250	98.3%	315.7 MB	98.1%
innd	36252	1.3%	5.0 MB	1.5%
innfeed	4931	0.2%	678.5 KB	0.2%
nnrpd	4821	0.2%	494.7 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2746256	100.0%	321.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2693179	95.7%
Negative hits	105577	3.8%
Positive hits	16524	0.6%
Cache misses	91	0.0%
TOTAL: 4	2815371	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.619	0.0%	185	0.000	14.157	1718.000
article cleanup	00:00:40.366	0.0%	2699494	0.006	0.015	0.036
article logging	00:01:26.344	0.1%	2699967	0.016	0.032	0.984
article parse	01:16:20.254	5.3%	226388161	0.008	0.020	0.075
article write	00:30:47.413	2.1%	2680528	0.306	0.689	4.891
artlog/artcncl	00:00:00.004	0.0%	183	0.000	0.022	1.000
data move	00:02:53.490	0.2%	243887569	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.147	0.0%	161	0.000	0.913	18.000
hishave/artcncl	00:00:00.058	0.0%	185	0.000	0.314	5.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:46.417	1.9%	2817438	0.099	0.591	8.426
history sync	00:00:00.010	0.0%	290	0.000	0.034	2.000
history write	00:38:27.491	2.7%	2695279	0.191	0.856	10.563
hiswrite/artcncl	00:00:00.027	0.0%	24	0.000	1.125	12.000
idle	13:17:31.324	55.4%	172443459	0.198	0.277	1.448
nntp read	01:11:02.885	4.9%	224263389	0.008	0.019	0.055
overview write	00:00:15.030	0.0%	2680528	0.000	0.006	0.014
perl filter	05:45:08.036	24.0%	2695279	4.940	7.683	11.998
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:31.439	0.3%	5427828	0.030	0.050	0.118
TOTAL: 23:59:19.029	23:16:53.354	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.248	0.0%	68465	0.000	0.004	0.167
article prepare	00:00:00.026	0.0%	144534	0.000	0.000	0.083
article read	00:00:10.210	0.0%	35688	0.000	0.286	38.000
backlog stats	00:00:00.348	0.0%	409550	0.000	0.001	0.050
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.606	0.0%	194248	0.000	0.013	0.250
data write	00:00:24.008	0.0%	219423	0.000	0.109	0.268
idle	23:57:31.611	99.9%	409550	13.225	210.601	5017.500
status file	00:00:00.974	0.0%	1209	0.000	0.806	31.500
TOTAL: 23:58:21.119	23:58:10.031	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:13:52.092	99.9%	166109	0.000	135.044	137.509
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.231	0.0%	332824	0.000	0.016	0.667
readart	00:00:00.019	0.0%	42	0.000	0.452	8.000
TOTAL: 06:14:17.409	06:13:57.342	99.9%	-	-	-	-

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	1229	2678547	2665608	8000	4939	99%	338:16:56
2	news.nask.pl	2	31870	13990	3902	13978	43%	23:52:15
3	news.icm.edu.pl	38	305	17	284	4	5%	17:43:16
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1279	2710731	2679624	12186	18921	98%	379:52:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	860.9 GB	271.2 MB	429.2 MB	861.6 GB	99%	338.3 KB
2	news.nask.pl	155.3 MB	2.2 MB	324.3 MB	481.9 MB	32%	17.6 KB
3	news.icm.edu.pl	723.9 KB	0.0 KB	368.5 KB	1.1 MB	66%	52.0 KB
4	localhost	15.4 KB	0.0 KB	0.0 KB	15.4 KB	100%	1.7 KB
TOTAL: 4		861.0 GB	273.5 MB	753.8 MB	862.0 GB	99%	335.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 5 04:15:02 - 04:59:59	65078	2.4%	24.12	23.1 GB	2.7%	8960.42
Sep 5 05:00:00 - 05:59:59	86369	3.2%	23.99	32.8 GB	3.8%	9563.08
Sep 5 06:00:00 - 06:59:59	92701	3.5%	25.75	34.2 GB	4.0%	9951.41
Sep 5 07:00:00 - 07:59:59	88377	3.3%	24.55	33.3 GB	3.9%	9686.13
Sep 5 08:00:00 - 08:59:59	102957	3.8%	28.60	37.6 GB	4.4%	10938.56
Sep 5 09:00:00 - 09:59:59	113425	4.2%	31.51	36.9 GB	4.3%	10752.72
Sep 5 10:00:00 - 10:59:59	115265	4.3%	32.02	37.4 GB	4.3%	10879.37
Sep 5 11:00:00 - 11:59:59	109580	4.1%	30.44	36.0 GB	4.2%	10495.83
Sep 5 12:00:00 - 12:59:59	107655	4.0%	29.90	38.3 GB	4.5%	11158.58
Sep 5 13:00:00 - 13:59:59	111040	4.1%	30.84	38.1 GB	4.4%	11105.61
Sep 5 14:00:00 - 14:59:59	110964	4.1%	30.82	38.1 GB	4.4%	11087.09
Sep 5 15:00:00 - 15:59:59	137717	5.1%	38.25	40.3 GB	4.7%	11733.06
Sep 5 16:00:00 - 16:59:59	133274	5.0%	37.02	39.6 GB	4.6%	11545.25
Sep 5 17:00:00 - 17:59:59	126780	4.7%	35.22	39.0 GB	4.5%	11365.63
Sep 5 18:00:00 - 18:59:59	117726	4.4%	32.70	38.6 GB	4.5%	11239.86
Sep 5 19:00:00 - 19:59:59	140404	5.2%	39.00	39.3 GB	4.6%	11455.91
Sep 5 20:00:00 - 20:59:59	121510	4.5%	33.75	34.2 GB	4.0%	9954.25
Sep 5 21:00:00 - 21:59:59	98882	3.7%	27.47	28.0 GB	3.3%	8168.72
Sep 5 22:00:00 - 22:59:59	92518	3.5%	25.70	30.2 GB	3.5%	8793.68
Sep 5 23:00:00 - 23:59:59	102742	3.8%	28.54	33.3 GB	3.9%	9705.15
Sep 6 00:00:00 - 00:59:59	99829	3.7%	27.73	30.0 GB	3.5%	8739.88
Sep 6 01:00:00 - 01:59:59	131987	4.9%	36.66	38.3 GB	4.5%	11153.40
Sep 6 02:00:00 - 02:59:59	129970	4.8%	36.10	38.9 GB	4.5%	11340.91
Sep 6 03:00:00 - 03:59:59	114369	4.3%	31.77	35.3 GB	4.1%	10283.57
Sep 6 04:00:00 - 04:15:02	29505	1.1%	32.71	9.0 GB	1.0%	10473.95
TOTAL: 24:00:00	2680624	100.0%	31.03	859.8 GB	100.0%	10434.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14221	11	250	1	13959
2	newsfeed.pionier.net.pl	5206	163	3966	0	1077
3	news.icm.edu.pl	16	0	0	0	16
TOTAL: 3		19443	174	4216	1	15052

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	113
it.comp.os.win.windows10	20
uk.rec.cycling.moderated	16
it.tlc.cellulari.android	10
comp.sys.raspberry-pi	6
comp.mobile.android	4
comp.mobile.ipad	2
hr.ponuda.hardver.komponente	1
de.rec.sf.startrek	1
alt.sex.telephones	1
TOTAL: 10	174

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11667
Binary in non-binary group	2233
Poison newsgroup	355
Scoring filter (8)	167
EMP (phl)	74
Scoring filter (9)	28
Scoring filter (10)	18
EMP (fsl)	12
Scoring filter (13)	10
Scoring filter (11)	9
Attached HTML file	2
HTML post	2
Too many newsgroups	1
Scoring filter (12)	1
TOTAL: 14	14579

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	76915	8638	33359	23856	0	558	11%	24:00:01
2	panorama	9810	1382	4805	1789	3	59460	14%	23:54:45
3	poznan	21304	251	13491	19	0	0	1%	24:02:44
4	nask	7854	32	5189	4	0	0	0%	23:52:31
5	okapi	0	0	0	0	0	2394	0%	23:50:00
TOTAL: 5		115883	10303	56844	25668	3	62412	8%	119:40:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.8 GB	5.9 GB	7.7 GB	93.3 KB/s	248.0 KB	24:00:01
2	panorama	8.5 MB	563.2 MB	571.7 MB	6.8 KB/s	184.6 KB	23:54:45
3	poznan	1004.3 KB	656.7 KB	1.6 MB	0.0 KB/s	6.2 KB	24:02:44
4	nask	351.5 KB	8.5 KB	360.0 KB	0.0 KB/s	10.0 KB	23:52:31
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		1.8 GB	6.4 GB	8.2 GB	20.1 KB/s	240.4 KB	119:40:01

Backlog files shrunk by innfeed:

Server	Size
panorama	4.8 MB
TOTAL: 1	4.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	42	36.0 KB	18	0	00:00:21
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 2		297	42	36.0 KB	18	9	06:14:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	681	42	36.0 KB	18	9	00:00:27
2	unresolved	2	0	0.0 KB	0	0	06:13:50
TOTAL: 2		683	42	36.0 KB	18	9	06:14:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.73	8.170	11.023	06:13:50
web.man.lodz.pl	1.039	3.697	00:00:21
mon.man.lodz.pl	0.924	3.113	00:00:03
isvcs01.man.lodz.pl	0.051	0.102	00:00:01
david.man.lodz.pl	0.328	1.063	00:00:01
TOTAL: 5	10.512	18.998	06:14:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.73	2
TOTAL: 3	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.73	1	0
TOTAL: 1	1	0

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.os.ms-windows.winnt	24
2	pl.comp.pecet	18
TOTAL: 2		42

Daily Usenet report

Sep 5 04:15:02 -- Sep 6 04:15:02