Daily Usenet report: Oct 20 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 20 05:31:57 newsfeed overchan[15839]: input line too long, skipping
Oct 20 09:48:40 newsfeed nnrpd[28752]: SERVER perl filter not defined
Oct 20 14:03:10 newsfeed overchan[15839]: input line too long, skipping
Oct 20 20:18:58 newsfeed overchan[15839]: input line too long, skipping
Oct 20 21:22:09 newsfeed overchan[15839]: input line too long, skipping
Oct 20 21:28:51 newsfeed overchan[15839]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	6232806	98.2%	695.7 MB	97.8%
innd	64211	1.0%	9.7 MB	1.4%
innfeed	47646	0.8%	5.7 MB	0.8%
nnrpd	4804	0.1%	500.6 KB	0.1%
overchan	6	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	6349474	100.0%	711.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	6142515	92.4%
Negative hits	339263	5.1%
Positive hits	165056	2.5%
Cache misses	381	0.0%
TOTAL: 4	6647215	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.304	0.0%	181	0.000	12.729	218.000
article cleanup	00:01:08.419	0.1%	6233101	0.008	0.011	0.017
article logging	00:04:08.913	0.3%	6233956	0.024	0.040	0.178
article parse	00:59:18.696	4.1%	188536796	0.010	0.019	0.028
article write	00:22:51.399	1.6%	6142683	0.065	0.223	0.601
artlog/artcncl	00:00:00.003	0.0%	180	0.000	0.017	0.250
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:24.100	0.2%	206194793	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.945	0.0%	179	0.000	5.279	40.500
hishave/artcncl	00:00:00.134	0.0%	181	0.000	0.740	7.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:54:31.106	3.8%	6680184	0.012	0.490	5.128
history sync	00:00:00.007	0.0%	290	0.000	0.024	0.500
history write	01:14:58.401	5.2%	6168909	0.061	0.729	7.990
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	12:21:04.535	51.5%	138304282	0.140	0.321	0.734
nntp read	01:11:59.767	5.0%	185332782	0.014	0.023	0.035
overview write	00:00:25.727	0.0%	6142683	0.003	0.004	0.006
perl filter	05:46:09.930	24.0%	6168909	1.083	3.367	7.480
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:08:27.760	0.6%	12501397	0.034	0.041	0.057
TOTAL: 24:00:03.161	23:08:32.146	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.140	0.0%	466417	0.000	0.002	0.011
article prepare	00:00:00.021	0.0%	81150	0.000	0.000	0.008
article read	00:00:43.349	0.1%	19370	0.000	2.238	87.857
backlog stats	00:00:00.881	0.0%	1333026	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:13.870	0.0%	817656	0.007	0.017	0.043
data write	00:00:42.891	0.0%	618003	0.021	0.069	0.107
idle	23:49:29.048	99.8%	1333026	31.678	64.342	735.508
status file	00:00:14.246	0.0%	17088	0.575	0.834	3.644
TOTAL: 23:52:03.491	23:51:25.446	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.445	61.3%	3099	0.000	6.275	90.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.220	0.7%	6982	0.000	0.032	3.333
readart	00:00:00.004	0.0%	110	0.000	0.036	0.500
TOTAL: 00:00:31.742	00:00:19.669	62.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	1448	6161191	6086256	5599	69336	98%	392:44:24
2	news.nask.pl	2	173532	54643	97391	21498	31%	24:00:27
3	news.icm.edu.pl	41	309	41	241	27	13%	17:40:19
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1493	6335033	6140941	103231	90861	96%	434:25:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	743.3 GB	15.4 GB	18.2 GB	776.9 GB	95%	132.3 KB
2	news.nask.pl	15.1 GB	2.6 GB	4.1 GB	21.8 GB	69%	300.3 KB
3	news.icm.edu.pl	1.9 MB	20.7 KB	877.3 KB	2.8 MB	68%	41.5 KB
4	localhost	3.2 KB	0.0 KB	0.0 KB	3.2 KB	100%	3.2 KB
TOTAL: 4		758.4 GB	18.0 GB	22.3 GB	798.7 GB	94%	134.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 20 04:15:02 - 04:59:59	93356	1.5%	34.60	31.4 GB	4.1%	12184.64
Oct 20 05:00:00 - 05:59:59	119707	1.9%	33.25	40.7 GB	5.4%	11850.82
Oct 20 06:00:00 - 06:59:59	119496	1.9%	33.19	39.4 GB	5.2%	11479.30
Oct 20 07:00:00 - 07:59:59	118552	1.9%	32.93	39.1 GB	5.2%	11399.22
Oct 20 08:00:00 - 08:59:59	488674	8.0%	135.74	23.0 GB	3.0%	6706.08
Oct 20 09:00:00 - 09:59:59	815437	13.3%	226.51	13.8 GB	1.8%	4032.93
Oct 20 10:00:00 - 10:59:59	838005	13.6%	232.78	15.0 GB	2.0%	4366.40
Oct 20 11:00:00 - 11:59:59	632983	10.3%	175.83	18.7 GB	2.5%	5437.54
Oct 20 12:00:00 - 12:59:59	283948	4.6%	78.87	35.0 GB	4.6%	10199.50
Oct 20 13:00:00 - 13:59:59	233569	3.8%	64.88	35.1 GB	4.6%	10233.37
Oct 20 14:00:00 - 14:59:59	202949	3.3%	56.37	36.6 GB	4.8%	10668.96
Oct 20 15:00:00 - 15:59:59	203238	3.3%	56.45	35.7 GB	4.7%	10400.71
Oct 20 16:00:00 - 16:59:59	271051	4.4%	75.29	36.0 GB	4.7%	10472.16
Oct 20 17:00:00 - 17:59:59	253704	4.1%	70.47	35.6 GB	4.7%	10379.26
Oct 20 18:00:00 - 18:59:59	171436	2.8%	47.62	26.9 GB	3.5%	7827.68
Oct 20 19:00:00 - 19:59:59	110367	1.8%	30.66	17.6 GB	2.3%	5139.13
Oct 20 20:00:00 - 20:59:59	111292	1.8%	30.91	19.9 GB	2.6%	5782.59
Oct 20 21:00:00 - 21:59:59	142396	2.3%	39.55	33.0 GB	4.4%	9620.68
Oct 20 22:00:00 - 22:59:59	179724	2.9%	49.92	38.8 GB	5.1%	11311.23
Oct 20 23:00:00 - 23:59:59	194620	3.2%	54.06	40.5 GB	5.3%	11795.20
Oct 21 00:00:00 - 00:59:59	130110	2.1%	36.14	29.9 GB	3.9%	8696.10
Oct 21 01:00:00 - 01:59:59	133528	2.2%	37.09	36.2 GB	4.8%	10545.18
Oct 21 02:00:00 - 02:59:59	149475	2.4%	41.52	39.7 GB	5.2%	11560.36
Oct 21 03:00:00 - 03:59:59	117071	1.9%	32.52	31.8 GB	4.2%	9255.97
Oct 21 04:00:00 - 04:15:03	26156	0.4%	28.97	8.4 GB	1.1%	9709.73
TOTAL: 24:00:01	6140844	100.0%	71.07	757.8 GB	100.0%	9196.88

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	69692	196	55800	1	13695
2	news.nask.pl	22060	26	8308	2	13724
3	news.icm.edu.pl	30	0	2	0	28
TOTAL: 3		91782	222	64110	3	27447

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	93
it.tlc.cellulari.android	54
it.comp.os.win.windows10	41
de.rec.sf.startrek	13
comp.mobile.android	6
alt.sex.telephones	5
uk.rec.cycling.moderated	3
it.comp.os.win.windows7	2
alt.sex.telephone.alt.sex.services	1
de.comp.lang.vbclassic	1
alt.sex.telephone.alt.sex	1
comp.sys.raspberry-pi	1
de.comm.software.mailreader	1
TOTAL: 13	222

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	21430
EMP (md5)	4937
Scoring filter (12)	73
Scoring filter (9)	43
Scoring filter (8)	28
Poison newsgroup	23
Scoring filter (11)	17
HTML post	15
Scoring filter (10)	13
Too many newsgroups	1
TOTAL: 10	26580

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	514
	newsfeed.pionier.net.pl	350
	news.icm.edu.pl	3
	TOTAL: 3	867
TOTAL: 1		867

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	228201	15435	136462	4	0	0	6%	23:59:37
2	panorama	262249	1796	231407	497	13	233635	0%	23:59:59
3	poznan	16696	430	9413	129	0	0	2%	23:59:57
4	icm	246022	114	210729	1564	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	1122	0%	23:57:51
TOTAL: 5		753168	17775	588011	2194	13	234757	2%	119:57:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	5.2 GB	361.1 KB	5.2 GB	63.0 KB/s	352.7 KB	23:59:37
2	panorama	24.0 MB	14.1 MB	38.1 MB	0.5 KB/s	17.0 KB	23:59:59
3	icm	7.1 MB	528.4 MB	535.5 MB	6.3 KB/s	326.8 KB	23:59:58
4	poznan	3.6 MB	5.8 MB	9.4 MB	0.1 KB/s	17.2 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		5.2 GB	548.7 MB	5.8 GB	14.0 KB/s	302.6 KB	119:57:22

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	110	125.4 KB	47	0	00:00:25
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		289	110	125.4 KB	47	1	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	673	110	125.4 KB	47	1	00:00:31
2	unresolved	1	0	0.0 KB	0	0	00:00:00
TOTAL: 2		674	110	125.4 KB	47	1	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.152	9.112	00:00:25
mon.man.lodz.pl	1.008	8.348	00:00:03
david.man.lodz.pl	0.300	2.956	00:00:01
isvcs01.man.lodz.pl	0.004	0.016	00:00:00
181.214.87.87	0.000	0.028	00:00:00
TOTAL: 5	2.464	20.460	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
181.214.87.87	1
TOTAL: 3	385

NNRP unrecognized commands (by host):

System	Conn
181.214.87.87	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
181.214.87.87	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	60
2	pl.comp.pecet	50
TOTAL: 2		110

Daily Usenet report

Oct 20 04:15:02 -- Oct 21 04:15:03