Daily Usenet report: Dec 6 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 6 04:15:02 newsfeed nnrpd[12298]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 04:15:02 newsfeed nnrpd[12304]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 04:15:02 newsfeed nnrpd[12306]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 16:17:01 newsfeed nnrpd[21488]: SERVER perl filter not defined
Dec 6 16:17:54 newsfeed nnrpd[21492]: SERVER perl filter not defined
Dec 6 16:18:34 newsfeed nnrpd[21495]: SERVER perl filter not defined
Dec 6 16:19:12 newsfeed nnrpd[21514]: SERVER perl filter not defined
Dec 6 16:19:31 newsfeed nnrpd[21516]: SERVER perl filter not defined
Dec 6 16:19:46 newsfeed nnrpd[21522]: SERVER perl filter not defined
Dec 6 16:20:00 newsfeed nnrpd[21528]: SERVER perl filter not defined
Dec 6 16:21:38 newsfeed nnrpd[21589]: SERVER perl filter not defined
Dec 7 00:22:25 newsfeed nnrpd[5721]: SERVER perl filter not defined
Dec 7 01:02:18 newsfeed nnrpd[7100]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3014362	98.5%	351.0 MB	98.2%
innd	36341	1.2%	5.2 MB	1.4%
nnrpd	4940	0.2%	517.4 KB	0.1%
innfeed	4862	0.2%	678.9 KB	0.2%
TOTAL: 4	3060505	100.0%	357.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3007461	96.8%
Negative hits	84132	2.7%
Positive hits	15062	0.5%
Cache misses	115	0.0%
TOTAL: 4	3106770	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.109	0.0%	191	0.000	11.042	179.250
article cleanup	00:00:43.913	0.1%	3011912	0.007	0.015	0.028
article logging	00:01:38.032	0.1%	3012594	0.015	0.033	0.943
article parse	01:17:16.380	5.4%	188832640	0.015	0.025	0.081
article write	00:27:11.252	1.9%	2995101	0.280	0.545	3.864
artlog/artcncl	00:00:00.004	0.0%	177	0.000	0.023	0.182
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:00.605	0.2%	207031252	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.338	0.0%	179	0.000	1.888	17.000
hishave/artcncl	00:00:00.061	0.0%	191	0.000	0.319	2.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:29:59.554	2.1%	3107247	0.098	0.579	8.503
history sync	00:00:00.013	0.0%	294	0.000	0.044	2.000
history write	00:43:01.502	3.0%	3008055	0.223	0.858	9.658
hiswrite/artcncl	00:00:00.004	0.0%	12	0.000	0.333	2.000
idle	12:33:44.248	52.4%	129560893	0.137	0.349	0.966
nntp read	01:13:06.544	5.1%	186398873	0.016	0.024	0.042
overview write	00:00:16.605	0.0%	2995101	0.001	0.006	0.014
perl filter	06:13:59.001	26.0%	3008052	5.261	7.460	10.058
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:51.927	0.3%	6023157	0.027	0.048	0.082
TOTAL: 23:59:15.839	23:08:52.092	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.164	0.0%	38728	0.000	0.004	0.250
article prepare	00:00:00.014	0.0%	42762	0.000	0.000	0.050
article read	00:00:26.070	0.0%	10694	0.000	2.438	1741.000
backlog stats	00:00:00.172	0.0%	209018	0.000	0.001	0.033
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:01.556	0.0%	108644	0.000	0.014	0.111
data write	00:00:08.660	0.0%	95877	0.000	0.090	0.240
idle	23:59:06.185	100.0%	209018	29.057	413.104	7898.778
status file	00:00:00.687	0.0%	892	0.000	0.770	5.333
TOTAL: 23:59:49.264	23:59:43.508	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	6	0.167	0.167	0.167
idle	20:11:52.163	99.9%	477021	0.000	152.430	67850.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:19.458	0.0%	954833	0.000	0.020	1.000
readart	00:00:01.056	0.0%	75	0.000	14.080	33.968
TOTAL: 20:12:54.911	20:12:12.678	99.9%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1303	2997404	2987325	5275	4804	99%	371:31:49
2	news.nask.pl	2	26674	9128	5534	12012	34%	23:34:59
3	news.icm.edu.pl	44	353	24	292	37	6%	17:34:57
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1360	3024441	2996487	11101	16853	99%	412:41:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	873.2 GB	125.4 MB	329.0 MB	873.7 GB	99%	306.2 KB
2	news.nask.pl	150.1 MB	4.1 MB	127.1 MB	281.4 MB	53%	13.6 KB
3	news.icm.edu.pl	543.4 KB	0.0 KB	1.0 MB	1.5 MB	34%	25.8 KB
4	localhost	15.5 KB	0.0 KB	0.0 KB	15.5 KB	100%	1.6 KB
TOTAL: 4		873.4 GB	129.5 MB	457.1 MB	874.0 GB	99%	304.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 6 04:15:02 - 04:59:59	95475	3.2%	35.39	26.9 GB	3.1%	10453.47
Dec 6 05:00:00 - 05:59:59	124935	4.2%	34.70	36.9 GB	4.2%	10738.13
Dec 6 06:00:00 - 06:59:59	125369	4.2%	34.82	36.8 GB	4.2%	10708.90
Dec 6 07:00:00 - 07:59:59	131835	4.4%	36.62	38.2 GB	4.4%	11137.22
Dec 6 08:00:00 - 08:59:59	128621	4.3%	35.73	37.8 GB	4.3%	11008.20
Dec 6 09:00:00 - 09:59:59	121551	4.1%	33.76	35.5 GB	4.1%	10338.03
Dec 6 10:00:00 - 10:59:59	88456	3.0%	24.57	29.7 GB	3.4%	8658.62
Dec 6 11:00:00 - 11:59:59	87998	2.9%	24.44	29.4 GB	3.4%	8562.59
Dec 6 12:00:00 - 12:59:59	107692	3.6%	29.91	34.3 GB	3.9%	10004.80
Dec 6 13:00:00 - 13:59:59	117658	3.9%	32.68	34.8 GB	4.0%	10141.14
Dec 6 14:00:00 - 14:59:59	125886	4.2%	34.97	35.1 GB	4.0%	10234.46
Dec 6 15:00:00 - 15:59:59	133080	4.4%	36.97	38.5 GB	4.4%	11226.71
Dec 6 16:00:00 - 16:59:59	108237	3.6%	30.07	34.6 GB	4.0%	10087.82
Dec 6 17:00:00 - 17:59:59	101024	3.4%	28.06	34.8 GB	4.0%	10138.73
Dec 6 18:00:00 - 18:59:59	133230	4.4%	37.01	41.5 GB	4.8%	12094.43
Dec 6 19:00:00 - 19:59:59	138059	4.6%	38.35	42.5 GB	4.9%	12381.36
Dec 6 20:00:00 - 20:59:59	144453	4.8%	40.13	41.5 GB	4.8%	12076.96
Dec 6 21:00:00 - 21:59:59	166738	5.6%	46.32	40.3 GB	4.6%	11731.94
Dec 6 22:00:00 - 22:59:59	154651	5.2%	42.96	38.9 GB	4.5%	11344.01
Dec 6 23:00:00 - 23:59:59	152693	5.1%	42.41	38.1 GB	4.4%	11108.69
Dec 7 00:00:00 - 00:59:59	119103	4.0%	33.08	33.6 GB	3.9%	9781.54
Dec 7 01:00:00 - 01:59:59	109723	3.7%	30.48	30.6 GB	3.5%	8903.67
Dec 7 02:00:00 - 02:59:59	132483	4.4%	36.80	38.1 GB	4.4%	11109.36
Dec 7 03:00:00 - 03:59:59	117706	3.9%	32.70	34.1 GB	3.9%	9943.41
Dec 7 04:00:00 - 04:15:02	30024	1.0%	33.29	8.8 GB	1.0%	10220.89
TOTAL: 24:00:00	2996680	100.0%	34.68	871.5 GB	100.0%	10577.33

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12207	19	399	4	11785
2	newsfeed.pionier.net.pl	5265	179	3467	0	1619
3	news.icm.edu.pl	33	0	0	0	33
TOTAL: 3		17505	198	3866	4	13437

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	98
it.tlc.cellulari.android	40
it.comp.os.win.windows10	19
it.comp.os.win.windows7	11
comp.mobile.android	10
comp.sys.raspberry-pi	9
comp.mobile.ipad	4
alt.sex.telephones	2
de.comp.lang.vbclassic	2
uk.rec.cycling.moderated	2
comp.sys.mac.vintage	1
TOTAL: 11	198

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11335
Binary in non-binary group	1182
Scoring filter (8)	82
EMP (phl)	80
Poison newsgroup	26
Scoring filter (9)	20
Scoring filter (10)	10
HTML post	9
Too many newsgroups	3
Scoring filter (11)	2
Scoring filter (12)	1
Scoring filter (13)	1
TOTAL: 12	12751

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	6284	2061	2150	656	20	33778	32%	24:01:46
2	poznan	13531	197	8680	28	1	0	1%	23:55:05
3	icm	40854	90	25070	7289	0	0	0%	24:02:02
4	nask	10995	76	6898	11	0	0	0%	23:55:11
5	okapi	0	0	0	0	0	2401	0%	24:00:00
TOTAL: 5		71664	2424	42798	7984	21	36179	3%	119:54:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	10.2 MB	2.5 MB	12.7 MB	0.1 KB/s	4.8 KB	24:01:46
2	icm	1.9 MB	2.0 GB	2.0 GB	24.4 KB/s	286.0 KB	24:02:02
3	nask	1.4 MB	57.4 KB	1.5 MB	0.0 KB/s	17.1 KB	23:55:11
4	poznan	798.3 KB	132.1 KB	930.4 KB	0.0 KB/s	4.1 KB	23:55:05
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		14.3 MB	2.0 GB	2.0 GB	4.9 KB/s	204.3 KB	119:54:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	291	44	42.3 KB	19	0	0	00:00:23
2	staticline-31-183-114-28.toya.net.pl	9	31	49.7 KB	1	0	2	00:19:57
3	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	0	00:00:00
TOTAL: 3		310	75	91.9 KB	20	10	2	20:12:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	685	44	42.3 KB	19	10	0	00:00:29
2	*.toya.net.pl	10	31	49.7 KB	1	0	2	00:24:24
3	unresolved	4	0	0.0 KB	0	0	0	19:48:00
TOTAL: 3		699	75	91.9 KB	20	10	2	20:12:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.200	25.953	39.158	19:48:00
staticline-31-183-114-28.toya.net.pl	0.229	0.178	00:19:57
staticline-31-183-134-125.toya.net.pl	0.032	0.018	00:04:27
web.man.lodz.pl	0.995	4.800	00:00:23
mon.man.lodz.pl	0.928	4.567	00:00:03
david.man.lodz.pl	0.332	1.506	00:00:01
isvcs01.man.lodz.pl	0.063	0.171	00:00:00
TOTAL: 7	28.532	50.398	20:12:54

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.200	4
staticline-31-183-134-125.toya.net.pl	1
TOTAL: 4	389

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.200	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	44	58.7%
2	lodman	31	41.3%
TOTAL: 2		75	100.0%

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

Newsgroup		Count
1	lodman.test	31
2	pl.comp.pecet	26
3	pl.comp.os.ms-windows.winnt	18
TOTAL: 3		75

Daily Usenet report

Dec 6 04:15:02 -- Dec 7 04:15:02