Daily Usenet report: Aug 11 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Aug 11 06:59:10 newsfeed overchan[19397]: input line too long, skipping
Aug 11 13:38:55 newsfeed overchan[19397]: input line too long, skipping
Aug 11 14:32:38 newsfeed nnrpd[10420]: SERVER perl filter not defined
Aug 11 15:44:10 newsfeed overchan[19397]: input line too long, skipping
Aug 11 16:21:56 newsfeed overchan[19397]: input line too long, skipping
Aug 11 17:57:25 newsfeed overchan[19397]: input line too long, skipping
Aug 11 18:28:33 newsfeed overchan[19397]: input line too long, skipping
Aug 11 19:00:47 newsfeed overchan[19397]: input line too long, skipping
Aug 11 20:59:17 newsfeed nnrpd[24685]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2655215	97.4%	302.5 MB	97.1%
innfeed	45707	1.7%	5.4 MB	1.7%
innd	19992	0.7%	3.2 MB	1.0%
nnrpd	4824	0.2%	510.9 KB	0.2%
overchan	8	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2725747	100.0%	311.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2642280	93.8%
Positive hits	95030	3.4%
Negative hits	77492	2.8%
Cache misses	870	0.0%
TOTAL: 4	2815672	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.249	0.0%	109	0.000	48.156	1947.000
article cleanup	00:00:31.307	0.0%	2651693	0.008	0.012	0.020
article logging	00:01:54.625	0.1%	2652152	0.030	0.043	0.160
article parse	00:39:04.241	2.7%	105094271	0.015	0.022	0.052
article write	00:17:03.354	1.2%	2646399	0.265	0.387	1.103
artlog/artcncl	00:00:00.005	0.0%	108	0.000	0.046	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:49.417	0.1%	120302366	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.361	0.0%	96	0.000	14.177	63.250
hishave/artcncl	00:00:00.056	0.0%	109	0.000	0.514	5.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:08.324	1.7%	2823402	0.211	0.513	3.145
history sync	00:00:00.015	0.0%	290	0.000	0.052	1.000
history write	01:16:07.471	5.3%	2648345	0.270	1.725	13.737
hiswrite/artcncl	00:00:00.146	0.0%	13	0.000	11.231	29.000
idle	16:04:14.727	67.0%	85564523	0.434	0.676	2.570
nntp read	00:54:54.541	3.8%	103206959	0.023	0.032	0.062
overview write	00:00:11.607	0.0%	2646399	0.002	0.004	0.007
perl filter	03:48:04.467	15.8%	2648343	3.381	5.167	9.051
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:53.951	0.3%	5529415	0.040	0.053	0.082
TOTAL: 24:00:00.847	23:33:04.864	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.065	0.0%	460590	0.000	0.002	0.005
article prepare	00:00:00.145	0.0%	583330	0.000	0.000	0.002
article read	00:02:17.887	0.2%	136441	0.206	1.011	29.571
backlog stats	00:00:02.074	0.0%	2287371	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:13.824	0.0%	931579	0.010	0.015	0.029
data write	00:06:11.307	0.4%	1356708	0.038	0.274	0.519
idle	23:40:31.731	99.3%	2287371	20.955	37.262	307.248
status file	00:00:13.969	0.0%	17450	0.442	0.801	5.694
TOTAL: 23:50:30.263	23:49:32.002	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:07:24.266	88.2%	3121	0.000	142.347	32932.286
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.248	0.0%	7035	0.000	0.035	1.000
readart	00:00:00.053	0.0%	49	0.000	1.082	9.000
TOTAL: 00:08:23.527	00:07:24.567	88.3%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1083	2650900	2645481	3201	2218	99%	394:44:30
2	news.nask.pl	1	98058	5632	89412	3014	5%	24:04:23
3	news.icm.edu.pl	40	374	182	123	69	48%	19:17:44
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1127	2749334	2651297	92736	5301	96%	438:06:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	711.1 GB	21.5 MB	32.2 MB	711.1 GB	99%	281.6 KB
2	news.nask.pl	203.1 MB	126.0 MB	139.8 MB	469.0 MB	43%	55.5 KB
3	news.icm.edu.pl	3.4 MB	8.8 KB	2.8 MB	6.1 MB	54%	25.0 KB
4	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.3 KB
TOTAL: 4		711.3 GB	147.6 MB	174.8 MB	711.6 GB	99%	280.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 11 04:15:02 - 04:59:59	66801	2.5%	24.76	19.5 GB	2.7%	7568.75
Aug 11 05:00:00 - 05:59:59	122715	4.6%	34.09	34.2 GB	4.8%	9947.39
Aug 11 06:00:00 - 06:59:59	130414	4.9%	36.23	35.3 GB	5.0%	10283.91
Aug 11 07:00:00 - 07:59:59	101124	3.8%	28.09	28.3 GB	4.0%	8240.58
Aug 11 08:00:00 - 08:59:59	111551	4.2%	30.99	31.3 GB	4.4%	9121.29
Aug 11 09:00:00 - 09:59:59	78529	3.0%	21.81	18.5 GB	2.6%	5384.40
Aug 11 10:00:00 - 10:59:59	125115	4.7%	34.75	31.3 GB	4.4%	9111.88
Aug 11 11:00:00 - 11:59:59	109085	4.1%	30.30	28.7 GB	4.0%	8345.94
Aug 11 12:00:00 - 12:59:59	80066	3.0%	22.24	22.4 GB	3.2%	6514.48
Aug 11 13:00:00 - 13:59:59	116382	4.4%	32.33	32.5 GB	4.6%	9463.64
Aug 11 14:00:00 - 14:59:59	129600	4.9%	36.00	36.4 GB	5.1%	10613.82
Aug 11 15:00:00 - 15:59:59	132354	5.0%	36.77	36.8 GB	5.2%	10726.98
Aug 11 16:00:00 - 16:59:59	130752	4.9%	36.32	36.9 GB	5.2%	10754.58
Aug 11 17:00:00 - 17:59:59	104282	3.9%	28.97	26.6 GB	3.7%	7742.96
Aug 11 18:00:00 - 18:59:59	80869	3.1%	22.46	22.4 GB	3.2%	6523.91
Aug 11 19:00:00 - 19:59:59	111879	4.2%	31.08	29.6 GB	4.2%	8612.12
Aug 11 20:00:00 - 20:59:59	110243	4.2%	30.62	24.6 GB	3.5%	7164.67
Aug 11 21:00:00 - 21:59:59	115226	4.3%	32.01	31.5 GB	4.4%	9182.33
Aug 11 22:00:00 - 22:59:59	115184	4.3%	32.00	29.5 GB	4.2%	8594.39
Aug 11 23:00:00 - 23:59:59	102082	3.9%	28.36	27.5 GB	3.9%	8013.50
Aug 12 00:00:00 - 00:59:59	82852	3.1%	23.01	23.5 GB	3.3%	6852.01
Aug 12 01:00:00 - 01:59:59	140076	5.3%	38.91	32.6 GB	4.6%	9494.88
Aug 12 02:00:00 - 02:59:59	122518	4.6%	34.03	33.2 GB	4.7%	9661.13
Aug 12 03:00:00 - 03:59:59	99934	3.8%	27.76	28.2 GB	4.0%	8212.50
Aug 12 04:00:00 - 04:15:03	29712	1.1%	32.90	8.8 GB	1.2%	10242.30
TOTAL: 24:00:01	2649345	100.0%	30.66	710.0 GB	100.0%	8616.77

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3177	22	1385	6	1764
2	newsfeed.pionier.net.pl	2510	143	1970	4	393
3	news.icm.edu.pl	75	0	1	0	74
TOTAL: 3		5762	165	3356	10	2231

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
comp.sys.mac.vintage	11
it.tlc.cellulari.android	10
it.comp.os.win.windows10	9
comp.mobile.android	9
de.comp.lang.perl	9
uk.rec.cycling.moderated	8
comp.sys.raspberry-pi	5
de.rec.sf.startrek	3
de.comp.lang.vbclassic	3
it.comp.os.win.windows7	1
TOTAL: 11	165

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

Groups	Count
pl.sci.matematyka	6
pl.praca.oferowana	3
bit.listserv.catholic	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1489
Binary in non-binary group	235
Scoring filter (16)	13
Scoring filter (9)	11
Scoring filter (8)	10
EMP (phl)	5
Scoring filter (11)	3
Scoring filter (18)	3
Scoring filter (10)	1
TOTAL: 9	1770

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	292
	news.nask.pl	165
	news.icm.edu.pl	2
	TOTAL: 3	459
TOTAL: 1		459

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	290689	132174	91788	24	0	2502	45%	23:59:58
2	panorama	233980	861	204135	68	1	255802	0%	23:59:58
3	poznan	7752	163	5284	14	0	0	2%	23:59:58
4	icm	302088	36	222806	13416	0	5	0%	23:59:58
5	okapi	0	0	0	0	0	925	0%	23:57:51
TOTAL: 5		834509	133234	524013	13522	1	259234	15%	119:57:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	71.4 GB	11.2 MB	71.4 GB	866.8 KB/s	566.5 KB	23:59:58
2	panorama	348.2 MB	17.7 MB	365.9 MB	4.3 KB/s	403.3 KB	23:59:58
3	icm	4.1 MB	6.5 GB	6.5 GB	79.3 KB/s	509.4 KB	23:59:58
4	poznan	644.5 KB	215.3 KB	859.8 KB	0.0 KB/s	4.9 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		71.8 GB	6.6 GB	78.3 GB	190.1 KB/s	559.5 KB	119:57:43

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	ool-4578089a.dyn.optonline.net	1	25	15.7 KB	1	0	00:00:06
2	web.man.lodz.pl	292	22	22.8 KB	11	0	00:00:25
3	031011249080.bialystok.vectranet.pl	5	2	1.8 KB	2	0	00:06:07
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:02
TOTAL: 4		300	49	40.3 KB	14	2	00:08:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.dyn.optonline.net	1	25	15.7 KB	1	0	00:00:06
2	*.man.lodz.pl	678	22	22.8 KB	11	2	00:00:31
3	*.bialystok.vectranet.pl	5	2	1.8 KB	2	0	00:06:07
4	*.dynamic.gprs.plus.pl	1	0	0.0 KB	0	0	00:00:50
5	unresolved	3	0	0.0 KB	0	0	00:00:46
TOTAL: 5		688	49	40.3 KB	14	2	00:08:22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
031011249080.bialystok.vectranet.pl	0.156	0.060	00:06:07
apn-77-113-21-175.dynamic.gprs.plus.pl	0.020	0.008	00:00:50
78.133.22.226	0.024	0.056	00:00:46
web.man.lodz.pl	1.552	4.500	00:00:25
ool-4578089a.dyn.optonline.net	0.036	0.032	00:00:06
mon.man.lodz.pl	1.060	3.296	00:00:03
isvcs01.man.lodz.pl	0.000	0.036	00:00:02
david.man.lodz.pl	0.356	1.168	00:00:01
TOTAL: 8	3.204	9.156	00:08:22

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
78.133.22.226	3
apn-77-113-21-175.dynamic.gprs.plus.pl	1
TOTAL: 4	388

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	25	51.0%
2	pl	22	44.9%
3	lodz	2	4.1%
TOTAL: 3		49	100.0%

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

Newsgroup		Count
1	lodman.info	25
2	pl.comp.pecet	16
3	pl.comp.os.ms-windows.winnt	6
4	lodz.test	2
TOTAL: 4		49

Daily Usenet report

Aug 11 04:15:02 -- Aug 12 04:15:03