Daily Usenet report: Feb 23 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Feb 23 08:22:19 newsfeed nnrpd[20410]: SERVER perl filter not defined
Feb 23 08:23:36 newsfeed nnrpd[20427]: SERVER perl filter not defined
Feb 23 10:23:29 newsfeed nnrpd[24562]: SERVER perl filter not defined
Feb 23 17:04:09 newsfeed overchan[10875]: input line too long, skipping
Feb 23 22:17:38 newsfeed nnrpd[16990]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1652303	96.9%	190.8 MB	96.6%
innfeed	35707	2.1%	4.2 MB	2.1%
innd	12109	0.7%	2.0 MB	1.0%
nnrpd	4936	0.3%	525.9 KB	0.3%
overchan	2	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	1705058	100.0%	197.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1634537	98.7%
Negative hits	11578	0.7%
Positive hits	9677	0.6%
Cache misses	33	0.0%
TOTAL: 4	1655825	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.176	0.0%	122	0.000	9.639	194.000
article cleanup	00:00:25.062	0.0%	1650374	0.012	0.015	0.020
article logging	00:01:19.406	0.1%	1650723	0.038	0.048	0.059
article parse	00:31:01.749	2.2%	54221704	0.020	0.034	0.043
article write	00:12:05.925	0.8%	1646863	0.271	0.441	0.790
artlog/artcncl	00:00:00.005	0.0%	120	0.000	0.042	1.000
data move	00:01:12.330	0.1%	63351158	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.222	0.0%	112	0.000	1.982	40.000
hishave/artcncl	00:00:00.089	0.0%	122	0.000	0.730	8.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:23:41.389	1.6%	1669202	0.040	0.852	5.343
history sync	00:00:00.025	0.0%	290	0.000	0.086	1.000
history write	00:20:31.551	1.4%	1647898	0.194	0.747	2.508
hiswrite/artcncl	00:00:00.004	0.0%	10	0.000	0.400	0.571
idle	18:23:31.752	76.6%	42720360	0.984	1.550	2.759
nntp read	00:47:40.896	3.3%	52848142	0.039	0.054	0.066
overview write	00:00:09.059	0.0%	1646863	0.003	0.006	0.008
perl filter	02:54:49.853	12.1%	1647898	4.286	6.366	8.022
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:43.170	0.3%	3308354	0.050	0.067	0.085
TOTAL: 24:00:00.696	23:40:13.663	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.140	0.0%	46582	0.000	0.003	0.021
article prepare	00:00:00.001	0.0%	3030	0.000	0.000	0.019
article read	00:00:03.328	0.0%	758	0.000	4.391	285.333
backlog stats	00:00:00.131	0.0%	130630	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.425	0.0%	67657	0.000	0.021	0.149
data write	00:00:04.390	0.0%	54067	0.026	0.081	0.199
idle	23:54:24.184	100.0%	130630	360.583	658.839	1377.817
status file	00:00:15.636	0.0%	12673	0.756	1.234	3.125
TOTAL: 23:54:58.597	23:54:49.235	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	6	0.000	0.000	0.000
idle	00:32:12.890	99.3%	3118	0.000	619.913	272342.714
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.640	0.0%	7026	0.000	0.091	16.211
readart	00:00:00.017	0.0%	93	0.000	0.183	12.000
TOTAL: 00:32:26.587	00:32:13.547	99.3%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	522	1648248	1647501	10	737	99%	165:30:24
2	news.nask.pl	2	10001	740	6642	2619	7%	22:32:37
3	news.icm.edu.pl	41	326	50	214	62	15%	18:26:23
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		570	1658579	1648295	6866	3418	99%	206:29:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	461.2 GB	8.3 MB	8.5 MB	461.2 GB	99%	293.4 KB
2	news.nask.pl	7.6 MB	9.0 MB	20.8 MB	37.3 MB	20%	11.4 KB
3	news.icm.edu.pl	1.6 MB	9.5 KB	3.4 MB	5.0 MB	32%	45.9 KB
4	localhost	5.9 KB	0.0 KB	0.0 KB	5.9 KB	100%	1.5 KB
TOTAL: 4		461.2 GB	17.3 MB	32.7 MB	461.2 GB	99%	292.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 23 04:15:02 - 04:59:59	47178	2.9%	17.49	11.1 GB	2.4%	4301.43
Feb 23 05:00:00 - 05:59:59	40986	2.5%	11.38	11.7 GB	2.5%	3395.03
Feb 23 06:00:00 - 06:59:59	41067	2.5%	11.41	12.5 GB	2.7%	3631.50
Feb 23 07:00:00 - 07:59:59	71732	4.4%	19.93	22.0 GB	4.8%	6414.74
Feb 23 08:00:00 - 08:59:59	72362	4.4%	20.10	22.7 GB	4.9%	6611.19
Feb 23 09:00:00 - 09:59:59	73621	4.5%	20.45	22.3 GB	4.8%	6495.11
Feb 23 10:00:00 - 10:59:59	78747	4.8%	21.87	24.9 GB	5.4%	7249.78
Feb 23 11:00:00 - 11:59:59	62897	3.8%	17.47	20.3 GB	4.4%	5900.67
Feb 23 12:00:00 - 12:59:59	46690	2.8%	12.97	15.7 GB	3.4%	4573.51
Feb 23 13:00:00 - 13:59:59	57677	3.5%	16.02	14.3 GB	3.1%	4177.03
Feb 23 14:00:00 - 14:59:59	107666	6.5%	29.91	22.2 GB	4.8%	6462.20
Feb 23 15:00:00 - 15:59:59	102507	6.2%	28.47	26.2 GB	5.7%	7631.79
Feb 23 16:00:00 - 16:59:59	80501	4.9%	22.36	22.6 GB	4.9%	6574.20
Feb 23 17:00:00 - 17:59:59	81406	4.9%	22.61	20.9 GB	4.5%	6086.72
Feb 23 18:00:00 - 18:59:59	96184	5.8%	26.72	28.9 GB	6.3%	8419.55
Feb 23 19:00:00 - 19:59:59	53653	3.3%	14.90	15.4 GB	3.3%	4475.17
Feb 23 20:00:00 - 20:59:59	83722	5.1%	23.26	24.6 GB	5.3%	7153.58
Feb 23 21:00:00 - 21:59:59	44335	2.7%	12.32	12.9 GB	2.8%	3763.17
Feb 23 22:00:00 - 22:59:59	57762	3.5%	16.05	13.2 GB	2.9%	3856.31
Feb 23 23:00:00 - 23:59:59	93419	5.7%	25.95	23.4 GB	5.1%	6823.30
Feb 24 00:00:00 - 00:59:59	48054	2.9%	13.35	14.3 GB	3.1%	4159.05
Feb 24 01:00:00 - 01:59:59	44570	2.7%	12.38	12.3 GB	2.7%	3592.40
Feb 24 02:00:00 - 02:59:59	71733	4.4%	19.93	20.9 GB	4.5%	6096.97
Feb 24 03:00:00 - 03:59:59	69040	4.2%	19.18	19.7 GB	4.3%	5736.82
Feb 24 04:00:00 - 04:15:02	20812	1.3%	23.07	5.8 GB	1.3%	6752.84
TOTAL: 24:00:00	1648321	100.0%	19.08	460.7 GB	100.0%	5591.48

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2719	1	1795	2	921
2	newsfeed.pionier.net.pl	1080	55	672	1	352
3	news.icm.edu.pl	63	0	1	0	62
TOTAL: 3		3862	56	2468	3	1335

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.mobile.android	25
it.comp.os.win.windows10	12
it.tlc.cellulari.android	7
uk.railway	6
nl.comp.sys.android	3
alt.sex.telephones	1
de.rec.sf.startrek	1
it.comp.os.win.windows7	1
TOTAL: 8	56

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	901
Binary in non-binary group	63
Scoring filter (9)	6
Scoring filter (8)	6
Scoring filter (10)	5
Too many newsgroups	2
Scoring filter (11)	2
Scoring filter (12)	1
TOTAL: 8	986

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	344
	news.nask.pl	4
	news.icm.edu.pl	1
	TOTAL: 3	349
TOTAL: 1		349

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	22428	82	13852	460	0	0	0%	23:59:58
2	panorama	34528	69	13398	28	1	33199	0%	23:59:58
3	poznan	1191	6	809	1	0	0	0%	23:41:19
4	nask	10343	2	8615	1	0	0	0%	23:59:44
5	okapi	0	0	0	0	0	1029	0%	23:57:51
TOTAL: 5		68490	159	36674	490	1	34228	0%	119:38:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	434.0 KB	84.7 KB	518.6 KB	0.0 KB/s	5.3 KB	23:59:58
2	icm	380.1 KB	133.7 MB	134.0 MB	1.6 KB/s	253.2 KB	23:59:58
3	poznan	23.1 KB	2.6 KB	25.7 KB	0.0 KB/s	3.7 KB	23:41:19
4	nask	2.3 KB	1.4 KB	3.7 KB	0.0 KB/s	1.2 KB	23:59:44
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		839.5 KB	133.7 MB	134.6 MB	0.3 KB/s	212.3 KB	119:38:50

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	Elapsed
1	web.man.lodz.pl	288	92	96.5 KB	40	0	00:00:19
2	az1-ss13.a2hosting.com	2	1	2.1 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		294	93	98.6 KB	41	4	00:32:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	580	92	96.5 KB	40	4	00:00:22
2	*.a2hosting.com	2	1	2.1 KB	1	0	00:00:12
3	*.consolidated.net	1	0	0.0 KB	0	0	00:31:47
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:01
5	unresolved	97	0	0.0 KB	0	0	00:00:02
TOTAL: 5		681	93	98.6 KB	41	4	00:32:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-2-88.consolidated.net	0.012	0.016	00:31:47
web.man.lodz.pl	1.492	4.532	00:00:19
az1-ss13.a2hosting.com	0.024	0.020	00:00:12
mon.man.lodz.pl	1.284	4.028	00:00:03
scan-141.security.ipip.net	0.004	0.008	00:00:01
212.51.192.185	0.344	1.408	00:00:01
125.64.94.200	0.004	0.008	00:00:01
isvcs01.man.lodz.pl	0.028	0.064	00:00:00
TOTAL: 8	3.192	10.084	00:32:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
client-66-116-2-88.consolidated.net	1
125.64.94.200	1
scan-141.security.ipip.net	1
TOTAL: 5	387

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Host: 212.191.2.21:119	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-2-88.consolidated.net	1	0
125.64.94.200	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	92	98.9%
2	lodman	1	1.1%
TOTAL: 2		93	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	52
2	pl.comp.pecet	40
3	lodman.test	1
TOTAL: 3		93

Daily Usenet report

Feb 23 04:15:02 -- Feb 24 04:15:02