Daily Usenet report: Oct 24 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Oct 24 10:46:11 newsfeed nnrpd[19780]: SERVER perl filter not defined
Oct 24 10:57:14 newsfeed nnrpd[20151]: SERVER perl filter not defined
Oct 24 11:35:54 newsfeed nnrpd[21631]: SERVER perl filter not defined
Oct 24 12:12:22 newsfeed nnrpd[22859]: SERVER perl filter not defined
Oct 24 12:13:17 newsfeed nnrpd[22865]: SERVER perl filter not defined
Oct 24 12:20:21 newsfeed nnrpd[23223]: SERVER perl filter not defined
Oct 24 15:31:23 newsfeed nnrpd[30413]: SERVER perl filter not defined
Oct 24 16:45:51 newsfeed overchan[4697]: input line too long, skipping
Oct 24 16:51:17 newsfeed overchan[4697]: input line too long, skipping
Oct 24 17:15:42 newsfeed overchan[4697]: input line too long, skipping
Oct 24 23:14:55 newsfeed overchan[4697]: input line too long, skipping
Oct 25 00:26:37 newsfeed nnrpd[17805]: SERVER perl filter not defined
Oct 25 00:33:30 newsfeed nnrpd[17920]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3497762	97.1%	405.3 MB	96.8%
innd	56281	1.6%	7.9 MB	1.9%
innfeed	42256	1.2%	4.9 MB	1.2%
nnrpd	4814	0.1%	498.2 KB	0.1%
overchan	6	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3601120	100.0%	418.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3455656	93.9%
Negative hits	190730	5.2%
Positive hits	33169	0.9%
Cache misses	483	0.0%
TOTAL: 4	3680038	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.631	0.0%	177	0.000	26.164	1118.500
article cleanup	00:00:52.017	0.1%	3493730	0.010	0.015	0.020
article logging	00:02:18.092	0.2%	3494918	0.026	0.040	0.156
article parse	01:06:22.567	4.6%	193496054	0.010	0.021	0.029
article write	00:22:37.437	1.6%	3438394	0.164	0.395	0.833
artlog/artcncl	00:00:00.007	0.0%	175	0.000	0.040	0.375
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:06.334	0.2%	212984464	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.581	0.0%	168	0.000	3.458	99.500
hishave/artcncl	00:00:01.085	0.0%	177	0.000	6.130	86.182
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:55:58.950	3.9%	3697860	0.066	0.908	5.083
history sync	00:00:00.010	0.0%	290	0.000	0.034	0.500
history write	01:52:27.160	7.8%	3472595	0.213	1.943	8.194
hiswrite/artcncl	00:00:00.024	0.0%	9	0.000	2.667	14.000
idle	11:42:25.046	48.8%	133568391	0.153	0.316	0.603
nntp read	01:16:18.661	5.3%	190833291	0.014	0.024	0.030
overview write	00:00:17.460	0.0%	3438394	0.003	0.005	0.007
perl filter	05:45:49.488	24.0%	3472595	4.122	5.975	7.710
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:56.418	0.3%	6898848	0.030	0.043	0.060
TOTAL: 24:00:03.248	23:13:35.968	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.163	0.0%	59501	0.000	0.003	0.015
article prepare	00:00:00.003	0.0%	15214	0.000	0.000	0.011
article read	00:01:51.916	0.1%	3765	0.000	29.725	224.333
backlog stats	00:00:00.163	0.0%	179482	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.235	0.0%	95673	0.006	0.023	0.067
data write	00:00:03.296	0.0%	74245	0.018	0.044	0.099
idle	23:52:20.348	99.8%	179482	305.772	478.824	982.538
status file	00:00:14.145	0.0%	15318	0.598	0.923	4.800
TOTAL: 23:54:46.114	23:54:32.269	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.176	55.8%	3084	0.000	5.894	27.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.194	0.6%	6794	0.000	0.029	0.667
readart	00:00:00.001	0.0%	44	0.000	0.023	0.500
TOTAL: 00:00:32.554	00:00:18.371	56.4%	-	-	-	-

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	1019	3469707	3430547	6510	32650	98%	388:37:57
2	news.nask.pl	2	38999	10588	5718	22693	27%	24:10:51
3	news.icm.edu.pl	44	235	15	214	6	6%	16:54:51
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1075	3508950	3441159	12442	55349	98%	429:43:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	844.1 GB	3.2 GB	5.5 GB	852.8 GB	98%	258.2 KB
2	news.nask.pl	93.0 MB	62.0 MB	3.5 GB	3.7 GB	2%	116.0 KB
3	news.icm.edu.pl	550.7 KB	13.8 KB	153.0 KB	717.5 KB	76%	34.2 KB
4	localhost	11.8 KB	0.0 KB	0.0 KB	11.8 KB	100%	1.3 KB
TOTAL: 4		844.2 GB	3.2 GB	9.0 GB	856.5 GB	98%	256.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 24 04:15:02 - 04:59:59	76829	2.2%	28.48	25.0 GB	3.0%	9716.42
Oct 24 05:00:00 - 05:59:59	118377	3.4%	32.88	38.8 GB	4.6%	11315.61
Oct 24 06:00:00 - 06:59:59	122456	3.6%	34.02	39.3 GB	4.7%	11453.99
Oct 24 07:00:00 - 07:59:59	103902	3.0%	28.86	33.5 GB	4.0%	9746.34
Oct 24 08:00:00 - 08:59:59	128280	3.7%	35.63	35.3 GB	4.2%	10290.70
Oct 24 09:00:00 - 09:59:59	166790	4.8%	46.33	34.6 GB	4.1%	10067.61
Oct 24 10:00:00 - 10:59:59	151329	4.4%	42.04	43.3 GB	5.1%	12613.14
Oct 24 11:00:00 - 11:59:59	146606	4.3%	40.72	42.9 GB	5.1%	12498.19
Oct 24 12:00:00 - 12:59:59	146166	4.2%	40.60	43.2 GB	5.1%	12571.61
Oct 24 13:00:00 - 13:59:59	142994	4.2%	39.72	42.6 GB	5.1%	12408.62
Oct 24 14:00:00 - 14:59:59	156803	4.6%	43.56	39.9 GB	4.7%	11611.80
Oct 24 15:00:00 - 15:59:59	171172	5.0%	47.55	41.4 GB	4.9%	12069.45
Oct 24 16:00:00 - 16:59:59	151442	4.4%	42.07	42.3 GB	5.0%	12318.72
Oct 24 17:00:00 - 17:59:59	152412	4.4%	42.34	40.7 GB	4.8%	11856.79
Oct 24 18:00:00 - 18:59:59	92431	2.7%	25.68	24.7 GB	2.9%	7186.55
Oct 24 19:00:00 - 19:59:59	67893	2.0%	18.86	19.5 GB	2.3%	5693.37
Oct 24 20:00:00 - 20:59:59	183673	5.3%	51.02	22.0 GB	2.6%	6404.31
Oct 24 21:00:00 - 21:59:59	234235	6.8%	65.07	26.8 GB	3.2%	7794.33
Oct 24 22:00:00 - 22:59:59	205005	6.0%	56.95	31.8 GB	3.8%	9257.28
Oct 24 23:00:00 - 23:59:59	189211	5.5%	52.56	37.2 GB	4.4%	10821.93
Oct 25 00:00:00 - 00:59:59	129179	3.8%	35.88	29.3 GB	3.5%	8533.53
Oct 25 01:00:00 - 01:59:59	119704	3.5%	33.25	30.8 GB	3.6%	8957.93
Oct 25 02:00:00 - 02:59:59	134705	3.9%	37.42	36.9 GB	4.4%	10755.27
Oct 25 03:00:00 - 03:59:59	119281	3.5%	33.13	32.3 GB	3.8%	9398.67
Oct 25 04:00:00 - 04:15:02	30213	0.9%	33.50	9.1 GB	1.1%	10603.64
TOTAL: 24:00:00	3441088	100.0%	39.83	843.1 GB	100.0%	10231.85

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	33079	194	20518	1	12366
2	news.nask.pl	23414	41	586	1	22786
3	news.icm.edu.pl	6	0	1	0	5
TOTAL: 3		56499	235	21105	2	35157

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	126
it.comp.os.win.windows10	30
it.tlc.cellulari.android	21
comp.mobile.ipad	16
comp.mobile.android	11
de.comm.software.mailreader	8
de.rec.sf.startrek	6
rec.arts.tv.x-factor	5
nl.comp.os.linux.techniek	3
uk.rec.cycling.moderated	3
comp.sys.raspberry-pi	2
alt.sex.telephone.alt.sex.services	1
alt.sex.telephones	1
it.tlc.cellulari.iphone	1
it.comp.os.win.windows7	1
TOTAL: 15	235

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

Groups	Count
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	29541
EMP (md5)	4219
Scoring filter (8)	64
Poison newsgroup	60
Scoring filter (9)	43
Scoring filter (10)	26
HTML post	8
EMP (phl)	7
Scoring filter (11)	4
TOTAL: 9	33972

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	747
	newsfeed.pionier.net.pl	438
	TOTAL: 2	1185
TOTAL: 1		1185

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44212	1701	16583	442	7	40659	3%	24:00:15
2	poznan	17579	292	10116	79	0	0	1%	24:06:04
3	icm	26861	69	20670	102	0	0	0%	24:09:52
4	nask	4155	7	2860	0	0	0	0%	24:09:01
5	okapi	0	0	0	0	0	1118	0%	24:05:42
TOTAL: 5		92807	2069	50229	623	7	41777	2%	120:30:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	12.3 MB	2.7 MB	15.0 MB	0.2 KB/s	7.2 KB	24:00:15
2	icm	10.9 MB	36.9 MB	47.8 MB	0.6 KB/s	286.4 KB	24:09:52
3	poznan	1.1 MB	2.3 MB	3.4 MB	0.0 KB/s	9.3 KB	24:06:04
4	nask	165.0 KB	0.0 KB	165.0 KB	0.0 KB/s	23.6 KB	24:09:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:05:42
TOTAL: 5		24.4 MB	41.9 MB	66.3 MB	0.2 KB/s	25.2 KB	120:30:54

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	44	41.1 KB	20	0	00:00:23
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:02
TOTAL: 2		297	44	41.1 KB	20	9	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	681	44	41.1 KB	20	9	0	00:00:31
TOTAL: 1		681	44	41.1 KB	20	9	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.236	11.376	00:00:23
mon.man.lodz.pl	1.032	12.264	00:00:03
isvcs01.man.lodz.pl	0.076	0.412	00:00:02
david.man.lodz.pl	0.344	3.980	00:00:01
TOTAL: 4	2.688	28.032	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	26
2	pl.comp.os.ms-windows.winnt	18
TOTAL: 2		44

Daily Usenet report

Oct 24 04:15:02 -- Oct 25 04:15:02