Daily Usenet report: Oct 13 04:15:01

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Oct 13 07:09:03 newsfeed nnrpd[1868992]: SERVER perl filter not defined
Oct 13 07:09:03 newsfeed nnrpd[1868992]: SERVER perl filter not defined
Oct 13 08:23:17 newsfeed nnrpd[1878012]: SERVER perl filter not defined
Oct 13 08:23:17 newsfeed nnrpd[1878012]: SERVER perl filter not defined
Oct 13 08:24:21 newsfeed nnrpd[1878240]: SERVER perl filter not defined
Oct 13 08:24:21 newsfeed nnrpd[1878240]: SERVER perl filter not defined
Oct 13 08:25:08 newsfeed nnrpd[1878345]: SERVER perl filter not defined
Oct 13 08:25:08 newsfeed nnrpd[1878345]: SERVER perl filter not defined
Oct 13 08:25:57 newsfeed nnrpd[1878352]: SERVER perl filter not defined
Oct 13 08:25:57 newsfeed nnrpd[1878352]: SERVER perl filter not defined
Oct 13 08:27:00 newsfeed nnrpd[1878364]: SERVER perl filter not defined
Oct 13 08:27:00 newsfeed nnrpd[1878364]: SERVER perl filter not defined
Oct 13 15:23:27 newsfeed nnrpd[29316]: SERVER perl filter not defined
Oct 13 15:23:27 newsfeed nnrpd[29316]: SERVER perl filter not defined
Oct 13 20:34:49 newsfeed nnrpd[66436]: SERVER perl filter not defined
Oct 13 20:34:49 newsfeed nnrpd[66436]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	203855	85.1%	22.8 MB	86.9%
innd	18574	7.8%	1.8 MB	6.9%
nnrpd	15820	6.6%	1.5 MB	5.6%
innfeed	1276	0.5%	160.7 KB	0.6%
controlchan	4	0.0%	0.3 KB	0.0%
overchan	2	0.0%	0.5 KB	0.0%
TOTAL: 6	239531	100.0%	26.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	405506	99.8%
Negative hits	694	0.2%
Positive hits	128	0.0%
Cache misses	20	0.0%
TOTAL: 4	406348	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.082	0.0%	116	0.000	0.707	6.000
article cleanup	00:00:00.942	0.0%	406248	0.000	0.002	0.005
article logging	00:00:04.750	0.0%	406268	0.004	0.012	0.021
article parse	00:00:06.750	0.0%	1366348	0.002	0.005	0.009
article write	00:00:12.792	0.0%	406244	0.017	0.031	0.063
artlog/artcncl	00:00:00.000	0.0%	38	0.000	0.000	0.000
data move	00:00:00.812	0.0%	1883390	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.040	0.0%	154	0.000	0.260	4.000
hishave/artcncl	00:00:00.000	0.0%	38	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:14.514	0.0%	407050	0.001	0.036	0.596
history sync	00:00:00.022	0.0%	690	0.000	0.032	0.500
history write	00:00:43.252	0.0%	406244	0.033	0.106	0.737
idle	47:35:57.028	99.8%	1639610	57.242	104.511	202.436
nntp read	00:00:09.740	0.0%	1294438	0.004	0.008	0.013
overview write	00:00:00.522	0.0%	406244	0.000	0.001	0.004
perl filter	00:02:59.866	0.1%	406244	0.335	0.443	0.581
python filter	00:00:00.092	0.0%	406260	0.000	0.000	0.002
site send	00:00:04.680	0.0%	823752	0.003	0.006	0.011
TOTAL: 47:41:50.104	47:40:35.884	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	11372	0.000	0.001	0.030
article prepare	00:00:00.000	0.0%	1048	0.000	0.000	0.000
article read	00:00:00.016	0.0%	262	0.000	0.061	1.000
backlog stats	00:00:00.010	0.0%	71606	0.000	0.000	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.224	0.0%	32846	0.000	0.007	0.045
data write	00:00:00.548	0.0%	21330	0.000	0.026	0.167
idle	47:40:39.968	100.0%	71606	1211.296	2397.005	9232.277
status file	00:00:00.184	0.0%	346	0.000	0.532	4.000
TOTAL: 47:40:41.706	47:40:40.962	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:50.392	76.5%	8860	0.000	12.460	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.236	0.2%	29844	0.000	0.008	0.333
TOTAL: 00:02:24.372	00:01:50.628	76.6%	-	-	-	-

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	120	407614	407612	0	2	99%	65:26:06
2	localhost	18	16	16	0	0	100%	00:00:00
3	news.icm.edu.pl	64	116	6	108	2	5%	13:22:42
TOTAL: 3		202	407746	407634	108	4	99%	78:48:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	3.6 GB	28.0 KB	3.6 GB	99%	9.3 KB
2	news.icm.edu.pl	65.0 KB	17.0 KB	82.0 KB	79%	10.2 KB
3	localhost	23.0 KB	0.0 KB	23.0 KB	100%	1.4 KB
TOTAL: 3		3.6 GB	45.0 KB	3.6 GB	99%	9.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:01 - 04:59:59	5189	2.5%	1.92	38.7 MB	2.1%	14.67
Oct 13 05:00:00 - 05:59:59	6264	3.1%	1.74	46.9 MB	2.5%	13.33
Oct 13 06:00:00 - 06:59:59	6923	3.4%	1.92	62.0 MB	3.4%	17.65
Oct 13 07:00:00 - 07:59:59	7240	3.6%	2.01	63.5 MB	3.4%	18.05
Oct 13 08:00:00 - 08:59:59	7668	3.8%	2.13	72.1 MB	3.9%	20.52
Oct 13 09:00:00 - 09:59:59	8170	4.0%	2.27	74.2 MB	4.0%	21.12
Oct 13 10:00:00 - 10:59:59	8002	3.9%	2.22	72.7 MB	3.9%	20.68
Oct 13 11:00:00 - 11:59:59	7414	3.6%	2.06	64.6 MB	3.5%	18.36
Oct 13 12:00:00 - 12:59:59	10629	5.2%	2.95	80.4 MB	4.4%	22.86
Oct 13 13:00:00 - 13:59:59	7140	3.5%	1.98	54.2 MB	2.9%	15.43
Oct 13 14:00:00 - 14:59:59	9700	4.8%	2.69	82.6 MB	4.5%	23.48
Oct 13 15:00:00 - 15:59:59	10634	5.2%	2.95	95.7 MB	5.2%	27.22
Oct 13 16:00:00 - 16:59:59	9979	4.9%	2.77	80.7 MB	4.4%	22.95
Oct 13 17:00:00 - 17:59:59	8565	4.2%	2.38	89.2 MB	4.8%	25.36
Oct 13 18:00:00 - 18:59:59	7758	3.8%	2.15	80.8 MB	4.4%	22.99
Oct 13 19:00:00 - 19:59:59	8631	4.2%	2.40	91.7 MB	5.0%	26.08
Oct 13 20:00:00 - 20:59:59	9907	4.9%	2.75	96.5 MB	5.2%	27.46
Oct 13 21:00:00 - 21:59:59	9416	4.6%	2.62	88.4 MB	4.8%	25.14
Oct 13 22:00:00 - 22:59:59	8935	4.4%	2.48	86.6 MB	4.7%	24.63
Oct 13 23:00:00 - 23:59:59	11736	5.8%	3.26	108.2 MB	5.9%	30.78
Oct 14 00:00:00 - 00:59:59	8653	4.2%	2.40	86.1 MB	4.7%	24.48
Oct 14 01:00:00 - 01:59:59	8754	4.3%	2.43	71.8 MB	3.9%	20.41
Oct 14 02:00:00 - 02:59:59	8896	4.4%	2.47	77.1 MB	4.2%	21.93
Oct 14 03:00:00 - 03:59:59	5723	2.8%	1.59	63.3 MB	3.4%	18.01
Oct 14 04:00:00 - 04:15:01	1898	0.9%	2.11	17.5 MB	0.9%	19.90
TOTAL: 24:00:00	203824	100.0%	2.36	1.8 GB	100.0%	21.87

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	11	1	10
2	news.icm.edu.pl	1	1	0
TOTAL: 2		12	2	10

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	21458	212	11068	48	0%	47:50:38
2	poznan	6	0	6	0	0%	01:40:00
TOTAL: 2		21464	212	11074	48	0%	49:30:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	793.6 KB	488.8 KB	1.3 MB	4.9 KB	47:50:38
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:40:00
TOTAL: 2		793.6 KB	488.8 KB	1.3 MB	4.9 KB	49:30:38

NNRP readership statistics:

System		Conn	Post	Elapsed
1	nms1.man.lodz.pl	2880	0	00:00:29
2	172.16.2.4	578	0	00:00:36
3	zl-dfwc-us-gp1-wk106b.internet-census.org	2	0	00:00:21
4	isvcs01.man.lodz.pl	16	16	00:00:00
5	20.46.235.234	2	0	00:00:19
6	176.186.132.66.censys-scanner.com	2	0	00:00:06
7	sh-ams-nl-cpp-wk103.internet-census.org	2	0	00:00:00
8	112.94.252.214	2	0	00:00:01
9	r4-226-e2.monitoring.internet-measurement.com	2	0	00:00:03
10	198.235.24.155	2	0	00:00:00
11	sh-ams-nl-cpp-wk104.internet-census.org	2	0	00:00:00
12	sh-chi-us-cpp-wk107.internet-census.org	2	0	00:00:00
13	zl-laxd-us-cpp-wk111.internet-census.org	4	0	00:00:00
14	sh-chi-us-cpp-wk106.internet-census.org	2	0	00:00:00
15	o003.scanner.modat.io	2	0	00:00:00
16	78.128.113.46	2	0	00:00:20
17	80.111.142.213	8	0	00:00:04
TOTAL: 17		3510	16	00:02:25

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.monitoring.internet-measurement.com	2	0	00:00:03
2	*.internet-census.org	14	0	00:00:21
3	*.186.132.66.censys-scanner.com	2	0	00:00:06
4	*.scanner.modat.io	2	0	00:00:00
5	*.man.lodz.pl	2896	16	00:00:30
6	unresolved	594	0	00:01:22
TOTAL: 6		3510	16	00:02:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.574	2.940	00:00:36
nms1.man.lodz.pl	7.892	14.170	00:00:29
zl-dfwc-us-gp1-wk106b.internet-census.org	0.008	0.008	00:00:21
78.128.113.46	0.004	0.012	00:00:20
20.46.235.234	0.002	0.012	00:00:19
176.186.132.66.censys-scanner.com	0.054	0.008	00:00:06
80.111.142.213	0.022	0.042	00:00:04
r4-226-e2.monitoring.internet-measurement.com	0.006	0.006	00:00:03
112.94.252.214	0.006	0.008	00:00:01
isvcs01.man.lodz.pl	0.068	0.086	00:00:00
o003.scanner.modat.io	0.002	0.010	00:00:00
zl-laxd-us-cpp-wk111.internet-census.org	0.008	0.022	00:00:00
198.235.24.155	0.010	0.006	00:00:00
sh-ams-nl-cpp-wk103.internet-census.org	0.004	0.010	00:00:00
sh-chi-us-cpp-wk106.internet-census.org	0.006	0.010	00:00:00
sh-chi-us-cpp-wk107.internet-census.org	0.000	0.016	00:00:00
sh-ams-nl-cpp-wk104.internet-census.org	0.004	0.014	00:00:00
TOTAL: 17	9.670	17.380	00:02:25

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

System	Conn
176.186.132.66.censys-scanner.com	2
TOTAL: 1	2

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

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

Daily Usenet report

Oct 13 04:15:01 -- Oct 14 04:15:01