Daily Usenet report: Nov 29 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 29 20:00:54 newsfeed nnrpd[15469]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2051165	98.2%	233.6 MB	97.9%
innd	28427	1.4%	3.9 MB	1.6%
nnrpd	4832	0.2%	492.8 KB	0.2%
innfeed	4820	0.2%	668.1 KB	0.3%
TOTAL: 4	2089244	100.0%	238.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2044002	96.2%
Negative hits	67023	3.2%
Positive hits	13959	0.7%
Cache misses	58	0.0%
TOTAL: 4	2125042	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.865	0.0%	174	0.000	10.718	310.000
article cleanup	00:00:29.759	0.0%	2049305	0.005	0.015	0.037
article logging	00:01:03.641	0.1%	2050001	0.015	0.031	0.682
article parse	00:53:51.066	3.7%	153014832	0.010	0.021	0.065
article write	00:20:24.296	1.4%	2031060	0.318	0.603	4.564
artlog/artcncl	00:00:00.002	0.0%	146	0.000	0.014	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:56.186	0.1%	166013585	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.045	0.0%	152	0.000	0.296	21.000
hishave/artcncl	00:00:00.013	0.0%	174	0.000	0.075	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:28.026	1.9%	2125454	0.131	0.775	8.344
history sync	00:00:00.019	0.0%	295	0.000	0.064	1.000
history write	00:39:55.350	2.8%	2044523	0.212	1.172	10.837
hiswrite/artcncl	00:00:00.004	0.0%	22	0.000	0.182	2.000
idle	15:41:31.413	65.4%	132784165	0.173	0.425	2.353
nntp read	00:53:42.195	3.7%	151463407	0.013	0.021	0.036
overview write	00:00:11.643	0.0%	2031060	0.000	0.006	0.015
perl filter	04:12:29.729	17.5%	2044523	5.151	7.410	10.037
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:34.752	0.2%	4101155	0.033	0.052	0.081
TOTAL: 23:59:16.676	23:16:40.004	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.156	0.0%	49306	0.000	0.003	0.167
article prepare	00:00:00.020	0.0%	68772	0.000	0.000	0.125
article read	00:00:38.990	0.0%	17200	0.000	2.267	222.333
backlog stats	00:00:00.239	0.0%	271267	0.000	0.001	0.038
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:01.774	0.0%	133600	0.000	0.013	0.125
data write	00:00:11.678	0.0%	132422	0.000	0.088	0.273
idle	23:59:24.245	99.9%	271267	18.792	318.374	8891.286
status file	00:00:00.685	0.0%	913	0.000	0.750	2.000
TOTAL: 24:00:24.608	24:00:17.787	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	10:24:49.744	99.9%	293651	0.000	127.668	600084.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:11.110	0.0%	588168	0.000	0.019	0.500
readart	00:00:00.677	0.0%	90	0.000	7.522	50.308
TOTAL: 10:25:29.524	10:25:01.531	99.9%	-	-	-	-

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	386	2028911	2021135	3084	4692	99%	176:52:44
2	news.nask.pl	2	29992	10715	5712	13565	35%	24:05:51
3	news.icm.edu.pl	50	273	14	256	3	5%	17:12:40
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		440	2059177	2031865	9052	18260	98%	218:11:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	615.0 GB	20.8 MB	43.0 MB	615.1 GB	99%	318.4 KB
2	news.nask.pl	49.0 MB	1.5 MB	149.9 MB	200.3 MB	24%	8.4 KB
3	news.icm.edu.pl	301.6 KB	18.8 KB	45.2 KB	365.6 KB	82%	21.5 KB
4	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 4		615.1 GB	22.4 MB	192.9 MB	615.3 GB	99%	314.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 29 04:15:02 - 04:59:59	85071	4.2%	31.53	23.4 GB	3.8%	9100.32
Nov 29 05:00:00 - 05:59:59	95943	4.7%	26.65	27.0 GB	4.4%	7857.19
Nov 29 06:00:00 - 06:59:59	53059	2.6%	14.74	18.7 GB	3.0%	5439.14
Nov 29 07:00:00 - 07:59:59	44965	2.2%	12.49	17.4 GB	2.8%	5077.74
Nov 29 08:00:00 - 08:59:59	44168	2.2%	12.27	18.2 GB	3.0%	5286.87
Nov 29 09:00:00 - 09:59:59	43087	2.1%	11.97	18.0 GB	2.9%	5253.51
Nov 29 10:00:00 - 10:59:59	44960	2.2%	12.49	17.5 GB	2.8%	5097.08
Nov 29 11:00:00 - 11:59:59	41933	2.1%	11.65	15.6 GB	2.5%	4556.19
Nov 29 12:00:00 - 12:59:59	98635	4.9%	27.40	27.2 GB	4.4%	7929.07
Nov 29 13:00:00 - 13:59:59	60041	3.0%	16.68	19.4 GB	3.2%	5652.55
Nov 29 14:00:00 - 14:59:59	61341	3.0%	17.04	16.3 GB	2.6%	4737.67
Nov 29 15:00:00 - 15:59:59	65062	3.2%	18.07	16.2 GB	2.6%	4725.99
Nov 29 16:00:00 - 16:59:59	73249	3.6%	20.35	18.6 GB	3.0%	5418.20
Nov 29 17:00:00 - 17:59:59	86981	4.3%	24.16	25.0 GB	4.1%	7275.03
Nov 29 18:00:00 - 18:59:59	90177	4.4%	25.05	27.4 GB	4.5%	7975.97
Nov 29 19:00:00 - 19:59:59	117992	5.8%	32.78	34.1 GB	5.5%	9925.76
Nov 29 20:00:00 - 20:59:59	136464	6.7%	37.91	38.3 GB	6.2%	11154.44
Nov 29 21:00:00 - 21:59:59	118744	5.8%	32.98	36.3 GB	5.9%	10586.35
Nov 29 22:00:00 - 22:59:59	115127	5.7%	31.98	34.3 GB	5.6%	9981.12
Nov 29 23:00:00 - 23:59:59	135571	6.7%	37.66	36.2 GB	5.9%	10535.96
Nov 30 00:00:00 - 00:59:59	87557	4.3%	24.32	27.7 GB	4.5%	8053.85
Nov 30 01:00:00 - 01:59:59	115765	5.7%	32.16	34.5 GB	5.6%	10048.77
Nov 30 02:00:00 - 02:59:59	111569	5.5%	30.99	32.9 GB	5.4%	9595.65
Nov 30 03:00:00 - 03:59:59	84773	4.2%	23.55	27.9 GB	4.5%	8127.63
Nov 30 04:00:00 - 04:15:02	19843	1.0%	22.00	6.6 GB	1.1%	7686.09
TOTAL: 24:00:00	2032077	100.0%	23.52	614.7 GB	100.0%	7459.90

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13793	54	442	3	13294
2	newsfeed.pionier.net.pl	5146	170	4338	0	638
3	news.icm.edu.pl	3	0	2	0	1
TOTAL: 3		18942	224	4782	3	13933

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	112
comp.mobile.ipad	35
it.tlc.cellulari.android	15
comp.sys.raspberry-pi	13
uk.rec.cycling.moderated	9
comp.mobile.android	8
it.comp.os.linux.ubuntu	8
it.comp.os.win.windows7	6
it.comp.os.win.windows10	6
comp.sys.mac.vintage	2
uk.net.providers.gradwell	2
alt.sex.telephones	2
rec.arts.tv.x-factor	2
de.rec.sf.startrek	2
hr.rec.glazba.hifi	1
hr.alt.cellular.gsm.iphone	1
TOTAL: 16	224

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

Groups	Count
pl.praca.oferowana	1
bit.listserv.catholic	1
christnet.philosophy	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12763
Binary in non-binary group	261
Scoring filter (8)	81
EMP (phl)	64
Scoring filter (9)	37
Scoring filter (10)	13
Scoring filter (13)	4
Scoring filter (20)	4
Too many newsgroups	3
Scoring filter (12)	3
Scoring filter (11)	2
HTML post	1
Attached HTML file	1
TOTAL: 13	13237

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	12173	2370	7853	517	31	38692	19%	24:02:49
2	icm	52268	195	24625	13652	0	0	0%	24:02:48
3	poznan	15203	93	10526	9	0	0	0%	23:55:15
4	nask	11327	78	6771	55	1	0	0%	19:34:25
5	okapi	0	0	0	0	0	2589	0%	24:00:01
TOTAL: 5		90971	2736	49775	14233	32	41281	3%	115:35:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	11.6 MB	2.5 MB	14.1 MB	0.2 KB/s	5.0 KB	24:02:49
2	icm	1.9 MB	3.3 GB	3.3 GB	40.0 KB/s	250.0 KB	24:02:48
3	poznan	557.8 KB	32.9 KB	590.7 KB	0.0 KB/s	5.8 KB	23:55:15
4	nask	478.6 KB	27.7 MB	28.2 MB	0.4 KB/s	216.9 KB	19:34:25
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		14.5 MB	3.3 GB	3.3 GB	8.4 KB/s	206.6 KB	115:35:18

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	76	89.7 KB	34	0	00:00:22
2	lze200.lze.lodz.pl	2	13	25.3 KB	1	0	00:04:08
3	pc-vicki.man.lodz.pl	1	1	1.4 KB	1	0	00:30:05
4	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 4		292	90	116.4 KB	36	1	10:25:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	673	77	91.1 KB	35	1	00:30:33
2	*.lze.lodz.pl	2	13	25.3 KB	1	0	00:04:08
3	unresolved	1	0	0.0 KB	0	0	08:35:23
4	*.toya.net.pl	4	0	0.0 KB	0	0	01:15:24
TOTAL: 4		680	90	116.4 KB	36	1	10:25:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.200	14.977	20.985	08:20:21
staticline-31-182-92-54.toya.net.pl	0.022	0.044	01:01:36
pc-vicki.man.lodz.pl	0.000	0.027	00:30:05
176.221.120.22	0.034	0.021	00:15:02
staticline-31-183-53-83.toya.net.pl	0.183	0.091	00:13:47
lze200.lze.lodz.pl	0.007	0.058	00:04:08
web.man.lodz.pl	1.010	6.153	00:00:22
mon.man.lodz.pl	0.928	5.975	00:00:03
david.man.lodz.pl	0.282	1.799	00:00:01
isvcs01.man.lodz.pl	0.008	0.028	00:00:00
TOTAL: 10	17.451	35.181	10:25:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
staticline-31-183-53-83.toya.net.pl	2
staticline-31-182-92-54.toya.net.pl	2
176.221.120.22	1
TOTAL: 5	388

NNRP client timeouts [Top 20]:

System	Conn	Peer
staticline-31-182-92-54.toya.net.pl	2	0
pc-vicki.man.lodz.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	54
2	pl.comp.os.ms-windows.winnt	22
3	pl.misc.telefonia.gsm	13
4	pl.sci.medycyna	1
TOTAL: 4		90

Daily Usenet report

Nov 29 04:15:02 -- Nov 30 04:15:02