Daily Usenet report: Sep 1 04:15:01

Unknown entries from news log file:

First 34 / 34 lines (100.0%)

Sep 1 07:21:19 newsfeed nnrpd[1133539]: SERVER perl filter not defined
Sep 1 07:21:19 newsfeed nnrpd[1133539]: SERVER perl filter not defined
Sep 1 08:05:37 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:05:37 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:05:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:05:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:05:39 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:05:39 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 08:15:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 1 08:15:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 1 09:15:01 newsfeed pgpverify[1146950]: pgpverify: verification failed
Sep 1 09:15:01 newsfeed pgpverify[1146950]: pgpverify: verification failed
Sep 1 11:40:35 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 1 11:40:35 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Sep 1 13:00:00 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 13:00:00 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 15:05:04 newsfeed pgpverify[1189387]: pgpverify: verification failed
Sep 1 15:05:04 newsfeed pgpverify[1189387]: pgpverify: verification failed
Sep 1 18:45:56 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 18:45:56 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 22:07:53 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 22:07:53 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 22:07:54 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 22:07:54 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 22:40:43 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 1 22:40:43 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 1 22:43:40 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 22:43:40 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 22:43:41 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 22:43:41 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 22:43:43 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 1 22:43:43 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 2 00:21:37 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 2 00:21:37 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:117 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	487077	92.6%	53.0 MB	93.0%
innd	21902	4.2%	2.3 MB	4.1%
nnrpd	15858	3.0%	1.5 MB	2.7%
innfeed	1248	0.2%	143.5 KB	0.2%
controlchan	86	0.0%	20.9 KB	0.0%
pgpverify	4	0.0%	0.5 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	526177	100.0%	57.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	973242	99.4%
Negative hits	5880	0.6%
Positive hits	206	0.0%
Cache misses	40	0.0%
TOTAL: 4	979368	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.110	0.0%	218	0.000	0.505	5.000
article cleanup	00:00:02.114	0.0%	974458	0.000	0.002	0.005
article logging	00:00:10.008	0.0%	974542	0.006	0.010	0.016
article parse	00:00:13.380	0.0%	3153800	0.002	0.004	0.007
article write	00:00:28.366	0.0%	974454	0.017	0.029	0.529
artlog/artcncl	00:00:00.000	0.0%	60	0.000	0.000	0.000
data move	00:00:02.360	0.0%	3975962	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.004	0.0%	278	0.000	0.014	2.000
hishave/artcncl	00:00:00.000	0.0%	60	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:36.260	0.0%	980556	0.000	0.037	0.320
history sync	00:00:00.030	0.0%	598	0.000	0.050	1.000
history write	00:01:40.654	0.1%	974454	0.024	0.103	0.369
idle	47:48:14.816	99.6%	3522620	22.019	48.854	99.937
nntp read	00:00:19.808	0.0%	2787624	0.005	0.007	0.011
overview write	00:00:01.140	0.0%	974454	0.000	0.001	0.003
perl filter	00:06:45.220	0.2%	974454	0.245	0.416	0.591
python filter	00:00:00.118	0.0%	974456	0.000	0.000	0.001
site send	00:00:10.654	0.0%	1961202	0.002	0.005	0.009
TOTAL: 48:00:37.946	47:58:45.042	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	12274	0.000	0.002	0.083
article prepare	00:00:00.002	0.0%	872	0.000	0.002	0.125
article read	00:00:00.000	0.0%	218	0.000	0.000	0.000
backlog stats	00:00:00.014	0.0%	68360	0.000	0.000	0.010
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.220	0.0%	32500	0.000	0.007	0.059
data write	00:00:00.532	0.0%	20164	0.000	0.026	0.121
idle	47:40:32.924	100.0%	68360	1232.125	2510.722	7710.462
status file	00:00:00.162	0.0%	324	0.000	0.500	4.000
TOTAL: 47:40:34.750	47:40:33.878	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:46.720	57.4%	8844	0.000	32.420	5414.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.234	0.0%	30060	0.000	0.008	0.500
readart	00:00:00.004	0.0%	20	0.000	0.200	1.000
TOTAL: 00:08:19.758	00:04:46.958	57.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	Other	Bad PGP	OK
bofh-control@killfile.org	2	0	2
control@eternal-september.org	2	0	2
moderator@dana.de	2	2	2
us-control@lists.killfile.org	0	2	0
TOTAL	6	4	6

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	192	959682	959678	2	2	99%	85:38:06
2	news.icm.edu.pl	84	186	4	180	2	2%	18:49:10
3	localhost	4	2	2	0	0	100%	00:00:00
TOTAL: 3		280	959870	959684	182	4	99%	104:27:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	7.1 GB	32.2 KB	7.1 GB	99%	7.8 KB
2	news.icm.edu.pl	162.5 KB	31.7 KB	194.1 KB	83%	32.4 KB
3	localhost	3.2 KB	0.0 KB	3.2 KB	100%	1.6 KB
TOTAL: 3		7.1 GB	63.9 KB	7.1 GB	99%	7.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 04:15:01 - 04:59:59	11735	2.4%	4.35	97.5 MB	2.7%	37.00
Sep 1 05:00:00 - 05:59:59	14243	2.9%	3.96	108.8 MB	3.0%	30.95
Sep 1 06:00:00 - 06:59:59	18391	3.8%	5.11	120.1 MB	3.3%	34.16
Sep 1 07:00:00 - 07:59:59	10575	2.2%	2.94	101.7 MB	2.8%	28.92
Sep 1 08:00:00 - 08:59:59	31736	6.5%	8.82	145.1 MB	4.0%	41.28
Sep 1 09:00:00 - 09:59:59	19787	4.1%	5.50	158.8 MB	4.3%	45.17
Sep 1 10:00:00 - 10:59:59	19643	4.0%	5.46	134.5 MB	3.7%	38.27
Sep 1 11:00:00 - 11:59:59	19289	4.0%	5.36	144.7 MB	4.0%	41.17
Sep 1 12:00:00 - 12:59:59	26966	5.5%	7.49	155.3 MB	4.3%	44.18
Sep 1 13:00:00 - 13:59:59	26106	5.4%	7.25	166.8 MB	4.6%	47.43
Sep 1 14:00:00 - 14:59:59	19776	4.1%	5.49	153.9 MB	4.2%	43.76
Sep 1 15:00:00 - 15:59:59	22170	4.6%	6.16	146.2 MB	4.0%	41.57
Sep 1 16:00:00 - 16:59:59	17489	3.6%	4.86	145.1 MB	4.0%	41.26
Sep 1 17:00:00 - 17:59:59	18311	3.8%	5.09	148.8 MB	4.1%	42.33
Sep 1 18:00:00 - 18:59:59	15376	3.2%	4.27	131.8 MB	3.6%	37.49
Sep 1 19:00:00 - 19:59:59	16182	3.3%	4.50	160.1 MB	4.4%	45.53
Sep 1 20:00:00 - 20:59:59	22900	4.7%	6.36	187.2 MB	5.1%	53.26
Sep 1 21:00:00 - 21:59:59	20413	4.2%	5.67	173.1 MB	4.7%	49.23
Sep 1 22:00:00 - 22:59:59	26845	5.5%	7.46	175.1 MB	4.8%	49.82
Sep 1 23:00:00 - 23:59:59	18545	3.8%	5.15	163.2 MB	4.5%	46.42
Sep 2 00:00:00 - 00:59:59	16778	3.4%	4.66	146.6 MB	4.0%	41.70
Sep 2 01:00:00 - 01:59:59	25287	5.2%	7.02	219.7 MB	6.0%	62.48
Sep 2 02:00:00 - 02:59:59	18681	3.8%	5.19	142.2 MB	3.9%	40.45
Sep 2 03:00:00 - 03:59:59	25707	5.3%	7.14	188.6 MB	5.2%	53.64
Sep 2 04:00:00 - 04:15:01	4072	0.8%	4.52	38.8 MB	1.1%	44.14
TOTAL: 24:00:00	487003	100.0%	5.64	3.6 GB	100.0%	43.30

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	43	1	42
2	news.icm.edu.pl	1	1	0
TOTAL: 2		44	2	42

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	20320	208	12064	10	1%	47:59:56
2	poznan	4	0	4	0	0%	01:00:00
TOTAL: 2		20324	208	12068	10	1%	48:59:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	732.2 KB	27.7 KB	760.0 KB	3.5 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:00:00
TOTAL: 2		732.2 KB	27.7 KB	760.0 KB	3.5 KB	48:59:56

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	172.16.2.4	578	16	23.1 KB	8	0	00:02:58
2	66.61.98.95	12	4	6.2 KB	2	0	00:00:14
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		592	20	29.4 KB	10	2	00:08:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	608	20	29.4 KB	10	0	00:04:06
2	*.stretchoid.com	4	0	0.0 KB	0	0	00:00:01
3	*.man.lodz.pl	2882	0	0.0 KB	0	2	00:03:36
4	*.internet-census.org	14	0	0.0 KB	0	0	00:00:25
5	*.172.132.66.censys-scanner.com	2	0	0.0 KB	0	0	00:00:09
6	*.infrawat.ch	4	0	0.0 KB	0	0	00:00:00
TOTAL: 6		3514	20	29.4 KB	10	2	00:08:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
nms2.man.lodz.pl	7.982	16.062	00:03:36
172.16.2.4	1.592	3.430	00:02:58
47.94.165.91	0.070	0.034	00:00:38
sh-chi-us-gp1-wk105c.internet-census.org	0.010	0.008	00:00:22
66.61.98.95	0.030	0.072	00:00:14
195.172.132.66.censys-scanner.com	0.050	0.014	00:00:09
45.205.1.244	0.008	0.010	00:00:07
80.111.142.213	0.022	0.050	00:00:05
zl-laxd-us-cpp-wk110.internet-census.org	0.006	0.010	00:00:01
sh-ams-nl-cpp-wk103.internet-census.org	0.002	0.012	00:00:01
azpdcsg1won1.stretchoid.com	0.008	0.028	00:00:01
45.141.233.16	0.004	0.012	00:00:01
193-32-209-232.infrawat.ch	0.004	0.010	00:00:00
195-140-214-19.infrawat.ch	0.006	0.010	00:00:00
sh-chi-us-cpp-wk107.internet-census.org	0.010	0.020	00:00:00
isvcs01.man.lodz.pl	0.008	0.016	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.004	0.012	00:00:00
zl-laxd-us-cpp-wk111.internet-census.org	0.006	0.010	00:00:00
TOTAL: 18	9.822	19.820	00:08:20

Curious NNRP server explorers [Top 20]:

System	Conn
nms2.man.lodz.pl	2880
80.111.142.213	8
47.94.165.91	6
azpdcsg1won1.stretchoid.com	4
sh-chi-us-cpp-wk107.internet-census.org	4
sh-ams-nl-cpp-wk103.internet-census.org	2
195.172.132.66.censys-scanner.com	2
195-140-214-19.infrawat.ch	2
sh-ams-nl-cpp-wk104.internet-census.org	2
sh-chi-us-gp1-wk105c.internet-census.org	2
45.205.1.244	2
zl-laxd-us-cpp-wk111.internet-census.org	2
zl-laxd-us-cpp-wk110.internet-census.org	2
45.141.233.16	2
193-32-209-232.infrawat.ch	2
TOTAL: 15	2922

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

System	Conn
azpdcsg1won1.stretchoid.com	2
195.172.132.66.censys-scanner.com	2
TOTAL: 2	4

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
195-140-214-19.infrawat.ch	2	2
47.94.165.91	2	2
TOTAL: 2	4	8

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	pl	16	80.0%
2	lodman	4	20.0%
TOTAL: 2		20	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	16
2	lodman.info	4
TOTAL: 2		20

Daily Usenet report

Sep 1 04:15:01 -- Sep 2 04:15:01