Daily Usenet report: Sep 5 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Sep 5 10:55:44 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 10:55:44 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 10:55:45 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 10:55:45 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 10:55:46 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 10:55:46 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 268800 duplicate size 0 rejected size 0]
Sep 5 18:33:49 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 18:33:49 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 19:55:24 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 19:55:24 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 21:26:08 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 5 21:26:08 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 5 23:37:26 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 23:37:26 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 23:37:27 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 23:37:27 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 23:37:30 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 23:37:30 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:37 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	489157	92.6%	52.8 MB	93.0%
innd	22122	4.2%	2.3 MB	4.0%
nnrpd	15970	3.0%	1.5 MB	2.6%
innfeed	1240	0.2%	162.1 KB	0.3%
overchan	2	0.0%	0.2 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	528493	100.0%	56.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	975018	99.9%
Negative hits	1142	0.1%
Positive hits	190	0.0%
Cache misses	20	0.0%
TOTAL: 4	976370	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.052	0.0%	112	0.000	0.464	2.000
article cleanup	00:00:02.090	0.0%	978198	0.000	0.002	0.006
article logging	00:00:10.642	0.0%	978274	0.006	0.011	0.017
article parse	00:00:15.394	0.0%	3257850	0.003	0.005	0.007
article write	00:00:27.006	0.0%	978192	0.017	0.028	0.127
artlog/artcncl	00:00:00.000	0.0%	26	0.000	0.000	0.000
data move	00:00:01.468	0.0%	4312664	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.026	0.0%	138	0.000	0.188	2.000
hishave/artcncl	00:00:00.000	0.0%	26	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:24.876	0.0%	979836	0.000	0.025	0.401
history sync	00:00:00.026	0.0%	732	0.000	0.036	0.500
history write	00:01:08.790	0.0%	978192	0.021	0.070	0.357
idle	47:50:26.206	99.6%	3725198	12.035	46.233	85.027
nntp read	00:00:20.958	0.0%	2947296	0.005	0.007	0.011
overview write	00:00:01.136	0.0%	978192	0.000	0.001	0.003
perl filter	00:06:47.232	0.2%	978192	0.259	0.416	0.553
python filter	00:00:00.134	0.0%	978192	0.000	0.000	0.001
site send	00:00:10.754	0.0%	1968058	0.003	0.005	0.009
TOTAL: 48:01:36.282	48:00:16.790	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	11608	0.000	0.001	0.032
article prepare	00:00:00.000	0.0%	2248	0.000	0.000	0.000
article read	00:00:00.016	0.0%	562	0.000	0.028	0.333
backlog stats	00:00:00.016	0.0%	72336	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.290	0.0%	33468	0.000	0.009	0.061
data write	00:00:00.668	0.0%	21694	0.000	0.031	0.130
idle	47:40:45.570	100.0%	72336	1213.275	2372.893	6899.529
status file	00:00:00.192	0.0%	326	0.000	0.589	4.000
TOTAL: 47:40:47.692	47:40:46.764	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:03:40.336	69.9%	8900	0.000	24.757	5272.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.268	0.1%	30108	0.000	0.009	0.333
TOTAL: 00:05:15.110	00:03:40.604	70.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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	84	968616	968610	2	4	99%	63:01:08
2	news.icm.edu.pl	78	184	6	176	2	3%	18:15:50
3	localhost	2	0	0	0	0	0%	00:00:00
TOTAL: 3		164	968800	968616	178	6	99%	81:16:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	7.8 GB	108.1 KB	7.8 GB	99%	8.5 KB
2	news.icm.edu.pl	244.0 KB	76.0 KB	319.9 KB	76%	40.0 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		7.8 GB	184.1 KB	7.8 GB	99%	8.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 5 04:15:01 - 04:59:59	10597	2.2%	3.93	80.6 MB	2.0%	30.58
Sep 5 05:00:00 - 05:59:59	22026	4.5%	6.12	151.4 MB	3.8%	43.06
Sep 5 06:00:00 - 06:59:59	13268	2.7%	3.69	130.6 MB	3.2%	37.15
Sep 5 07:00:00 - 07:59:59	21103	4.3%	5.86	142.4 MB	3.5%	40.52
Sep 5 08:00:00 - 08:59:59	17633	3.6%	4.90	159.1 MB	4.0%	45.26
Sep 5 09:00:00 - 09:59:59	23791	4.9%	6.61	189.3 MB	4.7%	53.84
Sep 5 10:00:00 - 10:59:59	21835	4.5%	6.07	153.2 MB	3.8%	43.57
Sep 5 11:00:00 - 11:59:59	15799	3.2%	4.39	141.2 MB	3.5%	40.15
Sep 5 12:00:00 - 12:59:59	19279	3.9%	5.36	156.3 MB	3.9%	44.47
Sep 5 13:00:00 - 13:59:59	26573	5.4%	7.38	204.0 MB	5.1%	58.02
Sep 5 14:00:00 - 14:59:59	17583	3.6%	4.88	170.1 MB	4.2%	48.38
Sep 5 15:00:00 - 15:59:59	20370	4.2%	5.66	164.9 MB	4.1%	46.91
Sep 5 16:00:00 - 16:59:59	22286	4.6%	6.19	154.5 MB	3.8%	43.94
Sep 5 17:00:00 - 17:59:59	26626	5.4%	7.40	192.2 MB	4.8%	54.66
Sep 5 18:00:00 - 18:59:59	19288	3.9%	5.36	240.2 MB	6.0%	68.32
Sep 5 19:00:00 - 19:59:59	20824	4.3%	5.78	196.7 MB	4.9%	55.96
Sep 5 20:00:00 - 20:59:59	18417	3.8%	5.12	155.7 MB	3.9%	44.29
Sep 5 21:00:00 - 21:59:59	24117	4.9%	6.70	187.6 MB	4.7%	53.36
Sep 5 22:00:00 - 22:59:59	15499	3.2%	4.31	141.5 MB	3.5%	40.25
Sep 5 23:00:00 - 23:59:59	26924	5.5%	7.48	183.9 MB	4.6%	52.32
Sep 6 00:00:00 - 00:59:59	19269	3.9%	5.35	153.6 MB	3.8%	43.69
Sep 6 01:00:00 - 01:59:59	24097	4.9%	6.69	210.7 MB	5.2%	59.94
Sep 6 02:00:00 - 02:59:59	20446	4.2%	5.68	165.7 MB	4.1%	47.13
Sep 6 03:00:00 - 03:59:59	16553	3.4%	4.60	148.3 MB	3.7%	42.19
Sep 6 04:00:00 - 04:15:01	4900	1.0%	5.44	45.4 MB	1.1%	51.60
TOTAL: 24:00:00	489103	100.0%	5.66	3.9 GB	100.0%	47.63

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	40	2	38
2	news.icm.edu.pl	1	1	0
TOTAL: 2		41	3	38

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	21938	442	11132	120	2%	47:59:56
2	poznan	8	0	6	0	0%	01:20:10
TOTAL: 2		21946	442	11138	120	2%	49:20:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	1.3 MB	2.0 MB	3.3 MB	6.0 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:20:10
TOTAL: 2		1.3 MB	2.0 MB	3.3 MB	6.0 KB	49:20:06

NNRP readership statistics:

System		Conn	Elapsed
1	69-5-169-116.infrawat.ch	2	00:00:00
2	zl-laxd-us-cpp-wk110.internet-census.org	2	00:00:00
3	sh-ams-nl-cpp-wk104.internet-census.org	4	00:00:00
4	69-5-169-77.infrawat.ch	2	00:00:00
5	66.61.98.95	20	00:00:17
6	205.210.31.234	2	00:00:00
7	azpdssrhor87.stretchoid.com	4	00:00:01
8	59.146.94.167.censys-scanner.com	6	00:00:02
9	16.5.0.242	2	00:00:07
10	zl-laxc-us-cpp-wk109.internet-census.org	2	00:00:00
11	95.163.5.83	6	00:02:45
12	71.195.132.66.censys-scanner.com	2	00:00:02
13	45.194.67.42	2	00:00:07
14	172.16.2.4	576	00:00:39
15	zl-laxk-us-gp1-wk133d.internet-census.org	2	00:00:21
16	80.111.142.213	8	00:00:06
17	45.205.1.244	2	00:00:07
18	sh-chi-us-cpp-wk105.internet-census.org	4	00:00:00
19	r4-10-a.monitoring.internet-measurement.com	2	00:00:03
20	nms2.man.lodz.pl	2880	00:00:28
TOTAL: 20		3530	00:05:16

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.195.132.66.censys-scanner.com	2	00:00:02
2	*.man.lodz.pl	2880	00:00:28
3	*.infrawat.ch	4	00:00:00
4	*.monitoring.internet-measurement.com	2	00:00:03
5	*.internet-census.org	14	00:00:22
6	*.stretchoid.com	4	00:00:01
7	unresolved	618	00:04:13
8	*.146.94.167.censys-scanner.com	6	00:00:02
TOTAL: 8		3530	00:05:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
95.163.5.83	0.010	0.042	00:02:45
172.16.2.4	1.550	3.332	00:00:39
nms2.man.lodz.pl	7.832	15.782	00:00:28
zl-laxk-us-gp1-wk133d.internet-census.org	0.006	0.010	00:00:21
66.61.98.95	0.062	0.108	00:00:17
45.205.1.244	0.006	0.008	00:00:07
45.194.67.42	0.002	0.012	00:00:07
16.5.0.242	0.004	0.014	00:00:07
80.111.142.213	0.014	0.052	00:00:06
r4-10-a.monitoring.internet-measurement.com	0.002	0.016	00:00:03
59.146.94.167.censys-scanner.com	0.068	0.036	00:00:02
71.195.132.66.censys-scanner.com	0.056	0.008	00:00:02
azpdssrhor87.stretchoid.com	0.012	0.026	00:00:01
zl-laxc-us-cpp-wk109.internet-census.org	0.010	0.006	00:00:00
205.210.31.234	0.004	0.012	00:00:00
69-5-169-116.infrawat.ch	0.008	0.012	00:00:00
sh-chi-us-cpp-wk105.internet-census.org	0.004	0.026	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.018	0.016	00:00:00
69-5-169-77.infrawat.ch	0.004	0.010	00:00:00
zl-laxd-us-cpp-wk110.internet-census.org	0.006	0.010	00:00:00
TOTAL: 20	9.678	19.538	00:05:16

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

System	Conn
azpdssrhor87.stretchoid.com	2
59.146.94.167.censys-scanner.com	2
71.195.132.66.censys-scanner.com	2
TOTAL: 3	6

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
69-5-169-116.infrawat.ch	2	2
59.146.94.167.censys-scanner.com	2	4
TOTAL: 2	4	10

Daily Usenet report

Sep 5 04:15:01 -- Sep 6 04:15:01