Daily Usenet report: Sep 7 04:15:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 7 07:26:09 newsfeed nnrpd[10942]: SERVER perl filter not defined
Sep 7 09:03:43 newsfeed nnrpd[14317]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2173059	96.8%	251.0 MB	96.4%
innfeed	47270	2.1%	5.6 MB	2.2%
innd	18845	0.8%	3.3 MB	1.3%
nnrpd	5412	0.2%	592.6 KB	0.2%
TOTAL: 4	2244586	100.0%	260.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2166954	98.1%
Negative hits	25084	1.1%
Positive hits	16073	0.7%
Cache misses	119	0.0%
TOTAL: 4	2208230	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.917	0.0%	196	0.000	14.883	485.000
article cleanup	00:00:31.847	0.0%	2173108	0.006	0.015	0.029
article logging	00:01:12.732	0.1%	2173466	0.019	0.033	0.220
article parse	00:51:24.507	3.6%	95690302	0.018	0.032	0.061
article write	00:22:05.974	1.5%	2169210	0.359	0.611	3.694
artlog/artcncl	00:00:00.003	0.0%	193	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:40.039	0.1%	109054282	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.416	0.0%	173	0.000	2.405	61.000
hishave/artcncl	00:00:00.095	0.0%	196	0.000	0.485	22.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:21:09.948	1.5%	2210966	0.008	0.574	8.917
history sync	00:00:00.018	0.0%	292	0.000	0.062	1.000
history write	00:28:00.936	1.9%	2169760	0.064	0.775	11.283
hiswrite/artcncl	00:00:00.037	0.0%	23	0.000	1.609	14.000
idle	15:36:39.274	65.0%	75531915	0.485	0.744	5.030
nntp read	00:43:39.481	3.0%	94021232	0.018	0.028	0.036
overview write	00:00:14.658	0.0%	2169210	0.001	0.007	0.016
perl filter	04:55:19.945	20.5%	2169758	5.177	8.167	10.979
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:33.158	0.3%	4379267	0.043	0.062	0.176
TOTAL: 24:00:14.680	23:26:35.985	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.273	0.0%	88590	0.000	0.003	0.111
article prepare	00:00:00.030	0.0%	69242	0.000	0.000	0.125
article read	00:00:47.085	0.1%	17295	0.000	2.722	887.000
backlog stats	00:00:00.205	0.0%	278244	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	24	0.000	0.000	0.000
data read	00:00:02.195	0.0%	146714	0.000	0.015	0.087
data write	00:00:11.525	0.0%	109931	0.000	0.105	0.416
idle	23:58:40.405	99.9%	278244	36.601	310.233	1726.086
status file	00:00:14.010	0.0%	16297	0.417	0.860	40.900
TOTAL: 24:00:09.485	23:59:55.728	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:37:00.356	99.9%	22759	0.000	572.097	301277.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.117	0.0%	47045	0.000	0.024	0.895
readart	00:00:02.080	0.0%	326	0.000	6.380	7.448
TOTAL: 03:37:19.649	03:37:03.553	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	1099	2168462	2164710	335	3417	99%	259:59:42
2	news.nask.pl	2	16930	3914	12577	439	23%	25:06:07
3	news.icm.edu.pl	43	294	59	165	70	20%	16:29:26
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1147	2185688	2168685	13077	3926	99%	301:35:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	668.4 GB	17.4 MB	30.5 MB	668.5 GB	99%	323.3 KB
2	news.nask.pl	18.9 MB	1.2 MB	1.3 MB	21.4 MB	88%	5.0 KB
3	news.icm.edu.pl	4.9 MB	0.0 KB	1.9 MB	6.7 MB	72%	53.2 KB
4	localhost	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	1.5 KB
TOTAL: 4		668.5 GB	18.6 MB	33.7 MB	668.5 GB	99%	322.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 04:15:03 - 04:59:59	67356	3.1%	24.97	21.3 GB	3.2%	8295.12
Sep 7 05:00:00 - 05:59:59	82754	3.8%	22.99	27.8 GB	4.2%	8099.43
Sep 7 06:00:00 - 06:59:59	86126	4.0%	23.92	27.8 GB	4.2%	8109.59
Sep 7 07:00:00 - 07:59:59	111720	5.2%	31.03	31.8 GB	4.8%	9259.24
Sep 7 08:00:00 - 08:59:59	83112	3.8%	23.09	22.0 GB	3.3%	6419.61
Sep 7 09:00:00 - 09:59:59	104789	4.8%	29.11	29.3 GB	4.4%	8541.96
Sep 7 10:00:00 - 10:59:59	85681	4.0%	23.80	26.9 GB	4.0%	7843.69
Sep 7 11:00:00 - 11:59:59	76683	3.5%	21.30	26.1 GB	3.9%	7590.49
Sep 7 12:00:00 - 12:59:59	75504	3.5%	20.97	26.0 GB	3.9%	7574.56
Sep 7 13:00:00 - 13:59:59	82746	3.8%	22.98	26.6 GB	4.0%	7750.25
Sep 7 14:00:00 - 14:59:59	85744	4.0%	23.82	25.3 GB	3.8%	7355.59
Sep 7 15:00:00 - 15:59:59	97610	4.5%	27.11	28.1 GB	4.2%	8172.20
Sep 7 16:00:00 - 16:59:59	84736	3.9%	23.54	22.9 GB	3.4%	6664.24
Sep 7 17:00:00 - 17:59:59	127563	5.9%	35.43	32.9 GB	4.9%	9587.27
Sep 7 18:00:00 - 18:59:59	117597	5.4%	32.67	32.5 GB	4.9%	9465.89
Sep 7 19:00:00 - 19:59:59	88492	4.1%	24.58	28.6 GB	4.3%	8328.09
Sep 7 20:00:00 - 20:59:59	92305	4.3%	25.64	30.4 GB	4.5%	8848.51
Sep 7 21:00:00 - 21:59:59	123932	5.7%	34.43	35.3 GB	5.3%	10279.41
Sep 7 22:00:00 - 22:59:59	93594	4.3%	26.00	30.9 GB	4.6%	8985.88
Sep 7 23:00:00 - 23:59:59	72134	3.3%	20.04	24.0 GB	3.6%	7001.45
Sep 8 00:00:00 - 00:59:59	75661	3.5%	21.02	26.2 GB	3.9%	7637.99
Sep 8 01:00:00 - 01:59:59	73720	3.4%	20.48	25.6 GB	3.8%	7469.58
Sep 8 02:00:00 - 02:59:59	79429	3.7%	22.06	27.8 GB	4.2%	8108.39
Sep 8 03:00:00 - 03:59:59	78358	3.6%	21.77	25.9 GB	3.9%	7532.21
Sep 8 04:00:00 - 04:15:02	21263	1.0%	23.57	6.5 GB	1.0%	7567.76
TOTAL: 23:59:59	2168609	100.0%	25.10	668.6 GB	100.0%	8114.10

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3726	295	2949	2	480
2	news.nask.pl	475	10	400	13	52
3	news.icm.edu.pl	56	0	0	0	56
TOTAL: 3		4257	305	3349	15	588

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	164
uk.railway	50
it.tlc.cellulari.android	42
comp.sys.raspberry-pi	11
it.comp.os.win.windows7	6
alt.binaries.666	6
alt.sex.telephones	5
comp.mobile.ipad	4
hr.comp.os.linux.ubuntu	4
hr.rec.glazba.hifi	4
it.comp.os.win.windows8	3
de.comp.lang.vbclassic	2
hr.alt.cellular.gsm.android	1
it.discussioni.energia	1
comp.sys.mac.vintage	1
comp.mobile.android	1
TOTAL: 16	305

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

Groups	Count
pl.praca.oferowana	13
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 3	15

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	169
EMP (md5)	54
HTML post	3
Scoring filter (12)	2
TOTAL: 4	228

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56683	4106	25492	10256	0	6	7%	24:01:39
2	panorama	52494	1302	23698	464	49	63039	2%	23:59:43
3	poznan	5783	77	3643	40	0	0	1%	23:51:54
4	nask	0	0	0	0	0	12835	0%	24:00:05
5	okapi	0	0	0	0	0	2086	0%	24:00:05
TOTAL: 5		114960	5485	52833	10760	49	77966	4%	119:53:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	568.2 MB	2.5 GB	3.0 GB	36.6 KB/s	220.3 KB	24:01:39
2	panorama	4.9 MB	2.0 MB	6.9 MB	0.1 KB/s	4.0 KB	23:59:43
3	poznan	2.7 MB	271.1 KB	3.0 MB	0.0 KB/s	25.9 KB	23:51:54
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:05
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:05
TOTAL: 5		575.8 MB	2.5 GB	3.0 GB	7.4 KB/s	195.4 KB	119:53:26

Backlog files shrunk by innfeed:

Server	Size
panorama	3.8 MB
okapi	488.3 KB
TOTAL: 2	4.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-191-92-103.p.lodz.pl	5	283	1.1 MB	8	0	02:10:32
2	web.man.lodz.pl	288	38	404.2 KB	17	0	00:00:32
3	pc-212-51-218-225.p.lodz.pl	1	5	11.2 KB	3	0	00:31:18
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		296	326	1.5 MB	28	2	03:37:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	6	288	1.1 MB	11	0	02:41:51
2	*.man.lodz.pl	674	38	404.2 KB	17	2	00:00:36
3	*.toya.net.pl	139	0	0.0 KB	0	0	00:00:10
4	unresolved	6	0	0.0 KB	0	0	00:54:26
5	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:14
TOTAL: 5		829	326	1.5 MB	28	2	03:37:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-191-92-103.p.lodz.pl	0.041	0.191	02:10:32
185.48.193.235	1.288	1.573	00:54:22
pc-212-51-218-225.p.lodz.pl	0.005	0.023	00:31:18
web.man.lodz.pl	1.031	5.818	00:00:32
host227.elsnet.pl	0.023	0.062	00:00:14
staticline41435.toya.net.pl	0.595	2.673	00:00:10
123.58.137.161	0.000	0.022	00:00:03
mon.man.lodz.pl	1.034	5.614	00:00:02
david.man.lodz.pl	0.321	1.814	00:00:00
71.94.21.184	0.005	0.015	00:00:00
isvcs01.man.lodz.pl	0.009	0.063	00:00:00
TOTAL: 11	4.352	17.868	03:37:19

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	139
david.man.lodz.pl	96
185.48.193.235	4
host227.elsnet.pl	4
123.58.137.161	1
71.94.21.184	1
TOTAL: 7	533

NNRP unrecognized commands (by host):

System	Conn
123.58.137.161	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	149
2	pl.misc.kolej	139
3	pl.comp.os.ms-windows.winnt	24
4	pl.comp.pecet	10
5	pl.internet.polip	4
TOTAL: 5		326

Daily Usenet report

Sep 7 04:15:03 -- Sep 8 04:15:02