Daily Usenet report: Sep 11 04:15:01

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Sep 11 06:35:15 newsfeed nnrpd[1167408]: SERVER perl filter not defined
Sep 11 06:35:15 newsfeed nnrpd[1167408]: SERVER perl filter not defined
Sep 11 06:37:40 newsfeed nnrpd[1167678]: SERVER perl filter not defined
Sep 11 06:37:40 newsfeed nnrpd[1167678]: SERVER perl filter not defined
Sep 11 08:01:19 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:77 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 11 08:01:19 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:77 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 11 19:39:15 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 11 19:39:15 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 371200 duplicate size 0 rejected size 0]
Sep 11 20:24:27 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 11 20:24:27 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 11 22:37:39 newsfeed nnrpd[72279]: SERVER perl filter not defined
Sep 11 22:37:39 newsfeed nnrpd[72279]: SERVER perl filter not defined
Sep 11 23:40:10 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 11 23:40:10 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 409600 duplicate size 0 rejected size 0]
Sep 12 00:58:03 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]
Sep 12 00:58:03 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:28 checkpoint seconds 0 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 256000 duplicate size 0 rejected size 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	463463	92.3%	50.0 MB	92.8%
innd	21524	4.3%	2.2 MB	4.1%
nnrpd	15756	3.1%	1.5 MB	2.8%
innfeed	1340	0.3%	143.5 KB	0.3%
controlchan	4	0.0%	0.2 KB	0.0%
overchan	4	0.0%	0.5 KB	0.0%
TOTAL: 6	502091	100.0%	53.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	924604	99.7%
Negative hits	2460	0.3%
Positive hits	64	0.0%
Cache misses	2	0.0%
TOTAL: 4	927130	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.028	0.0%	70	0.000	0.400	3.000
article cleanup	00:00:02.142	0.0%	931890	0.000	0.002	0.005
article logging	00:00:10.256	0.0%	931958	0.005	0.011	0.017
article parse	00:00:13.564	0.0%	2835540	0.003	0.005	0.007
article write	00:00:26.128	0.0%	931890	0.017	0.028	0.043
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:00:01.642	0.0%	3777712	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.024	0.0%	88	0.000	0.273	2.000
hishave/artcncl	00:00:00.000	0.0%	18	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:26.204	0.0%	934398	0.001	0.092	0.608
history sync	00:00:00.030	0.0%	682	0.000	0.044	0.667
history write	00:02:37.528	0.1%	931890	0.026	0.169	0.654
idle	47:47:31.790	99.5%	3270274	29.252	52.611	85.121
nntp read	00:00:19.420	0.0%	2561312	0.005	0.008	0.013
overview write	00:00:01.124	0.0%	931890	0.000	0.001	0.003
perl filter	00:06:44.208	0.2%	931890	0.282	0.434	0.664
python filter	00:00:00.142	0.0%	931896	0.000	0.000	0.001
site send	00:00:10.294	0.0%	1875228	0.003	0.005	0.008
TOTAL: 48:01:07.302	47:59:44.524	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	20172	0.000	0.001	0.062
article prepare	00:00:00.002	0.0%	35920	0.000	0.000	0.000
article read	00:00:01.594	0.0%	8976	0.000	0.178	1.000
backlog stats	00:00:00.010	0.0%	57634	0.000	0.000	0.012
callbacks	00:00:00.000	0.0%	12	0.000	0.000	0.000
data read	00:00:00.248	0.0%	29284	0.000	0.008	0.074
data write	00:00:00.302	0.0%	17720	0.000	0.017	0.094
idle	47:42:14.680	100.0%	57634	58.406	2979.746	11298.222
prepart/cb	00:00:00.000	0.0%	32	0.000	0.000	0.000
readart/cb	00:00:00.000	0.0%	8	0.000	0.000	0.000
status file	00:00:00.270	0.0%	376	0.000	0.718	5.000
TOTAL: 47:42:17.688	47:42:17.134	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:13.262	68.8%	8792	0.000	8.333	5235.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.240	0.2%	29962	0.000	0.008	0.500
readart	00:00:00.000	0.0%	8	0.000	0.000	0.000
TOTAL: 00:01:46.498	00:01:13.502	69.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	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	116	922378	922378	0	100%	67:24:20
2	localhost	8	6	6	0	100%	00:00:00
3	news.icm.edu.pl	28	50	0	50	0%	06:39:06
TOTAL: 3		152	922434	922384	50	99%	74:03:26

Incoming Volume (INN):

Server		AcceptVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	7.1 GB	7.1 GB	100%	8.0 KB
2	localhost	8.3 KB	8.3 KB	100%	1.4 KB
3	news.icm.edu.pl	0.0 KB	0.0 KB	0%	0
TOTAL: 3		7.1 GB	7.1 GB	100%	8.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 04:15:01 - 04:59:59	12262	2.6%	4.54	101.1 MB	2.8%	38.36
Sep 11 05:00:00 - 05:59:59	20906	4.5%	5.81	182.4 MB	5.0%	51.88
Sep 11 06:00:00 - 06:59:59	18902	4.1%	5.25	177.1 MB	4.9%	50.38
Sep 11 07:00:00 - 07:59:59	17488	3.8%	4.86	164.8 MB	4.5%	46.88
Sep 11 08:00:00 - 08:59:59	22503	4.9%	6.25	171.5 MB	4.7%	48.77
Sep 11 09:00:00 - 09:59:59	23349	5.0%	6.49	181.7 MB	5.0%	51.68
Sep 11 10:00:00 - 10:59:59	22304	4.8%	6.20	174.5 MB	4.8%	49.62
Sep 11 11:00:00 - 11:59:59	18822	4.1%	5.23	170.8 MB	4.7%	48.59
Sep 11 12:00:00 - 12:59:59	20798	4.5%	5.78	168.3 MB	4.6%	47.87
Sep 11 13:00:00 - 13:59:59	21522	4.6%	5.98	154.3 MB	4.3%	43.89
Sep 11 14:00:00 - 14:59:59	22917	4.9%	6.37	164.1 MB	4.5%	46.69
Sep 11 15:00:00 - 15:59:59	18294	3.9%	5.08	146.7 MB	4.0%	41.71
Sep 11 16:00:00 - 16:59:59	25833	5.6%	7.18	175.3 MB	4.8%	49.88
Sep 11 17:00:00 - 17:59:59	15796	3.4%	4.39	132.5 MB	3.7%	37.68
Sep 11 18:00:00 - 18:59:59	15596	3.4%	4.33	128.7 MB	3.6%	36.60
Sep 11 19:00:00 - 19:59:59	21319	4.6%	5.92	158.0 MB	4.4%	44.95
Sep 11 20:00:00 - 20:59:59	14150	3.1%	3.93	116.6 MB	3.2%	33.16
Sep 11 21:00:00 - 21:59:59	17770	3.8%	4.94	133.0 MB	3.7%	37.82
Sep 11 22:00:00 - 22:59:59	14327	3.1%	3.98	116.9 MB	3.2%	33.25
Sep 11 23:00:00 - 23:59:59	21078	4.5%	5.86	134.1 MB	3.7%	38.15
Sep 12 00:00:00 - 00:59:59	19310	4.2%	5.36	134.3 MB	3.7%	38.21
Sep 12 01:00:00 - 01:59:59	16161	3.5%	4.49	157.9 MB	4.4%	44.91
Sep 12 02:00:00 - 02:59:59	15767	3.4%	4.38	124.5 MB	3.4%	35.41
Sep 12 03:00:00 - 03:59:59	21777	4.7%	6.05	129.3 MB	3.6%	36.79
Sep 12 04:00:00 - 04:15:02	4469	1.0%	4.95	24.7 MB	0.7%	28.00
TOTAL: 24:00:01	463420	100.0%	5.36	3.5 GB	100.0%	42.94

Sites sending bad articles:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	18220	8538	2460	436	12	8766	46%	47:47:40
2	poznan	0	0	0	0	0	0	0%	00:40:00
TOTAL: 2		18220	8538	2460	436	12	8766	46%	48:27:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	26.6 MB	1.9 MB	28.4 MB	0.2 KB/s	3.2 KB	47:47:40
2	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:40:00
TOTAL: 2		26.6 MB	1.9 MB	28.4 MB	0.2 KB/s	3.2 KB	48:27:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	172.16.2.4	578	8	9.8 KB	4	0	00:00:38
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 2		584	8	9.8 KB	4	6	00:01:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	588	8	9.8 KB	4	0	00:00:43
2	*.man.lodz.pl	2886	0	0.0 KB	0	6	00:00:29
3	*.146.94.167.censys-scanner.com	6	0	0.0 KB	0	0	00:00:05
4	*.internet-census.org	10	0	0.0 KB	0	0	00:00:21
5	*.154.45.199.censys-scanner.com	6	0	0.0 KB	0	0	00:00:06
TOTAL: 5		3496	8	9.8 KB	4	6	00:01:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.460	6.858	00:00:38
nms2.man.lodz.pl	8.030	32.320	00:00:28
zl-dala-us-gp1-wk119d.internet-census.org	0.006	0.008	00:00:21
60.154.45.199.censys-scanner.com	0.058	0.130	00:00:06
59.146.94.167.censys-scanner.com	0.064	0.026	00:00:05
80.111.142.213	0.034	0.064	00:00:05
isvcs01.man.lodz.pl	0.022	0.098	00:00:01
205.210.31.172	0.008	0.008	00:00:00
zl-laxd-us-cpp-wk110.internet-census.org	0.006	0.010	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.002	0.010	00:00:00
sh-chi-us-cpp-wk107.internet-census.org	0.006	0.018	00:00:00
sh-ams-nl-cpp-wk103.internet-census.org	0.004	0.058	00:00:00
TOTAL: 12	9.700	39.608	00:01:47

Curious NNRP server explorers [Top 20]:

System	Conn
nms2.man.lodz.pl	2880
80.111.142.213	8
60.154.45.199.censys-scanner.com	6
59.146.94.167.censys-scanner.com	6
zl-laxd-us-cpp-wk110.internet-census.org	2
sh-chi-us-cpp-wk107.internet-census.org	2
sh-ams-nl-cpp-wk103.internet-census.org	2
zl-dala-us-gp1-wk119d.internet-census.org	2
sh-ams-nl-cpp-wk104.internet-census.org	2
205.210.31.172	2
TOTAL: 10	2912

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

System	Conn
59.146.94.167.censys-scanner.com	2
60.154.45.199.censys-scanner.com	2
TOTAL: 2	4

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
59.146.94.167.censys-scanner.com	2	4
60.154.45.199.censys-scanner.com	2	4
TOTAL: 2	4	10

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	8
TOTAL: 1		8

Daily Usenet report

Sep 11 04:15:01 -- Sep 12 04:15:02