Daily Usenet report: Oct 25 04:15:03

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Oct 25 09:21:48 newsfeed nnrpd[6305]: SERVER perl filter not defined
Oct 25 09:22:12 newsfeed nnrpd[6317]: SERVER perl filter not defined
Oct 25 11:42:45 newsfeed overchan[26436]: input line too long, skipping
Oct 25 16:46:16 newsfeed overchan[26436]: input line too long, skipping
Oct 25 16:59:52 newsfeed overchan[26436]: input line too long, skipping
Oct 25 20:27:23 newsfeed overchan[26436]: input line too long, skipping
Oct 25 20:29:52 newsfeed overchan[26436]: input line too long, skipping
Oct 25 23:46:01 newsfeed overchan[26436]: input line too long, skipping
Oct 26 00:11:05 newsfeed overchan[26436]: input line too long, skipping
Oct 26 00:17:28 newsfeed overchan[26436]: input line too long, skipping
Oct 26 01:11:39 newsfeed overchan[26436]: input line too long, skipping
Oct 26 01:31:01 newsfeed overchan[26436]: input line too long, skipping
Oct 26 01:33:54 newsfeed overchan[26436]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3191515	97.3%	366.7 MB	96.9%
innd	56811	1.7%	7.9 MB	2.1%
innfeed	27877	0.8%	3.3 MB	0.9%
nnrpd	4748	0.1%	491.4 KB	0.1%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3280964	100.0%	378.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3133384	91.7%
Negative hits	249538	7.3%
Positive hits	32847	1.0%
Cache misses	1962	0.1%
TOTAL: 4	3417731	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.998	0.0%	187	0.000	21.380	932.000
article cleanup	00:00:49.611	0.1%	3176661	0.010	0.016	0.021
article logging	00:02:12.790	0.2%	3177231	0.030	0.042	0.103
article parse	01:00:38.757	4.2%	161568515	0.014	0.023	0.035
article write	00:20:59.248	1.5%	3122220	0.161	0.403	0.775
artlog/artcncl	00:00:00.005	0.0%	173	0.000	0.029	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:35.419	0.3%	179240905	0.001	0.002	0.002
hisgrep/artcncl	00:00:01.616	0.0%	186	0.000	8.688	354.000
hishave/artcncl	00:00:00.242	0.0%	187	0.000	1.294	7.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:53:44.400	3.7%	3444323	0.104	0.936	4.559
history sync	00:00:00.023	0.0%	290	0.000	0.079	0.500
history write	01:56:36.239	8.1%	3158393	0.262	2.215	7.334
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	12:03:43.793	50.3%	121361817	0.163	0.358	0.989
nntp read	01:17:05.719	5.4%	159120299	0.014	0.029	0.041
overview write	00:00:16.534	0.0%	3122220	0.004	0.005	0.008
perl filter	05:27:06.954	22.7%	3158393	4.206	6.214	7.830
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:54.943	0.3%	6263642	0.036	0.047	0.063
TOTAL: 24:00:03.114	23:12:50.291	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.122	0.0%	42736	0.000	0.003	0.020
article prepare	00:00:00.002	0.0%	10048	0.000	0.000	0.036
article read	00:01:43.800	0.1%	2505	0.000	41.437	866.571
backlog stats	00:00:00.147	0.0%	139524	0.000	0.001	0.006
callbacks	00:00:00.001	0.0%	2	0.000	0.500	1.000
data read	00:00:01.692	0.0%	75508	0.005	0.022	0.063
data write	00:00:02.828	0.0%	56592	0.019	0.050	0.101
idle	23:52:53.249	99.9%	139524	367.213	616.190	1367.665
status file	00:00:09.846	0.0%	9399	0.597	1.048	9.800
TOTAL: 23:55:02.084	23:54:51.687	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.946	62.8%	3021	0.000	6.602	186.444
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.205	0.6%	6682	0.000	0.031	1.000
readart	00:00:00.001	0.0%	38	0.000	0.026	0.500
TOTAL: 00:00:31.743	00:00:20.152	63.5%	-	-	-	-

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	1023	3169126	3126914	9620	32592	98%	412:35:27
2	news.nask.pl	2	37714	9257	6547	21910	24%	23:57:11
3	news.icm.edu.pl	47	296	17	277	2	5%	17:58:32
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1075	3207138	3136190	16444	54504	97%	454:31:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	777.0 GB	2.6 GB	5.4 GB	785.1 GB	98%	260.6 KB
2	news.nask.pl	103.4 MB	41.1 MB	3.2 GB	3.3 GB	3%	111.5 KB
3	news.icm.edu.pl	353.9 KB	25.9 KB	25.9 KB	405.8 KB	87%	21.4 KB
4	localhost	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.6 KB
TOTAL: 4		777.1 GB	2.7 GB	8.6 GB	788.4 GB	98%	259.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 25 04:15:03 - 04:59:59	94384	3.0%	35.00	29.9 GB	3.9%	11624.13
Oct 25 05:00:00 - 05:59:59	127451	4.1%	35.40	41.1 GB	5.3%	11984.13
Oct 25 06:00:00 - 06:59:59	129206	4.1%	35.89	41.2 GB	5.3%	12010.73
Oct 25 07:00:00 - 07:59:59	132659	4.2%	36.85	43.4 GB	5.6%	12642.64
Oct 25 08:00:00 - 08:59:59	142779	4.6%	39.66	42.7 GB	5.5%	12433.62
Oct 25 09:00:00 - 09:59:59	186623	6.0%	51.84	29.4 GB	3.8%	8557.16
Oct 25 10:00:00 - 10:59:59	66934	2.1%	18.59	17.0 GB	2.2%	4953.26
Oct 25 11:00:00 - 11:59:59	126368	4.0%	35.10	37.0 GB	4.8%	10785.10
Oct 25 12:00:00 - 12:59:59	101699	3.2%	28.25	30.0 GB	3.9%	8743.27
Oct 25 13:00:00 - 13:59:59	95889	3.1%	26.64	25.3 GB	3.3%	7379.43
Oct 25 14:00:00 - 14:59:59	77513	2.5%	21.53	20.7 GB	2.7%	6041.85
Oct 25 15:00:00 - 15:59:59	71613	2.3%	19.89	20.7 GB	2.7%	6037.17
Oct 25 16:00:00 - 16:59:59	119325	3.8%	33.15	25.7 GB	3.3%	7499.54
Oct 25 17:00:00 - 17:59:59	102712	3.3%	28.53	29.6 GB	3.8%	8617.57
Oct 25 18:00:00 - 18:59:59	134745	4.3%	37.43	30.9 GB	4.0%	8993.70
Oct 25 19:00:00 - 19:59:59	137209	4.4%	38.11	37.4 GB	4.8%	10886.81
Oct 25 20:00:00 - 20:59:59	144206	4.6%	40.06	40.2 GB	5.2%	11696.99
Oct 25 21:00:00 - 21:59:59	135196	4.3%	37.55	40.0 GB	5.2%	11647.74
Oct 25 22:00:00 - 22:59:59	125515	4.0%	34.87	36.8 GB	4.7%	10729.72
Oct 25 23:00:00 - 23:59:59	63394	2.0%	17.61	15.6 GB	2.0%	4542.81
Oct 26 00:00:00 - 00:59:59	104999	3.3%	29.17	31.3 GB	4.0%	9113.10
Oct 26 01:00:00 - 01:59:59	179611	5.7%	49.89	41.0 GB	5.3%	11942.85
Oct 26 02:00:00 - 02:59:59	223996	7.1%	62.22	35.1 GB	4.5%	10214.11
Oct 26 03:00:00 - 03:59:59	259520	8.3%	72.09	27.4 GB	3.5%	7984.57
Oct 26 04:00:00 - 04:15:02	52665	1.7%	58.39	6.9 GB	0.9%	7978.02
TOTAL: 23:59:59	3136211	100.0%	36.30	776.4 GB	100.0%	9422.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	32991	155	17906	2	14928
2	news.nask.pl	22138	14	416	3	21705
3	news.icm.edu.pl	2	0	2	0	0
TOTAL: 3		55131	169	18324	5	36633

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	98
it.comp.os.win.windows10	35
nl.comp.os.linux.techniek	5
it.tlc.cellulari.android	5
de.comm.software.mailreader	5
comp.mobile.ipad	5
comp.mobile.android	4
de.rec.sf.startrek	4
it.comp.os.linux.ubuntu	4
comp.sys.raspberry-pi	3
uk.rec.cycling.moderated	1
TOTAL: 11	169

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	31795
EMP (md5)	4162
Scoring filter (9)	43
Scoring filter (8)	40
Scoring filter (10)	9
HTML post	8
Scoring filter (11)	4
Too many newsgroups	1
Scoring filter (13)	1
TOTAL: 9	36063

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	398
	news.nask.pl	172
	TOTAL: 2	570
TOTAL: 1		570

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	25450	1085	8500	285	6	32427	4%	23:58:21
2	poznan	14758	201	8779	31	0	0	1%	23:59:57
3	icm	22402	58	17529	119	0	0	0%	23:59:50
4	nask	4144	12	2803	0	0	0	0%	23:59:38
5	okapi	0	0	0	0	0	1115	0%	23:57:50
TOTAL: 5		66754	1356	37611	435	6	33542	2%	119:55:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	18.2 MB	3.5 MB	21.6 MB	0.3 KB/s	16.2 KB	23:58:21
2	icm	4.4 MB	13.7 MB	18.1 MB	0.2 KB/s	104.8 KB	23:59:50
3	poznan	1019.9 KB	499.4 KB	1.5 MB	0.0 KB/s	6.5 KB	23:59:57
4	nask	474.8 KB	0.0 KB	474.8 KB	0.0 KB/s	39.6 KB	23:59:38
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		24.0 MB	17.7 MB	41.7 MB	0.1 KB/s	23.9 KB	119:55:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	38	34.6 KB	19	0	00:00:26
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	38	34.6 KB	19	2	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	674	38	34.6 KB	19	2	0	00:00:31
TOTAL: 1		674	38	34.6 KB	19	2	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.356	11.652	00:00:26
mon.man.lodz.pl	1.032	11.984	00:00:03
david.man.lodz.pl	0.312	4.068	00:00:01
isvcs01.man.lodz.pl	0.016	0.092	00:00:00
TOTAL: 4	2.716	27.796	00:00:31

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	22
2	pl.comp.pecet	16
TOTAL: 2		38

Daily Usenet report

Oct 25 04:15:03 -- Oct 26 04:15:02