Daily Usenet report: Aug 30 04:15:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Aug 30 22:40:48 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 22:40:48 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:09:03 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 30 23:09:03 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 30 23:09:05 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 30 23:09:05 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 30 23:59:32 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:59:32 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:59:33 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:59:33 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:59:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 30 23:59:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 31 02:24:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:51 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:51 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:53 newsfeed innd: message repeated 5 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:53 newsfeed innd: message repeated 5 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 02:24:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:88 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	319051	89.6%	35.3 MB	90.6%
innd	19774	5.6%	2.0 MB	5.1%
nnrpd	16036	4.5%	1.5 MB	3.9%
innfeed	1218	0.3%	146.3 KB	0.4%
overchan	2	0.0%	0.1 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	356083	100.0%	38.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	637214	99.9%
Negative hits	380	0.1%
Positive hits	170	0.0%
Cache misses	0	0.0%
TOTAL: 4	637764	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.016	0.0%	70	0.000	0.229	2.000
article cleanup	00:00:01.502	0.0%	637676	0.000	0.002	0.005
article logging	00:00:06.760	0.0%	637750	0.005	0.011	0.020
article parse	00:00:10.330	0.0%	2130594	0.003	0.005	0.009
article write	00:00:18.834	0.0%	637674	0.017	0.030	0.103
artlog/artcncl	00:00:00.002	0.0%	26	0.000	0.077	0.500
data move	00:00:01.018	0.0%	2898088	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	96	0.000	0.021	1.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:23.336	0.0%	638198	0.001	0.037	0.601
history sync	00:00:00.022	0.0%	600	0.000	0.037	0.500
history write	00:00:58.582	0.0%	637674	0.029	0.092	0.368
idle	47:51:38.648	99.7%	2478214	13.949	69.525	152.439
nntp read	00:00:14.176	0.0%	1969426	0.004	0.007	0.011
overview write	00:00:00.746	0.0%	637674	0.000	0.001	0.004
perl filter	00:04:55.038	0.2%	637674	0.257	0.463	0.721
python filter	00:00:00.064	0.0%	637674	0.000	0.000	0.001
site send	00:00:07.396	0.0%	1286590	0.003	0.006	0.009
TOTAL: 48:00:34.024	47:58:56.472	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	11200	0.000	0.002	0.077
article prepare	00:00:00.002	0.0%	680	0.000	0.003	0.250
article read	00:00:00.006	0.0%	170	0.000	0.035	1.000
backlog stats	00:00:00.012	0.0%	70650	0.000	0.000	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.228	0.0%	32470	0.000	0.007	0.074
data write	00:00:00.614	0.0%	21064	0.000	0.029	0.104
idle	47:40:27.116	100.0%	70650	696.899	2429.259	15431.538
status file	00:00:00.170	0.0%	314	0.000	0.541	9.000
TOTAL: 47:40:29.048	47:40:28.168	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:55.222	79.9%	8948	0.000	12.877	5225.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.288	0.2%	30242	0.000	0.010	0.500
readart	00:00:00.000	0.0%	4	0.000	0.000	0.000
TOTAL: 00:02:24.290	00:01:55.510	80.1%	-	-	-	-

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	80	627300	627298	2	0	99%	60:29:06
2	news.icm.edu.pl	50	142	2	138	2	1%	13:36:06
3	localhost	2	0	0	0	0	0%	00:00:00
TOTAL: 3		132	627442	627300	140	2	99%	74:05:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	5.5 GB	0.0 KB	5.5 GB	100%	9.2 KB
2	news.icm.edu.pl	184.4 KB	29.8 KB	214.1 KB	86%	53.5 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		5.5 GB	29.8 KB	5.5 GB	99%	9.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 30 04:15:01 - 04:59:59	5615	1.8%	2.08	52.4 MB	1.9%	19.88
Aug 30 05:00:00 - 05:59:59	8813	2.8%	2.45	74.5 MB	2.6%	21.19
Aug 30 06:00:00 - 06:59:59	10740	3.4%	2.98	88.1 MB	3.1%	25.05
Aug 30 07:00:00 - 07:59:59	7611	2.4%	2.11	71.1 MB	2.5%	20.22
Aug 30 08:00:00 - 08:59:59	7633	2.4%	2.12	75.5 MB	2.7%	21.48
Aug 30 09:00:00 - 09:59:59	10641	3.3%	2.96	97.3 MB	3.5%	27.66
Aug 30 10:00:00 - 10:59:59	9007	2.8%	2.50	84.1 MB	3.0%	23.93
Aug 30 11:00:00 - 11:59:59	10076	3.2%	2.80	93.4 MB	3.3%	26.55
Aug 30 12:00:00 - 12:59:59	11324	3.5%	3.15	95.4 MB	3.4%	27.15
Aug 30 13:00:00 - 13:59:59	9258	2.9%	2.57	80.2 MB	2.8%	22.81
Aug 30 14:00:00 - 14:59:59	12777	4.0%	3.55	136.1 MB	4.8%	38.72
Aug 30 15:00:00 - 15:59:59	16130	5.1%	4.48	157.1 MB	5.6%	44.68
Aug 30 16:00:00 - 16:59:59	15865	5.0%	4.41	141.4 MB	5.0%	40.22
Aug 30 17:00:00 - 17:59:59	21173	6.6%	5.88	176.2 MB	6.3%	50.12
Aug 30 18:00:00 - 18:59:59	12717	4.0%	3.53	128.9 MB	4.6%	36.65
Aug 30 19:00:00 - 19:59:59	15532	4.9%	4.31	186.5 MB	6.6%	53.04
Aug 30 20:00:00 - 20:59:59	12617	4.0%	3.50	105.0 MB	3.7%	29.88
Aug 30 21:00:00 - 21:59:59	10688	3.4%	2.97	104.8 MB	3.7%	29.82
Aug 30 22:00:00 - 22:59:59	22514	7.1%	6.25	141.7 MB	5.0%	40.31
Aug 30 23:00:00 - 23:59:59	23996	7.5%	6.67	124.2 MB	4.4%	35.32
Aug 31 00:00:00 - 00:59:59	11028	3.5%	3.06	99.3 MB	3.5%	28.24
Aug 31 01:00:00 - 01:59:59	11089	3.5%	3.08	96.8 MB	3.4%	27.55
Aug 31 02:00:00 - 02:59:59	20096	6.3%	5.58	120.8 MB	4.3%	34.35
Aug 31 03:00:00 - 03:59:59	15077	4.7%	4.19	166.0 MB	5.9%	47.22
Aug 31 04:00:00 - 04:15:01	6983	2.2%	7.75	117.1 MB	4.2%	133.10
TOTAL: 24:00:00	319000	100.0%	3.69	2.7 GB	100.0%	33.35

Sites sending bad articles:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	22012	168	11068	6	0%	47:59:56
2	poznan	2	0	2	0	0%	00:40:00
TOTAL: 2		22014	168	11070	6	0%	48:39:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	388.9 KB	17.1 KB	406.0 KB	2.3 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	00:40:00
TOTAL: 2		388.9 KB	17.1 KB	406.0 KB	2.3 KB	48:39:56

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	66.61.98.95	56	4	5.2 KB	2	0	0	00:00:49
TOTAL: 1		56	4	5.2 KB	2	0	0	00:02:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	640	4	5.2 KB	2	00:01:29
2	*.infrawat.ch	4	0	0.0 KB	0	00:00:00
3	*.internet-census.org	12	0	0.0 KB	0	00:00:21
4	*.186.132.66.censys-scanner.com	2	0	0.0 KB	0	00:00:06
5	*.man.lodz.pl	2880	0	0.0 KB	0	00:00:23
6	*.monitoring.internet-measurement.com	2	0	0.0 KB	0	00:00:03
TOTAL: 6		3540	4	5.2 KB	2	00:02:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
66.61.98.95	0.138	0.326	00:00:49
172.16.2.4	1.580	3.420	00:00:33
nms2.man.lodz.pl	8.058	16.820	00:00:23
zl-dfwc-us-gp1-wk110d.internet-census.org	0.004	0.010	00:00:21
80.111.142.213	0.020	0.046	00:00:06
195.186.132.66.censys-scanner.com	0.052	0.018	00:00:06
r3-53-35.monitoring.internet-measurement.com	0.008	0.010	00:00:03
195-140-214-25.infrawat.ch	0.010	0.006	00:00:00
185-216-145-179.infrawat.ch	0.004	0.014	00:00:00
sh-chi-us-cpp-wk105.internet-census.org	0.008	0.010	00:00:00
zl-laxd-us-cpp-wk110.internet-census.org	0.006	0.028	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.008	0.010	00:00:00
sh-chi-us-cpp-wk107.internet-census.org	0.002	0.014	00:00:00
TOTAL: 13	9.898	20.732	00:02:25

Curious NNRP server explorers [Top 20]:

System	Conn
nms2.man.lodz.pl	2880
172.16.2.4	576
80.111.142.213	8
zl-laxd-us-cpp-wk110.internet-census.org	4
sh-ams-nl-cpp-wk104.internet-census.org	2
sh-chi-us-cpp-wk105.internet-census.org	2
185-216-145-179.infrawat.ch	2
zl-dfwc-us-gp1-wk110d.internet-census.org	2
sh-chi-us-cpp-wk107.internet-census.org	2
195.186.132.66.censys-scanner.com	2
r3-53-35.monitoring.internet-measurement.com	2
195-140-214-25.infrawat.ch	2
TOTAL: 12	3484

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

System	Conn
195.186.132.66.censys-scanner.com	2
TOTAL: 1	2

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
195-140-214-25.infrawat.ch	2	2
TOTAL: 1	2	4

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	lodman	4	100.0%
TOTAL: 1		4	100.0%

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

Newsgroup		Count
1	lodman.info	4
TOTAL: 1		4

Daily Usenet report

Aug 30 04:15:01 -- Aug 31 04:15:01