Daily Usenet report: Sep 14 04:15:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Sep 14 07:17:55 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 262400 duplicate size 0 rejected size 0]
Sep 14 07:17:55 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 262400 duplicate size 0 rejected size 0]
Sep 14 15:25:52 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 14 15:25:52 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 14 20:18:37 newsfeed nnrpd[575019]: SERVER perl filter not defined
Sep 14 20:18:37 newsfeed nnrpd[575019]: SERVER perl filter not defined
Sep 14 20:19:09 newsfeed nnrpd[575025]: SERVER perl filter not defined
Sep 14 20:19:09 newsfeed nnrpd[575025]: SERVER perl filter not defined
Sep 14 22:08:36 newsfeed nnrpd[587953]: SERVER perl filter not defined
Sep 14 22:08:36 newsfeed nnrpd[587953]: SERVER perl filter not defined
Sep 15 02:01:59 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 15 02:01:59 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:65 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	498210	92.7%	54.0 MB	93.1%
innd	22166	4.1%	2.3 MB	4.0%
nnrpd	15718	2.9%	1.5 MB	2.6%
innfeed	1320	0.2%	163.2 KB	0.3%
overchan	2	0.0%	0.2 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	537418	100.0%	58.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	988654	99.9%
Negative hits	1354	0.1%
Positive hits	86	0.0%
Cache misses	12	0.0%
TOTAL: 4	990106	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.028	0.0%	152	0.000	0.184	5.000
article cleanup	00:00:02.524	0.0%	993196	0.000	0.003	0.006
article logging	00:00:12.368	0.0%	993258	0.008	0.012	0.024
article parse	00:00:15.274	0.0%	3123744	0.003	0.005	0.008
article write	00:01:28.104	0.1%	993190	0.020	0.089	5.334
artlog/artcncl	00:00:00.000	0.0%	16	0.000	0.000	0.000
data move	00:00:01.812	0.0%	4029082	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.002	0.0%	168	0.000	0.012	1.000
hishave/artcncl	00:00:00.000	0.0%	16	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:53.500	0.1%	994718	0.001	0.114	9.256
history sync	00:00:00.032	0.0%	746	0.000	0.043	1.000
history write	00:01:46.402	0.1%	993192	0.026	0.107	2.182
idle	47:44:57.394	99.4%	3431146	10.107	50.099	141.996
nntp read	00:00:22.040	0.0%	2753514	0.005	0.008	0.012
overview write	00:00:01.212	0.0%	993190	0.000	0.001	0.004
perl filter	00:07:49.366	0.3%	993192	0.280	0.473	0.953
python filter	00:00:00.184	0.0%	993198	0.000	0.000	0.002
site send	00:00:12.392	0.0%	2000182	0.003	0.006	0.009
TOTAL: 48:01:49.534	47:59:02.634	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	18370	0.000	0.002	0.061
article prepare	00:00:00.000	0.0%	19864	0.000	0.000	0.000
article read	00:00:00.122	0.0%	4966	0.000	0.025	0.333
backlog stats	00:00:00.026	0.0%	73648	0.000	0.000	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.350	0.0%	36446	0.000	0.010	0.154
data write	00:00:00.596	0.0%	22684	0.000	0.026	0.107
idle	47:56:54.556	100.0%	73648	80.025	2343.778	16745.444
status file	00:00:00.184	0.0%	344	0.000	0.535	6.000
TOTAL: 47:56:56.706	47:56:55.868	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:25.520	72.3%	8776	0.000	9.745	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.294	0.2%	29704	0.000	0.010	0.333
TOTAL: 00:01:58.262	00:01:25.814	72.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	126	993158	993154	0	4	99%	66:05:18
2	localhost	8	6	6	0	0	100%	00:00:00
3	news.icm.edu.pl	46	86	2	82	2	2%	12:11:28
TOTAL: 3		180	993250	993162	82	6	99%	78:16:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	7.9 GB	16.8 KB	4.0 KB	7.9 GB	99%	8.3 KB
2	news.icm.edu.pl	16.8 KB	29.6 KB	0.0 KB	46.5 KB	36%	11.6 KB
3	localhost	12.2 KB	0.0 KB	0.0 KB	12.2 KB	100%	2.0 KB
TOTAL: 3		7.9 GB	46.5 KB	4.0 KB	7.9 GB	99%	8.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 14 04:15:01 - 04:59:59	15250	3.1%	5.65	119.1 MB	2.9%	45.20
Sep 14 05:00:00 - 05:59:59	25243	5.1%	7.01	172.3 MB	4.2%	49.00
Sep 14 06:00:00 - 06:59:59	13203	2.7%	3.67	122.4 MB	3.0%	34.81
Sep 14 07:00:00 - 07:59:59	15729	3.2%	4.37	116.1 MB	2.9%	33.03
Sep 14 08:00:00 - 08:59:59	16058	3.2%	4.46	137.3 MB	3.4%	39.05
Sep 14 09:00:00 - 09:59:59	19772	4.0%	5.49	157.7 MB	3.9%	44.85
Sep 14 10:00:00 - 10:59:59	20822	4.2%	5.78	189.9 MB	4.7%	54.00
Sep 14 11:00:00 - 11:59:59	18882	3.8%	5.25	170.8 MB	4.2%	48.58
Sep 14 12:00:00 - 12:59:59	25344	5.1%	7.04	265.6 MB	6.6%	75.55
Sep 14 13:00:00 - 13:59:59	16125	3.2%	4.48	126.2 MB	3.1%	35.89
Sep 14 14:00:00 - 14:59:59	20964	4.2%	5.82	189.8 MB	4.7%	53.97
Sep 14 15:00:00 - 15:59:59	12765	2.6%	3.55	98.8 MB	2.4%	28.11
Sep 14 16:00:00 - 16:59:59	23633	4.7%	6.56	221.9 MB	5.5%	63.12
Sep 14 17:00:00 - 17:59:59	44477	8.9%	12.35	400.5 MB	9.9%	113.92
Sep 14 18:00:00 - 18:59:59	31087	6.2%	8.64	175.2 MB	4.3%	49.82
Sep 14 19:00:00 - 19:59:59	15601	3.1%	4.33	116.5 MB	2.9%	33.13
Sep 14 20:00:00 - 20:59:59	13542	2.7%	3.76	108.8 MB	2.7%	30.94
Sep 14 21:00:00 - 21:59:59	11088	2.2%	3.08	107.1 MB	2.6%	30.45
Sep 14 22:00:00 - 22:59:59	11557	2.3%	3.21	92.4 MB	2.3%	26.29
Sep 14 23:00:00 - 23:59:59	26276	5.3%	7.30	206.6 MB	5.1%	58.76
Sep 15 00:00:00 - 00:59:59	24744	5.0%	6.87	189.0 MB	4.7%	53.76
Sep 15 01:00:00 - 01:59:59	20115	4.0%	5.59	166.6 MB	4.1%	47.40
Sep 15 02:00:00 - 02:59:59	33467	6.7%	9.30	235.8 MB	5.8%	67.07
Sep 15 03:00:00 - 03:59:59	15160	3.0%	4.21	109.7 MB	2.7%	31.20
Sep 15 04:00:00 - 04:15:02	7264	1.5%	8.05	58.1 MB	1.4%	66.01
TOTAL: 24:00:01	498168	100.0%	5.77	4.0 GB	100.0%	48.05

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	33	1	32
2	news.icm.edu.pl	1	1	0
TOTAL: 2		34	2	32

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	2
TOTAL: 1	2

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	20150	4518	7880	448	5526	22%	47:59:58
2	poznan	4	0	2	0	0	0%	00:40:10
TOTAL: 2		20154	4518	7882	448	5526	22%	48:40:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.8 MB	3.5 MB	18.3 MB	0.1 KB/s	3.8 KB	47:59:58
2	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:40:10
TOTAL: 2		14.8 MB	3.5 MB	18.3 MB	0.1 KB/s	3.8 KB	48:40:08

NNRP readership statistics:

System		Conn	Post	Elapsed
1	sh-chi-us-cpp-wk106.internet-census.org	2	0	00:00:00
2	sh-chi-us-cpp-wk105.internet-census.org	2	0	00:00:00
3	zl-dala-us-gp1-wk122d.internet-census.org	2	0	00:00:21
4	zl-laxc-us-cpp-wk109.internet-census.org	4	0	00:00:00
5	sh-ams-nl-cpp-wk101.internet-census.org	4	0	00:00:00
6	isvcs01.man.lodz.pl	6	6	00:00:00
7	217-146-80-106.infrawat.ch	2	0	00:00:00
8	nms1.man.lodz.pl	2214	0	00:00:22
9	172.16.2.4	576	0	00:00:34
10	69-5-169-86.infrawat.ch	2	0	00:00:00
11	80.111.142.213	8	0	00:00:05
12	57.195.132.66.censys-scanner.com	2	0	00:00:03
13	nms2.man.lodz.pl	666	0	00:00:06
14	azpdes29k534.stretchoid.com	4	0	00:00:20
15	r4-95-5f.monitoring.internet-measurement.com	2	0	00:00:03
TOTAL: 15		3496	6	00:01:59

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.monitoring.internet-measurement.com	2	0	00:00:03
2	unresolved	584	0	00:00:39
3	*.195.132.66.censys-scanner.com	2	0	00:00:03
4	*.infrawat.ch	4	0	00:00:00
5	*.man.lodz.pl	2886	6	00:00:29
6	*.stretchoid.com	4	0	00:00:20
7	*.internet-census.org	14	0	00:00:21
TOTAL: 7		3496	6	00:01:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.578	3.524	00:00:34
nms1.man.lodz.pl	6.514	13.404	00:00:22
zl-dala-us-gp1-wk122d.internet-census.org	0.002	0.018	00:00:21
azpdes29k534.stretchoid.com	0.010	0.022	00:00:20
nms2.man.lodz.pl	1.946	3.694	00:00:06
80.111.142.213	0.024	0.056	00:00:05
r4-95-5f.monitoring.internet-measurement.com	0.006	0.010	00:00:03
57.195.132.66.censys-scanner.com	0.042	0.022	00:00:03
69-5-169-86.infrawat.ch	0.006	0.012	00:00:00
217-146-80-106.infrawat.ch	0.002	0.014	00:00:00
sh-ams-nl-cpp-wk101.internet-census.org	0.014	0.020	00:00:00
zl-laxc-us-cpp-wk109.internet-census.org	0.014	0.018	00:00:00
sh-chi-us-cpp-wk105.internet-census.org	0.010	0.006	00:00:00
sh-chi-us-cpp-wk106.internet-census.org	0.006	0.008	00:00:00
isvcs01.man.lodz.pl	0.018	0.040	00:00:00
TOTAL: 15	10.192	20.868	00:01:59

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

System	Conn
azpdes29k534.stretchoid.com	2
57.195.132.66.censys-scanner.com	2
TOTAL: 2	4

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

Command	Count
MGLNDD_212.191.2.21_119	2
EHLO www.censys.io	2
TOTAL: 2	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
69-5-169-86.infrawat.ch	2	2
TOTAL: 1	2	4

Daily Usenet report

Sep 14 04:15:01 -- Sep 15 04:15:02