Daily Usenet report: Dec 28 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 28 10:13:10 newsfeed nnrpd[16599]: SERVER perl filter not defined
Dec 28 21:12:16 newsfeed controlchan[11022]: skipping newgroup alt.kimmo.korpi y alt@alt.alt (would be created): Empty description
Dec 28 21:12:19 newsfeed controlchan[11022]: skipping newgroup alt.kimmo.korpi y alt@alt.alt (would be created): Empty description
Dec 28 21:12:23 newsfeed controlchan[11022]: skipping newgroup alt.kimmo.korpi y alt@alt.alt (would be created): Empty description

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2968777	98.6%	347.7 MB	98.4%
innd	31856	1.1%	4.6 MB	1.3%
innfeed	4991	0.2%	712.0 KB	0.2%
nnrpd	4789	0.2%	495.2 KB	0.1%
controlchan	6	0.0%	0.8 KB	0.0%
TOTAL: 5	3010419	100.0%	353.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2963376	96.4%
Negative hits	98428	3.2%
Positive hits	12260	0.4%
Cache misses	129	0.0%
TOTAL: 4	3074193	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.952	0.0%	177	0.000	11.028	125.200
article cleanup	00:00:48.860	0.1%	2968897	0.007	0.016	0.031
article logging	00:01:50.502	0.1%	2969453	0.019	0.037	0.762
article parse	01:20:49.311	5.6%	153507772	0.013	0.032	0.071
article write	01:25:16.176	5.9%	2954197	0.351	1.732	5.643
artlog/artcncl	00:00:00.004	0.0%	164	0.000	0.024	0.111
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:03:04.547	0.2%	170693040	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.711	0.0%	159	0.000	4.472	51.250
hishave/artcncl	00:00:00.091	0.0%	177	0.000	0.514	13.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:16.085	2.1%	3077016	0.114	0.590	8.260
history sync	00:00:00.018	0.0%	291	0.000	0.062	1.000
history write	00:41:15.547	2.9%	2966272	0.262	0.835	9.795
hiswrite/artcncl	00:00:00.025	0.0%	18	0.000	1.389	15.000
idle	11:53:51.868	49.6%	86753827	0.289	0.494	1.050
nntp read	01:02:01.833	4.3%	150924959	0.013	0.025	0.036
overview write	00:00:20.159	0.0%	2954197	0.001	0.007	0.017
perl filter	06:18:59.036	26.3%	2966268	4.776	7.666	9.533
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:55.204	0.4%	6233653	0.036	0.057	0.087
TOTAL: 24:00:25.788	23:24:31.929	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.353	0.0%	384355	0.000	0.004	0.167
article prepare	00:00:00.314	0.0%	1245946	0.000	0.000	0.025
article read	00:12:12.970	0.8%	311177	0.000	2.355	386.200
backlog stats	00:00:02.027	0.0%	1773640	0.000	0.001	0.036
callbacks	00:00:00.000	0.0%	13	0.000	0.000	0.000
data read	00:00:10.386	0.0%	730003	0.000	0.014	0.125
data write	00:02:35.610	0.2%	1063197	0.000	0.146	0.451
idle	23:44:24.112	98.9%	1773640	8.872	48.186	5221.667
status file	00:00:01.018	0.0%	1251	0.000	0.814	9.000
TOTAL: 24:00:08.870	23:59:27.790	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:19:08.515	99.7%	22844	0.000	207.867	384.222
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.008	0.0%	46381	0.000	0.022	0.789
readart	00:00:00.019	0.0%	66	0.000	0.288	5.000
TOTAL: 01:19:24.821	01:19:09.542	99.7%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
alt@alt.alt	3	0	0	0	3	3
TOTAL	3	0	0	0	3	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	459	2953461	2943788	6987	2686	99%	409:28:50
2	news.nask.pl	1	24112	9639	2456	12017	39%	24:00:47
3	news.icm.edu.pl	45	187	18	162	7	9%	15:47:48
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		507	2977761	2953446	9605	14710	99%	449:17:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	868.9 GB	51.7 MB	62.9 MB	869.0 GB	99%	309.3 KB
2	news.nask.pl	54.2 MB	771.5 KB	155.5 MB	210.5 MB	25%	10.0 KB
3	news.icm.edu.pl	331.6 KB	0.0 KB	750.0 KB	1.1 MB	30%	43.3 KB
4	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 4		868.9 GB	52.5 MB	219.2 MB	869.2 GB	99%	307.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 28 04:15:02 - 04:59:59	102761	3.5%	38.09	29.7 GB	3.4%	11541.26
Dec 28 05:00:00 - 05:59:59	141466	4.8%	39.30	40.9 GB	4.7%	11922.99
Dec 28 06:00:00 - 06:59:59	133271	4.5%	37.02	39.4 GB	4.5%	11475.60
Dec 28 07:00:00 - 07:59:59	119070	4.0%	33.08	38.6 GB	4.4%	11237.65
Dec 28 08:00:00 - 08:59:59	132513	4.5%	36.81	37.3 GB	4.3%	10857.23
Dec 28 09:00:00 - 09:59:59	145795	4.9%	40.50	38.4 GB	4.4%	11191.22
Dec 28 10:00:00 - 10:59:59	114011	3.9%	31.67	35.1 GB	4.0%	10217.62
Dec 28 11:00:00 - 11:59:59	98640	3.3%	27.40	31.6 GB	3.6%	9197.26
Dec 28 12:00:00 - 12:59:59	102901	3.5%	28.58	33.1 GB	3.8%	9627.54
Dec 28 13:00:00 - 13:59:59	117015	4.0%	32.50	39.2 GB	4.5%	11419.80
Dec 28 14:00:00 - 14:59:59	149223	5.1%	41.45	39.8 GB	4.6%	11594.22
Dec 28 15:00:00 - 15:59:59	146601	5.0%	40.72	40.0 GB	4.6%	11646.56
Dec 28 16:00:00 - 16:59:59	143434	4.9%	39.84	40.0 GB	4.6%	11656.51
Dec 28 17:00:00 - 17:59:59	131690	4.5%	36.58	37.6 GB	4.3%	10956.57
Dec 28 18:00:00 - 18:59:59	118040	4.0%	32.79	36.6 GB	4.2%	10651.72
Dec 28 19:00:00 - 19:59:59	114401	3.9%	31.78	35.6 GB	4.1%	10358.49
Dec 28 20:00:00 - 20:59:59	130450	4.4%	36.24	42.5 GB	4.9%	12377.33
Dec 28 21:00:00 - 21:59:59	112206	3.8%	31.17	36.4 GB	4.2%	10589.37
Dec 28 22:00:00 - 22:59:59	102324	3.5%	28.42	32.6 GB	3.7%	9493.29
Dec 28 23:00:00 - 23:59:59	75442	2.6%	20.96	21.8 GB	2.5%	6335.34
Dec 29 00:00:00 - 00:59:59	69910	2.4%	19.42	20.7 GB	2.4%	6016.96
Dec 29 01:00:00 - 01:59:59	154357	5.2%	42.88	43.1 GB	5.0%	12541.91
Dec 29 02:00:00 - 02:59:59	121149	4.1%	33.65	33.7 GB	3.9%	9807.41
Dec 29 03:00:00 - 03:59:59	140951	4.8%	39.15	35.4 GB	4.1%	10312.15
Dec 29 04:00:00 - 04:15:01	35737	1.2%	39.66	10.4 GB	1.2%	12099.01
TOTAL: 23:59:59	2953358	100.0%	34.18	869.2 GB	100.0%	10548.55

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12235	39	284	8	11904
2	newsfeed.pionier.net.pl	3011	176	2342	2	491
3	news.icm.edu.pl	9	0	0	0	9
TOTAL: 3		15255	215	2626	10	12404

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	145
comp.mobile.android	44
it.comp.os.win.windows10	10
comp.mobile.ipad	4
it.tlc.cellulari.android	3
it.comp.os.win.windows7	2
comp.sys.raspberry-pi	2
it.discussioni.energia	2
de.rec.sf.startrek	1
hr.alt.cellular.gsm.android	1
it.tlc.cellulari.iphone	1
TOTAL: 11	215

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11180
Binary in non-binary group	408
EMP (phl)	134
Scoring filter (8)	45
Scoring filter (9)	36
Scoring filter (10)	11
HTML post	9
Scoring filter (14)	5
Scoring filter (12)	4
Too many newsgroups	3
Scoring filter (11)	2
Scoring filter (13)	2
Scoring filter (17)	2
Excessively crossposted test article	2
EMP (fsl)	2
Too many newsgroups (low_xpost)	1
TOTAL: 16	11846

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	322996	287536	13799	20953	3	51791	89%	23:55:39
2	panorama	37639	1448	33266	874	30	296642	3%	24:03:49
3	poznan	13290	205	9248	36	0	0	1%	23:50:35
4	nask	5715	12	4509	0	0	0	0%	23:57:14
5	okapi	0	0	0	0	0	1449	0%	24:00:00
TOTAL: 5		379640	289201	60822	21863	33	349882	76%	119:47:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	51.6 GB	3.9 GB	55.4 GB	674.9 KB/s	188.5 KB	23:55:39
2	panorama	3.7 MB	7.7 MB	11.4 MB	0.1 KB/s	5.0 KB	24:03:49
3	poznan	1.0 MB	124.3 KB	1.2 MB	0.0 KB/s	4.9 KB	23:50:35
4	nask	159.5 KB	0.0 KB	159.5 KB	0.0 KB/s	13.3 KB	23:57:14
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		51.6 GB	3.9 GB	55.5 GB	134.8 KB/s	186.9 KB	119:47:17

Backlog files shrunk by innfeed:

Server	Size
panorama	25.8 MB
TOTAL: 1	25.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	64	54.4 KB	28	0	00:00:22
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:02
TOTAL: 2		289	64	54.4 KB	28	1	01:19:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	673	64	54.4 KB	28	1	00:00:29
2	unresolved	2	0	0.0 KB	0	0	01:18:53
3	*.hsd1.nj.comcast.net	2	0	0.0 KB	0	0	00:00:01
TOTAL: 3		677	64	54.4 KB	28	1	01:19:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.32	1.148	1.780	01:18:50
web.man.lodz.pl	0.920	5.902	00:00:22
196.52.20.22	0.004	0.018	00:00:03
mon.man.lodz.pl	1.017	5.532	00:00:03
isvcs01.man.lodz.pl	0.000	0.022	00:00:02
c-73-195-247-24.hsd1.nj.comcast.net	0.000	0.038	00:00:01
david.man.lodz.pl	0.361	1.779	00:00:01
TOTAL: 7	3.450	15.071	01:19:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
c-73-195-247-24.hsd1.nj.comcast.net	2
89.38.41.32	1
196.52.20.22	1
TOTAL: 5	388

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	50
2	pl.comp.os.ms-windows.winnt	14
TOTAL: 2		64

Daily Usenet report

Dec 28 04:15:02 -- Dec 29 04:15:01