Daily Usenet report: Dec 18 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Dec 18 11:09:46 newsfeed nnrpd[10647]: SERVER perl filter not defined
Dec 18 12:25:23 newsfeed nnrpd[13494]: SERVER perl filter not defined
Dec 18 14:42:49 newsfeed nnrpd[18387]: SERVER perl filter not defined
Dec 18 14:42:59 newsfeed nnrpd[18388]: SERVER perl filter not defined
Dec 18 14:45:15 newsfeed nnrpd[18607]: SERVER perl filter not defined
Dec 18 16:36:04 newsfeed nnrpd[22634]: SERVER perl filter not defined
Dec 18 17:32:05 newsfeed overchan[27486]: input line too long, skipping
Dec 18 17:39:08 newsfeed overchan[27486]: input line too long, skipping
Dec 18 17:41:40 newsfeed overchan[27486]: input line too long, skipping
Dec 18 19:47:23 newsfeed overchan[2016]: input line too long, skipping
Dec 18 19:48:39 newsfeed overchan[2016]: input line too long, skipping
Dec 18 22:35:00 newsfeed nnrpd[10040]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4296868	98.5%	491.7 MB	98.2%
innfeed	34706	0.8%	4.1 MB	0.8%
innd	27370	0.6%	4.5 MB	0.9%
nnrpd	4795	0.1%	505.4 KB	0.1%
overchan	6	0.0%	1.1 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	4363747	100.0%	500.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4267755	98.0%
Negative hits	77692	1.8%
Positive hits	11295	0.3%
Cache misses	102	0.0%
TOTAL: 4	4356844	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.212	0.0%	158	0.000	20.329	581.000
article cleanup	00:00:48.726	0.1%	4295895	0.007	0.011	0.015
article logging	00:02:37.950	0.2%	4296750	0.027	0.037	0.109
article parse	00:55:40.573	3.9%	85861932	0.019	0.039	0.059
article write	00:22:05.169	1.5%	4286496	0.178	0.309	0.602
artlog/artcncl	00:00:00.006	0.0%	156	0.000	0.038	0.333
data move	00:03:01.468	0.2%	103824815	0.001	0.002	0.006
hisgrep/artcncl	00:00:00.787	0.0%	140	0.000	5.621	49.600
hishave/artcncl	00:00:00.071	0.0%	158	0.000	0.449	2.818
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:54:56.616	8.0%	4382684	0.135	1.574	7.503
history sync	00:00:00.009	0.0%	289	0.000	0.031	2.000
history write	05:46:29.359	24.1%	4290769	0.482	4.845	14.122
hiswrite/artcncl	00:00:00.040	0.0%	18	2.222	2.222	2.222
idle	06:49:29.207	28.4%	46116755	0.109	0.533	1.253
nntp read	00:45:27.770	3.2%	82244905	0.019	0.033	0.043
overview write	00:00:21.136	0.0%	4286496	0.004	0.005	0.006
perl filter	06:48:34.812	28.4%	4290755	3.254	5.713	7.568
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:31.194	0.5%	8593420	0.034	0.046	0.060
TOTAL: 24:00:10.445	23:36:08.105	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.132	0.0%	50645	0.000	0.003	0.011
article prepare	00:00:00.003	0.0%	10712	0.000	0.000	0.024
article read	00:01:41.011	0.1%	2679	1.000	37.705	289.500
backlog stats	00:00:00.106	0.0%	148461	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	29	0.000	0.000	0.000
data read	00:00:01.296	0.0%	82004	0.003	0.016	0.043
data write	00:00:03.287	0.0%	62004	0.025	0.053	0.101
idle	23:42:00.701	99.8%	148461	370.550	574.701	1028.547
status file	00:00:17.412	0.0%	12401	0.440	1.404	62.500
TOTAL: 23:44:16.891	23:44:03.948	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:31:05.144	99.3%	3068	0.000	607.935	204752.889
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.229	0.0%	6738	0.000	0.034	0.526
readart	00:00:00.812	0.0%	32	0.000	25.375	405.000
TOTAL: 00:31:17.631	00:31:06.185	99.4%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	329	4285595	4275064	4752	5779	99%	534:35:53
2	news.nask.pl	2	14888	10107	1224	3557	67%	23:20:37
3	news.icm.edu.pl	49	263	31	226	6	11%	18:22:55
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		388	4300753	4285209	6202	9342	99%	576:19:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	856.7 GB	26.7 MB	172.3 MB	856.9 GB	99%	209.9 KB
2	news.nask.pl	57.3 MB	1.2 MB	16.2 MB	74.8 MB	76%	5.6 KB
3	news.icm.edu.pl	1.0 MB	29.5 KB	107.4 KB	1.2 MB	88%	32.1 KB
4	localhost	10.6 KB	0.0 KB	0.0 KB	10.6 KB	100%	1.5 KB
TOTAL: 4		856.8 GB	27.9 MB	188.6 MB	857.0 GB	99%	209.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:02 - 04:59:59	90319	2.1%	33.48	20.6 GB	2.4%	7992.46
Dec 18 05:00:00 - 05:59:59	144082	3.4%	40.02	35.7 GB	4.2%	10403.75
Dec 18 06:00:00 - 06:59:59	144226	3.4%	40.06	35.3 GB	4.1%	10273.69
Dec 18 07:00:00 - 07:59:59	155511	3.6%	43.20	39.1 GB	4.6%	11386.18
Dec 18 08:00:00 - 08:59:59	166434	3.9%	46.23	41.0 GB	4.8%	11939.40
Dec 18 09:00:00 - 09:59:59	165734	3.9%	46.04	40.0 GB	4.7%	11648.36
Dec 18 10:00:00 - 10:59:59	191352	4.5%	53.15	38.8 GB	4.5%	11306.51
Dec 18 11:00:00 - 11:59:59	192329	4.5%	53.42	38.6 GB	4.5%	11236.60
Dec 18 12:00:00 - 12:59:59	178477	4.2%	49.58	40.8 GB	4.8%	11870.99
Dec 18 13:00:00 - 13:59:59	182699	4.3%	50.75	39.3 GB	4.6%	11439.82
Dec 18 14:00:00 - 14:59:59	163539	3.8%	45.43	39.8 GB	4.6%	11590.11
Dec 18 15:00:00 - 15:59:59	162164	3.8%	45.05	35.2 GB	4.1%	10243.61
Dec 18 16:00:00 - 16:59:59	165419	3.9%	45.95	36.2 GB	4.2%	10556.52
Dec 18 17:00:00 - 17:59:59	194013	4.5%	53.89	35.3 GB	4.1%	10293.26
Dec 18 18:00:00 - 18:59:59	184539	4.3%	51.26	34.1 GB	4.0%	9940.56
Dec 18 19:00:00 - 19:59:59	166542	3.9%	46.26	34.6 GB	4.0%	10079.42
Dec 18 20:00:00 - 20:59:59	171021	4.0%	47.51	38.1 GB	4.4%	11090.86
Dec 18 21:00:00 - 21:59:59	185703	4.3%	51.58	38.1 GB	4.4%	11086.45
Dec 18 22:00:00 - 22:59:59	187019	4.4%	51.95	39.1 GB	4.6%	11400.16
Dec 18 23:00:00 - 23:59:59	248928	5.8%	69.15	37.9 GB	4.4%	11032.48
Dec 19 00:00:00 - 00:59:59	229913	5.4%	63.86	31.4 GB	3.7%	9150.53
Dec 19 01:00:00 - 01:59:59	258199	6.0%	71.72	34.5 GB	4.0%	10039.06
Dec 19 02:00:00 - 02:59:59	203054	4.7%	56.40	28.9 GB	3.4%	8415.38
Dec 19 03:00:00 - 03:59:59	122235	2.9%	33.95	20.0 GB	2.3%	5830.95
Dec 19 04:00:00 - 04:15:02	33040	0.8%	36.63	5.2 GB	0.6%	6078.20
TOTAL: 24:00:00	4286491	100.0%	49.61	857.5 GB	100.0%	10406.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6335	299	4615	6	1415
2	news.nask.pl	3880	50	486	9	3335
3	news.icm.edu.pl	6	0	2	0	4
TOTAL: 3		10221	349	5103	15	4754

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	205
it.comp.os.win.windows10	44
comp.mobile.android	27
comp.sys.raspberry-pi	16
it.tlc.cellulari.android	14
nl.comp.os.linux.techniek	13
it.comp.os.linux.ubuntu	10
comp.mobile.ipad	8
it.comp.os.win.windows7	5
uk.rec.cycling.moderated	2
de.comp.lang.vbclassic	2
alt.sex.fetish.wrestling.male	1
alt.sex.eroticca.marketplace	1
de.comm.software.mailreader	1
TOTAL: 14	349

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2790
Binary in non-binary group	935
Scoring filter (8)	49
Scoring filter (9)	37
EMP (phl)	25
Scoring filter (10)	16
HTML post	16
Poison newsgroup	6
Scoring filter (11)	5
EMP (fsl)	2
Scoring filter (12)	1
TOTAL: 11	3882

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	558
	news.nask.pl	300
	TOTAL: 2	858
TOTAL: 1		858

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	35004	1106	12850	265	67	36883	3%	23:58:55
2	poznan	15572	404	9840	23	0	0	2%	23:50:39
3	icm	23132	90	19541	55	0	0	0%	23:50:39
4	nask	3202	3	2679	0	0	0	0%	23:50:39
5	okapi	0	0	0	0	0	1324	0%	23:48:32
TOTAL: 5		76910	1603	44910	343	67	38207	2%	119:19:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.0 MB	722.1 KB	6.7 MB	0.1 KB/s	5.0 KB	23:58:55
2	icm	4.5 MB	12.4 MB	16.9 MB	0.2 KB/s	119.7 KB	23:50:39
3	poznan	1.6 MB	173.7 KB	1.7 MB	0.0 KB/s	4.2 KB	23:50:39
4	nask	7.6 KB	0.0 KB	7.6 KB	0.0 KB/s	2.5 KB	23:50:39
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:48:32
TOTAL: 5		12.1 MB	13.3 MB	25.4 MB	0.1 KB/s	13.4 KB	119:19:24

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	30	23.9 KB	13	0	00:00:27
2	193.107.222.155	1	2	2.6 KB	2	0	00:30:43
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 3		296	32	26.5 KB	15	7	00:31:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	678	30	23.9 KB	13	7	00:00:32
2	unresolved	1	2	2.6 KB	2	0	00:30:43
TOTAL: 2		679	32	26.5 KB	15	7	00:31:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
193.107.222.155	0.008	0.008	00:30:43
web.man.lodz.pl	1.376	6.232	00:00:27
mon.man.lodz.pl	1.100	5.976	00:00:03
david.man.lodz.pl	0.424	2.064	00:00:01
isvcs01.man.lodz.pl	0.052	0.168	00:00:00
TOTAL: 5	2.960	14.448	00:31:16

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
TOTAL: 2	383

NNRP client timeouts [Top 20]:

System	Conn	Peer
193.107.222.155	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	30	93.8%
2	lodz	2	6.2%
TOTAL: 2		32	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	30
2	lodz.ogloszenia	1
3	lodz.test	1
TOTAL: 3		32

Daily Usenet report

Dec 18 04:15:02 -- Dec 19 04:15:02