Daily Usenet report: Jun 13 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jun 13 04:15:02 newsfeed nnrpd[21547]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 13 04:15:02 newsfeed nnrpd[21554]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 13 04:15:02 newsfeed nnrpd[21558]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 13 09:57:39 newsfeed nnrpd[1437]: SERVER perl filter not defined
Jun 13 10:02:19 newsfeed nnrpd[1559]: SERVER perl filter not defined
Jun 13 10:03:35 newsfeed nnrpd[1564]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2874636	97.8%	327.0 MB	97.4%
innd	31585	1.1%	4.7 MB	1.4%
innfeed	28592	1.0%	3.4 MB	1.0%
nnrpd	4946	0.2%	506.4 KB	0.1%
TOTAL: 4	2939759	100.0%	335.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2862722	93.0%
Negative hits	181674	5.9%
Positive hits	32579	1.1%
Cache misses	226	0.0%
TOTAL: 4	3077201	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.542	0.0%	167	0.000	27.198	832.000
article cleanup	00:00:36.457	0.0%	2872144	0.004	0.013	0.032
article logging	00:01:21.043	0.1%	2872832	0.015	0.028	0.548
article parse	01:07:43.790	4.7%	217194124	0.008	0.019	0.067
article write	00:25:00.594	1.7%	2852459	0.312	0.526	3.757
artlog/artcncl	00:00:00.004	0.0%	165	0.000	0.024	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:32.982	0.2%	234972962	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.288	0.0%	150	0.000	1.920	52.500
hishave/artcncl	00:00:00.022	0.0%	167	0.000	0.132	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:33.982	1.6%	3078323	0.025	0.440	12.968
history sync	00:00:00.017	0.0%	294	0.000	0.058	1.000
history write	00:33:31.375	2.3%	2863807	0.077	0.702	22.063
hiswrite/artcncl	00:00:00.006	0.0%	17	0.000	0.353	3.000
idle	13:58:43.842	58.3%	170879424	0.104	0.294	0.638
nntp read	01:03:08.099	4.4%	214989925	0.010	0.018	0.032
overview write	00:00:14.572	0.0%	2852459	0.001	0.005	0.014
perl filter	05:32:49.318	23.1%	2863807	4.704	6.973	9.018
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:39.708	0.3%	5776611	0.031	0.048	0.090
TOTAL: 23:59:16.090	23:13:00.641	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.309	0.0%	116505	0.000	0.003	0.100
article prepare	00:00:00.039	0.0%	135066	0.000	0.000	0.250
article read	00:00:52.208	0.1%	33598	0.000	1.554	480.000
backlog stats	00:00:00.315	0.0%	425892	0.000	0.001	0.039
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:02.611	0.0%	221694	0.000	0.012	0.074
data write	00:00:18.292	0.0%	205209	0.000	0.089	0.250
idle	23:59:00.723	99.9%	425892	15.997	202.729	2002.333
status file	00:00:06.330	0.0%	9356	0.000	0.677	3.667
TOTAL: 24:00:33.340	24:00:20.827	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:10:57.881	99.9%	6725	0.000	1703.774	336381.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.310	0.0%	14396	0.000	0.022	0.667
readart	00:00:00.670	0.0%	180	0.000	3.722	11.500
TOTAL: 03:11:13.436	03:10:58.861	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	682	2857002	2830884	15098	11020	99%	234:20:25
2	news.nask.pl	2	40616	22904	9039	8673	56%	23:58:52
3	news.icm.edu.pl	39	229	22	201	6	9%	15:49:25
4	localhost	4	3	3	0	0	100%	00:00:01
TOTAL: 4		727	2897850	2853813	24338	19699	98%	274:08:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	820.4 GB	697.9 MB	1.1 GB	822.1 GB	99%	303.3 KB
2	news.nask.pl	2.5 GB	30.3 MB	294.2 MB	2.9 GB	88%	95.1 KB
3	news.icm.edu.pl	482.4 KB	24.8 KB	146.3 KB	653.5 KB	73%	23.3 KB
4	localhost	5.3 KB	0.0 KB	0.0 KB	5.3 KB	100%	1.8 KB
TOTAL: 4		822.9 GB	728.2 MB	1.3 GB	825.0 GB	99%	301.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 13 04:15:02 - 04:59:59	90564	3.2%	33.57	27.0 GB	3.3%	10512.56
Jun 13 05:00:00 - 05:59:59	119141	4.2%	33.09	35.9 GB	4.4%	10443.47
Jun 13 06:00:00 - 06:59:59	118093	4.1%	32.80	35.0 GB	4.3%	10200.37
Jun 13 07:00:00 - 07:59:59	130871	4.6%	36.35	37.2 GB	4.5%	10829.17
Jun 13 08:00:00 - 08:59:59	127096	4.5%	35.30	35.6 GB	4.3%	10359.72
Jun 13 09:00:00 - 09:59:59	113162	4.0%	31.43	32.0 GB	3.9%	9325.89
Jun 13 10:00:00 - 10:59:59	92636	3.2%	25.73	26.3 GB	3.2%	7649.98
Jun 13 11:00:00 - 11:59:59	106886	3.7%	29.69	31.1 GB	3.8%	9061.08
Jun 13 12:00:00 - 12:59:59	102391	3.6%	28.44	28.6 GB	3.5%	8332.84
Jun 13 13:00:00 - 13:59:59	128231	4.5%	35.62	37.6 GB	4.6%	10958.85
Jun 13 14:00:00 - 14:59:59	125873	4.4%	34.96	36.7 GB	4.5%	10680.63
Jun 13 15:00:00 - 15:59:59	110702	3.9%	30.75	33.8 GB	4.1%	9855.96
Jun 13 16:00:00 - 16:59:59	110907	3.9%	30.81	33.4 GB	4.1%	9724.09
Jun 13 17:00:00 - 17:59:59	105573	3.7%	29.33	30.8 GB	3.7%	8970.33
Jun 13 18:00:00 - 18:59:59	110891	3.9%	30.80	32.2 GB	3.9%	9375.13
Jun 13 19:00:00 - 19:59:59	120276	4.2%	33.41	33.2 GB	4.0%	9673.75
Jun 13 20:00:00 - 20:59:59	101526	3.6%	28.20	30.9 GB	3.8%	9014.54
Jun 13 21:00:00 - 21:59:59	135563	4.7%	37.66	38.3 GB	4.7%	11157.22
Jun 13 22:00:00 - 22:59:59	136597	4.8%	37.94	37.1 GB	4.5%	10809.26
Jun 13 23:00:00 - 23:59:59	130045	4.6%	36.12	37.5 GB	4.6%	10916.77
Jun 14 00:00:00 - 00:59:59	107009	3.7%	29.72	30.5 GB	3.7%	8878.76
Jun 14 01:00:00 - 01:59:59	139337	4.9%	38.70	38.3 GB	4.7%	11142.31
Jun 14 02:00:00 - 02:59:59	136880	4.8%	38.02	39.0 GB	4.7%	11363.48
Jun 14 03:00:00 - 03:59:59	122052	4.3%	33.90	35.2 GB	4.3%	10252.34
Jun 14 04:00:00 - 04:15:02	31791	1.1%	35.25	9.4 GB	1.1%	10890.71
TOTAL: 24:00:00	2854093	100.0%	33.03	822.5 GB	100.0%	9982.64

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	11436	152	7830	0	3454
2	news.nask.pl	8937	32	510	3	8392
3	news.icm.edu.pl	5	0	2	0	3
TOTAL: 3		20378	184	8342	3	11849

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	59
uk.railway	56
comp.mobile.ipad	20
it.tlc.cellulari.android	17
comp.sys.raspberry-pi	17
uk.rec.cycling.moderated	7
comp.mobile.android	4
it.comp.os.win.windows7	2
it.comp.os.linux.ubuntu	1
de.comp.lang.vbclassic	1
TOTAL: 10	184

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

Groups	Count
bit.listserv.catholic	1
pl.praca.oferowana	1
pl.sci.matematyka	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7427
Binary in non-binary group	2960
Poison newsgroup	198
Scoring filter (8)	189
PFM bot misc	107
EMP (phl)	88
Scoring filter (9)	47
Scoring filter (10)	43
Scoring filter (12)	29
Scoring filter (11)	25
Scoring filter (13)	16
Scoring filter (14)	11
Scoring filter (15)	8
Scoring filter (17)	7
Scoring filter (16)	2
HTML post	1
Scoring filter (20)	1
Scoring filter (18)	1
Angle-bracket bot	1
TOTAL: 19	11161

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	76575	25612	35517	3888	1	162	33%	24:00:38
2	panorama	51943	1523	34395	499	34	79661	2%	23:59:28
3	poznan	29583	998	16744	81	0	0	3%	23:58:17
4	nask	16922	525	11660	5	0	0	3%	24:08:13
5	okapi	0	0	0	0	0	2472	0%	24:00:01
TOTAL: 5		175023	28658	98316	4473	35	82295	16%	120:06:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.5 GB	712.8 MB	5.2 GB	63.0 KB/s	184.7 KB	24:00:38
2	poznan	199.9 MB	9.1 MB	209.0 MB	2.5 KB/s	198.4 KB	23:58:17
3	nask	124.0 MB	514.2 KB	124.5 MB	1.5 KB/s	240.5 KB	24:08:13
4	panorama	14.6 MB	1.6 MB	16.3 MB	0.2 KB/s	8.2 KB	23:59:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		4.8 GB	724.1 MB	5.5 GB	13.4 KB/s	175.3 KB	120:06:37

Backlog files shrunk by innfeed:

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

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-191-92-103.p.lodz.pl	17	108	228.6 KB	9	0	02:21:56
2	web.man.lodz.pl	292	48	46.8 KB	19	0	00:00:22
3	pc-212-51-218-225.p.lodz.pl	2	24	59.1 KB	2	0	00:38:02
4	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 4		314	180	334.4 KB	30	3	03:11:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	19	132	287.7 KB	11	0	02:59:58
2	*.man.lodz.pl	679	48	46.8 KB	19	3	00:00:27
3	unresolved	6	0	0.0 KB	0	0	00:10:34
4	*.elsnet.pl	3	0	0.0 KB	0	0	00:00:12
TOTAL: 4		707	180	334.4 KB	30	3	03:11:13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-191-92-103.p.lodz.pl	0.058	0.259	02:21:56
pc-212-51-218-225.p.lodz.pl	0.010	0.026	00:38:02
185.48.195.188	0.200	0.312	00:10:13
web.man.lodz.pl	0.972	4.389	00:00:22
host227.elsnet.pl	0.007	0.048	00:00:12
212.51.203.199	0.010	0.024	00:00:11
89.38.41.146	0.000	0.014	00:00:10
mon.man.lodz.pl	0.852	3.436	00:00:03
david.man.lodz.pl	0.247	1.255	00:00:01
isvcs01.man.lodz.pl	0.008	0.049	00:00:00
TOTAL: 10	2.364	9.812	03:11:13

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	3
212.51.203.199	2
185.48.195.188	2
89.38.41.146	2
TOTAL: 6	393

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	1	0
pc-212-191-92-103.p.lodz.pl	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	125
2	pl.comp.os.ms-windows.winnt	40
3	pl.comp.pecet	8
4	pl.misc.kolej	7
TOTAL: 4		180

Daily Usenet report

Jun 13 04:15:02 -- Jun 14 04:15:02