Daily Usenet report: Dec 2 04:15:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Dec 2 06:07:50 newsfeed overchan[3056]: input line too long, skipping
Dec 2 06:25:49 newsfeed overchan[3056]: input line too long, skipping
Dec 2 06:26:05 newsfeed overchan[3056]: input line too long, skipping
Dec 2 07:42:23 newsfeed overchan[3056]: input line too long, skipping
Dec 2 22:57:45 newsfeed overchan[3056]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2608833	97.4%	304.5 MB	97.1%
innfeed	47727	1.8%	5.7 MB	1.8%
innd	18023	0.7%	3.0 MB	1.0%
nnrpd	4812	0.2%	515.3 KB	0.2%
overchan	6	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2679402	100.0%	313.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2581969	93.8%
Positive hits	90757	3.3%
Negative hits	78030	2.8%
Cache misses	1172	0.0%
TOTAL: 4	2751928	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.309	0.0%	366	0.000	25.434	271.750
article cleanup	00:00:32.694	0.0%	2613158	0.008	0.013	0.016
article logging	00:03:37.802	0.3%	2613749	0.032	0.083	10.251
article parse	00:41:38.944	2.9%	87065656	0.016	0.029	0.046
article write	00:17:29.386	1.2%	2603735	0.208	0.403	3.931
artlog/artcncl	00:00:00.023	0.0%	362	0.000	0.064	1.000
artlog/artparse	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:03:50.956	0.3%	101264749	0.001	0.002	0.003
hisgrep/artcncl	00:00:02.128	0.0%	365	0.000	5.830	203.750
hishave/artcncl	00:00:01.526	0.0%	366	0.000	4.169	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:49:31.058	7.6%	2780312	0.341	2.363	32.065
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	03:45:44.565	15.7%	2605006	1.395	5.199	79.355
hiswrite/artcncl	00:00:00.012	0.0%	1	12.000	12.000	12.000
idle	11:23:27.105	47.5%	61493300	0.123	0.667	1.890
nntp read	01:07:33.780	4.7%	85133334	0.033	0.048	0.064
overview write	00:00:13.304	0.0%	2603735	0.003	0.005	0.008
perl filter	04:12:14.269	17.5%	2605006	4.020	5.810	7.896
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:39.430	0.4%	5374931	0.047	0.063	0.082
TOTAL: 24:00:08.082	23:31:46.307	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.042	0.0%	326061	0.000	0.003	0.011
article prepare	00:00:00.060	0.0%	291294	0.000	0.000	0.003
article read	00:01:10.263	0.1%	66415	0.000	1.058	130.500
backlog stats	00:00:01.123	0.0%	1298012	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:13.084	0.0%	663416	0.008	0.020	0.031
data write	00:02:11.937	0.2%	671350	0.023	0.197	0.366
idle	23:44:20.789	99.5%	1298012	28.673	65.840	1223.702
status file	00:03:21.279	0.2%	16791	0.787	11.987	312.050
TOTAL: 23:52:03.642	23:51:19.577	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:11.931	47.3%	2988	0.000	3.993	38.222
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.230	0.9%	6576	0.000	0.035	1.000
readart	00:00:00.001	0.0%	12	0.000	0.083	0.500
TOTAL: 00:00:25.238	00:00:12.162	48.2%	-	-	-	-

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	787	2595758	2590510	842	4406	99%	512:29:47
2	news.nask.pl	32	95876	8325	82506	5045	8%	28:18:19
3	news.icm.edu.pl	40	286	125	137	24	43%	17:40:49
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		860	2691920	2598960	83485	9475	96%	558:28:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	670.6 GB	110.2 MB	111.7 MB	670.8 GB	99%	271.1 KB
2	news.nask.pl	1.3 GB	686.5 MB	697.1 MB	2.6 GB	48%	205.7 KB
3	news.icm.edu.pl	2.0 MB	16.7 KB	843.4 KB	2.8 MB	70%	19.5 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		671.8 GB	796.7 MB	809.6 MB	673.4 GB	99%	270.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 2 04:15:03 - 04:59:59	104248	4.0%	38.65	16.0 GB	2.4%	6214.41
Dec 2 05:00:00 - 05:59:59	144384	5.6%	40.11	32.4 GB	4.8%	9435.40
Dec 2 06:00:00 - 06:59:59	126775	4.9%	35.22	33.2 GB	4.9%	9660.76
Dec 2 07:00:00 - 07:59:59	101672	3.9%	28.24	31.8 GB	4.7%	9257.70
Dec 2 08:00:00 - 08:59:59	120323	4.6%	33.42	34.2 GB	5.1%	9962.70
Dec 2 09:00:00 - 09:59:59	110967	4.3%	30.82	33.6 GB	5.0%	9788.90
Dec 2 10:00:00 - 10:59:59	150552	5.8%	41.82	34.9 GB	5.2%	10165.65
Dec 2 11:00:00 - 11:59:59	136338	5.2%	37.87	31.2 GB	4.7%	9096.08
Dec 2 12:00:00 - 12:59:59	134543	5.2%	37.37	32.6 GB	4.9%	9495.84
Dec 2 13:00:00 - 13:59:59	113022	4.3%	31.39	31.9 GB	4.7%	9278.05
Dec 2 14:00:00 - 14:59:59	121457	4.7%	33.74	31.1 GB	4.6%	9055.11
Dec 2 15:00:00 - 15:59:59	102165	3.9%	28.38	26.2 GB	3.9%	7627.15
Dec 2 16:00:00 - 16:59:59	91844	3.5%	25.51	26.1 GB	3.9%	7595.08
Dec 2 17:00:00 - 17:59:59	103298	4.0%	28.69	30.2 GB	4.5%	8785.09
Dec 2 18:00:00 - 18:59:59	91543	3.5%	25.43	25.5 GB	3.8%	7416.89
Dec 2 19:00:00 - 19:59:59	78235	3.0%	21.73	23.6 GB	3.5%	6871.87
Dec 2 20:00:00 - 20:59:59	94246	3.6%	26.18	19.4 GB	2.9%	5653.65
Dec 2 21:00:00 - 21:59:59	156319	6.0%	43.42	32.9 GB	4.9%	9578.70
Dec 2 22:00:00 - 22:59:59	136532	5.3%	37.93	33.6 GB	5.0%	9793.94
Dec 2 23:00:00 - 23:59:59	98016	3.8%	27.23	27.6 GB	4.1%	8041.24
Dec 3 00:00:00 - 00:59:59	67758	2.6%	18.82	20.4 GB	3.0%	5928.02
Dec 3 01:00:00 - 01:59:59	75472	2.9%	20.96	23.1 GB	3.4%	6739.84
Dec 3 02:00:00 - 02:59:59	77970	3.0%	21.66	22.4 GB	3.3%	6535.59
Dec 3 03:00:00 - 03:59:59	51932	2.0%	14.43	15.1 GB	2.2%	4392.00
Dec 3 04:00:00 - 04:16:01	8842	0.3%	9.20	2.6 GB	0.4%	2813.52
TOTAL: 24:00:58	2598453	100.0%	30.05	671.4 GB	100.0%	8142.95

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5128	3	3914	12	1199
2	newsfeed.pionier.net.pl	4861	140	4230	5	486
3	news.icm.edu.pl	29	0	1	0	28
TOTAL: 3		10018	143	8145	17	1713

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	93
it.comp.os.win.windows10	16
it.tlc.cellulari.android	16
nl.comp.os.linux.techniek	5
comp.mobile.android	4
comp.sys.raspberry-pi	3
de.rec.sf.startrek	3
it.comp.os.win.windows7	3
TOTAL: 8	143

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

Groups	Count
pl.sci.matematyka	16
bit.listserv.catholic	1
TOTAL: 2	17

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	960
Binary in non-binary group	46
EMP (phl)	45
Scoring filter (16)	33
Scoring filter (8)	19
Excessively crossposted test article	3
Too many newsgroups	3
Scoring filter (9)	2
Too many newsgroups (low_xpost)	1
Scoring filter (10)	1
HTML post	1
TOTAL: 11	1114

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	news.nask.pl	90
	TOTAL: 1	90
Including strange strings
	newsfeed.pionier.net.pl	455
	news.nask.pl	131
	news.icm.edu.pl	5
	TOTAL: 3	591
TOTAL: 2		681

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	217291	63991	89432	22	0	8	29%	24:00:25
2	panorama	175221	847	146537	269	3	181479	0%	24:00:31
3	poznan	7271	83	4943	11	0	0	1%	23:48:45
4	icm	184768	67	159748	7613	0	0	0%	24:00:25
5	okapi	0	0	0	0	0	1078	0%	23:58:18
TOTAL: 5		584551	64988	400660	7915	3	182565	11%	119:48:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	23.8 GB	6.5 MB	23.8 GB	288.4 KB/s	389.4 KB	24:00:25
2	panorama	25.1 MB	2.8 MB	27.9 MB	0.3 KB/s	25.6 KB	24:00:31
3	icm	16.2 MB	2.8 GB	2.8 GB	34.4 KB/s	386.8 KB	24:00:25
4	poznan	363.2 KB	90.2 KB	453.4 KB	0.0 KB/s	4.8 KB	23:48:45
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:18
TOTAL: 5		23.8 GB	2.8 GB	26.6 GB	64.8 KB/s	383.1 KB	119:48:24

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	12	12.9 KB	6	0	0	00:00:18
TOTAL: 1		288	12	12.9 KB	6	0	0	00:00:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	12	12.9 KB	6	00:00:23
2	unresolved	96	0	0.0 KB	0	00:00:01
TOTAL: 2		672	12	12.9 KB	6	00:00:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.472	5.180	00:00:18
mon.man.lodz.pl	1.252	5.156	00:00:05
212.51.192.185	0.428	1.788	00:00:01
TOTAL: 3	3.152	12.124	00:00:24

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	10
2	pl.comp.pecet	2
TOTAL: 2		12

Daily Usenet report

Dec 2 04:15:03 -- Dec 3 04:16:01