Daily Usenet report: Oct 15 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Oct 15 06:29:23 newsfeed nnrpd[2209873]: SERVER perl filter not defined
Oct 15 06:29:23 newsfeed nnrpd[2209873]: SERVER perl filter not defined
Oct 15 07:20:15 newsfeed nnrpd[2216127]: SERVER perl filter not defined
Oct 15 07:20:15 newsfeed nnrpd[2216127]: SERVER perl filter not defined
Oct 15 08:15:00 newsfeed nnrpd[2222610]: SERVER perl filter not defined
Oct 15 08:15:00 newsfeed nnrpd[2222610]: SERVER perl filter not defined
Oct 15 08:24:12 newsfeed nnrpd[2223717]: SERVER perl filter not defined
Oct 15 08:24:12 newsfeed nnrpd[2223717]: SERVER perl filter not defined
Oct 15 08:30:32 newsfeed nnrpd[2224629]: SERVER perl filter not defined
Oct 15 08:30:32 newsfeed nnrpd[2224629]: SERVER perl filter not defined
Oct 15 08:32:00 newsfeed nnrpd[2224643]: SERVER perl filter not defined
Oct 15 08:32:00 newsfeed nnrpd[2224643]: SERVER perl filter not defined
Oct 15 17:00:06 newsfeed pgpverify[2285223]: pgpverify: /usr/bin/gpg1: no such file
Oct 15 17:00:06 newsfeed pgpverify[2285223]: pgpverify: /usr/bin/gpg1: no such file
Oct 15 17:00:14 newsfeed pgpverify[2285398]: pgpverify: /usr/bin/gpg1: no such file
Oct 15 17:00:14 newsfeed pgpverify[2285398]: pgpverify: /usr/bin/gpg1: no such file
Oct 16 00:18:24 newsfeed nnrpd[2337752]: SERVER perl filter not defined
Oct 16 00:18:24 newsfeed nnrpd[2337752]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	180154	81.1%	19.9 MB	83.4%
innd	24776	11.2%	2.3 MB	9.7%
nnrpd	15830	7.1%	1.5 MB	6.2%
innfeed	1234	0.6%	163.1 KB	0.7%
controlchan	46	0.0%	10.0 KB	0.0%
pgpverify	4	0.0%	0.5 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	222046	100.0%	23.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	359780	99.7%
Negative hits	988	0.3%
Cache misses	166	0.0%
Positive hits	84	0.0%
TOTAL: 4	361018	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.046	0.0%	138	0.000	0.333	3.000
article cleanup	00:00:01.132	0.0%	360130	0.000	0.003	0.010
article logging	00:00:04.900	0.0%	360200	0.005	0.014	0.024
article parse	00:00:09.732	0.0%	1679228	0.003	0.006	0.010
article write	00:00:12.924	0.0%	359992	0.019	0.036	0.207
artlog/artcncl	00:00:00.000	0.0%	32	0.000	0.000	0.000
data move	00:00:01.116	0.0%	2486454	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	170	0.000	0.012	0.091
hishave/artcncl	00:00:00.000	0.0%	32	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.408	0.0%	361216	0.000	0.004	0.011
history sync	00:00:00.018	0.0%	596	0.000	0.030	0.500
history write	00:00:19.634	0.0%	360000	0.019	0.055	0.125
idle	47:54:39.984	99.8%	1966344	39.162	87.716	213.663
nntp read	00:00:14.640	0.0%	1639988	0.005	0.009	0.012
overview write	00:00:00.576	0.0%	359992	0.000	0.002	0.005
perl filter	00:04:02.080	0.1%	360000	0.388	0.672	1.004
python filter	00:00:00.066	0.0%	360014	0.000	0.000	0.002
site send	00:00:05.228	0.0%	740142	0.003	0.007	0.012
TOTAL: 48:00:35.174	47:59:53.486	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	20152	0.000	0.001	0.024
article prepare	00:00:00.000	0.0%	1424	0.000	0.000	0.000
article read	00:00:00.010	0.0%	356	0.000	0.028	1.000
backlog stats	00:00:00.018	0.0%	109296	0.000	0.000	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.408	0.0%	55132	0.000	0.007	0.032
data write	00:00:01.080	0.0%	34142	0.000	0.032	0.076
idle	47:59:20.044	100.0%	109296	466.205	1580.662	3523.797
status file	00:00:00.130	0.0%	322	0.000	0.404	4.000
TOTAL: 47:59:22.672	47:59:21.718	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:18.250	75.7%	8842	1.000	8.850	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.354	0.3%	29818	0.000	0.012	0.333
TOTAL: 00:01:43.380	00:01:18.604	76.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	54
flushlogs	4
go	4
logmode	4
lowmark	2
mode	298
name	4
pause	4
paused	4
reload	4
reserve	4
TOTAL: 11	386

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	4	4	0	4
TOTAL	0	0	4	4	0	4

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	120	360044	359886	22	136	99%	61:52:36
2	localhost	16	14	14	0	0	100%	00:00:00
3	news.icm.edu.pl	52	70	2	66	2	2%	09:44:08
TOTAL: 3		188	360128	359902	88	138	99%	71:36:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	5.5 GB	2.0 MB	96.6 KB	5.5 GB	99%	16.0 KB
2	news.icm.edu.pl	241.0 KB	0.0 KB	21.2 KB	262.2 KB	91%	65.6 KB
3	localhost	21.2 KB	0.0 KB	0.0 KB	21.2 KB	100%	1.5 KB
TOTAL: 3		5.5 GB	2.0 MB	117.8 KB	5.5 GB	99%	16.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 15 04:15:01 - 04:59:59	6137	3.4%	2.27	144.1 MB	5.1%	54.67
Oct 15 05:00:00 - 05:59:59	8095	4.5%	2.25	209.5 MB	7.4%	59.58
Oct 15 06:00:00 - 06:59:59	8298	4.6%	2.31	218.9 MB	7.8%	62.28
Oct 15 07:00:00 - 07:59:59	10509	5.8%	2.92	254.3 MB	9.0%	72.32
Oct 15 08:00:00 - 08:59:59	9679	5.4%	2.69	241.0 MB	8.5%	68.55
Oct 15 09:00:00 - 09:59:59	6873	3.8%	1.91	86.0 MB	3.0%	24.45
Oct 15 10:00:00 - 10:59:59	8384	4.7%	2.33	100.8 MB	3.6%	28.66
Oct 15 11:00:00 - 11:59:59	8562	4.8%	2.38	97.5 MB	3.5%	27.73
Oct 15 12:00:00 - 12:59:59	7923	4.4%	2.20	94.4 MB	3.3%	26.85
Oct 15 13:00:00 - 13:59:59	8007	4.4%	2.22	106.5 MB	3.8%	30.28
Oct 15 14:00:00 - 14:59:59	10007	5.6%	2.78	187.1 MB	6.6%	53.22
Oct 15 15:00:00 - 15:59:59	6796	3.8%	1.89	86.8 MB	3.1%	24.69
Oct 15 16:00:00 - 16:59:59	6230	3.5%	1.73	75.3 MB	2.7%	21.41
Oct 15 17:00:00 - 17:59:59	6651	3.7%	1.85	83.9 MB	3.0%	23.86
Oct 15 18:00:00 - 18:59:59	6555	3.6%	1.82	84.0 MB	3.0%	23.89
Oct 15 19:00:00 - 19:59:59	6831	3.8%	1.90	94.5 MB	3.3%	26.88
Oct 15 20:00:00 - 20:59:59	6280	3.5%	1.74	76.1 MB	2.7%	21.65
Oct 15 21:00:00 - 21:59:59	7502	4.2%	2.08	89.8 MB	3.2%	25.55
Oct 15 22:00:00 - 22:59:59	8536	4.7%	2.37	91.2 MB	3.2%	25.93
Oct 15 23:00:00 - 23:59:59	6938	3.9%	1.93	82.7 MB	2.9%	23.53
Oct 16 00:00:00 - 00:59:59	5967	3.3%	1.66	69.6 MB	2.5%	19.80
Oct 16 01:00:00 - 01:59:59	5168	2.9%	1.44	68.7 MB	2.4%	19.55
Oct 16 02:00:00 - 02:59:59	6392	3.6%	1.78	79.3 MB	2.8%	22.54
Oct 16 03:00:00 - 03:59:59	5091	2.8%	1.41	67.7 MB	2.4%	19.27
Oct 16 04:00:00 - 04:15:01	2623	1.5%	2.91	32.1 MB	1.1%	36.49
TOTAL: 24:00:00	180034	100.0%	2.08	2.8 GB	100.0%	33.44

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	103	65	38
2	news.icm.edu.pl	1	0	1
TOTAL: 2		104	65	39

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6
Binary in non-binary group	2
TOTAL: 2	8

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	35
	TOTAL: 1	35
TOTAL: 1		35

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	36704	350	19794	6	0%	47:58:54
2	poznan	2	0	2	0	0%	00:40:00
TOTAL: 2		36706	350	19796	6	0%	48:38:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	608.9 KB	12.6 KB	621.5 KB	1.7 KB	47:58:54
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	00:40:00
TOTAL: 2		608.9 KB	12.6 KB	621.5 KB	1.7 KB	48:38:54

NNRP readership statistics:

System		Conn	Post	Elapsed
1	198.235.24.25	2	0	00:00:00
2	stanton.probe.onyphe.net	2	0	00:00:06
3	azpdss57fhss.stretchoid.com	4	0	00:00:20
4	nms1.man.lodz.pl	2880	0	00:00:22
5	141.98.83.86	2	0	00:00:00
6	167.94.138.193	2	0	00:00:01
7	isvcs01.man.lodz.pl	14	14	00:00:00
8	205.210.31.108	2	0	00:00:04
9	o34.scanner.modat.io	2	0	00:00:00
10	167.94.138.184	6	0	00:00:07
11	172.16.2.4	576	0	00:00:32
12	fuentes.probe.onyphe.net	2	0	00:00:01
13	62.60.131.226	2	0	00:00:00
14	193.29.13.13	2	0	00:00:00
15	80.111.142.213	8	0	00:00:05
TOTAL: 15		3506	14	00:01:44

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	unresolved	602	0	00:00:53
2	*.scanner.modat.io	2	0	00:00:00
3	*.stretchoid.com	4	0	00:00:20
4	*.man.lodz.pl	2894	14	00:00:23
5	*.probe.onyphe.net	4	0	00:00:07
TOTAL: 5		3506	14	00:01:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.270	3.172	00:00:32
nms1.man.lodz.pl	5.998	14.832	00:00:22
azpdss57fhss.stretchoid.com	0.006	0.022	00:00:20
167.94.138.184	0.076	0.030	00:00:07
stanton.probe.onyphe.net	0.002	0.012	00:00:06
80.111.142.213	0.018	0.046	00:00:05
205.210.31.108	0.004	0.010	00:00:04
167.94.138.193	0.070	0.008	00:00:01
fuentes.probe.onyphe.net	0.006	0.008	00:00:01
isvcs01.man.lodz.pl	0.054	0.080	00:00:00
198.235.24.25	0.006	0.010	00:00:00
141.98.83.86	0.006	0.014	00:00:00
o34.scanner.modat.io	0.004	0.010	00:00:00
193.29.13.13	0.006	0.006	00:00:00
62.60.131.226	0.004	0.010	00:00:00
TOTAL: 15	7.530	18.270	00:01:44

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
fuentes.probe.onyphe.net	4
193.29.13.13	2
62.60.131.226	2
azpdss57fhss.stretchoid.com	2
167.94.138.193	2
167.94.138.184	2
141.98.83.86	2
TOTAL: 7	16

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
#003	6
EHLO www.censys.io	4
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	2
#026#003#003#001¥#001	2
MGLNDD_212.191.2.21_119	2
TOTAL: 5	16

NNRP client timeouts [Top 20]:

System	Conn	Peer
193.29.13.13	2	2
62.60.131.226	2	2
167.94.138.184	2	4
141.98.83.86	2	2
TOTAL: 4	8	14

Daily Usenet report

Oct 15 04:15:01 -- Oct 16 04:15:01