Daily Usenet report: Oct 25 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 25 11:33:18 newsfeed nnrpd[10643]: SERVER perl filter not defined
Oct 25 11:33:47 newsfeed nnrpd[10645]: SERVER perl filter not defined
Oct 25 11:34:06 newsfeed nnrpd[10646]: SERVER perl filter not defined
Oct 25 15:46:20 newsfeed nnrpd[19356]: SERVER perl filter not defined
Oct 25 22:35:27 newsfeed nnrpd[1306]: SERVER perl filter not defined
Oct 26 00:36:38 newsfeed nnrpd[5538]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2197277	97.8%	255.3 MB	97.4%
innfeed	25358	1.1%	3.1 MB	1.2%
innd	18510	0.8%	3.2 MB	1.2%
nnrpd	4964	0.2%	525.6 KB	0.2%
TOTAL: 4	2246109	100.0%	262.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2192154	98.2%
Negative hits	26387	1.2%
Positive hits	14147	0.6%
Cache misses	93	0.0%
TOTAL: 4	2232781	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.529	0.0%	272	0.000	16.651	368.000
article cleanup	00:00:36.929	0.0%	2195808	0.000	0.017	0.043
article logging	00:01:05.634	0.1%	2196255	0.000	0.030	0.516
article parse	01:00:07.203	4.2%	222779660	0.000	0.016	0.068
article write	00:21:33.524	1.5%	2192583	0.283	0.590	4.318
artlog/artcncl	00:00:00.005	0.0%	218	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:01.195	0.2%	236448369	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.201	0.0%	212	0.000	0.948	17.000
hishave/artcncl	00:00:00.223	0.0%	272	0.000	0.820	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:49:14.489	3.4%	2233605	0.050	1.323	15.462
history sync	00:00:00.014	0.0%	297	0.000	0.047	1.000
history write	01:06:07.959	4.6%	2193130	0.192	1.809	24.170
hiswrite/artcncl	00:00:00.118	0.0%	60	0.000	1.967	28.000
idle	14:27:13.798	60.2%	161161298	0.190	0.323	564.935
nntp read	00:59:47.262	4.2%	221050280	0.009	0.016	0.035
overview write	00:00:12.837	0.0%	2192583	0.000	0.006	0.028
perl filter	04:10:41.247	17.4%	2193130	1.375	6.858	10.116
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:02.667	0.3%	4422342	0.034	0.055	0.086
TOTAL: 23:59:29.562	23:03:49.834	96.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.195	0.0%	81224	0.000	0.002	0.091
article prepare	00:00:00.008	0.0%	49062	0.000	0.000	0.050
article read	00:00:54.669	0.1%	12278	0.000	4.453	810.000
backlog stats	00:00:00.158	0.0%	225098	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	12	0.000	0.000	0.000
data read	00:00:01.268	0.0%	121099	0.000	0.010	0.074
data write	00:00:07.758	0.0%	88187	0.000	0.088	0.538
idle	23:58:16.370	99.9%	225098	71.574	383.372	3265.909
status file	00:00:11.573	0.0%	7887	0.000	1.467	505.714
TOTAL: 23:59:40.162	23:59:31.999	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:50:38.209	99.5%	6236	0.000	487.205	261223.143
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.244	0.0%	13154	0.000	0.019	1.000
readart	00:00:00.036	0.0%	37	0.000	0.973	10.667
TOTAL: 00:50:52.618	00:50:38.489	99.5%	-	-	-	-

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	993	2193634	2190790	72	2772	99%	320:19:34
2	news.nask.pl	2	14395	2888	11257	250	20%	24:26:17
3	news.icm.edu.pl	37	418	28	179	211	6%	19:48:18
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1039	2208453	2193712	11508	3233	99%	364:34:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	602.6 GB	31.5 MB	32.5 MB	602.7 GB	99%	288.1 KB
2	news.nask.pl	8.8 MB	572.0 KB	647.3 KB	10.0 MB	88%	3.3 KB
3	news.icm.edu.pl	1.1 MB	129.9 KB	6.3 MB	7.6 MB	14%	32.5 KB
4	localhost	10.4 KB	0.0 KB	0.0 KB	10.4 KB	100%	1.7 KB
TOTAL: 4		602.6 GB	32.2 MB	39.5 MB	602.7 GB	99%	287.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 25 04:15:02 - 04:59:59	65608	3.0%	24.32	21.4 GB	3.6%	8310.67
Oct 25 05:00:00 - 05:59:59	82692	3.8%	22.97	26.1 GB	4.3%	7603.79
Oct 25 06:00:00 - 06:59:59	60129	2.7%	16.70	18.6 GB	3.1%	5431.14
Oct 25 07:00:00 - 07:59:59	52856	2.4%	14.68	17.8 GB	3.0%	5171.46
Oct 25 08:00:00 - 08:59:59	87839	4.0%	24.40	27.0 GB	4.5%	7854.66
Oct 25 09:00:00 - 09:59:59	94436	4.3%	26.23	28.5 GB	4.7%	8302.21
Oct 25 10:00:00 - 10:59:59	100245	4.6%	27.85	28.2 GB	4.7%	8227.15
Oct 25 11:00:00 - 11:59:59	135179	6.2%	37.55	30.3 GB	5.0%	8818.80
Oct 25 12:00:00 - 12:59:59	124343	5.7%	34.54	28.2 GB	4.7%	8217.33
Oct 25 13:00:00 - 13:59:59	86684	4.0%	24.08	23.8 GB	4.0%	6943.32
Oct 25 14:00:00 - 14:59:59	101920	4.6%	28.31	28.4 GB	4.7%	8286.27
Oct 25 15:00:00 - 15:59:59	120152	5.5%	33.38	31.9 GB	5.3%	9295.32
Oct 25 16:00:00 - 16:59:59	117661	5.4%	32.68	32.5 GB	5.4%	9470.77
Oct 25 17:00:00 - 17:59:59	132807	6.1%	36.89	32.1 GB	5.3%	9356.02
Oct 25 18:00:00 - 18:59:59	136917	6.2%	38.03	31.1 GB	5.2%	9054.56
Oct 25 19:00:00 - 19:59:59	64318	2.9%	17.87	17.9 GB	3.0%	5210.14
Oct 25 20:00:00 - 20:59:59	52450	2.4%	14.57	14.7 GB	2.4%	4283.85
Oct 25 21:00:00 - 21:59:59	62970	2.9%	17.49	17.4 GB	2.9%	5079.07
Oct 25 22:00:00 - 22:59:59	91758	4.2%	25.49	25.9 GB	4.3%	7532.11
Oct 25 23:00:00 - 23:59:59	73166	3.3%	20.32	19.7 GB	3.3%	5725.63
Oct 26 00:00:00 - 00:59:59	63299	2.9%	17.58	18.6 GB	3.1%	5416.51
Oct 26 01:00:00 - 01:59:59	54597	2.5%	15.17	16.0 GB	2.7%	4672.99
Oct 26 02:00:00 - 02:59:59	106391	4.9%	29.55	29.7 GB	4.9%	8641.47
Oct 26 03:00:00 - 03:59:59	102629	4.7%	28.51	29.2 GB	4.9%	8513.06
Oct 26 04:00:00 - 04:15:02	22339	1.0%	24.77	6.5 GB	1.1%	7541.12
TOTAL: 24:00:00	2193385	100.0%	25.39	601.6 GB	100.0%	7301.06

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3174	280	2446	2	446
2	news.nask.pl	288	6	229	1	52
3	news.icm.edu.pl	212	0	5	0	207
TOTAL: 3		3674	286	2680	3	705

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	101
it.comp.os.win.windows10	56
it.tlc.cellulari.android	35
comp.mobile.android	18
hr.alt.cellular.gsm.iphone	11
comp.mobile.ipad	11
comp.sys.mac.vintage	9
hr.ponuda.hardver.komponente	8
it.comp.os.win.windows7	8
it.comp.os.win.windows8	6
nl.comp.os.linux.techniek	6
comp.sys.raspberry-pi	5
it.comp.os.linux.ubuntu	5
it.discussioni.energia	1
de.rec.sf.startrek	1
hr.alt.cellular.gsm.android	1
uk.rec.cycling.moderated	1
hr.ponuda.hardver.konfiguracije	1
uk.net.providers.gradwell	1
de.comp.lang.vbclassic	1
TOTAL: 20	286

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

Groups	Count
pl.sci.historia	2
pl.sci.matematyka	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	207
Binary in non-binary group	45
Too many newsgroups	5
Scoring filter (8)	1
TOTAL: 4	258

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	45527	3322	23008	6633	0	6	7%	24:01:22
2	panorama	44559	1087	29936	538	25	49636	2%	23:59:44
3	poznan	4028	38	2712	19	0	0	0%	24:02:32
4	okapi	0	0	0	0	0	1882	0%	24:00:00
5	nask	0	0	0	0	0	11522	0%	24:00:00
TOTAL: 5		94114	4447	55656	7190	25	63046	4%	120:03:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	562.5 MB	1.7 GB	2.2 GB	27.0 KB/s	234.8 KB	24:01:22
2	panorama	5.2 MB	4.7 MB	10.0 MB	0.1 KB/s	6.3 KB	23:59:44
3	poznan	202.8 KB	38.1 KB	240.8 KB	0.0 KB/s	4.2 KB	24:02:32
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		568.0 MB	1.7 GB	2.2 GB	5.4 KB/s	201.8 KB	120:03:38

Backlog files shrunk by innfeed:

Server	Size
panorama	976.7 KB
TOTAL: 1	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	34	34.3 KB	16	0	00:00:24
2	pc-vicki.man.lodz.pl	1	3	4.6 KB	1	0	00:30:28
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 3		295	37	38.9 KB	17	6	00:50:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	679	37	38.9 KB	17	6	00:30:58
2	unresolved	4	0	0.0 KB	0	0	00:19:05
3	*.toya.net.pl	30	0	0.0 KB	0	0	00:00:02
4	*.elsnet.pl	8	0	0.0 KB	0	0	00:00:45
TOTAL: 4		721	37	38.9 KB	17	6	00:50:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-vicki.man.lodz.pl	0.000	0.024	00:30:28
185.48.193.54	0.189	0.264	00:19:05
host227.elsnet.pl	0.022	0.134	00:00:45
web.man.lodz.pl	0.902	4.579	00:00:24
mon.man.lodz.pl	0.849	4.180	00:00:02
staticline41435.toya.net.pl	0.094	0.453	00:00:02
isvcs01.man.lodz.pl	0.034	0.079	00:00:01
david.man.lodz.pl	0.273	1.323	00:00:00
TOTAL: 8	2.363	11.036	00:50:52

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	30
host227.elsnet.pl	8
185.48.193.54	4
TOTAL: 5	426

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	20
2	pl.comp.os.ms-windows.winnt	14
3	pl.sci.medycyna	3
TOTAL: 3		37

Daily Usenet report

Oct 25 04:15:02 -- Oct 26 04:15:02