Daily Usenet report: Oct 7 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Oct 7 12:02:27 newsfeed nnrpd[858019]: SERVER perl filter not defined
Oct 7 12:02:27 newsfeed nnrpd[858019]: SERVER perl filter not defined
Oct 7 12:59:51 newsfeed nnrpd[864683]: SERVER perl filter not defined
Oct 7 12:59:51 newsfeed nnrpd[864683]: SERVER perl filter not defined
Oct 7 19:34:13 newsfeed nnrpd[912422]: SERVER perl filter not defined
Oct 7 19:34:13 newsfeed nnrpd[912422]: SERVER perl filter not defined
Oct 7 19:34:29 newsfeed nnrpd[912425]: SERVER perl filter not defined
Oct 7 19:34:29 newsfeed nnrpd[912425]: SERVER perl filter not defined
Oct 7 19:35:27 newsfeed nnrpd[912497]: SERVER perl filter not defined
Oct 7 19:35:27 newsfeed nnrpd[912497]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	261475	86.0%	29.2 MB	87.9%
innd	25502	8.4%	2.4 MB	7.2%
nnrpd	15736	5.2%	1.4 MB	4.4%
innfeed	1358	0.4%	177.4 KB	0.5%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	304075	100.0%	33.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	519686	99.8%
Negative hits	678	0.1%
Cache misses	184	0.0%
Positive hits	150	0.0%
TOTAL: 4	520698	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.016	0.0%	84	0.000	0.190	3.000
article cleanup	00:00:01.650	0.0%	520394	0.000	0.003	0.008
article logging	00:00:07.074	0.0%	520462	0.005	0.014	0.025
article parse	00:00:16.526	0.0%	2988366	0.004	0.006	0.009
article write	00:00:21.172	0.0%	520196	0.018	0.041	0.401
artlog/artcncl	00:00:00.000	0.0%	34	0.000	0.000	0.000
data move	00:00:01.824	0.0%	4305106	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.002	0.0%	118	0.000	0.017	1.000
hishave/artcncl	00:00:00.000	0.0%	34	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:09.994	0.0%	521178	0.000	0.019	0.382
history sync	00:00:00.014	0.0%	594	0.000	0.024	1.000
history write	00:00:38.434	0.0%	520196	0.017	0.074	0.436
idle	47:51:13.000	99.7%	3388370	4.961	50.842	358.238
nntp read	00:00:24.100	0.0%	2921950	0.003	0.008	0.013
overview write	00:00:00.736	0.0%	520196	0.000	0.001	0.004
perl filter	00:06:16.210	0.2%	520196	0.379	0.723	0.992
python filter	00:00:00.082	0.0%	520206	0.000	0.000	0.001
site send	00:00:07.278	0.0%	1059194	0.003	0.007	0.012
TOTAL: 48:00:35.506	47:59:38.112	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	18724	0.000	0.001	0.029
article prepare	00:00:00.000	0.0%	1560	0.000	0.000	0.000
article read	00:00:00.006	0.0%	388	0.000	0.015	1.000
backlog stats	00:00:00.018	0.0%	104886	0.000	0.000	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.406	0.0%	52300	0.000	0.008	0.036
data write	00:00:01.122	0.0%	32652	0.000	0.034	0.119
idle	47:39:52.982	100.0%	104886	911.278	1635.995	3444.885
status file	00:00:00.200	0.0%	362	0.000	0.552	4.000
TOTAL: 47:39:55.768	47:39:54.756	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:36.372	78.7%	8784	1.000	10.971	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.340	0.3%	29624	0.000	0.011	0.500
readart	00:00:00.000	0.0%	6	0.000	0.000	0.000
TOTAL: 00:02:02.418	00:01:36.712	79.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	56	522818	522606	4	208	99%	56:12:42
2	news.icm.edu.pl	66	126	18	102	6	14%	15:14:38
3	localhost	12	10	10	0	0	100%	00:00:00
TOTAL: 3		134	522954	522634	106	214	99%	71:27:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	9.9 GB	3.2 MB	9.9 GB	99%	19.9 KB
2	news.icm.edu.pl	797.4 KB	122.3 KB	919.7 KB	86%	38.3 KB
3	localhost	19.2 KB	0.0 KB	19.2 KB	100%	1.9 KB
TOTAL: 3		9.9 GB	3.4 MB	9.9 GB	99%	19.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 04:15:02 - 04:59:59	3316	1.3%	1.23	47.5 MB	0.9%	18.01
Oct 7 05:00:00 - 05:59:59	5778	2.2%	1.60	65.4 MB	1.3%	18.62
Oct 7 06:00:00 - 06:59:59	6353	2.4%	1.76	72.7 MB	1.4%	20.69
Oct 7 07:00:00 - 07:59:59	6859	2.6%	1.91	93.0 MB	1.8%	26.46
Oct 7 08:00:00 - 08:59:59	7031	2.7%	1.95	89.5 MB	1.8%	25.45
Oct 7 09:00:00 - 09:59:59	13145	5.0%	3.65	95.1 MB	1.9%	27.04
Oct 7 10:00:00 - 10:59:59	6780	2.6%	1.88	83.5 MB	1.6%	23.76
Oct 7 11:00:00 - 11:59:59	8149	3.1%	2.26	72.0 MB	1.4%	20.48
Oct 7 12:00:00 - 12:59:59	5608	2.1%	1.56	64.6 MB	1.3%	18.38
Oct 7 13:00:00 - 13:59:59	7550	2.9%	2.10	79.3 MB	1.6%	22.57
Oct 7 14:00:00 - 14:59:59	6543	2.5%	1.82	82.2 MB	1.6%	23.38
Oct 7 15:00:00 - 15:59:59	6892	2.6%	1.91	68.5 MB	1.3%	19.49
Oct 7 16:00:00 - 16:59:59	5693	2.2%	1.58	70.4 MB	1.4%	20.03
Oct 7 17:00:00 - 17:59:59	4969	1.9%	1.38	56.6 MB	1.1%	16.09
Oct 7 18:00:00 - 18:59:59	6440	2.5%	1.79	63.8 MB	1.3%	18.14
Oct 7 19:00:00 - 19:59:59	5221	2.0%	1.45	63.4 MB	1.2%	18.04
Oct 7 20:00:00 - 20:59:59	7257	2.8%	2.02	115.8 MB	2.3%	32.95
Oct 7 21:00:00 - 21:59:59	13287	5.1%	3.69	274.9 MB	5.4%	78.20
Oct 7 22:00:00 - 22:59:59	28064	10.7%	7.80	758.6 MB	14.9%	215.77
Oct 7 23:00:00 - 23:59:59	16665	6.4%	4.63	388.0 MB	7.6%	110.37
Oct 8 00:00:00 - 00:59:59	23398	9.0%	6.50	566.6 MB	11.1%	161.18
Oct 8 01:00:00 - 01:59:59	10636	4.1%	2.95	232.1 MB	4.6%	66.01
Oct 8 02:00:00 - 02:59:59	18290	7.0%	5.08	462.4 MB	9.1%	131.53
Oct 8 03:00:00 - 03:59:59	33627	12.9%	9.34	1019.2 MB	20.0%	289.91
Oct 8 04:00:00 - 04:15:01	3766	1.4%	4.18	99.1 MB	1.9%	112.63
TOTAL: 23:59:59	261317	100.0%	3.02	5.0 GB	100.0%	60.26

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	138	104	34
2	news.icm.edu.pl	3	3	0
TOTAL: 2		141	107	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	Spool	%Took	Elapsed
1	icm	34786	380	18392	8	20	1%	47:59:54
2	poznan	32	2	18	0	0	6%	03:40:50
TOTAL: 2		34818	382	18410	8	20	1%	51:40:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	670.7 KB	125.5 KB	796.2 KB	2.1 KB	47:59:54
2	poznan	7.1 KB	0.0 KB	7.1 KB	3.6 KB	03:40:50
TOTAL: 2		677.8 KB	125.5 KB	803.4 KB	2.1 KB	51:40:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	172.16.2.4	576	6	9.6 KB	4	2	00:00:33
2	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 2		584	6	9.6 KB	4	10	00:02:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	594	6	9.6 KB	4	2	00:00:55
2	*.man.lodz.pl	2888	0	0.0 KB	0	8	00:00:22
3	*.stretchoid.com	8	0	0.0 KB	0	0	00:00:41
4	*.scanner.modat.io	2	0	0.0 KB	0	0	00:00:00
5	*.monitoring.internet-measurement.com	2	0	0.0 KB	0	0	00:00:03
TOTAL: 5		3494	6	9.6 KB	4	10	00:02:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.314	2.986	00:00:33
nms1.man.lodz.pl	6.020	14.210	00:00:22
azpdcg603kkf.stretchoid.com	0.002	0.026	00:00:20
azpdcg2o59ep.stretchoid.com	0.008	0.022	00:00:20
66.132.153.112	0.064	0.012	00:00:07
80.111.142.213	0.016	0.048	00:00:05
205.210.31.224	0.008	0.006	00:00:05
fierce.monitoring.internet-measurement.com	0.004	0.010	00:00:03
205.210.31.2	0.002	0.012	00:00:02
106.75.75.129	0.006	0.008	00:00:01
isvcs01.man.lodz.pl	0.014	0.058	00:00:00
198.235.24.213	0.010	0.004	00:00:00
o34.scanner.modat.io	0.004	0.010	00:00:00
TOTAL: 13	7.472	17.412	00:02:03

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2880
80.111.142.213	8
azpdcg603kkf.stretchoid.com	4
azpdcg2o59ep.stretchoid.com	4
198.235.24.213	2
66.132.153.112	2
o34.scanner.modat.io	2
205.210.31.2	2
fierce.monitoring.internet-measurement.com	2
106.75.75.129	2
205.210.31.224	2
TOTAL: 11	2910

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

System	Conn
azpdcg2o59ep.stretchoid.com	2
66.132.153.112	2
azpdcg603kkf.stretchoid.com	2
TOTAL: 3	6

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

Command	Count
MGLNDD_212.191.2.21_119	4
EHLO www.censys.io	2
TOTAL: 2	6

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	pl.internet.polip	6
TOTAL: 1		6

Daily Usenet report

Oct 7 04:15:02 -- Oct 8 04:15:01