Daily Usenet report: Dec 6 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Dec 6 08:04:06 newsfeed overchan[28457]: input line too long, skipping
Dec 6 08:08:49 newsfeed overchan[28457]: input line too long, skipping
Dec 6 10:41:41 newsfeed overchan[28457]: input line too long, skipping
Dec 6 10:50:28 newsfeed nnrpd[10155]: SERVER perl filter not defined
Dec 6 15:01:16 newsfeed nnrpd[18615]: SERVER perl filter not defined
Dec 6 15:05:10 newsfeed nnrpd[18696]: SERVER perl filter not defined
Dec 6 15:28:37 newsfeed overchan[28457]: input line too long, skipping
Dec 6 16:01:06 newsfeed nnrpd[20636]: SERVER perl filter not defined
Dec 6 16:20:29 newsfeed nnrpd[21305]: SERVER perl filter not defined
Dec 6 17:11:39 newsfeed overchan[28457]: input line too long, skipping
Dec 6 17:11:56 newsfeed overchan[28457]: input line too long, skipping
Dec 6 17:45:15 newsfeed nnrpd[24076]: SERVER perl filter not defined
Dec 6 18:24:03 newsfeed nnrpd[25387]: SERVER perl filter not defined
Dec 6 18:49:43 newsfeed nnrpd[26143]: SERVER perl filter not defined
Dec 6 22:22:51 newsfeed overchan[28457]: input line too long, skipping
Dec 6 23:43:01 newsfeed overchan[28457]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2306185	96.9%	274.8 MB	96.7%
innfeed	49606	2.1%	5.9 MB	2.1%
innd	18261	0.8%	3.0 MB	1.0%
nnrpd	4936	0.2%	525.1 KB	0.2%
overchan	9	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2378998	100.0%	284.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2287864	91.8%
Positive hits	123905	5.0%
Negative hits	79823	3.2%
Cache misses	552	0.0%
TOTAL: 4	2492144	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.637	0.0%	82	0.000	32.159	100.000
article cleanup	00:00:32.435	0.0%	2305796	0.009	0.014	0.019
article logging	00:01:52.892	0.1%	2306222	0.035	0.049	0.065
article parse	00:45:33.499	3.2%	77694462	0.019	0.035	0.049
article write	00:17:48.988	1.2%	2297532	0.348	0.465	0.803
artlog/artcncl	00:00:00.001	0.0%	82	0.000	0.012	1.000
data move	00:02:14.717	0.2%	90621468	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.617	0.0%	82	0.000	7.524	31.000
hishave/artcncl	00:00:00.165	0.0%	82	0.000	2.012	23.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:17:40.086	9.6%	2506282	0.838	3.296	8.569
history sync	00:00:00.018	0.0%	290	0.000	0.062	1.000
history write	03:22:46.547	14.1%	2299943	1.805	5.290	11.300
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	11:21:27.581	47.3%	52181220	0.258	0.784	2.012
nntp read	01:00:19.613	4.2%	75968433	0.031	0.048	0.062
overview write	00:00:13.597	0.0%	2297532	0.004	0.006	0.009
perl filter	04:24:30.913	18.4%	2299931	5.548	6.901	8.498
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:46.685	0.4%	4900258	0.048	0.071	0.089
TOTAL: 24:00:02.984	23:40:50.991	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:02.121	0.0%	595786	0.001	0.004	0.006
article prepare	00:00:00.198	0.0%	703712	0.000	0.000	0.001
article read	00:01:35.786	0.1%	171253	0.252	0.559	6.016
backlog stats	00:00:02.460	0.0%	2535662	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:25.125	0.0%	1190449	0.013	0.021	0.040
data write	00:06:37.713	0.5%	1413139	0.135	0.281	0.374
idle	23:39:46.355	99.3%	2535662	20.520	33.595	80.943
status file	00:00:23.813	0.0%	17229	0.603	1.382	9.573
TOTAL: 23:50:15.339	23:48:53.571	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:14.658	49.7%	3116	0.000	4.704	31.556
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.354	1.2%	6928	0.000	0.051	2.000
readart	00:00:00.034	0.1%	80	0.000	0.425	6.500
TOTAL: 00:00:29.477	00:00:15.046	51.0%	-	-	-	-

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	976	2297683	2292898	1975	2810	99%	449:45:59
2	news.nask.pl	2	127971	5455	117064	5452	4%	24:01:51
3	news.icm.edu.pl	46	262	147	111	4	56%	18:08:47
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1033	2425924	2298508	119150	8266	94%	491:56:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	659.2 GB	108.5 MB	109.3 MB	659.4 GB	99%	301.2 KB
2	news.nask.pl	369.6 MB	440.3 MB	454.3 MB	1.2 GB	29%	118.7 KB
3	news.icm.edu.pl	3.2 MB	17.7 KB	42.0 KB	3.3 MB	98%	22.3 KB
4	localhost	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	1.5 KB
TOTAL: 4		659.5 GB	548.8 MB	563.7 MB	660.6 GB	99%	300.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 6 04:15:02 - 04:59:59	64178	2.8%	23.79	20.2 GB	3.1%	7866.48
Dec 6 05:00:00 - 05:59:59	113559	4.9%	31.54	36.4 GB	5.5%	10604.31
Dec 6 06:00:00 - 06:59:59	110354	4.8%	30.65	35.9 GB	5.5%	10463.84
Dec 6 07:00:00 - 07:59:59	99375	4.3%	27.60	31.1 GB	4.7%	9051.19
Dec 6 08:00:00 - 08:59:59	121420	5.3%	33.73	30.2 GB	4.6%	8794.17
Dec 6 09:00:00 - 09:59:59	107202	4.7%	29.78	33.6 GB	5.1%	9800.10
Dec 6 10:00:00 - 10:59:59	100089	4.4%	27.80	32.3 GB	4.9%	9406.10
Dec 6 11:00:00 - 11:59:59	101723	4.4%	28.26	30.9 GB	4.7%	9002.86
Dec 6 12:00:00 - 12:59:59	112960	4.9%	31.38	30.4 GB	4.6%	8857.77
Dec 6 13:00:00 - 13:59:59	125082	5.4%	34.74	30.5 GB	4.6%	8885.90
Dec 6 14:00:00 - 14:59:59	131882	5.7%	36.63	33.6 GB	5.1%	9794.85
Dec 6 15:00:00 - 15:59:59	56989	2.5%	15.83	16.2 GB	2.5%	4715.26
Dec 6 16:00:00 - 16:59:59	62745	2.7%	17.43	17.0 GB	2.6%	4953.26
Dec 6 17:00:00 - 17:59:59	54171	2.4%	15.05	14.1 GB	2.1%	4106.57
Dec 6 18:00:00 - 18:59:59	67738	2.9%	18.82	18.9 GB	2.9%	5519.32
Dec 6 19:00:00 - 19:59:59	118598	5.2%	32.94	30.6 GB	4.6%	8899.65
Dec 6 20:00:00 - 20:59:59	103001	4.5%	28.61	28.4 GB	4.3%	8266.61
Dec 6 21:00:00 - 21:59:59	99381	4.3%	27.61	28.8 GB	4.4%	8379.13
Dec 6 22:00:00 - 22:59:59	103651	4.5%	28.79	28.8 GB	4.4%	8393.53
Dec 6 23:00:00 - 23:59:59	81657	3.6%	22.68	22.1 GB	3.4%	6433.81
Dec 7 00:00:00 - 00:59:59	93155	4.1%	25.88	19.6 GB	3.0%	5719.99
Dec 7 01:00:00 - 01:59:59	91970	4.0%	25.55	28.5 GB	4.3%	8315.59
Dec 7 02:00:00 - 02:59:59	97732	4.3%	27.15	33.0 GB	5.0%	9618.72
Dec 7 03:00:00 - 03:59:59	64120	2.8%	17.81	22.2 GB	3.4%	6478.83
Dec 7 04:00:00 - 04:15:02	14686	0.6%	16.28	5.1 GB	0.8%	5931.37
TOTAL: 24:00:00	2297418	100.0%	26.59	658.6 GB	100.0%	7993.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5518	6	3283	1	2228
2	newsfeed.pionier.net.pl	3156	180	2564	4	408
3	news.icm.edu.pl	10	0	2	0	8
TOTAL: 3		8684	186	5849	5	2644

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	99
it.comp.os.win.windows10	52
it.tlc.cellulari.android	19
comp.mobile.android	12
it.comp.os.win.windows7	3
it.comp.os.linux.ubuntu	1
TOTAL: 6	186

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2045
Binary in non-binary group	99
Scoring filter (16)	18
Scoring filter (8)	11
Scoring filter (9)	10
EMP (phl)	7
Too many newsgroups	5
Scoring filter (10)	5
Scoring filter (12)	2
Scoring filter (11)	2
HTML post	1
Scoring filter (20)	1
Scoring filter (13)	1
TOTAL: 13	2207

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	12
	TOTAL: 1	12
Including strange strings
	newsfeed.pionier.net.pl	345
	news.nask.pl	74
	news.icm.edu.pl	6
	TOTAL: 3	425
TOTAL: 2		437

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nask	387620	170654	123154	9	134	44%	23:59:58
2	panorama	306918	832	277655	79	321382	0%	23:59:58
3	poznan	6476	89	4540	26	0	1%	23:59:58
4	icm	326922	14	299920	5947	268	0%	23:59:58
5	okapi	0	0	0	0	1080	0%	23:57:51
TOTAL: 5		1027936	171589	705269	6061	322864	16%	119:57:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	63.4 GB	2.3 MB	63.4 GB	769.3 KB/s	389.5 KB	23:59:58
2	panorama	220.9 MB	6.7 MB	227.6 MB	2.7 KB/s	255.8 KB	23:59:58
3	poznan	959.8 KB	76.5 KB	1.0 MB	0.0 KB/s	9.0 KB	23:59:58
4	icm	112.1 KB	2.2 GB	2.2 GB	26.3 KB/s	381.7 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		63.6 GB	2.2 GB	65.8 GB	159.7 KB/s	388.3 KB	119:57:43

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	80	79.2 KB	36	0	00:00:22
2	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 2		296	80	79.2 KB	36	8	00:00:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	584	80	79.2 KB	36	8	00:00:27
2	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 2		680	80	79.2 KB	36	8	00:00:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.576	4.104	00:00:22
mon.man.lodz.pl	1.340	4.064	00:00:03
isvcs01.man.lodz.pl	0.052	0.140	00:00:01
212.51.192.185	0.388	1.300	00:00:01
TOTAL: 4	3.356	9.608	00:00:28

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	74
2	pl.comp.pecet	6
TOTAL: 2		80

Daily Usenet report

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