Daily Usenet report: Oct 12 04:15:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Oct 12 08:16:48 newsfeed nnrpd[1703111]: SERVER perl filter not defined
Oct 12 08:16:48 newsfeed nnrpd[1703111]: SERVER perl filter not defined
Oct 12 13:42:01 newsfeed nnrpd[1742275]: SERVER perl filter not defined
Oct 12 13:42:01 newsfeed nnrpd[1742275]: SERVER perl filter not defined
Oct 12 13:54:19 newsfeed nnrpd[1743861]: SERVER perl filter not defined
Oct 12 13:54:19 newsfeed nnrpd[1743861]: SERVER perl filter not defined
Oct 12 16:30:58 newsfeed nnrpd[1763036]: SERVER perl filter not defined
Oct 12 16:30:58 newsfeed nnrpd[1763036]: SERVER perl filter not defined
Oct 12 16:37:50 newsfeed nnrpd[1763799]: SERVER perl filter not defined
Oct 12 16:37:50 newsfeed nnrpd[1763799]: SERVER perl filter not defined
Oct 12 22:00:02 newsfeed pgpverify[1802173]: pgpverify: verification failed
Oct 12 22:00:02 newsfeed pgpverify[1802173]: pgpverify: verification failed
Oct 12 22:00:03 newsfeed pgpverify[1802181]: pgpverify: verification failed
Oct 12 22:00:03 newsfeed pgpverify[1802181]: pgpverify: verification failed
Oct 12 22:55:46 newsfeed nnrpd[1808956]: SERVER perl filter not defined
Oct 12 22:55:46 newsfeed nnrpd[1808956]: SERVER perl filter not defined
Oct 12 22:56:42 newsfeed nnrpd[1808962]: SERVER perl filter not defined
Oct 12 22:56:42 newsfeed nnrpd[1808962]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	202848	85.1%	22.8 MB	86.9%
innd	18092	7.6%	1.8 MB	6.7%
nnrpd	16068	6.7%	1.5 MB	5.8%
innfeed	1234	0.5%	158.9 KB	0.6%
controlchan	6	0.0%	0.6 KB	0.0%
pgpverify	4	0.0%	0.4 KB	0.0%
overchan	2	0.0%	0.3 KB	0.0%
TOTAL: 7	238254	100.0%	26.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	404602	99.8%
Negative hits	756	0.2%
Positive hits	158	0.0%
Cache misses	0	0.0%
TOTAL: 4	405516	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.030	0.0%	42	0.000	0.714	12.000
article cleanup	00:00:01.036	0.0%	407718	0.000	0.003	0.006
article logging	00:00:04.822	0.0%	407758	0.005	0.012	0.022
article parse	00:00:06.576	0.0%	1366222	0.002	0.005	0.008
article write	00:00:13.920	0.0%	407710	0.015	0.034	0.143
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:00:01.058	0.0%	1855306	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.002	0.0%	48	0.000	0.042	1.000
hishave/artcncl	00:00:00.000	0.0%	6	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:03.902	0.0%	408618	0.001	0.010	0.115
history sync	00:00:00.020	0.0%	614	0.000	0.033	0.500
history write	00:00:39.636	0.0%	407710	0.027	0.097	0.628
idle	47:54:53.122	99.8%	1611394	52.929	107.046	183.272
nntp read	00:00:09.314	0.0%	1276662	0.004	0.007	0.011
overview write	00:00:00.482	0.0%	407710	0.000	0.001	0.004
perl filter	00:03:15.644	0.1%	407710	0.352	0.480	0.673
python filter	00:00:00.070	0.0%	407724	0.000	0.000	0.002
site send	00:00:05.044	0.0%	826486	0.003	0.006	0.010
TOTAL: 48:00:35.358	47:59:34.678	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	11028	0.000	0.002	0.027
article prepare	00:00:00.000	0.0%	1000	0.000	0.000	0.000
article read	00:00:00.006	0.0%	250	0.000	0.024	0.333
backlog stats	00:00:00.010	0.0%	69432	0.000	0.000	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.242	0.0%	31738	0.000	0.008	0.042
data write	00:00:00.596	0.0%	20556	0.000	0.029	0.143
idle	47:40:50.026	100.0%	69432	1134.278	2472.203	8228.603
status file	00:00:00.240	0.0%	320	0.000	0.750	5.000
TOTAL: 47:40:51.736	47:40:51.144	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:02:01.830	76.2%	8962	0.000	13.594	9811.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.206	0.1%	30192	0.000	0.007	0.500
TOTAL: 00:02:39.782	00:02:02.036	76.4%	-	-	-	-

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	0	4	0	0
TOTAL	0	0	0	4	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	64	405686	405682	0	4	99%	59:51:18
2	localhost	16	14	14	0	0	100%	00:00:00
3	news.icm.edu.pl	62	152	4	144	4	2%	15:56:56
TOTAL: 3		142	405852	405700	144	8	99%	75:48:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	3.5 GB	64.8 KB	3.5 GB	99%	9.1 KB
2	news.icm.edu.pl	64.7 KB	34.1 KB	98.7 KB	65%	12.3 KB
3	localhost	19.3 KB	0.0 KB	19.3 KB	100%	1.4 KB
TOTAL: 3		3.5 GB	98.8 KB	3.5 GB	99%	9.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 12 04:15:02 - 04:59:59	10659	5.3%	3.95	72.0 MB	4.0%	27.33
Oct 12 05:00:00 - 05:59:59	8045	4.0%	2.23	79.8 MB	4.4%	22.69
Oct 12 06:00:00 - 06:59:59	5677	2.8%	1.58	55.6 MB	3.1%	15.82
Oct 12 07:00:00 - 07:59:59	6102	3.0%	1.70	51.9 MB	2.9%	14.76
Oct 12 08:00:00 - 08:59:59	6202	3.1%	1.72	59.7 MB	3.3%	16.98
Oct 12 09:00:00 - 09:59:59	7652	3.8%	2.13	68.0 MB	3.8%	19.35
Oct 12 10:00:00 - 10:59:59	7675	3.8%	2.13	62.9 MB	3.5%	17.89
Oct 12 11:00:00 - 11:59:59	9244	4.6%	2.57	84.8 MB	4.7%	24.11
Oct 12 12:00:00 - 12:59:59	7268	3.6%	2.02	66.3 MB	3.7%	18.86
Oct 12 13:00:00 - 13:59:59	9132	4.5%	2.54	75.3 MB	4.2%	21.43
Oct 12 14:00:00 - 14:59:59	9535	4.7%	2.65	87.5 MB	4.9%	24.88
Oct 12 15:00:00 - 15:59:59	9149	4.5%	2.54	82.2 MB	4.6%	23.39
Oct 12 16:00:00 - 16:59:59	7132	3.5%	1.98	72.8 MB	4.1%	20.70
Oct 12 17:00:00 - 17:59:59	6200	3.1%	1.72	60.6 MB	3.4%	17.23
Oct 12 18:00:00 - 18:59:59	7661	3.8%	2.13	84.5 MB	4.7%	24.03
Oct 12 19:00:00 - 19:59:59	6798	3.4%	1.89	78.7 MB	4.4%	22.38
Oct 12 20:00:00 - 20:59:59	8410	4.1%	2.34	88.4 MB	4.9%	25.15
Oct 12 21:00:00 - 21:59:59	6681	3.3%	1.86	62.9 MB	3.5%	17.88
Oct 12 22:00:00 - 22:59:59	8112	4.0%	2.25	68.0 MB	3.8%	19.35
Oct 12 23:00:00 - 23:59:59	10704	5.3%	2.97	82.7 MB	4.6%	23.52
Oct 13 00:00:00 - 00:59:59	15202	7.5%	4.22	117.2 MB	6.5%	33.34
Oct 13 01:00:00 - 01:59:59	12752	6.3%	3.54	97.6 MB	5.4%	27.75
Oct 13 02:00:00 - 02:59:59	8437	4.2%	2.34	73.6 MB	4.1%	20.93
Oct 13 03:00:00 - 03:59:59	6764	3.3%	1.88	52.3 MB	2.9%	14.87
Oct 13 04:00:00 - 04:15:01	1628	0.8%	1.81	9.7 MB	0.5%	11.05
TOTAL: 23:59:59	202821	100.0%	2.35	1.8 GB	100.0%	21.27

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	22	2	20
2	news.icm.edu.pl	2	2	0
TOTAL: 2		24	4	20

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	20710	134	10802	116	0%	47:59:54
2	poznan	6	0	4	0	0%	01:00:10
TOTAL: 2		20716	134	10806	116	0%	49:00:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	535.5 KB	1.9 MB	2.4 MB	9.9 KB	47:59:54
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:00:10
TOTAL: 2		535.5 KB	1.9 MB	2.4 MB	9.9 KB	49:00:04

NNRP readership statistics:

System		Conn	Post	Elapsed
1	89-21-67-159.infrawat.ch	2	0	00:00:00
2	sh-chi-us-cpp-wk107.internet-census.org	2	0	00:00:00
3	89-21-67-182.infrawat.ch	2	0	00:00:00
4	zl-dfwc-us-gp1-wk109a.internet-census.org	2	0	00:00:21
5	80.111.142.213	8	0	00:00:05
6	200.138.46.169	2	0	00:00:00
7	198.235.24.224	2	0	00:00:00
8	66.61.98.95	40	0	00:00:24
9	193-124-20-250.infrawat.ch	2	0	00:00:00
10	azpdsg50wom3.stretchoid.com	2	0	00:00:19
11	sh-ams-nl-cpp-wk104.internet-census.org	4	0	00:00:00
12	sh-chi-us-cpp-wk105.internet-census.org	2	0	00:00:00
13	210.172.132.66.censys-scanner.com	6	0	00:00:18
14	175.186.132.66.censys-scanner.com	2	0	00:00:03
15	36.255.97.33	2	0	00:00:02
16	zl-laxd-us-cpp-wk111.internet-census.org	2	0	00:00:00
17	172.16.2.4	576	0	00:00:34
18	isvcs01.man.lodz.pl	14	14	00:00:00
19	nms1.man.lodz.pl	2880	0	00:00:30
20	zl-laxd-us-cpp-wk110.internet-census.org	2	0	00:00:00
TOTAL: 20		3554	14	00:02:40

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.stretchoid.com	2	0	00:00:19
2	*.internet-census.org	14	0	00:00:21
3	unresolved	630	0	00:01:06
4	*.infrawat.ch	6	0	00:00:00
5	*.172.132.66.censys-scanner.com	6	0	00:00:18
6	*.man.lodz.pl	2894	14	00:00:30
7	*.186.132.66.censys-scanner.com	2	0	00:00:03
TOTAL: 7		3554	14	00:02:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.540	3.276	00:00:34
nms1.man.lodz.pl	8.072	15.830	00:00:30
66.61.98.95	0.100	0.218	00:00:24
zl-dfwc-us-gp1-wk109a.internet-census.org	0.002	0.012	00:00:21
azpdsg50wom3.stretchoid.com	0.006	0.008	00:00:19
210.172.132.66.censys-scanner.com	0.064	0.038	00:00:18
80.111.142.213	0.024	0.044	00:00:05
175.186.132.66.censys-scanner.com	0.052	0.022	00:00:03
36.255.97.33	0.008	0.010	00:00:02
isvcs01.man.lodz.pl	0.064	0.078	00:00:00
89-21-67-159.infrawat.ch	0.008	0.008	00:00:00
198.235.24.224	0.006	0.008	00:00:00
89-21-67-182.infrawat.ch	0.010	0.008	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.012	0.018	00:00:00
sh-chi-us-cpp-wk107.internet-census.org	0.006	0.008	00:00:00
193-124-20-250.infrawat.ch	0.004	0.012	00:00:00
zl-laxd-us-cpp-wk110.internet-census.org	0.004	0.010	00:00:00
200.138.46.169	0.000	0.014	00:00:00
zl-laxd-us-cpp-wk111.internet-census.org	0.006	0.008	00:00:00
sh-chi-us-cpp-wk105.internet-census.org	0.006	0.008	00:00:00
TOTAL: 20	9.994	19.638	00:02:40

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

System	Conn
210.172.132.66.censys-scanner.com	2
175.186.132.66.censys-scanner.com	2
TOTAL: 2	4

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

Command	Count
EHLO www.censys.io	4
TOTAL: 1	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-21-67-182.infrawat.ch	2	2
210.172.132.66.censys-scanner.com	2	4
89-21-67-159.infrawat.ch	2	2
TOTAL: 3	6	14

Daily Usenet report

Oct 12 04:15:02 -- Oct 13 04:15:01