Daily Usenet report: Aug 18 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Aug 18 10:16:52 newsfeed nnrpd[9207]: SERVER perl filter not defined
Aug 18 10:26:20 newsfeed nnrpd[9540]: SERVER perl filter not defined
Aug 18 13:04:07 newsfeed nnrpd[14868]: SERVER perl filter not defined
Aug 18 13:24:47 newsfeed nnrpd[15702]: SERVER perl filter not defined
Aug 18 18:32:58 newsfeed nnrpd[26290]: SERVER perl filter not defined
Aug 18 23:42:22 newsfeed nnrpd[4713]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2367961	97.2%	270.2 MB	96.7%
innfeed	43847	1.8%	5.2 MB	1.9%
innd	19638	0.8%	3.4 MB	1.2%
nnrpd	5123	0.2%	555.5 KB	0.2%
TOTAL: 4	2436569	100.0%	279.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2362623	97.9%
Negative hits	34508	1.4%
Positive hits	16140	0.7%
Cache misses	110	0.0%
TOTAL: 4	2413381	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.188	0.0%	276	0.000	18.797	1677.000
article cleanup	00:00:34.961	0.0%	2367691	0.006	0.015	0.028
article logging	00:01:16.930	0.1%	2368008	0.017	0.032	0.405
article parse	01:04:21.753	4.5%	110745534	0.025	0.035	0.075
article write	00:25:51.697	1.8%	2364010	0.390	0.656	3.859
artlog/artcncl	00:00:00.008	0.0%	255	0.000	0.031	1.000
data move	00:05:57.655	0.4%	125789886	0.001	0.003	0.007
hisgrep/artcncl	00:00:00.320	0.0%	195	0.000	1.641	65.000
hishave/artcncl	00:00:00.174	0.0%	276	0.000	0.630	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:58.180	2.6%	2415031	0.122	0.918	12.542
history sync	00:00:00.018	0.0%	291	0.000	0.062	2.000
history write	00:48:25.576	3.4%	2364382	0.211	1.229	17.244
hiswrite/artcncl	00:00:00.116	0.0%	81	0.000	1.432	18.000
idle	13:32:27.609	56.4%	75629810	0.250	0.645	4.097
nntp read	01:08:27.605	4.8%	108798439	0.023	0.038	0.058
overview write	00:00:15.737	0.0%	2364010	0.000	0.007	0.015
perl filter	05:38:51.114	23.5%	2364382	6.992	8.599	12.324
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:39.207	0.3%	4771520	0.030	0.059	0.114
TOTAL: 24:00:12.331	23:28:13.848	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.242	0.0%	75593	0.000	0.003	0.167
article prepare	00:00:00.018	0.0%	47012	0.000	0.000	0.250
article read	00:00:38.121	0.0%	11720	0.000	3.253	1003.000
backlog stats	00:00:00.199	0.0%	235019	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:01.823	0.0%	127870	0.000	0.014	0.121
data write	00:00:09.096	0.0%	88937	0.000	0.102	1.375
idle	23:58:17.104	99.9%	235019	39.328	367.192	1687.432
status file	00:00:12.897	0.0%	15923	0.267	0.810	30.000
TOTAL: 23:59:32.186	23:59:19.500	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:46:18.028	99.7%	38604	0.000	165.217	66805.455
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.626	0.0%	78068	0.000	0.021	0.667
readart	00:00:00.685	0.0%	87	0.000	7.874	13.392
TOTAL: 01:46:34.096	01:46:20.339	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	1087	2363403	2359482	607	3314	99%	330:04:33
2	news.nask.pl	1	16663	4319	12025	319	25%	24:03:23
3	news.icm.edu.pl	43	223	27	157	39	12%	15:47:36
4	localhost	7	6	6	0	0	100%	00:00:01
TOTAL: 4		1138	2380295	2363834	12789	3672	99%	369:55:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	843.8 GB	16.5 MB	19.3 MB	843.8 GB	99%	374.5 KB
2	news.nask.pl	23.5 MB	859.0 KB	944.2 KB	25.2 MB	93%	5.6 KB
3	news.icm.edu.pl	1.4 MB	8.7 KB	2.4 MB	3.8 MB	36%	59.5 KB
4	localhost	8.9 KB	0.0 KB	0.0 KB	8.9 KB	100%	1.5 KB
TOTAL: 4		843.8 GB	17.3 MB	22.6 MB	843.9 GB	99%	373.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 18 04:15:02 - 04:59:59	76545	3.2%	28.37	27.9 GB	3.3%	10833.32
Aug 18 05:00:00 - 05:59:59	68052	2.9%	18.90	27.9 GB	3.3%	8129.72
Aug 18 06:00:00 - 06:59:59	88798	3.8%	24.67	33.0 GB	3.9%	9607.57
Aug 18 07:00:00 - 07:59:59	112735	4.8%	31.32	38.9 GB	4.6%	11329.76
Aug 18 08:00:00 - 08:59:59	107258	4.5%	29.79	38.3 GB	4.5%	11146.20
Aug 18 09:00:00 - 09:59:59	100863	4.3%	28.02	35.2 GB	4.2%	10264.29
Aug 18 10:00:00 - 10:59:59	99508	4.2%	27.64	34.6 GB	4.1%	10073.24
Aug 18 11:00:00 - 11:59:59	80121	3.4%	22.26	29.3 GB	3.5%	8539.55
Aug 18 12:00:00 - 12:59:59	78034	3.3%	21.68	32.4 GB	3.8%	9437.91
Aug 18 13:00:00 - 13:59:59	100098	4.2%	27.80	35.0 GB	4.1%	10193.75
Aug 18 14:00:00 - 14:59:59	94870	4.0%	26.35	34.1 GB	4.0%	9929.51
Aug 18 15:00:00 - 15:59:59	98604	4.2%	27.39	34.8 GB	4.1%	10128.18
Aug 18 16:00:00 - 16:59:59	110154	4.7%	30.60	34.6 GB	4.1%	10085.37
Aug 18 17:00:00 - 17:59:59	117142	5.0%	32.54	36.3 GB	4.3%	10573.48
Aug 18 18:00:00 - 18:59:59	123214	5.2%	34.23	39.1 GB	4.6%	11377.76
Aug 18 19:00:00 - 19:59:59	121238	5.1%	33.68	38.6 GB	4.6%	11249.43
Aug 18 20:00:00 - 20:59:59	110229	4.7%	30.62	37.0 GB	4.4%	10764.30
Aug 18 21:00:00 - 21:59:59	108889	4.6%	30.25	38.1 GB	4.5%	11095.88
Aug 18 22:00:00 - 22:59:59	104372	4.4%	28.99	39.3 GB	4.7%	11454.41
Aug 18 23:00:00 - 23:59:59	105206	4.5%	29.22	39.9 GB	4.7%	11620.77
Aug 19 00:00:00 - 00:59:59	70965	3.0%	19.71	27.8 GB	3.3%	8102.52
Aug 19 01:00:00 - 01:59:59	75483	3.2%	20.97	30.4 GB	3.6%	8847.86
Aug 19 02:00:00 - 02:59:59	95270	4.0%	26.46	36.7 GB	4.4%	10692.45
Aug 19 03:00:00 - 03:59:59	92818	3.9%	25.78	35.5 GB	4.2%	10337.21
Aug 19 04:00:00 - 04:15:02	23240	1.0%	25.76	8.9 GB	1.1%	10390.78
TOTAL: 24:00:00	2363706	100.0%	27.36	843.6 GB	100.0%	10237.65

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3571	177	3026	4	364
2	news.nask.pl	367	9	283	14	61
3	news.icm.edu.pl	62	0	2	0	60
TOTAL: 3		4000	186	3311	18	485

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	66
it.comp.os.win.windows10	48
comp.mobile.ipad	21
it.comp.os.win.windows7	15
it.tlc.cellulari.android	13
comp.mobile.android	6
comp.sys.raspberry-pi	4
alt.sex.telephones	4
it.discussioni.energia	4
it.comp.os.win.windows8	2
de.comm.software.mailreader	2
hr.alt.cellular.gsm.android	1
TOTAL: 12	186

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

Groups	Count
pl.praca.oferowana	9
pl.sci.matematyka	4
pl.sci.historia	4
bit.listserv.catholic	1
TOTAL: 4	18

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	101
EMP (md5)	59
Too many newsgroups	5
EMP (fsl)	2
HTML post	1
TOTAL: 5	168

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	47085	1106	23659	8621	0	6	2%	23:54:31
2	panorama	39531	989	11840	311	26	62482	2%	24:03:28
3	poznan	6257	69	4147	32	0	0	1%	23:39:10
4	nask	0	0	0	0	0	12646	0%	24:00:03
5	okapi	0	0	0	0	0	2157	0%	24:00:03
TOTAL: 5		92873	2164	39646	8964	26	77291	2%	119:37:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	poznan	5.8 MB	1.0 MB	6.9 MB	0.1 KB/s	69.6 KB	23:39:10
2	icm	4.5 MB	2.0 GB	2.0 GB	25.0 KB/s	220.9 KB	23:54:31
3	panorama	4.1 MB	1.2 MB	5.3 MB	0.1 KB/s	4.2 KB	24:03:28
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		14.5 MB	2.0 GB	2.1 GB	5.0 KB/s	194.2 KB	119:37:15

Backlog files shrunk by innfeed:

Server	Size
panorama	3.8 MB
TOTAL: 1	3.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	2	51	88.9 KB	5	0	00:24:30
2	web.man.lodz.pl	288	36	31.0 KB	18	0	00:00:30
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 3		296	87	119.9 KB	23	6	01:46:33

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	2	51	88.9 KB	5	0	00:24:30
2	*.man.lodz.pl	678	36	31.0 KB	18	6	00:00:35
3	unresolved	5	0	0.0 KB	0	0	01:21:18
4	*.shodan.io	1	0	0.0 KB	0	0	00:00:01
5	*.dsl.klmzmi.ameritech.net	1	0	0.0 KB	0	0	00:00:03
6	*.toya.net.pl	68	0	0.0 KB	0	0	00:00:04
TOTAL: 6		755	87	119.9 KB	23	6	01:46:33

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.134	2.185	2.950	01:21:00
lze200.lze.lodz.pl	0.018	0.059	00:24:30
web.man.lodz.pl	1.102	4.391	00:00:30
185.15.133.56	0.003	0.046	00:00:18
staticline41435.toya.net.pl	0.230	0.999	00:00:04
adsl-69-208-66-77.dsl.klmzmi.ameritech.net	0.007	0.011	00:00:03
mon.man.lodz.pl	0.978	4.029	00:00:03
census8.shodan.io	0.003	0.012	00:00:01
david.man.lodz.pl	0.310	1.319	00:00:00
isvcs01.man.lodz.pl	0.039	0.101	00:00:00
TOTAL: 10	4.875	13.917	01:46:33

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	68
185.15.133.56	3
185.48.193.134	2
census8.shodan.io	1
adsl-69-208-66-77.dsl.klmzmi.ameritech.net	1
TOTAL: 7	459

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.rec.kuchnia	27
2	pl.comp.os.ms-windows.winnt	26
3	pl.comp.pecet	24
4	pl.misc.telefonia.gsm	7
5	pl.rec.dom	3
TOTAL: 5		87

Daily Usenet report

Aug 18 04:15:02 -- Aug 19 04:15:02