Daily Usenet report: Sep 4 04:15:01

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Sep 4 08:35:42 newsfeed nnrpd[1664577]: SERVER perl filter not defined
Sep 4 08:35:42 newsfeed nnrpd[1664577]: SERVER perl filter not defined
Sep 4 08:36:09 newsfeed nnrpd[1664583]: SERVER perl filter not defined
Sep 4 08:36:09 newsfeed nnrpd[1664583]: SERVER perl filter not defined
Sep 4 10:46:14 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 4 10:46:14 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 4 11:25:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 11:25:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 13:51:50 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 4 13:51:50 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 4 13:51:52 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 4 13:51:52 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 4 13:57:53 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 13:57:53 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 13:57:54 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 13:57:54 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:126 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 18:22:32 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 18:22:32 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 4 20:46:58 newsfeed nnrpd[50387]: SERVER perl filter not defined
Sep 4 20:46:58 newsfeed nnrpd[50387]: SERVER perl filter not defined
Sep 5 02:57:28 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 02:57:28 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 02:57:46 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 02:57:46 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 02:57:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 358400 duplicate size 0 rejected size 0]
Sep 5 02:57:54 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:27 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	702006	94.3%	77.0 MB	94.6%
innd	24484	3.3%	2.7 MB	3.3%
nnrpd	16402	2.2%	1.5 MB	1.9%
innfeed	1334	0.2%	150.1 KB	0.2%
controlchan	4	0.0%	0.2 KB	0.0%
overchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	744232	100.0%	81.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1397120	99.9%
Negative hits	900	0.1%
Cache misses	68	0.0%
Positive hits	64	0.0%
TOTAL: 4	1398152	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.006	0.0%	54	0.000	0.111	3.000
article cleanup	00:00:03.088	0.0%	1398552	0.000	0.002	0.006
article logging	00:00:15.350	0.0%	1398674	0.007	0.011	0.024
article parse	00:00:20.006	0.0%	4259406	0.002	0.005	0.008
article write	00:00:38.972	0.0%	1398550	0.016	0.028	0.263
artlog/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:00:13.532	0.0%	5576386	0.000	0.002	0.005
hisgrep/artcncl	00:00:00.002	0.0%	64	0.000	0.031	1.000
hishave/artcncl	00:00:00.000	0.0%	10	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:37.668	0.0%	1399574	0.001	0.027	0.491
history sync	00:00:00.036	0.0%	670	0.000	0.054	0.667
history write	00:01:45.546	0.1%	1398552	0.014	0.075	0.551
idle	47:24:21.982	99.4%	4875500	8.049	35.004	203.921
nntp read	00:00:33.454	0.0%	3777608	0.006	0.009	0.017
overview write	00:00:01.476	0.0%	1398550	0.000	0.001	0.003
perl filter	00:09:34.458	0.3%	1398552	0.205	0.411	0.726
python filter	00:00:00.198	0.0%	1398558	0.000	0.000	0.001
site send	00:00:15.690	0.0%	2810384	0.003	0.006	0.009
TOTAL: 47:41:10.316	47:38:41.464	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	22082	0.000	0.001	0.043
article prepare	00:00:00.002	0.0%	14464	0.000	0.000	0.000
article read	00:00:01.884	0.0%	3616	0.000	0.521	0.542
backlog stats	00:00:00.012	0.0%	60070	0.000	0.000	0.016
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.278	0.0%	30966	0.000	0.009	0.051
data write	00:00:00.362	0.0%	17760	0.000	0.020	0.116
idle	47:42:17.466	100.0%	60070	67.544	2858.956	16305.919
status file	00:00:00.262	0.0%	364	0.000	0.720	9.000
TOTAL: 47:42:21.220	47:42:20.288	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:14:20.318	76.1%	10874	0.000	79.117	5504.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.320	0.0%	35880	0.000	0.009	0.500
readart	00:00:00.662	0.1%	1678	0.000	0.395	0.397
TOTAL: 00:18:50.358	00:14:21.300	76.2%	-	-	-	-

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	196	1394062	1394062	0	0	100%	76:08:40
2	localhost	8	6	6	0	0	100%	00:00:00
3	news.icm.edu.pl	34	124	0	122	2	0%	07:58:24
TOTAL: 3		238	1394192	1394068	122	2	99%	84:07:04

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	10.5 GB	0.0 KB	10.5 GB	100%	7.9 KB
2	localhost	9.1 KB	0.0 KB	9.1 KB	100%	1.5 KB
3	news.icm.edu.pl	0.0 KB	37.8 KB	37.8 KB	0%	18.9 KB
TOTAL: 3		10.5 GB	37.8 KB	10.5 GB	99%	7.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 04:15:01 - 04:59:59	36903	5.3%	13.67	256.2 MB	4.7%	97.21
Sep 4 05:00:00 - 05:59:59	22498	3.2%	6.25	256.8 MB	4.8%	73.05
Sep 4 06:00:00 - 06:59:59	42002	6.0%	11.67	294.0 MB	5.4%	83.62
Sep 4 07:00:00 - 07:59:59	24381	3.5%	6.77	256.1 MB	4.7%	72.84
Sep 4 08:00:00 - 08:59:59	28047	4.0%	7.79	260.0 MB	4.8%	73.95
Sep 4 09:00:00 - 09:59:59	27638	3.9%	7.68	239.6 MB	4.4%	68.15
Sep 4 10:00:00 - 10:59:59	32371	4.6%	8.99	249.2 MB	4.6%	70.89
Sep 4 11:00:00 - 11:59:59	58829	8.4%	16.34	315.1 MB	5.8%	89.62
Sep 4 12:00:00 - 12:59:59	33857	4.8%	9.40	257.1 MB	4.8%	73.14
Sep 4 13:00:00 - 13:59:59	34081	4.9%	9.47	185.7 MB	3.4%	52.81
Sep 4 14:00:00 - 14:59:59	28079	4.0%	7.80	200.8 MB	3.7%	57.10
Sep 4 15:00:00 - 15:59:59	31974	4.6%	8.88	179.0 MB	3.3%	50.92
Sep 4 16:00:00 - 16:59:59	22772	3.2%	6.33	178.8 MB	3.3%	50.87
Sep 4 17:00:00 - 17:59:59	18485	2.6%	5.13	161.7 MB	3.0%	46.00
Sep 4 18:00:00 - 18:59:59	20695	2.9%	5.75	172.3 MB	3.2%	49.00
Sep 4 19:00:00 - 19:59:59	18302	2.6%	5.08	183.6 MB	3.4%	52.21
Sep 4 20:00:00 - 20:59:59	25109	3.6%	6.97	252.7 MB	4.7%	71.89
Sep 4 21:00:00 - 21:59:59	27424	3.9%	7.62	250.6 MB	4.6%	71.30
Sep 4 22:00:00 - 22:59:59	24891	3.5%	6.91	246.3 MB	4.6%	70.05
Sep 4 23:00:00 - 23:59:59	17440	2.5%	4.84	159.2 MB	3.0%	45.29
Sep 5 00:00:00 - 00:59:59	19380	2.8%	5.38	146.5 MB	2.7%	41.66
Sep 5 01:00:00 - 01:59:59	31053	4.4%	8.63	227.4 MB	4.2%	64.68
Sep 5 02:00:00 - 02:59:59	54062	7.7%	15.02	290.9 MB	5.4%	82.74
Sep 5 03:00:00 - 03:59:59	16661	2.4%	4.63	136.6 MB	2.5%	38.86
Sep 5 04:00:00 - 04:15:01	5005	0.7%	5.55	39.6 MB	0.7%	44.97
TOTAL: 24:00:00	701939	100.0%	8.12	5.3 GB	100.0%	63.95

Sites sending bad articles:

Server		Total	Other
1	newsfeed.pionier.net.pl	61	61
2	news.icm.edu.pl	1	1
TOTAL: 2		62	62

INND Perl filter [Top 20]:

Reason	Count
Scoring filter (9)	2
TOTAL: 1	2

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	17452	3600	9718	16	8750	20%	47:41:00
2	poznan	0	0	0	0	0	0%	00:40:00
TOTAL: 2		17452	3600	9718	16	8750	20%	48:21:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	7.6 MB	32.9 KB	7.6 MB	2.2 KB	47:41:00
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	00:40:00
TOTAL: 2		7.6 MB	32.9 KB	7.6 MB	2.2 KB	48:21:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	95.163.5.83	18	836	1.3 MB	4	0	00:14:47
2	66.61.98.95	44	6	9.2 KB	2	0	00:00:40
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 3		68	842	1.3 MB	6	6	00:18:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	650	842	1.3 MB	6	0	00:16:11
2	*.man.lodz.pl	2886	0	0.0 KB	0	6	00:00:26
3	*.172.132.66.censys-scanner.com	2	0	0.0 KB	0	0	00:00:02
4	*.internet-census.org	14	0	0.0 KB	0	0	00:00:21
5	*.scanner.modat.io	12	0	0.0 KB	0	0	00:00:05
6	*.academyforinternetresearch.org	30	0	0.0 KB	0	0	00:01:42
7	*.infrawat.ch	8	0	0.0 KB	0	0	00:00:01
TOTAL: 7		3602	842	1.3 MB	6	6	00:18:51

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
95.163.5.83	0.132	0.216	00:14:47
prod49client01.academyforinternetresearch.org	0.582	0.172	00:01:42
66.61.98.95	0.144	0.210	00:00:40
172.16.2.4	1.446	3.502	00:00:36
nms2.man.lodz.pl	7.634	16.996	00:00:26
zl-amsc-nl-gp1-wk128d.internet-census.org	0.004	0.010	00:00:20
80.111.142.213	0.014	0.052	00:00:05
204.172.132.66.censys-scanner.com	0.050	0.020	00:00:02
o062.scanner.modat.io	0.004	0.014	00:00:01
205.210.31.35	0.002	0.012	00:00:01
o046.scanner.modat.io	0.004	0.012	00:00:00
o060.scanner.modat.io	0.006	0.016	00:00:00
o011.scanner.modat.io	0.004	0.012	00:00:00
o009.scanner.modat.io	0.006	0.016	00:00:00
o014.scanner.modat.io	0.004	0.010	00:00:00
69-5-169-114.infrawat.ch	0.004	0.014	00:00:00
31-14-254-124.infrawat.ch	0.006	0.010	00:00:00
zl-laxc-us-cpp-wk109.internet-census.org	0.008	0.024	00:00:00
isvcs01.man.lodz.pl	0.024	0.048	00:00:00
89-21-67-158.infrawat.ch	0.006	0.012	00:00:00
TOTAL: 25	10.110	21.452	00:18:51

Curious NNRP server explorers [Top 20]:

System	Conn
nms2.man.lodz.pl	2880
172.16.2.4	576
prod49client01.academyforinternetresearch.org	30
80.111.142.213	8
sh-ams-nl-cpp-wk103.internet-census.org	4
zl-laxc-us-cpp-wk109.internet-census.org	4
o046.scanner.modat.io	2
89-21-67-158.infrawat.ch	2
o060.scanner.modat.io	2
204.172.132.66.censys-scanner.com	2
zl-amsc-nl-gp1-wk128d.internet-census.org	2
sh-chi-us-cpp-wk105.internet-census.org	2
o014.scanner.modat.io	2
sh-chi-us-cpp-wk107.internet-census.org	2
31-14-254-124.infrawat.ch	2
o009.scanner.modat.io	2
203.55.131.5	2
69-5-169-114.infrawat.ch	2
194-88-98-114.infrawat.ch	2
o011.scanner.modat.io	2
TOTAL: 22	3534

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

System	Conn
o011.scanner.modat.io	4
o014.scanner.modat.io	4
o062.scanner.modat.io	4
o046.scanner.modat.io	4
o009.scanner.modat.io	4
o060.scanner.modat.io	4
204.172.132.66.censys-scanner.com	2
TOTAL: 7	26

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

Command	Count
SYST	12
FEAT	12
EHLO www.censys.io	2
TOTAL: 3	26

NNRP client timeouts [Top 20]:

System	Conn	Peer
prod49client01.academyforinternetresearch.org	2	2
31-14-254-124.infrawat.ch	2	2
69-5-169-114.infrawat.ch	2	2
TOTAL: 3	6	12

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	lodman.info	842
TOTAL: 1		842

Daily Usenet report

Sep 4 04:15:01 -- Sep 5 04:15:01