Daily Usenet report: May 18 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 18 10:50:08 newsfeed nnrpd[8383]: SERVER perl filter not defined
May 18 11:51:04 newsfeed overchan[2610]: input line too long, skipping
May 18 12:50:13 newsfeed nnrpd[12599]: SERVER perl filter not defined
May 18 12:50:48 newsfeed nnrpd[12600]: SERVER perl filter not defined
May 18 15:06:24 newsfeed nnrpd[17411]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2783139	97.2%	317.9 MB	96.8%
innfeed	45694	1.6%	5.4 MB	1.7%
innd	27377	1.0%	4.5 MB	1.4%
nnrpd	5919	0.2%	644.9 KB	0.2%
overchan	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2862131	100.0%	328.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2714468	85.4%
Negative hits	272935	8.6%
Positive hits	192222	6.0%
Cache misses	321	0.0%
TOTAL: 4	3179946	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.128	0.0%	317	0.000	13.022	1231.000
article cleanup	00:00:41.696	0.0%	2780468	0.006	0.015	0.025
article logging	00:01:30.785	0.1%	2781069	0.015	0.033	0.780
article parse	01:34:12.498	6.6%	214489510	0.014	0.026	0.077
article write	00:25:16.063	1.8%	2709482	0.341	0.560	2.928
artlog/artcncl	00:00:00.005	0.0%	229	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:21.236	0.2%	233165414	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.536	0.0%	269	0.000	1.993	29.000
hishave/artcncl	00:00:00.072	0.0%	317	0.000	0.227	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:13.735	2.5%	3181486	0.066	0.683	28.245
history sync	00:00:00.009	0.0%	290	0.000	0.031	1.000
history write	00:49:25.590	3.4%	2716044	0.215	1.092	59.268
hiswrite/artcncl	00:00:00.026	0.0%	48	0.000	0.542	22.000
idle	11:36:00.037	48.4%	146380046	0.124	0.285	0.494
nntp read	01:23:36.103	5.8%	212567607	0.015	0.024	0.038
overview write	00:00:15.721	0.0%	2709482	0.001	0.006	0.017
perl filter	06:49:33.460	28.5%	2716044	5.947	9.048	12.275
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:21.209	0.4%	5695037	0.032	0.056	0.091
TOTAL: 23:58:16.768	23:25:32.909	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.166	0.0%	439680	0.000	0.003	0.087
article prepare	00:00:00.042	0.0%	168462	0.000	0.000	0.083
article read	00:01:04.626	0.1%	39793	0.000	1.624	504.667
backlog stats	00:00:01.048	0.0%	1668698	0.000	0.001	0.012
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:12.094	0.0%	990760	0.000	0.012	0.075
data write	00:00:55.930	0.1%	858382	0.000	0.065	0.185
idle	23:55:09.875	99.8%	1668698	14.328	51.603	1246.526
status file	00:00:19.396	0.0%	16338	0.167	1.187	475.636
TOTAL: 23:58:22.271	23:57:44.177	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:00:32.547	99.9%	12912	0.000	1117.762	450266.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.588	0.0%	26857	0.000	0.022	0.667
readart	00:00:00.421	0.0%	91	0.000	4.626	35.000
TOTAL: 04:00:50.887	04:00:33.556	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	972	2710406	2642031	4163	64212	97%	315:30:34
2	news.nask.pl	2	197407	67413	123163	6831	34%	24:28:30
3	news.icm.edu.pl	41	370	37	282	51	10%	16:01:41
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1020	2908187	2709485	127608	71094	93%	356:00:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	908.8 GB	21.7 GB	21.9 GB	952.3 GB	95%	369.0 KB
2	news.nask.pl	22.7 GB	755.7 MB	848.2 MB	24.3 GB	93%	342.5 KB
3	news.icm.edu.pl	898.9 KB	11.2 KB	3.3 MB	4.1 MB	21%	48.3 KB
4	localhost	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	2.2 KB
TOTAL: 4		931.4 GB	22.4 GB	22.7 GB	976.6 GB	95%	368.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 18 04:15:02 - 04:59:59	91581	3.4%	33.94	26.4 GB	2.8%	10259.01
May 18 05:00:00 - 05:59:59	132221	4.9%	36.73	38.8 GB	4.2%	11308.81
May 18 06:00:00 - 06:59:59	122746	4.5%	34.10	39.9 GB	4.3%	11625.79
May 18 07:00:00 - 07:59:59	116657	4.3%	32.40	41.0 GB	4.4%	11951.30
May 18 08:00:00 - 08:59:59	113753	4.2%	31.60	40.9 GB	4.4%	11926.38
May 18 09:00:00 - 09:59:59	113114	4.2%	31.42	41.2 GB	4.4%	12000.69
May 18 10:00:00 - 10:59:59	110003	4.1%	30.56	40.7 GB	4.4%	11856.98
May 18 11:00:00 - 11:59:59	103230	3.8%	28.68	38.9 GB	4.2%	11337.45
May 18 12:00:00 - 12:59:59	107082	3.9%	29.75	39.0 GB	4.2%	11371.64
May 18 13:00:00 - 13:59:59	114402	4.2%	31.78	39.4 GB	4.2%	11468.63
May 18 14:00:00 - 14:59:59	116687	4.3%	32.41	39.6 GB	4.2%	11534.07
May 18 15:00:00 - 15:59:59	121251	4.5%	33.68	41.8 GB	4.5%	12180.46
May 18 16:00:00 - 16:59:59	115049	4.2%	31.96	41.1 GB	4.4%	11985.12
May 18 17:00:00 - 17:59:59	111914	4.1%	31.09	42.5 GB	4.6%	12391.56
May 18 18:00:00 - 18:59:59	123552	4.6%	34.32	42.1 GB	4.5%	12272.51
May 18 19:00:00 - 19:59:59	131425	4.8%	36.51	40.9 GB	4.4%	11903.93
May 18 20:00:00 - 20:59:59	123968	4.6%	34.44	38.9 GB	4.2%	11324.66
May 18 21:00:00 - 21:59:59	98162	3.6%	27.27	37.6 GB	4.0%	10961.71
May 18 22:00:00 - 22:59:59	118634	4.4%	32.95	38.3 GB	4.1%	11144.81
May 18 23:00:00 - 23:59:59	112914	4.2%	31.36	38.5 GB	4.1%	11212.52
May 19 00:00:00 - 00:59:59	87704	3.2%	24.36	30.6 GB	3.3%	8911.21
May 19 01:00:00 - 01:59:59	104628	3.9%	29.06	37.8 GB	4.1%	11014.75
May 19 02:00:00 - 02:59:59	92757	3.4%	25.77	32.7 GB	3.5%	9523.14
May 19 03:00:00 - 03:59:59	99038	3.7%	27.51	34.4 GB	3.7%	10008.64
May 19 04:00:00 - 04:15:03	28765	1.1%	31.85	9.0 GB	1.0%	10395.51
TOTAL: 24:00:01	2711237	100.0%	31.38	932.2 GB	100.0%	11312.90

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	64764	238	62088	0	2438
2	news.nask.pl	6858	7	2419	8	4424
3	news.icm.edu.pl	51	0	1	0	50
TOTAL: 3		71673	245	64508	8	6912

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	141
it.tlc.cellulari.android	46
it.comp.os.win.windows7	18
it.comp.os.win.windows10	17
comp.sys.raspberry-pi	8
hr.ponuda.hardver.komponente	4
comp.mobile.ipad	3
uk.rec.cycling.moderated	2
de.comp.office-pakete.ms-office.word	2
alt.sex.telephones	1
comp.mobile.android	1
de.comm.software.mailreader	1
it.comp.dotnet	1
TOTAL: 13	245

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

Groups	Count
pl.praca.oferowana	4
pl.sci.historia	3
bit.listserv.catholic	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3231
Binary in non-binary group	2440
Scoring filter (8)	175
Poison newsgroup	161
PFM bot misc	107
EMP (phl)	47
Scoring filter (9)	32
Scoring filter (12)	28
EMP (fsl)	20
Scoring filter (13)	11
Scoring filter (10)	11
HTML post	9
Scoring filter (11)	7
Scoring filter (14)	7
Scoring filter (35)	2
Scoring filter (24)	2
Scoring filter (20)	2
Scoring filter (25)	2
Scoring filter (15)	2
Scoring filter (23)	2
TOTAL: 31	6310

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	303634	28286	165305	9	0	0	9%	23:50:01
2	panorama	180539	1418	151680	479	3	286080	0%	23:58:40
3	icm	322613	1323	259620	9249	0	0	0%	24:00:01
4	poznan	12053	614	6866	73	0	0	5%	24:00:01
5	okapi	0	0	0	0	0	2359	0%	23:50:00
TOTAL: 5		818839	31641	583471	9810	3	288439	3%	119:38:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	10.4 GB	774.1 KB	10.4 GB	127.4 KB/s	386.3 KB	23:50:01
2	poznan	112.4 MB	5.4 MB	117.7 MB	1.4 KB/s	175.5 KB	24:00:01
3	icm	71.7 MB	2.9 GB	3.0 GB	36.5 KB/s	298.7 KB	24:00:01
4	panorama	44.9 MB	4.2 MB	49.1 MB	0.6 KB/s	26.5 KB	23:58:40
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		10.6 GB	3.0 GB	13.6 GB	33.1 KB/s	344.0 KB	119:38:43

Backlog files shrunk by innfeed:

Server	Size
panorama	4.8 MB
okapi	488.4 KB
TOTAL: 2	5.2 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	52	75.7 KB	21	0	00:00:26
2	dmcs2.p.lodz.pl	5	29	64.6 KB	3	0	02:05:23
3	pc-212-51-218-225.p.lodz.pl	3	10	24.8 KB	4	0	01:31:24
4	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 4		300	91	165.1 KB	28	4	04:00:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	915	52	75.7 KB	21	4	00:00:35
2	*.p.lodz.pl	8	39	89.4 KB	7	0	03:36:48
3	unresolved	2	0	0.0 KB	0	0	00:23:24
4	*.toya.net.pl	28	0	0.0 KB	0	0	00:00:02
TOTAL: 4		953	91	165.1 KB	28	4	04:00:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dmcs2.p.lodz.pl	0.008	0.044	02:05:23
pc-212-51-218-225.p.lodz.pl	0.006	0.042	01:31:24
185.48.194.153	0.537	0.855	00:23:24
web.man.lodz.pl	0.936	3.274	00:00:26
mon.man.lodz.pl	1.706	5.255	00:00:06
staticline41435.toya.net.pl	0.085	0.310	00:00:02
isvcs01.man.lodz.pl	0.019	0.056	00:00:01
david.man.lodz.pl	0.362	0.894	00:00:01
TOTAL: 8	3.659	10.730	04:00:50

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	527
david.man.lodz.pl	96
staticline41435.toya.net.pl	28
185.48.194.153	2
TOTAL: 4	653

NNRP client timeouts [Top 20]:

System	Conn	Peer
dmcs2.p.lodz.pl	4	1
pc-212-51-218-225.p.lodz.pl	3	0
TOTAL: 2	7	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	44
2	pl.misc.elektronika	29
3	pl.misc.kolej	10
4	pl.comp.os.ms-windows.winnt	8
TOTAL: 4		91

Daily Usenet report

May 18 04:15:02 -- May 19 04:15:03