Daily Usenet report: Feb 14 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 14 11:04:58 newsfeed nnrpd[12887]: SERVER perl filter not defined
Feb 14 12:48:21 newsfeed overchan[30606]: input line too long, skipping
Feb 14 17:29:16 newsfeed nnrpd[26251]: SERVER perl filter not defined
Feb 14 23:52:32 newsfeed nnrpd[7099]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2113769	97.0%	241.3 MB	96.6%
innfeed	44402	2.0%	5.3 MB	2.1%
innd	16251	0.7%	2.6 MB	1.1%
nnrpd	4989	0.2%	536.6 KB	0.2%
overchan	2	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2179414	100.0%	249.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2081372	98.6%
Negative hits	19283	0.9%
Positive hits	9971	0.5%
Cache misses	52	0.0%
TOTAL: 4	2110678	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.983	0.0%	122	0.000	16.254	170.000
article cleanup	00:00:25.669	0.0%	2098440	0.007	0.012	0.019
article logging	00:01:22.786	0.1%	2098925	0.023	0.039	0.059
article parse	00:31:24.211	2.2%	63712447	0.017	0.030	0.042
article write	00:13:48.890	1.0%	2094718	0.145	0.396	0.848
artlog/artcncl	00:00:00.005	0.0%	122	0.000	0.041	1.000
data move	00:01:14.813	0.1%	74646113	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.756	0.0%	115	0.000	6.574	129.500
hishave/artcncl	00:00:00.014	0.0%	122	0.000	0.115	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:10:31.543	0.7%	2125487	0.040	0.297	2.341
history sync	00:00:00.017	0.0%	291	0.000	0.058	1.000
history write	00:45:03.596	3.1%	2095903	0.178	1.290	5.823
hiswrite/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
idle	17:52:34.325	74.5%	49682003	0.854	1.295	2.934
nntp read	00:48:45.415	3.4%	61967150	0.027	0.047	0.071
overview write	00:00:10.435	0.0%	2094718	0.003	0.005	0.008
perl filter	03:08:17.288	13.1%	2095903	3.332	5.390	7.521
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:11.177	0.3%	4207684	0.035	0.060	0.086
TOTAL: 24:00:01.022	23:37:52.923	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.150	0.0%	55650	0.000	0.003	0.016
article prepare	00:00:00.001	0.0%	15044	0.000	0.000	0.013
article read	00:00:20.043	0.0%	3758	0.160	5.333	73.667
backlog stats	00:00:00.159	0.0%	166332	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	5	0.000	0.000	0.000
data read	00:00:01.580	0.0%	83117	0.005	0.019	0.048
data write	00:00:10.378	0.0%	72834	0.048	0.142	0.330
idle	23:52:14.727	99.9%	166332	302.812	516.646	865.061
status file	00:00:17.439	0.0%	16396	0.517	1.064	1.815
TOTAL: 23:53:15.370	23:53:04.477	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.093	0.0%	510	0.000	0.182	1.000
idle	05:10:56.833	99.9%	3603	0.000	5178.138	451333.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.326	0.0%	7907	0.000	0.041	1.368
readart	00:00:00.039	0.0%	59	0.000	0.661	34.000
TOTAL: 05:11:13.136	05:10:57.291	99.9%	-	-	-	-

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	987	2109036	2108046	7	983	99%	245:59:00
2	news.nask.pl	3	11061	938	7311	2812	8%	24:18:59
3	news.icm.edu.pl	42	278	40	233	5	14%	18:13:27
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1036	2120378	2109027	7551	3800	99%	288:31:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	510.3 GB	9.1 MB	9.4 MB	510.3 GB	99%	253.7 KB
2	news.nask.pl	8.7 MB	10.8 MB	21.3 MB	40.8 MB	21%	11.1 KB
3	news.icm.edu.pl	1.2 MB	0.0 KB	238.5 KB	1.4 MB	83%	31.9 KB
4	localhost	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.6 KB
TOTAL: 4		510.3 GB	19.9 MB	30.9 MB	510.4 GB	99%	253.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 14 04:15:02 - 04:59:59	56253	2.7%	20.85	16.0 GB	3.1%	6200.98
Feb 14 05:00:00 - 05:59:59	78452	3.7%	21.79	23.5 GB	4.6%	6837.26
Feb 14 06:00:00 - 06:59:59	89988	4.3%	25.00	26.6 GB	5.2%	7754.91
Feb 14 07:00:00 - 07:59:59	108583	5.1%	30.16	21.3 GB	4.2%	6202.75
Feb 14 08:00:00 - 08:59:59	103332	4.9%	28.70	23.0 GB	4.5%	6690.14
Feb 14 09:00:00 - 09:59:59	89130	4.2%	24.76	22.6 GB	4.4%	6595.64
Feb 14 10:00:00 - 10:59:59	82792	3.9%	23.00	22.2 GB	4.3%	6452.94
Feb 14 11:00:00 - 11:59:59	127355	6.0%	35.38	23.8 GB	4.7%	6941.02
Feb 14 12:00:00 - 12:59:59	100775	4.8%	27.99	23.4 GB	4.6%	6816.19
Feb 14 13:00:00 - 13:59:59	82801	3.9%	23.00	23.8 GB	4.7%	6930.99
Feb 14 14:00:00 - 14:59:59	96557	4.6%	26.82	24.8 GB	4.9%	7231.21
Feb 14 15:00:00 - 15:59:59	90067	4.3%	25.02	24.5 GB	4.8%	7122.53
Feb 14 16:00:00 - 16:59:59	121120	5.7%	33.64	26.3 GB	5.2%	7653.70
Feb 14 17:00:00 - 17:59:59	128400	6.1%	35.67	23.7 GB	4.7%	6912.38
Feb 14 18:00:00 - 18:59:59	110446	5.2%	30.68	24.3 GB	4.8%	7073.25
Feb 14 19:00:00 - 19:59:59	105680	5.0%	29.36	22.3 GB	4.4%	6509.00
Feb 14 20:00:00 - 20:59:59	76521	3.6%	21.26	21.6 GB	4.2%	6278.20
Feb 14 21:00:00 - 21:59:59	57952	2.7%	16.10	14.4 GB	2.8%	4194.22
Feb 14 22:00:00 - 22:59:59	57088	2.7%	15.86	15.2 GB	3.0%	4432.04
Feb 14 23:00:00 - 23:59:59	47559	2.3%	13.21	14.7 GB	2.9%	4292.77
Feb 15 00:00:00 - 00:59:59	60359	2.9%	16.77	15.8 GB	3.1%	4588.09
Feb 15 01:00:00 - 01:59:59	100831	4.8%	28.01	23.6 GB	4.6%	6885.73
Feb 15 02:00:00 - 02:59:59	51242	2.4%	14.23	14.2 GB	2.8%	4129.88
Feb 15 03:00:00 - 03:59:59	53752	2.5%	14.93	13.3 GB	2.6%	3886.49
Feb 15 04:00:00 - 04:15:02	32390	1.5%	35.91	5.3 GB	1.0%	6201.48
TOTAL: 24:00:00	2109425	100.0%	24.41	510.2 GB	100.0%	6192.18

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2762	1	1725	1	1035
2	newsfeed.pionier.net.pl	1455	152	823	7	473
3	news.icm.edu.pl	5	0	0	0	5
TOTAL: 3		4222	153	2548	8	1513

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.tlc.cellulari.android	52
it.comp.os.win.windows10	43
uk.railway	32
comp.mobile.android	10
nl.comp.os.linux.techniek	6
de.comp.lang.perl	4
it.comp.os.win.windows7	3
de.comp.lang.vbclassic	2
it.discussioni.energia	1
TOTAL: 9	153

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

Groups	Count
pl.sci.historia	4
pl.praca.oferowana	3
bit.listserv.catholic	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	869
Binary in non-binary group	128
Scoring filter (8)	11
Scoring filter (9)	6
HTML post	5
Scoring filter (11)	4
Scoring filter (18)	3
Scoring filter (10)	2
Scoring filter (12)	1
TOTAL: 9	1029

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	472
	news.nask.pl	12
	TOTAL: 2	484
TOTAL: 1		484

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	26711	1824	15320	921	1	0	6%	23:59:55
2	panorama	42995	367	15217	115	9	40080	0%	23:59:57
3	poznan	1494	26	950	2	0	0	1%	23:32:13
4	nask	10829	7	9022	0	0	65	0%	23:59:57
5	okapi	0	0	0	0	0	1039	0%	23:57:50
TOTAL: 5		82029	2224	40509	1038	10	41184	2%	119:29:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.2 GB	269.6 MB	1.4 GB	17.3 KB/s	544.8 KB	23:59:55
2	panorama	1.2 MB	522.8 KB	1.7 MB	0.0 KB/s	3.5 KB	23:59:57
3	nask	272.1 KB	0.0 KB	272.1 KB	0.0 KB/s	38.9 KB	23:59:57
4	poznan	65.1 KB	66.8 KB	131.9 KB	0.0 KB/s	4.7 KB	23:32:13
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		1.2 GB	270.2 MB	1.4 GB	3.5 KB/s	459.1 KB	119:29:52

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	58	60.5 KB	28	0	00:00:17
2	az1-ss13.a2hosting.com	2	1	1.1 KB	1	0	00:00:13
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		293	59	61.5 KB	29	3	05:11:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	580	58	60.5 KB	28	3	00:05:43
2	*.a2hosting.com	2	1	1.1 KB	1	0	00:00:13
3	*.consolidated.net	13	0	0.0 KB	0	0	05:05:12
4	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	00:00:01
5	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
6	unresolved	98	0	0.0 KB	0	0	00:00:01
TOTAL: 6		696	59	61.5 KB	29	3	05:11:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-2-88.consolidated.net	0.084	0.248	05:05:12
mbox.man.lodz.pl	0.004	0.004	00:05:21
web.man.lodz.pl	1.432	3.476	00:00:17
az1-ss13.a2hosting.com	0.024	0.032	00:00:13
mon.man.lodz.pl	1.208	3.312	00:00:03
ip230.208-100-26.static.steadfastdns.net	0.008	0.012	00:00:01
212.51.192.185	0.424	1.040	00:00:01
scan-141.security.ipip.net	0.000	0.008	00:00:00
isvcs01.man.lodz.pl	0.024	0.044	00:00:00
85.93.20.6	0.008	0.020	00:00:00
TOTAL: 10	3.216	8.196	05:11:12

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	13
ip230.208-100-26.static.steadfastdns.net	2
85.93.20.6	2
scan-141.security.ipip.net	1
mbox.man.lodz.pl	1
TOTAL: 7	403

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
85.93.20.6	2
TOTAL: 2	6

NNRP unrecognized commands (by command):

Command	Count
#003	2
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
TOTAL: 5	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-2-88.consolidated.net	9	0
85.93.20.6	2	2
TOTAL: 2	11	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	58	98.3%
2	lodz	1	1.7%
TOTAL: 2		59	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	36
2	pl.comp.os.ms-windows.winnt	22
3	lodz.ogloszenia	1
TOTAL: 3		59

Daily Usenet report

Feb 14 04:15:02 -- Feb 15 04:15:02