Daily Usenet report: Dec 4 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Dec 4 13:24:09 newsfeed nnrpd[1486292]: SERVER perl filter not defined
Dec 4 13:24:09 newsfeed nnrpd[1486292]: SERVER perl filter not defined
Dec 4 13:24:54 newsfeed nnrpd[1486570]: SERVER perl filter not defined
Dec 4 13:24:54 newsfeed nnrpd[1486570]: SERVER perl filter not defined
Dec 4 14:35:51 newsfeed nnrpd[1494871]: SERVER perl filter not defined
Dec 4 14:35:51 newsfeed nnrpd[1494871]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	147206	77.9%	16.5 MB	80.9%
innd	22764	12.0%	2.1 MB	10.4%
nnrpd	17784	9.4%	1.6 MB	8.0%
innfeed	1256	0.7%	140.3 KB	0.7%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	189014	100.0%	20.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	293000	99.7%
Negative hits	742	0.3%
Positive hits	102	0.0%
Cache misses	6	0.0%
TOTAL: 4	293850	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.014	0.0%	62	0.000	0.226	1.000
article cleanup	00:00:00.826	0.0%	293414	0.000	0.003	0.014
article logging	00:00:03.784	0.0%	293470	0.004	0.013	0.029
article parse	00:00:05.514	0.0%	1016994	0.003	0.005	0.010
article write	00:01:52.118	0.1%	293404	0.023	0.382	67.678
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
data move	00:00:00.956	0.0%	1473974	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.004	0.0%	76	0.000	0.053	1.000
hishave/artcncl	00:00:00.000	0.0%	14	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:01.258	0.0%	294242	0.000	0.004	0.013
history sync	00:00:00.018	0.0%	790	0.000	0.023	0.500
history write	00:01:07.342	0.0%	293406	0.033	0.230	31.690
idle	47:52:40.256	99.7%	1246828	45.216	138.239	339.972
nntp read	00:00:08.874	0.0%	978908	0.005	0.009	0.017
overview write	00:00:00.488	0.0%	293404	0.000	0.002	0.006
perl filter	00:02:34.308	0.1%	293406	0.387	0.526	0.706
python filter	00:00:00.066	0.0%	293412	0.000	0.000	0.003
site send	00:00:04.236	0.0%	604468	0.002	0.007	0.013
TOTAL: 48:02:04.996	47:58:40.062	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.026	0.0%	17650	0.000	0.001	0.024
article prepare	00:00:00.000	0.0%	1552	0.000	0.000	0.000
article read	00:00:00.010	0.0%	388	0.000	0.026	1.000
backlog stats	00:00:00.026	0.0%	99968	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.288	0.0%	49456	0.000	0.006	0.043
data write	00:00:00.838	0.0%	30974	0.000	0.027	0.086
idle	47:58:24.382	100.0%	99968	1086.121	1727.597	3975.795
status file	00:00:00.176	0.0%	340	0.000	0.518	12.000
TOTAL: 47:58:26.576	47:58:25.746	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:06:26.136	92.3%	9908	0.000	38.972	10044.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.272	0.1%	34494	0.000	0.008	0.333
readart	00:00:00.000	0.0%	198	0.000	0.000	0.000
TOTAL: 00:06:58.574	00:06:26.408	92.3%	-	-	-	-

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	118	294724	294720	0	4	99%	68:28:52
2	localhost	8	6	6	0	0	100%	00:00:00
3	news.icm.edu.pl	60	98	4	88	6	4%	11:55:10
TOTAL: 3		186	294828	294730	88	10	99%	80:24:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	2.9 GB	53.8 KB	0.0 KB	2.9 GB	99%	10.5 KB
2	news.icm.edu.pl	53.7 KB	42.9 KB	19.0 KB	115.6 KB	46%	11.6 KB
3	localhost	8.7 KB	0.0 KB	0.0 KB	8.7 KB	100%	1.5 KB
TOTAL: 3		2.9 GB	96.7 KB	19.0 KB	2.9 GB	99%	10.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 4 04:15:01 - 04:59:59	2584	1.8%	0.96	27.4 MB	1.8%	10.38
Dec 4 05:00:00 - 05:59:59	2989	2.0%	0.83	31.9 MB	2.1%	9.06
Dec 4 06:00:00 - 06:59:59	4255	2.9%	1.18	45.7 MB	3.0%	13.00
Dec 4 07:00:00 - 07:59:59	4105	2.8%	1.14	38.0 MB	2.5%	10.81
Dec 4 08:00:00 - 08:59:59	5558	3.8%	1.54	52.0 MB	3.5%	14.80
Dec 4 09:00:00 - 09:59:59	5097	3.5%	1.42	59.8 MB	4.0%	17.00
Dec 4 10:00:00 - 10:59:59	8058	5.5%	2.24	77.3 MB	5.1%	21.97
Dec 4 11:00:00 - 11:59:59	5858	4.0%	1.63	77.2 MB	5.1%	21.96
Dec 4 12:00:00 - 12:59:59	11882	8.1%	3.30	120.8 MB	8.0%	34.36
Dec 4 13:00:00 - 13:59:59	8082	5.5%	2.25	74.5 MB	4.9%	21.19
Dec 4 14:00:00 - 14:59:59	7787	5.3%	2.16	89.6 MB	5.9%	25.48
Dec 4 15:00:00 - 15:59:59	5477	3.7%	1.52	65.4 MB	4.3%	18.61
Dec 4 16:00:00 - 16:59:59	7930	5.4%	2.20	93.2 MB	6.2%	26.52
Dec 4 17:00:00 - 17:59:59	6468	4.4%	1.80	82.4 MB	5.5%	23.43
Dec 4 18:00:00 - 18:59:59	6647	4.5%	1.85	61.2 MB	4.1%	17.40
Dec 4 19:00:00 - 19:59:59	6202	4.2%	1.72	56.9 MB	3.8%	16.20
Dec 4 20:00:00 - 20:59:59	5192	3.5%	1.44	49.0 MB	3.2%	13.93
Dec 4 21:00:00 - 21:59:59	4197	2.9%	1.17	43.5 MB	2.9%	12.37
Dec 4 22:00:00 - 22:59:59	4544	3.1%	1.26	44.4 MB	2.9%	12.62
Dec 4 23:00:00 - 23:59:59	9465	6.4%	2.63	80.6 MB	5.3%	22.93
Dec 5 00:00:00 - 00:59:59	7798	5.3%	2.17	70.1 MB	4.6%	19.93
Dec 5 01:00:00 - 01:59:59	6141	4.2%	1.71	59.1 MB	3.9%	16.82
Dec 5 02:00:00 - 02:59:59	3856	2.6%	1.07	41.1 MB	2.7%	11.69
Dec 5 03:00:00 - 03:59:59	5335	3.6%	1.48	50.7 MB	3.4%	14.42
Dec 5 04:00:00 - 04:15:01	1659	1.1%	1.84	15.8 MB	1.0%	17.96
TOTAL: 24:00:00	147166	100.0%	1.70	1.5 GB	100.0%	17.87

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	30	2	28
2	news.icm.edu.pl	3	2	1
TOTAL: 2		33	4	29

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2
TOTAL: 1	2

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	icm	32844	384	17258	4	1%	47:58:02
2	poznan	4	0	4	0	0%	01:00:00
TOTAL: 2		32848	384	17262	4	1%	48:58:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	593.1 KB	14.1 KB	607.2 KB	1.6 KB	47:58:02
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:00:00
TOTAL: 2		593.1 KB	14.1 KB	607.2 KB	1.6 KB	48:58:02

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	66.61.98.95	372	182	132.6 KB	172	0	00:05:01
2	172.16.2.4	574	16	33.0 KB	8	0	00:00:33
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 3		952	198	165.6 KB	180	6	00:06:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	968	198	165.6 KB	180	0	00:06:12
2	*.stretchoid.com	4	0	0.0 KB	0	0	00:00:20
3	*.man.lodz.pl	2886	0	0.0 KB	0	6	00:00:26
TOTAL: 3		3858	198	165.6 KB	180	6	00:06:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
66.61.98.95	0.800	1.736	00:05:01
172.16.2.4	1.254	2.726	00:00:33
nms1.man.lodz.pl	5.816	12.886	00:00:25
azpdwsu6yepv.stretchoid.com	0.008	0.016	00:00:20
167.94.138.162	0.072	0.034	00:00:16
207.90.244.14	0.004	0.010	00:00:07
80.111.142.213	0.020	0.036	00:00:05
205.210.31.55	0.006	0.006	00:00:03
147.185.132.156	0.002	0.012	00:00:02
167.94.138.207	0.066	0.010	00:00:00
isvcs01.man.lodz.pl	0.022	0.036	00:00:00
TOTAL: 11	8.070	17.508	00:06:59

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2880
80.111.142.213	8
167.94.138.162	6
azpdwsu6yepv.stretchoid.com	4
167.94.138.207	2
207.90.244.14	2
205.210.31.55	2
147.185.132.156	2
TOTAL: 8	2906

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

System	Conn
azpdwsu6yepv.stretchoid.com	2
167.94.138.207	2
167.94.138.162	2
TOTAL: 3	6

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.162	2	4
TOTAL: 1	2	8

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	lodman	182	91.9%
2	pl	16	8.1%
TOTAL: 2		198	100.0%

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

Newsgroup		Count
1	lodman.info	182
2	pl.comp.os.ms-windows.winnt	16
TOTAL: 2		198

Daily Usenet report

Dec 4 04:15:01 -- Dec 5 04:15:01