Daily Usenet report: Sep 8 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 8 04:15:01 newsfeed nnrpd[28992]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 8 04:15:01 newsfeed nnrpd[28995]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 8 12:58:55 newsfeed nnrpd[15704]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2830761	98.4%	334.2 MB	98.1%
innd	37804	1.3%	5.2 MB	1.5%
nnrpd	4826	0.2%	505.9 KB	0.1%
innfeed	4579	0.2%	656.8 KB	0.2%
TOTAL: 4	2877970	100.0%	340.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2824537	95.8%
Negative hits	106951	3.6%
Positive hits	16604	0.6%
Cache misses	110	0.0%
TOTAL: 4	2948202	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.263	0.0%	190	0.000	11.911	228.500
article cleanup	00:00:42.546	0.0%	2830660	0.006	0.015	0.031
article logging	00:01:32.299	0.1%	2831231	0.015	0.033	1.258
article parse	01:17:14.987	5.4%	215623182	0.008	0.021	0.057
article write	00:32:34.801	2.3%	2811199	0.337	0.695	4.319
artlog/artcncl	00:00:00.007	0.0%	185	0.000	0.038	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:39.544	0.2%	233394216	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.124	0.0%	171	0.000	0.725	19.000
hishave/artcncl	00:00:00.085	0.0%	190	0.000	0.447	19.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:26:12.662	1.8%	2950281	0.082	0.533	8.379
history sync	00:00:00.014	0.0%	295	0.000	0.047	1.000
history write	00:37:53.005	2.6%	2826657	0.169	0.804	9.102
hiswrite/artcncl	00:00:00.111	0.0%	19	0.000	5.842	103.000
idle	13:15:44.545	55.2%	155846158	0.217	0.306	1.797
nntp read	01:11:14.375	4.9%	213362088	0.009	0.020	0.041
overview write	00:00:15.782	0.0%	2811199	0.000	0.006	0.015
perl filter	05:43:31.426	23.9%	2826657	4.231	7.292	9.871
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:48.875	0.3%	5670829	0.030	0.051	0.090
TOTAL: 24:00:19.778	23:14:27.451	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.189	0.0%	48473	0.000	0.004	0.150
article prepare	00:00:00.031	0.0%	112764	0.000	0.000	0.042
article read	00:00:08.631	0.0%	27997	0.000	0.308	17.820
backlog stats	00:00:00.308	0.0%	332555	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.141	0.0%	164662	0.000	0.013	0.077
data write	00:00:17.239	0.0%	165241	0.000	0.104	1.059
idle	23:59:46.834	100.0%	332555	20.384	259.767	3774.875
status file	00:00:01.298	0.0%	1626	0.000	0.798	8.000
TOTAL: 24:00:24.769	24:00:16.671	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:57:27.576	99.8%	19213	0.000	366.813	187020.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.808	0.0%	39256	0.000	0.021	1.000
readart	00:00:02.733	0.0%	120	0.000	22.775	49.333
TOTAL: 01:57:43.963	01:57:31.117	99.8%	-	-	-	-

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	1279	2812583	2798375	8969	5239	99%	361:56:13
2	news.nask.pl	2	29693	12234	3270	14189	41%	23:48:23
3	news.icm.edu.pl	36	310	14	286	10	4%	17:21:56
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1319	2842587	2810624	12525	19438	98%	403:06:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	841.6 GB	285.5 MB	535.9 MB	842.4 GB	99%	315.1 KB
2	news.nask.pl	61.3 MB	657.9 KB	192.2 MB	254.2 MB	24%	9.9 KB
3	news.icm.edu.pl	792.7 KB	46.2 KB	301.3 KB	1.1 MB	69%	47.5 KB
4	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 4		841.7 GB	286.2 MB	728.4 MB	842.7 GB	99%	312.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 8 04:15:01 - 04:59:59	79047	2.8%	29.29	25.9 GB	3.1%	10081.55
Sep 8 05:00:00 - 05:59:59	107406	3.8%	29.84	34.9 GB	4.2%	10159.96
Sep 8 06:00:00 - 06:59:59	109111	3.9%	30.31	35.6 GB	4.2%	10371.36
Sep 8 07:00:00 - 07:59:59	114603	4.1%	31.83	37.1 GB	4.4%	10816.28
Sep 8 08:00:00 - 08:59:59	114746	4.1%	31.87	37.8 GB	4.5%	11008.94
Sep 8 09:00:00 - 09:59:59	112239	4.0%	31.18	36.0 GB	4.3%	10475.74
Sep 8 10:00:00 - 10:59:59	129910	4.6%	36.09	36.6 GB	4.4%	10653.23
Sep 8 11:00:00 - 11:59:59	128189	4.6%	35.61	36.5 GB	4.3%	10621.14
Sep 8 12:00:00 - 12:59:59	124294	4.4%	34.53	36.2 GB	4.3%	10533.56
Sep 8 13:00:00 - 13:59:59	114950	4.1%	31.93	35.4 GB	4.2%	10321.77
Sep 8 14:00:00 - 14:59:59	113249	4.0%	31.46	36.2 GB	4.3%	10556.00
Sep 8 15:00:00 - 15:59:59	103009	3.7%	28.61	34.0 GB	4.0%	9895.45
Sep 8 16:00:00 - 16:59:59	110429	3.9%	30.67	33.4 GB	4.0%	9720.02
Sep 8 17:00:00 - 17:59:59	120055	4.3%	33.35	34.8 GB	4.1%	10142.07
Sep 8 18:00:00 - 18:59:59	132593	4.7%	36.83	36.9 GB	4.4%	10738.36
Sep 8 19:00:00 - 19:59:59	119431	4.2%	33.18	35.7 GB	4.3%	10407.46
Sep 8 20:00:00 - 20:59:59	124704	4.4%	34.64	37.0 GB	4.4%	10773.50
Sep 8 21:00:00 - 21:59:59	135605	4.8%	37.67	37.9 GB	4.5%	11041.25
Sep 8 22:00:00 - 22:59:59	154701	5.5%	42.97	38.8 GB	4.6%	11299.41
Sep 8 23:00:00 - 23:59:59	143070	5.1%	39.74	36.9 GB	4.4%	10759.59
Sep 9 00:00:00 - 00:59:59	110906	3.9%	30.81	30.1 GB	3.6%	8773.85
Sep 9 01:00:00 - 01:59:59	100894	3.6%	28.03	28.0 GB	3.3%	8162.26
Sep 9 02:00:00 - 02:59:59	101040	3.6%	28.07	32.5 GB	3.9%	9469.25
Sep 9 03:00:00 - 03:59:59	84739	3.0%	23.54	28.1 GB	3.4%	8194.86
Sep 9 04:00:00 - 04:15:02	21628	0.8%	23.98	7.3 GB	0.9%	8485.18
TOTAL: 24:00:01	2810548	100.0%	32.53	839.7 GB	100.0%	10190.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14412	26	182	5	14199
2	newsfeed.pionier.net.pl	5606	136	3815	0	1655
3	news.icm.edu.pl	10	0	2	0	8
TOTAL: 3		20028	162	3999	5	15862

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	98
it.tlc.cellulari.android	22
comp.mobile.android	11
comp.sys.raspberry-pi	6
alt.windows7.general	6
it.comp.os.win.windows10	5
it.comp.os.win.windows7	4
de.comm.software.mailreader	4
de.comp.lang.php	3
hr.alt.cellular.gsm.android	1
it.comp.os.win.windows8	1
uk.rec.cycling.moderated	1
TOTAL: 12	162

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

Groups	Count
pl.sci.historia	2
bit.listserv.catholic	1
newsguy.general	1
pl.praca.oferowana	1
TOTAL: 4	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13490
Binary in non-binary group	1499
Scoring filter (8)	118
EMP (phl)	88
Scoring filter (9)	48
Scoring filter (10)	20
Scoring filter (13)	9
Scoring filter (12)	6
HTML post	4
Scoring filter (11)	3
Scoring filter (20)	3
Scoring filter (24)	1
Scoring filter (14)	1
EMP (fsl)	1
TOTAL: 14	15291

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	27108	2817	20903	764	4	24157	10%	24:00:00
2	icm	56588	454	23682	23657	0	0	0%	23:59:05
3	poznan	19168	228	12053	21	0	0	1%	23:57:19
4	nask	6638	28	4489	28	0	0	0%	23:58:29
5	okapi	0	0	0	0	0	2401	0%	24:00:02
TOTAL: 5		109502	3527	61127	24470	4	26558	3%	119:54:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	13.3 MB	5.4 MB	18.7 MB	0.2 KB/s	5.4 KB	24:00:00
2	icm	4.1 MB	5.7 GB	5.7 GB	69.5 KB/s	248.8 KB	23:59:05
3	poznan	915.8 KB	70.4 KB	986.2 KB	0.0 KB/s	4.0 KB	23:57:19
4	nask	255.0 KB	16.5 MB	16.7 MB	0.2 KB/s	305.8 KB	23:58:29
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		18.5 MB	5.7 GB	5.8 GB	14.0 KB/s	215.6 KB	119:54:55

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	6	80	147.3 KB	8	0	00:59:02
2	web.man.lodz.pl	291	40	39.9 KB	17	0	00:00:21
3	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		298	120	187.2 KB	25	1	01:57:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	6	80	147.3 KB	8	0	00:59:02
2	*.man.lodz.pl	676	40	39.9 KB	17	1	00:00:25
3	unresolved	2	0	0.0 KB	0	0	00:58:15
TOTAL: 3		684	120	187.2 KB	25	1	01:57:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.032	0.098	00:59:02
185.48.193.73	0.904	1.287	00:58:15
web.man.lodz.pl	1.079	4.099	00:00:21
mon.man.lodz.pl	0.972	3.595	00:00:03
david.man.lodz.pl	0.283	1.108	00:00:01
isvcs01.man.lodz.pl	0.005	0.016	00:00:00
TOTAL: 6	3.275	10.203	01:57:43

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.telefonia.gsm	37
2	pl.comp.pecet	28
3	pl.comp.os.ms-windows.winnt	28
4	pl.rec.kuchnia	19
5	pl.rec.dom	4
6	pl.comp.os.linux	3
7	pl.internet.polip	1
TOTAL: 7		120

Daily Usenet report

Sep 8 04:15:01 -- Sep 9 04:15:02