Daily Usenet report: Sep 6 04:15:01

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Sep 6 09:06:43 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 6 09:06:43 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 6 09:06:44 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 6 09:06:44 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 6 11:55:42 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 11:55:42 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 13:44:05 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 13:44:05 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:11 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:11 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:13 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:13 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:17 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 14:32:17 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 6 18:41:37 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 18:41:37 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 18:41:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 18:41:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 18:41:40 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 18:41:40 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 21:44:03 newsfeed innd: message repeated 6 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 21:44:03 newsfeed innd: message repeated 6 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 6 23:59:31 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 6 23:59:31 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 6 23:59:33 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 6 23:59:33 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:51 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	571134	93.5%	61.4 MB	93.9%
innd	22372	3.7%	2.4 MB	3.6%
nnrpd	15774	2.6%	1.5 MB	2.3%
innfeed	1316	0.2%	149.9 KB	0.2%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	610600	100.0%	65.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1139254	99.9%
Negative hits	1346	0.1%
Positive hits	58	0.0%
Cache misses	24	0.0%
TOTAL: 4	1140682	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.008	0.0%	44	0.000	0.182	4.000
article cleanup	00:00:02.442	0.0%	1142486	0.000	0.002	0.005
article logging	00:00:11.708	0.0%	1142660	0.007	0.010	0.017
article parse	00:00:17.356	0.0%	3699570	0.003	0.005	0.007
article write	00:00:51.280	0.0%	1142484	0.015	0.045	3.216
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
data move	00:00:01.800	0.0%	4849488	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	58	0.000	0.034	1.000
hishave/artcncl	00:00:00.000	0.0%	14	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:13.606	0.0%	1143902	0.001	0.064	3.757
history sync	00:00:00.028	0.0%	636	0.000	0.044	1.000
history write	00:01:58.816	0.1%	1142484	0.022	0.104	3.767
idle	47:46:42.788	99.5%	4237936	8.173	40.586	86.195
nntp read	00:00:23.424	0.0%	3329666	0.005	0.007	0.011
overview write	00:00:01.260	0.0%	1142484	0.000	0.001	0.003
perl filter	00:07:46.754	0.3%	1142484	0.234	0.409	0.576
python filter	00:00:00.166	0.0%	1142484	0.000	0.000	0.001
site send	00:00:12.132	0.0%	2297088	0.003	0.005	0.009
TOTAL: 48:00:57.770	47:59:43.570	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.030	0.0%	21776	0.000	0.001	0.091
article prepare	00:00:00.002	0.0%	34400	0.000	0.000	0.000
article read	00:00:00.614	0.0%	8606	0.000	0.071	0.072
backlog stats	00:00:00.008	0.0%	62636	0.000	0.000	0.024
callbacks	00:00:00.000	0.0%	12	0.000	0.000	0.000
data read	00:00:00.254	0.0%	32520	0.000	0.008	0.067
data write	00:00:00.336	0.0%	20498	0.000	0.016	0.091
idle	47:40:18.832	100.0%	62636	36.936	2739.939	16226.270
status file	00:00:00.196	0.0%	332	0.000	0.590	6.000
TOTAL: 47:40:21.230	47:40:20.272	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:02:46.646	68.4%	8792	0.000	18.954	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.264	0.1%	29892	0.000	0.009	0.500
TOTAL: 00:04:03.810	00:02:46.910	68.5%	-	-	-	-

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	92	1129538	1129536	0	2	99%	65:39:20
2	news.icm.edu.pl	22	68	2	66	0	2%	05:03:30
3	localhost	2	0	0	0	0	0%	00:00:00
TOTAL: 3		116	1129606	1129538	66	2	99%	70:42:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	9.0 GB	18.2 KB	9.0 GB	99%	8.3 KB
2	news.icm.edu.pl	18.2 KB	0.0 KB	18.2 KB	100%	9.1 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		9.0 GB	18.2 KB	9.0 GB	99%	8.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 6 04:15:01 - 04:59:59	12317	2.2%	4.56	102.0 MB	2.2%	38.69
Sep 6 05:00:00 - 05:59:59	22707	4.0%	6.31	134.8 MB	2.9%	38.34
Sep 6 06:00:00 - 06:59:59	15854	2.8%	4.40	131.1 MB	2.9%	37.29
Sep 6 07:00:00 - 07:59:59	15738	2.8%	4.37	127.3 MB	2.8%	36.20
Sep 6 08:00:00 - 08:59:59	22526	3.9%	6.26	159.6 MB	3.5%	45.40
Sep 6 09:00:00 - 09:59:59	26843	4.7%	7.46	179.7 MB	3.9%	51.11
Sep 6 10:00:00 - 10:59:59	29111	5.1%	8.09	202.5 MB	4.4%	57.61
Sep 6 11:00:00 - 11:59:59	27846	4.9%	7.74	197.6 MB	4.3%	56.20
Sep 6 12:00:00 - 12:59:59	27684	4.8%	7.69	206.9 MB	4.5%	58.84
Sep 6 13:00:00 - 13:59:59	27347	4.8%	7.60	220.9 MB	4.8%	62.84
Sep 6 14:00:00 - 14:59:59	37941	6.6%	10.54	213.3 MB	4.6%	60.67
Sep 6 15:00:00 - 15:59:59	15449	2.7%	4.29	152.0 MB	3.3%	43.25
Sep 6 16:00:00 - 16:59:59	18861	3.3%	5.24	170.9 MB	3.7%	48.61
Sep 6 17:00:00 - 17:59:59	19641	3.4%	5.46	174.6 MB	3.8%	49.66
Sep 6 18:00:00 - 18:59:59	22881	4.0%	6.36	194.8 MB	4.2%	55.40
Sep 6 19:00:00 - 19:59:59	20069	3.5%	5.57	193.3 MB	4.2%	55.00
Sep 6 20:00:00 - 20:59:59	20681	3.6%	5.74	236.2 MB	5.1%	67.18
Sep 6 21:00:00 - 21:59:59	24195	4.2%	6.72	188.3 MB	4.1%	53.56
Sep 6 22:00:00 - 22:59:59	19185	3.4%	5.33	188.9 MB	4.1%	53.73
Sep 6 23:00:00 - 23:59:59	23497	4.1%	6.53	236.8 MB	5.1%	67.35
Sep 7 00:00:00 - 00:59:59	20656	3.6%	5.74	177.5 MB	3.9%	50.49
Sep 7 01:00:00 - 01:59:59	44325	7.8%	12.31	359.2 MB	7.8%	102.18
Sep 7 02:00:00 - 02:59:59	22393	3.9%	6.22	205.9 MB	4.5%	58.58
Sep 7 03:00:00 - 03:59:59	26328	4.6%	7.31	150.2 MB	3.3%	42.72
Sep 7 04:00:00 - 04:15:01	6964	1.2%	7.73	94.8 MB	2.1%	107.73
TOTAL: 24:00:00	571039	100.0%	6.61	4.5 GB	100.0%	54.51

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other
1	newsfeed.pionier.net.pl	88	0	0	1	0	0	0	0	87
TOTAL: 1		88	0	0	1	0	0	0	0	87

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	13840	8002	3476	594	12	9716	57%	47:59:56
2	poznan	4	0	2	0	0	0	0%	00:40:10
TOTAL: 2		13844	8002	3478	594	12	9716	57%	48:40:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.4 MB	2.3 MB	23.7 MB	0.1 KB/s	2.8 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:40:10
TOTAL: 2		21.4 MB	2.3 MB	23.7 MB	0.1 KB/s	2.8 KB	48:40:06

NNRP readership statistics:

System		Conn	Elapsed
1	nms2.man.lodz.pl	2880	00:00:30
2	95.163.5.83	4	00:01:46
3	80.111.142.213	8	00:00:05
4	sh-ams-nl-cpp-wk103.internet-census.org	2	00:00:00
5	45.79.190.133.socradar-curiosity-wide-scan-project.socradar.com	2	00:00:20
6	172.16.2.4	576	00:00:35
7	o010.scanner.modat.io	2	00:00:00
8	80.66.83.80	2	00:00:00
9	zl-laxd-us-cpp-wk111.internet-census.org	2	00:00:00
10	16.5.0.254	2	00:00:07
11	zl-laxc-us-cpp-wk109.internet-census.org	2	00:00:00
12	o035.scanner.modat.io	2	00:00:00
13	g55.58-98-113.ppp.wakwak.ne.jp	6	00:00:11
14	sh-chi-us-cpp-wk107.internet-census.org	4	00:00:01
15	sh-ams-nl-cpp-wk104.internet-census.org	2	00:00:00
16	zl-amsc-nl-gp1-wk129b.internet-census.org	2	00:00:20
17	62.195.132.66.censys-scanner.com	6	00:00:02
TOTAL: 17		3504	00:04:04

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.79.190.133.socradar-curiosity-wide-scan-project.socradar.com	2	00:00:20
2	*.58-98-113.ppp.wakwak.ne.jp	6	00:00:11
3	unresolved	592	00:02:35
4	*.195.132.66.censys-scanner.com	6	00:00:02
5	*.scanner.modat.io	4	00:00:01
6	*.man.lodz.pl	2880	00:00:30
7	*.internet-census.org	14	00:00:22
TOTAL: 7		3504	00:04:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
95.163.5.83	0.010	0.028	00:01:46
172.16.2.4	1.478	3.270	00:00:35
nms2.man.lodz.pl	7.616	15.412	00:00:30
zl-amsc-nl-gp1-wk129b.internet-census.org	0.000	0.016	00:00:20
45.79.190.133.socradar-curiosity-wide-scan-project.socradar.com	0.008	0.008	00:00:20
g55.58-98-113.ppp.wakwak.ne.jp	0.040	0.030	00:00:11
16.5.0.254	0.002	0.014	00:00:07
80.111.142.213	0.016	0.052	00:00:05
62.195.132.66.censys-scanner.com	0.072	0.036	00:00:02
sh-chi-us-cpp-wk107.internet-census.org	0.008	0.024	00:00:01
o010.scanner.modat.io	0.006	0.012	00:00:00
o035.scanner.modat.io	0.004	0.012	00:00:00
sh-ams-nl-cpp-wk103.internet-census.org	0.004	0.010	00:00:00
80.66.83.80	0.006	0.010	00:00:00
zl-laxc-us-cpp-wk109.internet-census.org	0.008	0.012	00:00:00
zl-laxd-us-cpp-wk111.internet-census.org	0.004	0.012	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.006	0.008	00:00:00
TOTAL: 17	9.288	18.966	00:04:04

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

System	Conn
o010.scanner.modat.io	4
o035.scanner.modat.io	4
62.195.132.66.censys-scanner.com	2
80.66.83.80	2
TOTAL: 4	12

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

Command	Count
FEAT	4
SYST	4
EHLO www.censys.io	2
#003	2
TOTAL: 4	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.83.80	2	2
62.195.132.66.censys-scanner.com	2	4
TOTAL: 2	4	8

Daily Usenet report

Sep 6 04:15:01 -- Sep 7 04:15:01