Daily Usenet report: May 25 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 25 11:47:31 newsfeed nnrpd[1559110]: SERVER perl filter not defined
May 25 11:47:31 newsfeed nnrpd[1559110]: SERVER perl filter not defined
May 25 16:59:16 newsfeed nnrpd[1596344]: SERVER perl filter not defined
May 25 16:59:16 newsfeed nnrpd[1596344]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	213429	84.2%	23.5 MB	86.0%
innd	22404	8.8%	2.1 MB	7.8%
nnrpd	16422	6.5%	1.6 MB	5.7%
innfeed	1236	0.5%	140.8 KB	0.5%
overchan	2	0.0%	0.3 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	253495	100.0%	27.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	425546	99.8%
Negative hits	760	0.2%
Positive hits	86	0.0%
Cache misses	4	0.0%
TOTAL: 4	426396	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.124	0.0%	30	0.000	4.133	38.000
article cleanup	00:00:02.180	0.0%	426654	0.000	0.005	0.011
article logging	00:00:08.888	0.0%	426690	0.012	0.021	0.030
article parse	00:00:21.258	0.0%	1379060	0.009	0.015	0.022
article write	00:01:53.542	0.1%	426654	0.182	0.266	0.773
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:00:02.196	0.0%	2169528	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.002	0.0%	48	0.000	0.042	1.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:00:34.842	0.0%	427486	0.008	0.082	0.466
history sync	00:00:00.032	0.0%	656	0.000	0.049	1.000
history write	00:00:48.282	0.0%	426654	0.041	0.113	0.368
idle	47:48:02.356	99.5%	1651764	35.202	104.181	241.355
nntp read	00:00:22.404	0.0%	1313650	0.010	0.017	0.025
overview write	00:00:01.240	0.0%	426654	0.000	0.003	0.007
perl filter	00:07:32.070	0.3%	426654	0.755	1.060	1.395
python filter	00:00:00.144	0.0%	426658	0.000	0.000	0.002
site send	00:00:10.184	0.0%	865272	0.007	0.012	0.018
TOTAL: 48:01:01.036	47:59:59.744	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	11956	0.000	0.003	0.059
article prepare	00:00:00.000	0.0%	1952	0.000	0.000	0.000
article read	00:00:00.020	0.0%	488	0.000	0.041	1.000
backlog stats	00:00:00.012	0.0%	74248	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.302	0.0%	34238	0.000	0.009	0.045
data write	00:00:01.202	0.0%	22268	0.000	0.054	0.178
idle	47:58:39.376	100.0%	74248	972.974	2326.250	7969.165
status file	00:00:00.124	0.0%	320	0.000	0.388	1.500
TOTAL: 47:58:41.898	47:58:41.074	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:58:09.114	99.5%	42128	0.000	253.729	2183.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.656	0.0%	162498	0.000	0.029	0.500
readart	00:00:07.842	0.1%	33060	0.000	0.237	0.272
TOTAL: 02:58:57.702	02:58:21.612	99.7%	-	-	-	-

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	130	426866	426862	4	99%	70:36:44
2	news.icm.edu.pl	58	72	4	68	5%	10:27:20
3	localhost	6	4	4	0	100%	00:00:00
TOTAL: 3		194	426942	426870	72	99%	81:04:04

Incoming Volume (INN):

Server		AcceptVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	4.8 GB	4.8 GB	100%	11.7 KB
2	news.icm.edu.pl	881.3 KB	881.3 KB	100%	220.3 KB
3	localhost	5.8 KB	5.8 KB	100%	1.4 KB
TOTAL: 3		4.8 GB	4.8 GB	100%	11.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 25 04:15:01 - 04:59:59	5146	2.4%	1.91	56.0 MB	2.3%	21.26
May 25 05:00:00 - 05:59:59	7711	3.6%	2.14	83.3 MB	3.4%	23.68
May 25 06:00:00 - 06:59:59	10243	4.8%	2.85	120.8 MB	5.0%	34.37
May 25 07:00:00 - 07:59:59	10594	5.0%	2.94	130.5 MB	5.4%	37.13
May 25 08:00:00 - 08:59:59	11414	5.3%	3.17	129.0 MB	5.3%	36.70
May 25 09:00:00 - 09:59:59	9821	4.6%	2.73	126.8 MB	5.2%	36.06
May 25 10:00:00 - 10:59:59	9215	4.3%	2.56	91.3 MB	3.8%	25.96
May 25 11:00:00 - 11:59:59	8313	3.9%	2.31	78.4 MB	3.2%	22.30
May 25 12:00:00 - 12:59:59	9850	4.6%	2.74	118.2 MB	4.9%	33.62
May 25 13:00:00 - 13:59:59	7711	3.6%	2.14	98.2 MB	4.0%	27.93
May 25 14:00:00 - 14:59:59	6321	3.0%	1.76	78.9 MB	3.2%	22.44
May 25 15:00:00 - 15:59:59	14436	6.8%	4.01	210.0 MB	8.6%	59.73
May 25 16:00:00 - 16:59:59	9050	4.2%	2.51	116.1 MB	4.8%	33.02
May 25 17:00:00 - 17:59:59	8599	4.0%	2.39	95.9 MB	3.9%	27.27
May 25 18:00:00 - 18:59:59	7129	3.3%	1.98	89.3 MB	3.7%	25.40
May 25 19:00:00 - 19:59:59	8141	3.8%	2.26	109.0 MB	4.5%	31.00
May 25 20:00:00 - 20:59:59	7992	3.7%	2.22	98.4 MB	4.0%	28.00
May 25 21:00:00 - 21:59:59	6266	2.9%	1.74	59.9 MB	2.5%	17.05
May 25 22:00:00 - 22:59:59	8131	3.8%	2.26	74.4 MB	3.1%	21.16
May 25 23:00:00 - 23:59:59	9068	4.2%	2.52	96.2 MB	4.0%	27.36
May 26 00:00:00 - 00:59:59	9322	4.4%	2.59	81.6 MB	3.4%	23.21
May 26 01:00:00 - 01:59:59	10062	4.7%	2.79	98.3 MB	4.0%	27.96
May 26 02:00:00 - 02:59:59	9391	4.4%	2.61	104.1 MB	4.3%	29.61
May 26 03:00:00 - 03:59:59	7754	3.6%	2.15	72.5 MB	3.0%	20.61
May 26 04:00:00 - 04:15:01	1722	0.8%	1.91	16.2 MB	0.7%	18.43
TOTAL: 24:00:00	213402	100.0%	2.47	2.4 GB	100.0%	28.84

Sites sending bad articles:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	22188	368	11464	120	1%	47:59:56
2	poznan	4	0	4	0	0%	01:00:00
TOTAL: 2		22192	368	11468	120	1%	48:59:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	1.1 MB	1.4 MB	2.6 MB	5.4 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:00:00
TOTAL: 2		1.1 MB	1.4 MB	2.6 MB	5.4 KB	48:59:56

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	syn-066-061-096-239.res.spectrum.com	8	33052	35.6 MB	4	0	02:57:32
2	172.16.2.4	576	8	17.1 KB	4	0	00:00:34
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		588	33060	35.6 MB	8	4	02:58:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.res.spectrum.com	8	33052	35.6 MB	4	0	02:57:32
2	unresolved	780	8	17.1 KB	4	0	00:00:50
3	*.man.lodz.pl	2866	0	0.0 KB	0	4	00:00:30
4	*.monitoring.internet-measurement.com	2	0	0.0 KB	0	0	00:00:03
5	*.stretchoid.com	2	0	0.0 KB	0	0	00:00:00
TOTAL: 5		3658	33060	35.6 MB	8	4	02:58:58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
syn-066-061-096-239.res.spectrum.com	1.888	4.998	02:57:32
172.16.2.4	2.250	3.962	00:00:34
nms1.man.lodz.pl	9.690	18.160	00:00:30
80.111.142.213	0.026	0.048	00:00:06
198.235.24.197	0.002	0.018	00:00:04
grand.monitoring.internet-measurement.com	0.002	0.014	00:00:03
198.235.24.162	0.004	0.012	00:00:02
212.51.192.185	0.668	1.204	00:00:02
azpdwgvj2rig.stretchoid.com	0.006	0.014	00:00:00
isvcs01.man.lodz.pl	0.018	0.022	00:00:00
TOTAL: 10	14.554	28.452	02:58:58

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2862
212.51.192.185	192
80.111.142.213	8
grand.monitoring.internet-measurement.com	2
azpdwgvj2rig.stretchoid.com	2
198.235.24.162	2
198.235.24.197	2
TOTAL: 7	3070

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

System	Conn
azpdwgvj2rig.stretchoid.com	2
TOTAL: 1	2

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

Command	Count
MGLNDD_212.191.2.21_119	2
TOTAL: 1	2

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	lodman	33052	100.0%
2	pl	8	0.0%
TOTAL: 2		33060	100.0%

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

Newsgroup		Count
1	lodman.info	33052
2	pl.comp.os.ms-windows.winnt	8
TOTAL: 2		33060

Daily Usenet report

May 25 04:15:01 -- May 26 04:15:01