Daily Usenet report: Jan 3 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Jan 3 11:23:09 newsfeed nnrpd[2833]: SERVER perl filter not defined
Jan 3 13:20:34 newsfeed nnrpd[7152]: SERVER perl filter not defined
Jan 3 13:33:09 newsfeed nnrpd[7535]: SERVER perl filter not defined
Jan 3 14:57:47 newsfeed overchan[19176]: input line too long, skipping
Jan 3 15:04:44 newsfeed overchan[19176]: input line too long, skipping
Jan 3 15:07:29 newsfeed overchan[19176]: input line too long, skipping
Jan 3 15:18:55 newsfeed overchan[19176]: input line too long, skipping
Jan 3 16:14:18 newsfeed overchan[19176]: input line too long, skipping
Jan 3 18:32:19 newsfeed overchan[19176]: input line too long, skipping
Jan 3 18:41:07 newsfeed overchan[19176]: input line too long, skipping
Jan 3 18:49:52 newsfeed overchan[19176]: input line too long, skipping
Jan 3 19:09:43 newsfeed overchan[19176]: input line too long, skipping
Jan 3 20:55:29 newsfeed overchan[19176]: input line too long, skipping
Jan 3 23:51:56 newsfeed overchan[19176]: input line too long, skipping
Jan 4 02:08:07 newsfeed overchan[19176]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3723632	97.8%	419.7 MB	97.4%
innfeed	45360	1.2%	5.3 MB	1.2%
innd	34039	0.9%	5.3 MB	1.2%
nnrpd	4779	0.1%	498.8 KB	0.1%
overchan	13	0.0%	2.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3807824	100.0%	430.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3681426	96.2%
Negative hits	129100	3.4%
Positive hits	16707	0.4%
Cache misses	127	0.0%
TOTAL: 4	3827360	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.396	0.0%	196	0.000	37.735	2078.000
article cleanup	00:00:40.934	0.0%	3727721	0.007	0.011	0.015
article logging	00:02:16.704	0.2%	3728760	0.024	0.037	0.140
article parse	00:44:30.219	3.1%	105984573	0.015	0.025	0.042
article write	00:17:16.007	1.2%	3708739	0.148	0.279	0.509
artlog/artcncl	00:00:00.004	0.0%	193	0.000	0.021	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:15.111	0.2%	122519898	0.001	0.001	0.004
hisgrep/artcncl	00:00:00.618	0.0%	157	0.000	3.936	80.000
hishave/artcncl	00:00:00.105	0.0%	196	0.000	0.536	3.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:33:18.175	6.5%	3869942	0.202	1.447	6.708
history sync	00:00:00.013	0.0%	290	0.000	0.045	0.500
history write	04:08:35.810	17.3%	3721648	0.959	4.008	8.655
hiswrite/artcncl	00:00:00.030	0.0%	39	0.000	0.769	15.000
idle	10:24:20.933	43.4%	74630102	0.238	0.502	1.517
nntp read	00:48:09.901	3.3%	103101475	0.020	0.028	0.039
overview write	00:00:17.854	0.0%	3708739	0.003	0.005	0.006
perl filter	05:25:27.366	22.6%	3721645	3.998	5.247	6.594
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:13.950	0.4%	7436393	0.032	0.042	0.058
TOTAL: 24:00:03.932	23:32:31.130	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.136	0.0%	57846	0.000	0.002	0.012
article prepare	00:00:00.003	0.0%	11516	0.000	0.000	0.016
article read	00:01:21.811	0.1%	2859	0.611	28.615	276.100
backlog stats	00:00:00.123	0.0%	161382	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.206	0.0%	86242	0.003	0.014	0.027
data write	00:00:03.361	0.0%	67938	0.023	0.049	0.120
idle	23:52:17.443	99.9%	161382	320.693	532.509	1026.213
status file	00:00:14.324	0.0%	16350	0.550	0.876	8.823
TOTAL: 23:54:12.720	23:53:58.407	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:58.265	83.0%	3056	0.000	19.066	6843.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.163	0.2%	6759	0.000	0.024	1.000
readart	00:00:00.051	0.1%	46	0.000	1.109	25.000
TOTAL: 00:01:10.173	00:00:58.479	83.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	524	3710604	3692064	9019	9521	99%	529:23:46
2	news.nask.pl	2	22516	11598	1535	9383	51%	23:49:35
3	news.icm.edu.pl	52	246	18	223	5	7%	18:23:36
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		582	3733369	3703683	10777	18909	99%	571:36:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	702.1 GB	775.1 MB	1.4 GB	704.3 GB	99%	199.5 KB
2	news.nask.pl	73.9 MB	5.4 MB	833.4 MB	912.7 MB	8%	44.5 KB
3	news.icm.edu.pl	436.2 KB	18.9 KB	65.7 KB	520.8 KB	83%	22.6 KB
4	localhost	6.9 KB	0.0 KB	0.0 KB	6.9 KB	100%	2.3 KB
TOTAL: 4		702.2 GB	780.5 MB	2.2 GB	705.2 GB	99%	198.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 3 04:15:02 - 04:59:59	140618	3.8%	52.12	19.7 GB	2.8%	7643.79
Jan 3 05:00:00 - 05:59:59	201594	5.4%	56.00	28.9 GB	4.1%	8405.20
Jan 3 06:00:00 - 06:59:59	154965	4.2%	43.05	33.4 GB	4.8%	9730.63
Jan 3 07:00:00 - 07:59:59	96623	2.6%	26.84	22.5 GB	3.2%	6548.00
Jan 3 08:00:00 - 08:59:59	117116	3.2%	32.53	26.0 GB	3.7%	7584.41
Jan 3 09:00:00 - 09:59:59	115139	3.1%	31.98	24.7 GB	3.5%	7200.79
Jan 3 10:00:00 - 10:59:59	139112	3.8%	38.64	37.8 GB	5.4%	10997.43
Jan 3 11:00:00 - 11:59:59	113568	3.1%	31.55	28.5 GB	4.1%	8313.36
Jan 3 12:00:00 - 12:59:59	91570	2.5%	25.44	19.3 GB	2.8%	5631.43
Jan 3 13:00:00 - 13:59:59	92920	2.5%	25.81	21.7 GB	3.1%	6329.26
Jan 3 14:00:00 - 14:59:59	128316	3.5%	35.64	31.3 GB	4.5%	9125.34
Jan 3 15:00:00 - 15:59:59	127565	3.4%	35.43	27.1 GB	3.9%	7907.19
Jan 3 16:00:00 - 16:59:59	149961	4.0%	41.66	34.8 GB	5.0%	10149.32
Jan 3 17:00:00 - 17:59:59	154046	4.2%	42.79	38.2 GB	5.4%	11118.46
Jan 3 18:00:00 - 18:59:59	154109	4.2%	42.81	33.9 GB	4.8%	9886.57
Jan 3 19:00:00 - 19:59:59	185589	5.0%	51.55	36.5 GB	5.2%	10620.06
Jan 3 20:00:00 - 20:59:59	213948	5.8%	59.43	31.7 GB	4.5%	9229.30
Jan 3 21:00:00 - 21:59:59	270805	7.3%	75.22	32.9 GB	4.7%	9579.76
Jan 3 22:00:00 - 22:59:59	203782	5.5%	56.61	24.1 GB	3.4%	7033.61
Jan 3 23:00:00 - 23:59:59	287889	7.8%	79.97	32.4 GB	4.6%	9429.94
Jan 4 00:00:00 - 00:59:59	134126	3.6%	37.26	28.1 GB	4.0%	8171.84
Jan 4 01:00:00 - 01:59:59	137403	3.7%	38.17	27.2 GB	3.9%	7923.04
Jan 4 02:00:00 - 02:59:59	126479	3.4%	35.13	27.1 GB	3.9%	7886.89
Jan 4 03:00:00 - 03:59:59	135209	3.7%	37.56	28.2 GB	4.0%	8203.82
Jan 4 04:00:00 - 04:15:02	30979	0.8%	34.34	6.8 GB	1.0%	7885.48
TOTAL: 24:00:00	3703431	100.0%	42.86	702.8 GB	100.0%	8529.58

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10139	48	230	7	9854
2	newsfeed.pionier.net.pl	9872	165	5833	0	3874
3	news.icm.edu.pl	5	0	1	0	4
TOTAL: 3		20016	213	6064	7	13732

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	109
comp.mobile.ipad	27
it.comp.os.win.windows10	21
it.tlc.cellulari.android	18
comp.mobile.android	12
comp.sys.raspberry-pi	11
it.discussioni.energia	9
it.comp.os.linux.ubuntu	3
hr.rec.glazba.hifi	1
de.comm.provider.telefonie	1
alt.sex.telephones	1
TOTAL: 11	213

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

Groups	Count
pl.sci.matematyka	5
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 3	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	7890
EMP (md5)	4697
Scoring filter (9)	49
Scoring filter (8)	19
Scoring filter (10)	13
Scoring filter (16)	6
Scoring filter (18)	4
Scoring filter (13)	3
Scoring filter (12)	2
Scoring filter (11)	2
HTML post	2
Scoring filter (14)	1
Too many newsgroups	1
Too many newsgroups (low_xpost)	1
TOTAL: 14	12690

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	687
	newsfeed.pionier.net.pl	352
	TOTAL: 2	1039
TOTAL: 1		1039

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44157	1077	15487	409	4	41073	2%	23:59:57
2	poznan	18590	403	11092	62	0	0	2%	23:59:38
3	icm	22299	56	18403	2	0	0	0%	23:59:55
4	nask	1695	8	1354	0	0	0	0%	23:47:10
5	okapi	0	0	0	0	0	1372	0%	23:57:50
TOTAL: 5		86741	1544	46336	473	4	42445	1%	119:44:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.0 MB	62.2 KB	21.1 MB	0.2 KB/s	371.7 KB	23:59:55
2	panorama	14.1 MB	10.9 MB	25.0 MB	0.3 KB/s	17.2 KB	23:59:57
3	poznan	1.8 MB	2.0 MB	3.8 MB	0.0 KB/s	8.3 KB	23:59:38
4	nask	12.9 KB	0.0 KB	12.9 KB	0.0 KB/s	1.6 KB	23:47:10
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		36.9 MB	12.9 MB	49.8 MB	0.1 KB/s	25.3 KB	119:44:30

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	44	38.0 KB	19	0	00:00:23
2	212.51.203.199	2	2	25.4 KB	1	0	00:00:41
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		293	46	63.4 KB	20	3	00:01:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	44	38.0 KB	19	3	00:00:28
2	unresolved	2	2	25.4 KB	1	0	00:00:41
TOTAL: 2		677	46	63.4 KB	20	3	00:01:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.51.203.199	0.012	0.064	00:00:41
web.man.lodz.pl	1.268	6.896	00:00:23
mon.man.lodz.pl	1.136	6.768	00:00:03
david.man.lodz.pl	0.320	2.348	00:00:01
isvcs01.man.lodz.pl	0.020	0.076	00:00:00
TOTAL: 5	2.756	16.152	00:01:09

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	46	100.0%
TOTAL: 1		46	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	44
2	pl.rec.radio.amatorskie	2
TOTAL: 2		46

Daily Usenet report

Jan 3 04:15:02 -- Jan 4 04:15:02