Daily Usenet report: Aug 31 04:15:01

Unknown entries from news log file:

First 34 / 34 lines (100.0%)

Aug 31 09:40:20 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Aug 31 09:40:20 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Aug 31 10:00:00 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 31 10:00:00 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Aug 31 10:20:40 newsfeed nnrpd[980608]: SERVER perl filter not defined
Aug 31 10:20:40 newsfeed nnrpd[980608]: SERVER perl filter not defined
Aug 31 10:29:55 newsfeed nnrpd[981659]: SERVER perl filter not defined
Aug 31 10:29:55 newsfeed nnrpd[981659]: SERVER perl filter not defined
Aug 31 14:25:10 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 14:25:10 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 14:25:11 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 14:25:11 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 16:52:41 newsfeed nnrpd[1027302]: SERVER perl filter not defined
Aug 31 16:52:41 newsfeed nnrpd[1027302]: SERVER perl filter not defined
Aug 31 16:54:22 newsfeed nnrpd[1027698]: SERVER perl filter not defined
Aug 31 16:54:22 newsfeed nnrpd[1027698]: SERVER perl filter not defined
Aug 31 17:36:27 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 17:36:27 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 18:42:52 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 18:42:52 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 384000 duplicate size 0 rejected size 0]
Aug 31 22:40:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 22:40:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:34 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:34 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:35 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:35 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:36 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:36 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:39 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Aug 31 23:07:39 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 00:42:57 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 00:42:57 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:114 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 1 00:44:24 newsfeed nnrpd[1083633]: SERVER perl filter not defined
Sep 1 00:44:24 newsfeed nnrpd[1083633]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	455439	92.2%	49.9 MB	92.7%
innd	21498	4.4%	2.3 MB	4.2%
nnrpd	15882	3.2%	1.5 MB	2.8%
innfeed	1228	0.2%	153.4 KB	0.3%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	494051	100.0%	53.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	909692	99.9%
Negative hits	1198	0.1%
Positive hits	122	0.0%
Cache misses	2	0.0%
TOTAL: 4	911014	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.010	0.0%	88	0.000	0.114	2.000
article cleanup	00:00:01.886	0.0%	910612	0.000	0.002	0.005
article logging	00:00:09.152	0.0%	910676	0.007	0.010	0.014
article parse	00:00:12.986	0.0%	2846934	0.003	0.005	0.008
article write	00:00:23.186	0.0%	910610	0.015	0.025	0.049
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:00:01.458	0.0%	3713920	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.004	0.0%	94	0.000	0.043	1.000
hishave/artcncl	00:00:00.000	0.0%	6	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.248	0.0%	911926	0.001	0.027	0.423
history sync	00:00:00.030	0.0%	616	0.000	0.049	1.000
history write	00:01:11.246	0.0%	910610	0.019	0.078	0.410
idle	47:50:18.670	99.6%	3302266	16.852	52.152	114.230
nntp read	00:00:17.974	0.0%	2563712	0.005	0.007	0.012
overview write	00:00:00.994	0.0%	910610	0.000	0.001	0.003
perl filter	00:06:17.036	0.2%	910610	0.241	0.414	0.601
python filter	00:00:00.102	0.0%	910620	0.000	0.000	0.001
site send	00:00:10.028	0.0%	1833704	0.003	0.005	0.008
TOTAL: 48:00:32.866	47:59:29.010	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.006	0.0%	12448	0.000	0.000	0.020
article prepare	00:00:00.000	0.0%	1328	0.000	0.000	0.000
article read	00:00:00.014	0.0%	332	0.000	0.042	1.000
backlog stats	00:00:00.020	0.0%	76326	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.240	0.0%	35626	0.000	0.007	0.041
data write	00:00:00.550	0.0%	22994	0.000	0.024	0.088
idle	47:40:02.020	100.0%	76326	1089.664	2248.277	7152.310
status file	00:00:00.142	0.0%	312	0.000	0.455	8.000
TOTAL: 47:40:03.880	47:40:02.992	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:28.290	75.8%	8884	0.000	9.938	5261.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.216	0.2%	30102	0.000	0.007	0.333
readart	00:00:00.000	0.0%	8	0.000	0.000	0.000
TOTAL: 00:01:56.526	00:01:28.506	76.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	158	899364	899364	0	0	100%	71:50:24
2	localhost	12	10	10	0	0	100%	00:00:00
3	news.icm.edu.pl	62	122	2	118	2	1%	13:34:30
TOTAL: 3		232	899496	899376	118	2	99%	85:24:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	6.8 GB	0.0 KB	6.8 GB	100%	8.0 KB
2	news.icm.edu.pl	17.6 KB	16.6 KB	34.2 KB	51%	8.5 KB
3	localhost	15.7 KB	0.0 KB	15.7 KB	100%	1.6 KB
TOTAL: 3		6.8 GB	16.6 KB	6.8 GB	99%	8.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 31 04:15:01 - 04:59:59	10995	2.4%	4.07	86.5 MB	2.5%	32.82
Aug 31 05:00:00 - 05:59:59	16396	3.6%	4.55	158.0 MB	4.5%	44.94
Aug 31 06:00:00 - 06:59:59	12949	2.8%	3.60	113.9 MB	3.2%	32.39
Aug 31 07:00:00 - 07:59:59	15575	3.4%	4.33	133.5 MB	3.8%	37.99
Aug 31 08:00:00 - 08:59:59	25174	5.5%	6.99	148.2 MB	4.2%	42.16
Aug 31 09:00:00 - 09:59:59	24916	5.5%	6.92	139.6 MB	4.0%	39.72
Aug 31 10:00:00 - 10:59:59	18471	4.1%	5.13	154.6 MB	4.4%	43.98
Aug 31 11:00:00 - 11:59:59	13989	3.1%	3.89	133.5 MB	3.8%	37.97
Aug 31 12:00:00 - 12:59:59	14112	3.1%	3.92	140.6 MB	4.0%	39.98
Aug 31 13:00:00 - 13:59:59	11410	2.5%	3.17	115.1 MB	3.3%	32.73
Aug 31 14:00:00 - 14:59:59	19591	4.3%	5.44	144.5 MB	4.1%	41.11
Aug 31 15:00:00 - 15:59:59	19255	4.2%	5.35	153.6 MB	4.4%	43.68
Aug 31 16:00:00 - 16:59:59	17081	3.8%	4.74	165.8 MB	4.7%	47.16
Aug 31 17:00:00 - 17:59:59	14169	3.1%	3.94	122.4 MB	3.5%	34.82
Aug 31 18:00:00 - 18:59:59	15397	3.4%	4.28	131.4 MB	3.7%	37.38
Aug 31 19:00:00 - 19:59:59	18110	4.0%	5.03	122.3 MB	3.5%	34.77
Aug 31 20:00:00 - 20:59:59	15156	3.3%	4.21	123.1 MB	3.5%	35.01
Aug 31 21:00:00 - 21:59:59	19720	4.3%	5.48	149.9 MB	4.3%	42.65
Aug 31 22:00:00 - 22:59:59	18563	4.1%	5.16	166.1 MB	4.7%	47.25
Aug 31 23:00:00 - 23:59:59	21171	4.6%	5.88	149.6 MB	4.3%	42.56
Sep 1 00:00:00 - 00:59:59	23390	5.1%	6.50	157.1 MB	4.5%	44.70
Sep 1 01:00:00 - 01:59:59	21826	4.8%	6.06	154.9 MB	4.4%	44.07
Sep 1 02:00:00 - 02:59:59	27350	6.0%	7.60	176.7 MB	5.0%	50.26
Sep 1 03:00:00 - 03:59:59	34242	7.5%	9.51	227.0 MB	6.5%	64.57
Sep 1 04:00:00 - 04:15:01	6395	1.4%	7.10	39.0 MB	1.1%	44.28
TOTAL: 24:00:00	455403	100.0%	5.27	3.4 GB	100.0%	41.56

Sites sending bad articles:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	23434	318	12146	14	1%	47:59:56
2	poznan	2	0	2	0	0%	00:40:02
TOTAL: 2		23436	318	12148	14	1%	48:39:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	1.2 MB	65.9 KB	1.3 MB	4.0 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	00:40:02
TOTAL: 2		1.2 MB	65.9 KB	1.3 MB	4.0 KB	48:39:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	66.61.98.95	8	8	12.7 KB	4	0	00:00:14
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:00
TOTAL: 2		18	8	12.7 KB	4	10	00:01:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	596	8	12.7 KB	4	0	00:00:53
2	*.infrawat.ch	4	0	0.0 KB	0	0	00:00:00
3	*.man.lodz.pl	2890	0	0.0 KB	0	10	00:00:23
4	*.scanner.modat.io	4	0	0.0 KB	0	0	00:00:00
5	*.internet-census.org	14	0	0.0 KB	0	0	00:00:22
6	*.172.132.66.censys-scanner.com	10	0	0.0 KB	0	0	00:00:17
TOTAL: 6		3518	8	12.7 KB	4	10	00:01:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.550	3.256	00:00:32
nms2.man.lodz.pl	7.706	16.016	00:00:23
sh-chi-us-gp1-wk137c.internet-census.org	0.006	0.010	00:00:21
210.172.132.66.censys-scanner.com	0.068	0.068	00:00:17
66.61.98.95	0.028	0.046	00:00:14
80.111.142.213	0.020	0.046	00:00:05
isvcs01.man.lodz.pl	0.046	0.058	00:00:00
198.235.24.136	0.002	0.016	00:00:00
31-14-254-120.infrawat.ch	0.012	0.008	00:00:00
sh-chi-us-cpp-wk106.internet-census.org	0.004	0.018	00:00:00
69-5-169-186.infrawat.ch	0.004	0.010	00:00:00
o069.scanner.modat.io	0.010	0.006	00:00:00
o002.scanner.modat.io	0.002	0.012	00:00:00
sh-ams-nl-cpp-wk103.internet-census.org	0.006	0.010	00:00:00
sh-ams-nl-cpp-wk101.internet-census.org	0.004	0.016	00:00:00
sh-chi-us-cpp-wk105.internet-census.org	0.004	0.016	00:00:00
zl-laxd-us-cpp-wk111.internet-census.org	0.012	0.004	00:00:00
zl-laxd-us-cpp-wk110.internet-census.org	0.006	0.010	00:00:00
TOTAL: 18	9.490	19.626	00:01:57

Curious NNRP server explorers [Top 20]:

System	Conn
nms2.man.lodz.pl	2880
172.16.2.4	578
210.172.132.66.censys-scanner.com	10
80.111.142.213	8
sh-chi-us-gp1-wk137c.internet-census.org	2
zl-laxd-us-cpp-wk110.internet-census.org	2
o002.scanner.modat.io	2
31-14-254-120.infrawat.ch	2
sh-chi-us-cpp-wk106.internet-census.org	2
sh-ams-nl-cpp-wk101.internet-census.org	2
zl-laxd-us-cpp-wk111.internet-census.org	2
sh-ams-nl-cpp-wk103.internet-census.org	2
69-5-169-186.infrawat.ch	2
o069.scanner.modat.io	2
198.235.24.136	2
sh-chi-us-cpp-wk105.internet-census.org	2
TOTAL: 16	3500

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

System	Conn
210.172.132.66.censys-scanner.com	4
TOTAL: 1	4

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

Command	Count
EHLO www.censys.io	2
#026#003#001	2
TOTAL: 2	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
210.172.132.66.censys-scanner.com	2	6
31-14-254-120.infrawat.ch	2	2
TOTAL: 2	4	12

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	lodman.info	8
TOTAL: 1		8

Daily Usenet report

Aug 31 04:15:01 -- Sep 1 04:15:01