Daily Usenet report: Sep 27 04:15:03

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Sep 27 08:24:42 newsfeed overchan[4735]: input line too long, skipping
Sep 27 08:25:02 newsfeed overchan[4735]: input line too long, skipping
Sep 27 08:57:07 newsfeed overchan[4735]: input line too long, skipping
Sep 27 09:07:12 newsfeed nnrpd[16002]: SERVER perl filter not defined
Sep 27 12:19:15 newsfeed nnrpd[23289]: SERVER perl filter not defined
Sep 27 13:35:30 newsfeed overchan[4735]: input line too long, skipping
Sep 27 13:35:48 newsfeed overchan[4735]: input line too long, skipping
Sep 27 17:42:45 newsfeed nnrpd[2701]: SERVER perl filter not defined
Sep 27 17:42:54 newsfeed nnrpd[2702]: SERVER perl filter not defined
Sep 27 19:33:55 newsfeed overchan[4735]: input line too long, skipping
Sep 27 19:35:58 newsfeed overchan[4735]: input line too long, skipping
Sep 27 19:44:06 newsfeed overchan[4735]: input line too long, skipping
Sep 27 20:02:41 newsfeed overchan[4735]: input line too long, skipping
Sep 27 20:04:56 newsfeed overchan[4735]: input line too long, skipping
Sep 27 21:52:45 newsfeed overchan[4735]: input line too long, skipping
Sep 27 22:00:51 newsfeed overchan[4735]: input line too long, skipping
Sep 27 22:04:12 newsfeed overchan[4735]: input line too long, skipping
Sep 27 22:17:35 newsfeed overchan[4735]: input line too long, skipping
Sep 27 22:54:42 newsfeed overchan[4735]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3292262	97.1%	380.6 MB	96.8%
innd	55386	1.6%	7.8 MB	2.0%
innfeed	36519	1.1%	4.3 MB	1.1%
nnrpd	4770	0.1%	499.1 KB	0.1%
overchan	16	0.0%	2.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3388954	100.0%	393.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3190020	80.7%
Negative hits	595356	15.1%
Positive hits	164850	4.2%
Cache misses	356	0.0%
TOTAL: 4	3950582	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.053	0.0%	222	0.000	13.752	83.455
article cleanup	00:00:50.320	0.1%	3305475	0.009	0.015	0.021
article logging	00:02:21.303	0.2%	3306368	0.023	0.043	0.127
article parse	01:14:26.868	5.2%	171849850	0.012	0.026	0.053
article write	00:26:22.519	1.8%	3196177	0.142	0.495	1.070
artlog/artcncl	00:00:00.006	0.0%	209	0.000	0.029	0.143
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:06:09.161	0.4%	190925232	0.001	0.002	0.004
hisgrep/artcncl	00:00:00.386	0.0%	216	0.000	1.787	11.000
hishave/artcncl	00:00:00.147	0.0%	222	0.000	0.662	7.636
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:31:01.080	2.2%	4011770	0.033	0.464	4.392
history sync	00:00:00.012	0.0%	290	0.000	0.041	0.500
history write	01:22:20.895	5.7%	3231173	0.136	1.529	8.795
hiswrite/artcncl	00:00:00.001	0.0%	6	0.000	0.167	1.000
idle	11:16:00.189	46.9%	113041535	0.215	0.359	0.772
nntp read	01:36:42.018	6.7%	169590172	0.020	0.034	0.059
overview write	00:00:18.016	0.0%	3196177	0.003	0.006	0.008
perl filter	06:31:14.059	27.2%	3231173	3.174	7.265	10.068
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:09.101	0.4%	6607390	0.036	0.056	0.079
TOTAL: 24:00:02.098	23:13:59.134	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.944	0.0%	351529	0.000	0.003	0.011
article prepare	00:00:00.033	0.0%	70030	0.000	0.000	0.021
article read	00:01:35.330	0.1%	16594	0.348	5.745	201.000
backlog stats	00:00:00.767	0.0%	1167871	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:13.561	0.0%	739927	0.008	0.018	0.078
data write	00:00:38.246	0.0%	524947	0.023	0.073	0.168
idle	23:48:59.292	99.8%	1167871	26.017	73.415	1166.854
status file	00:00:15.197	0.0%	12878	0.626	1.180	27.544
TOTAL: 23:52:16.106	23:51:43.370	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:58:25.603	99.3%	18698	0.000	187.485	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.771	0.0%	37986	0.000	0.020	0.667
readart	00:00:00.001	0.0%	18	0.000	0.056	0.500
TOTAL: 00:58:49.889	00:58:26.375	99.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	1158	3213974	3114160	13809	86005	96%	410:29:33
2	news.nask.pl	2	169115	69444	77961	21710	41%	23:50:34
3	news.icm.edu.pl	37	297	63	218	16	21%	19:12:16
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1202	3383390	3183671	91988	107731	94%	453:32:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	824.4 GB	21.0 GB	24.3 GB	869.7 GB	94%	285.0 KB
2	news.nask.pl	19.9 GB	716.2 MB	3.8 GB	24.5 GB	81%	281.4 KB
3	news.icm.edu.pl	1.4 MB	121.3 KB	698.6 KB	2.2 MB	63%	28.1 KB
4	localhost	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	1.5 KB
TOTAL: 4		844.3 GB	21.7 GB	28.1 GB	894.2 GB	94%	284.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 27 04:15:03 - 04:59:59	84502	2.7%	31.33	28.5 GB	3.4%	11093.62
Sep 27 05:00:00 - 05:59:59	105904	3.3%	29.42	37.3 GB	4.4%	10866.02
Sep 27 06:00:00 - 06:59:59	91246	2.9%	25.35	31.7 GB	3.8%	9221.84
Sep 27 07:00:00 - 07:59:59	113949	3.6%	31.65	33.9 GB	4.0%	9873.81
Sep 27 08:00:00 - 08:59:59	124921	3.9%	34.70	38.6 GB	4.6%	11245.44
Sep 27 09:00:00 - 09:59:59	137972	4.3%	38.33	38.9 GB	4.6%	11344.23
Sep 27 10:00:00 - 10:59:59	131821	4.1%	36.62	37.8 GB	4.5%	11020.87
Sep 27 11:00:00 - 11:59:59	136359	4.3%	37.88	40.0 GB	4.7%	11639.18
Sep 27 12:00:00 - 12:59:59	140951	4.4%	39.15	40.9 GB	4.8%	11922.50
Sep 27 13:00:00 - 13:59:59	200786	6.3%	55.77	27.7 GB	3.3%	8061.05
Sep 27 14:00:00 - 14:59:59	123264	3.9%	34.24	35.1 GB	4.2%	10231.15
Sep 27 15:00:00 - 15:59:59	131368	4.1%	36.49	36.2 GB	4.3%	10557.76
Sep 27 16:00:00 - 16:59:59	132462	4.2%	36.80	39.0 GB	4.6%	11358.85
Sep 27 17:00:00 - 17:59:59	122328	3.8%	33.98	37.6 GB	4.5%	10941.88
Sep 27 18:00:00 - 18:59:59	127441	4.0%	35.40	40.3 GB	4.8%	11732.42
Sep 27 19:00:00 - 19:59:59	172731	5.4%	47.98	38.2 GB	4.5%	11118.29
Sep 27 20:00:00 - 20:59:59	126125	4.0%	35.03	38.1 GB	4.5%	11102.28
Sep 27 21:00:00 - 21:59:59	166538	5.2%	46.26	32.6 GB	3.9%	9508.05
Sep 27 22:00:00 - 22:59:59	269038	8.5%	74.73	25.0 GB	3.0%	7273.16
Sep 27 23:00:00 - 23:59:59	143489	4.5%	39.86	35.1 GB	4.2%	10236.93
Sep 28 00:00:00 - 00:59:59	88445	2.8%	24.57	29.4 GB	3.5%	8568.79
Sep 28 01:00:00 - 01:59:59	113498	3.6%	31.53	38.9 GB	4.6%	11323.22
Sep 28 02:00:00 - 02:59:59	114934	3.6%	31.93	35.2 GB	4.2%	10262.45
Sep 28 03:00:00 - 03:59:59	71191	2.2%	19.78	24.3 GB	2.9%	7082.21
Sep 28 04:00:00 - 04:15:02	12231	0.4%	13.56	3.6 GB	0.4%	4241.95
TOTAL: 23:59:59	3183494	100.0%	36.85	844.1 GB	100.0%	10244.54

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	86373	158	71151	0	15064
2	news.nask.pl	22167	20	2714	4	19429
3	news.icm.edu.pl	19	0	3	0	16
TOTAL: 3		108559	178	73868	4	34509

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	93
it.tlc.cellulari.android	29
it.comp.os.win.windows10	18
de.rec.sf.startrek	11
it.comp.os.win.windows7	9
comp.sys.raspberry-pi	9
comp.mobile.ipad	5
comp.mobile.android	3
it.politica.m5s	1
TOTAL: 9	178

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
Binary in non-binary group	30753
EMP (md5)	2738
Scoring filter (8)	81
Scoring filter (9)	22
HTML post	16
Scoring filter (10)	10
Scoring filter (12)	1
Too many newsgroups	1
TOTAL: 8	33622

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	508
	newsfeed.pionier.net.pl	376
	news.icm.edu.pl	3
	TOTAL: 3	887
TOTAL: 1		887

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	200039	12346	124003	6	0	1	6%	23:59:57
2	panorama	156510	1772	133052	411	5	220459	1%	23:59:56
3	poznan	16754	254	10270	44	0	0	1%	23:59:48
4	icm	241549	148	211338	2000	0	1	0%	23:59:57
5	okapi	0	0	0	0	0	1821	0%	23:57:50
TOTAL: 5		614852	14520	478663	2461	5	222282	2%	119:57:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	4.2 GB	1.4 MB	4.2 GB	50.5 KB/s	353.5 KB	23:59:57
2	panorama	20.8 MB	6.3 MB	27.1 MB	0.3 KB/s	12.7 KB	23:59:56
3	icm	4.9 MB	647.3 MB	652.1 MB	7.7 KB/s	310.9 KB	23:59:57
4	poznan	936.2 KB	339.4 KB	1.2 MB	0.0 KB/s	4.3 KB	23:59:48
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		4.2 GB	655.3 MB	4.8 GB	11.7 KB/s	298.1 KB	119:57:28

Backlog files shrunk by innfeed:

Server	Size
panorama	5.7 MB
TOTAL: 1	5.7 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	18	18.2 KB	9	0	00:00:34
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	18	18.2 KB	9	4	00:58:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	18	18.2 KB	9	4	00:00:47
2	unresolved	3	0	0.0 KB	0	0	00:58:01
TOTAL: 2		679	18	18.2 KB	9	4	00:58:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.40.130	0.748	1.284	00:58:01
web.man.lodz.pl	1.460	4.316	00:00:34
mon.man.lodz.pl	1.268	4.308	00:00:09
david.man.lodz.pl	0.400	1.444	00:00:02
isvcs01.man.lodz.pl	0.028	0.088	00:00:00
TOTAL: 5	3.904	11.440	00:58:49

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.40.130	3
TOTAL: 3	387

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Sep 27 04:15:03 -- Sep 28 04:15:02