Daily Usenet report: Sep 4 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Sep 4 07:11:15 newsfeed nnrpd[20978]: SERVER perl filter not defined
Sep 4 07:57:40 newsfeed nnrpd[22450]: SERVER perl filter not defined
Sep 4 09:37:19 newsfeed nnrpd[25908]: SERVER perl filter not defined
Sep 4 11:50:04 newsfeed pgpverify[30603]: pgpverify: verification failed
Sep 4 14:43:54 newsfeed nnrpd[4139]: SERVER perl filter not defined
Sep 4 14:59:35 newsfeed nnrpd[4740]: SERVER perl filter not defined
Sep 4 15:00:42 newsfeed nnrpd[4832]: SERVER perl filter not defined
Sep 4 15:10:44 newsfeed nnrpd[5177]: SERVER perl filter not defined
Sep 4 15:44:26 newsfeed nnrpd[6233]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2733657	97.3%	318.6 MB	96.9%
innfeed	48195	1.7%	5.7 MB	1.7%
innd	22638	0.8%	3.9 MB	1.2%
nnrpd	5341	0.2%	583.0 KB	0.2%
controlchan	3	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2809835	100.0%	328.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2727577	98.1%
Negative hits	37136	1.3%
Positive hits	16590	0.6%
Cache misses	114	0.0%
TOTAL: 4	2781417	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.251	0.0%	200	0.000	11.255	385.000
article cleanup	00:00:39.649	0.0%	2733658	0.006	0.015	0.029
article logging	00:01:26.596	0.1%	2734058	0.018	0.032	0.149
article parse	01:03:52.549	4.4%	114252262	0.016	0.034	0.080
article write	00:27:02.415	1.9%	2729400	0.338	0.594	2.841
artlog/artcncl	00:00:00.003	0.0%	200	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:08.847	0.1%	130684399	0.001	0.001	0.008
hisgrep/artcncl	00:00:00.565	0.0%	186	0.000	3.038	269.000
hishave/artcncl	00:00:00.053	0.0%	200	0.000	0.265	14.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:43.469	1.3%	2783507	0.052	0.404	9.351
history sync	00:00:00.014	0.0%	297	0.000	0.047	2.000
history write	00:31:57.924	2.2%	2729761	0.159	0.703	14.095
hiswrite/artcncl	00:00:00.017	0.0%	14	0.000	1.214	7.000
idle	13:53:54.201	57.9%	83376286	0.280	0.600	2.032
nntp read	00:51:09.709	3.6%	112075168	0.016	0.027	0.038
overview write	00:00:17.774	0.0%	2729400	0.001	0.007	0.013
perl filter	06:07:16.855	25.5%	2729761	5.391	8.073	11.070
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:36.243	0.4%	5505413	0.030	0.061	0.123
TOTAL: 24:00:16.545	23:24:09.134	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.310	0.0%	112588	0.000	0.003	0.113
article prepare	00:00:00.010	0.0%	20166	0.000	0.000	0.050
article read	00:00:33.421	0.0%	5021	0.000	6.656	1288.000
backlog stats	00:00:00.201	0.0%	251143	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	14	0.000	0.000	0.000
data read	00:00:01.988	0.0%	138056	0.000	0.014	0.083
data write	00:00:03.802	0.0%	93379	0.000	0.041	0.384
idle	23:58:48.588	99.9%	251143	49.526	343.743	1377.409
status file	00:00:13.559	0.0%	16257	0.333	0.834	7.917
TOTAL: 23:59:55.746	23:59:41.879	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	02:32:29.751	99.8%	48813	0.000	187.445	90988.714
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.242	0.0%	98678	0.000	0.023	0.857
readart	00:00:00.868	0.0%	146	0.000	5.945	54.571
TOTAL: 02:32:51.268	02:32:32.861	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
admin@openwatcom.news-admin.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1372	2728444	2723759	737	3948	99%	307:11:22
2	news.nask.pl	2	16634	4826	11540	268	29%	23:36:41
3	news.icm.edu.pl	30	302	58	204	40	19%	16:40:36
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1413	2745388	2728651	12481	4256	99%	347:28:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	825.8 GB	23.7 MB	32.1 MB	825.9 GB	99%	317.5 KB
2	news.nask.pl	26.5 MB	720.4 KB	783.8 KB	28.0 MB	94%	5.6 KB
3	news.icm.edu.pl	5.1 MB	8.5 KB	864.9 KB	6.0 MB	85%	62.7 KB
4	localhost	11.5 KB	0.0 KB	0.0 KB	11.5 KB	100%	1.4 KB
TOTAL: 4		825.8 GB	24.5 MB	33.7 MB	825.9 GB	99%	316.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 04:15:03 - 04:59:59	83381	3.1%	30.92	25.1 GB	3.0%	9757.40
Sep 4 05:00:00 - 05:59:59	90645	3.3%	25.18	31.4 GB	3.8%	9159.04
Sep 4 06:00:00 - 06:59:59	122943	4.5%	34.15	36.2 GB	4.4%	10545.79
Sep 4 07:00:00 - 07:59:59	109985	4.0%	30.55	33.8 GB	4.1%	9850.27
Sep 4 08:00:00 - 08:59:59	117658	4.3%	32.68	35.7 GB	4.3%	10412.85
Sep 4 09:00:00 - 09:59:59	131946	4.8%	36.65	35.2 GB	4.3%	10251.76
Sep 4 10:00:00 - 10:59:59	96734	3.5%	26.87	25.4 GB	3.1%	7405.19
Sep 4 11:00:00 - 11:59:59	116064	4.3%	32.24	33.4 GB	4.1%	9742.70
Sep 4 12:00:00 - 12:59:59	123014	4.5%	34.17	35.1 GB	4.3%	10222.55
Sep 4 13:00:00 - 13:59:59	108249	4.0%	30.07	32.9 GB	4.0%	9588.73
Sep 4 14:00:00 - 14:59:59	105437	3.9%	29.29	33.5 GB	4.1%	9761.61
Sep 4 15:00:00 - 15:59:59	115578	4.2%	32.10	35.3 GB	4.3%	10280.20
Sep 4 16:00:00 - 16:59:59	112421	4.1%	31.23	34.8 GB	4.2%	10123.39
Sep 4 17:00:00 - 17:59:59	107032	3.9%	29.73	32.2 GB	3.9%	9385.83
Sep 4 18:00:00 - 18:59:59	132486	4.9%	36.80	36.8 GB	4.5%	10720.86
Sep 4 19:00:00 - 19:59:59	130584	4.8%	36.27	38.2 GB	4.6%	11127.74
Sep 4 20:00:00 - 20:59:59	112116	4.1%	31.14	34.4 GB	4.2%	10021.51
Sep 4 21:00:00 - 21:59:59	108964	4.0%	30.27	35.8 GB	4.3%	10429.37
Sep 4 22:00:00 - 22:59:59	113795	4.2%	31.61	37.7 GB	4.6%	10981.29
Sep 4 23:00:00 - 23:59:59	106959	3.9%	29.71	35.8 GB	4.3%	10441.48
Sep 5 00:00:00 - 00:59:59	91292	3.3%	25.36	30.8 GB	3.7%	8973.33
Sep 5 01:00:00 - 01:59:59	104238	3.8%	28.95	31.7 GB	3.8%	9220.28
Sep 5 02:00:00 - 02:59:59	134140	4.9%	37.26	39.4 GB	4.8%	11484.94
Sep 5 03:00:00 - 03:59:59	123881	4.5%	34.41	35.0 GB	4.2%	10201.27
Sep 5 04:00:00 - 04:15:03	29258	1.1%	32.40	8.8 GB	1.1%	10256.50
TOTAL: 24:00:00	2728800	100.0%	31.58	824.7 GB	100.0%	10008.94

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4273	130	3648	0	495
2	news.nask.pl	342	4	247	14	77
3	news.icm.edu.pl	42	0	1	0	41
TOTAL: 3		4657	134	3896	14	613

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	47
it.tlc.cellulari.android	40
it.comp.os.win.windows10	22
comp.sys.raspberry-pi	4
it.comp.os.win.windows7	4
uk.rec.cycling.moderated	4
it.comp.os.win.windows8	3
comp.mobile.android	3
hr.alt.cellular.gsm.iphone	2
hr.alt.cellular.gsm.android	1
it.discussioni.energia	1
de.comp.lang.vbclassic	1
hr.rec.glazba.hifi	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 14	134

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	153
EMP (md5)	41
EMP (fsl)	14
Attached HTML file	2
HTML post	2
Too many newsgroups	1
TOTAL: 6	213

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	70754	1596	41570	621	33	68742	2%	23:59:47
2	icm	42521	1080	25635	777	0	20	2%	24:06:53
3	poznan	7704	63	4848	24	0	0	0%	24:01:47
4	okapi	0	0	0	0	0	2259	0%	24:00:00
5	nask	0	0	0	0	0	12259	0%	24:00:00
TOTAL: 5		120979	2739	72053	1422	33	83280	2%	120:08:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.1 MB	240.5 MB	254.6 MB	3.0 KB/s	140.4 KB	24:06:53
2	panorama	6.7 MB	2.4 MB	9.1 MB	0.1 KB/s	4.2 KB	23:59:47
3	poznan	4.1 MB	247.5 KB	4.4 MB	0.1 KB/s	51.7 KB	24:01:47
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		25.0 MB	243.1 MB	268.1 MB	0.6 KB/s	66.0 KB	120:08:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	7	68	136.1 KB	8	0	00:51:13
2	web.man.lodz.pl	288	40	181.5 KB	19	0	00:00:35
3	auriga.rz.izeto.pl	3	38	60.7 KB	2	0	00:00:04
4	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 4		306	146	378.2 KB	29	8	02:32:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	7	68	136.1 KB	8	0	00:51:13
2	*.man.lodz.pl	680	40	181.5 KB	19	8	00:00:40
3	*.rz.izeto.pl	3	38	60.7 KB	2	0	00:00:04
4	*.dsl.klmzmi.ameritech.net	1	0	0.0 KB	0	0	00:00:03
5	*.shodan.io	1	0	0.0 KB	0	0	00:00:00
6	unresolved	4	0	0.0 KB	0	0	01:40:00
7	*.elsnet.pl	5	0	0.0 KB	0	0	00:00:42
8	*.toya.net.pl	102	0	0.0 KB	0	0	00:00:06
TOTAL: 8		803	146	378.2 KB	29	8	02:32:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.235	2.881	4.147	01:39:59
lze200.lze.lodz.pl	0.027	0.122	00:51:13
host227.elsnet.pl	0.012	0.090	00:00:42
web.man.lodz.pl	1.078	5.696	00:00:35
staticline41435.toya.net.pl	0.336	2.020	00:00:06
auriga.rz.izeto.pl	0.012	0.071	00:00:04
ppp-69-214-2-96.dsl.klmzmi.ameritech.net	0.000	0.022	00:00:03
mon.man.lodz.pl	1.058	5.534	00:00:02
david.man.lodz.pl	0.257	1.818	00:00:00
isvcs01.man.lodz.pl	0.057	0.169	00:00:00
94.31.70.74	0.040	0.046	00:00:00
census2.shodan.io	0.005	0.019	00:00:00
TOTAL: 12	5.763	19.754	02:32:50

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	102
david.man.lodz.pl	96
host227.elsnet.pl	5
185.48.193.235	2
94.31.70.74	2
ppp-69-214-2-96.dsl.klmzmi.ameritech.net	1
census2.shodan.io	1
TOTAL: 8	497

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
185.48.193.235	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	108	74.0%
2	lodman	38	26.0%
TOTAL: 2		146	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	70
2	lodman.info	38
3	pl.rec.dom	22
4	pl.comp.os.ms-windows.winnt	8
5	pl.news.admin	4
6	pl.internet.polip	2
7	pl.misc.telefonia.gsm	2
TOTAL: 7		146

Daily Usenet report

Sep 4 04:15:03 -- Sep 5 04:15:03