Daily Usenet report: Oct 14 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 14 18:01:42 newsfeed nnrpd[2119519]: SERVER perl filter not defined
Oct 14 18:01:42 newsfeed nnrpd[2119519]: SERVER perl filter not defined
Oct 14 23:01:00 newsfeed pgpverify[2155233]: pgpverify: /usr/bin/gpg1: no such file
Oct 14 23:01:00 newsfeed pgpverify[2155233]: pgpverify: /usr/bin/gpg1: no such file
Oct 15 00:35:35 newsfeed nnrpd[2166610]: SERVER perl filter not defined
Oct 15 00:35:35 newsfeed nnrpd[2166610]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	180071	81.0%	19.9 MB	83.3%
innd	25110	11.3%	2.3 MB	9.8%
nnrpd	15748	7.1%	1.5 MB	6.2%
innfeed	1282	0.6%	170.0 KB	0.7%
controlchan	24	0.0%	5.5 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	222239	100.0%	23.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	359722	99.7%
Negative hits	942	0.3%
Cache misses	126	0.0%
Positive hits	114	0.0%
TOTAL: 4	360904	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.010	0.0%	74	0.000	0.135	2.000
article cleanup	00:00:01.008	0.0%	360224	0.000	0.003	0.009
article logging	00:00:04.548	0.0%	360294	0.005	0.013	0.023
article parse	00:00:08.016	0.0%	1398730	0.004	0.006	0.011
article write	00:00:11.888	0.0%	360098	0.016	0.033	0.055
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:00:01.322	0.0%	2117254	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.000	0.0%	92	0.000	0.000	0.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:01.342	0.0%	361266	0.000	0.004	0.010
history sync	00:00:00.036	0.0%	606	0.000	0.059	1.000
history write	00:00:14.970	0.0%	360098	0.021	0.042	0.091
idle	47:55:23.354	99.8%	1680660	52.788	102.652	215.792
nntp read	00:00:11.752	0.0%	1355848	0.006	0.009	0.014
overview write	00:00:00.460	0.0%	360098	0.000	0.001	0.005
perl filter	00:03:35.296	0.1%	360098	0.383	0.598	0.866
python filter	00:00:00.052	0.0%	360102	0.000	0.000	0.002
site send	00:00:05.060	0.0%	738762	0.003	0.007	0.012
TOTAL: 48:00:35.594	47:59:59.114	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	18498	0.000	0.002	0.031
article prepare	00:00:00.000	0.0%	1800	0.000	0.000	0.000
article read	00:00:00.008	0.0%	450	0.000	0.018	1.000
backlog stats	00:00:00.008	0.0%	100420	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.440	0.0%	50452	0.000	0.009	0.054
data write	00:00:00.954	0.0%	31114	0.000	0.031	0.106
idle	47:39:54.164	100.0%	100420	475.274	1708.765	3850.051
status file	00:00:00.190	0.0%	336	0.000	0.565	4.000
TOTAL: 47:39:56.714	47:39:55.798	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:37.230	78.4%	8852	1.000	10.984	10018.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.320	0.3%	30050	0.000	0.011	0.333
readart	00:00:00.032	0.0%	68	0.471	0.471	0.471
TOTAL: 00:02:04.024	00:01:37.582	78.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newgroups-request@fido7.org	0	0	2	2	0	2
TOTAL	0	0	2	2	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	148	360046	359912	8	126	99%	65:24:00
2	news.icm.edu.pl	62	100	12	88	0	12%	12:35:58
3	localhost	6	4	4	0	0	100%	00:00:00
TOTAL: 3		216	360150	359928	96	126	99%	77:59:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	4.4 GB	1.8 MB	4.4 GB	99%	12.7 KB
2	news.icm.edu.pl	1.8 MB	0.0 KB	1.8 MB	100%	156.5 KB
3	localhost	5.9 KB	0.0 KB	5.9 KB	100%	1.5 KB
TOTAL: 3		4.4 GB	1.8 MB	4.4 GB	99%	12.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 14 04:15:01 - 04:59:59	4819	2.7%	1.79	62.0 MB	2.8%	23.52
Oct 14 05:00:00 - 05:59:59	6096	3.4%	1.69	74.0 MB	3.3%	21.04
Oct 14 06:00:00 - 06:59:59	7490	4.2%	2.08	84.7 MB	3.8%	24.11
Oct 14 07:00:00 - 07:59:59	6676	3.7%	1.85	82.1 MB	3.7%	23.34
Oct 14 08:00:00 - 08:59:59	10037	5.6%	2.79	127.5 MB	5.7%	36.27
Oct 14 09:00:00 - 09:59:59	9521	5.3%	2.64	111.7 MB	5.0%	31.77
Oct 14 10:00:00 - 10:59:59	7609	4.2%	2.11	85.2 MB	3.8%	24.24
Oct 14 11:00:00 - 11:59:59	10329	5.7%	2.87	133.5 MB	6.0%	37.98
Oct 14 12:00:00 - 12:59:59	8692	4.8%	2.41	102.9 MB	4.6%	29.27
Oct 14 13:00:00 - 13:59:59	9914	5.5%	2.75	134.0 MB	6.0%	38.11
Oct 14 14:00:00 - 14:59:59	9197	5.1%	2.55	115.8 MB	5.2%	32.93
Oct 14 15:00:00 - 15:59:59	5859	3.3%	1.63	75.3 MB	3.4%	21.41
Oct 14 16:00:00 - 16:59:59	9095	5.1%	2.53	89.7 MB	4.0%	25.51
Oct 14 17:00:00 - 17:59:59	5058	2.8%	1.41	59.0 MB	2.6%	16.78
Oct 14 18:00:00 - 18:59:59	6041	3.4%	1.68	75.9 MB	3.4%	21.59
Oct 14 19:00:00 - 19:59:59	6792	3.8%	1.89	87.1 MB	3.9%	24.79
Oct 14 20:00:00 - 20:59:59	7765	4.3%	2.16	85.3 MB	3.8%	24.28
Oct 14 21:00:00 - 21:59:59	6520	3.6%	1.81	83.3 MB	3.7%	23.70
Oct 14 22:00:00 - 22:59:59	6500	3.6%	1.81	74.3 MB	3.3%	21.13
Oct 14 23:00:00 - 23:59:59	7929	4.4%	2.20	93.4 MB	4.2%	26.56
Oct 15 00:00:00 - 00:59:59	6719	3.7%	1.87	74.3 MB	3.3%	21.12
Oct 15 01:00:00 - 01:59:59	6536	3.6%	1.82	80.6 MB	3.6%	22.93
Oct 15 02:00:00 - 02:59:59	6513	3.6%	1.81	80.0 MB	3.6%	22.77
Oct 15 03:00:00 - 03:59:59	6460	3.6%	1.79	133.6 MB	6.0%	37.99
Oct 15 04:00:00 - 04:15:01	1797	1.0%	1.99	33.3 MB	1.5%	37.82
TOTAL: 24:00:00	179964	100.0%	2.08	2.2 GB	100.0%	26.53

Sites sending bad articles:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	33686	424	18096	28	6	1%	47:59:54
2	poznan	18	0	12	0	0	0%	02:06:12
TOTAL: 2		33704	424	18108	28	6	1%	50:06:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	832.0 KB	471.2 KB	1.3 MB	2.9 KB	47:59:54
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	02:06:12
TOTAL: 2		832.0 KB	471.2 KB	1.3 MB	2.9 KB	50:06:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	syn-066-061-098-095.res.spectrum.com	8	68	70.5 KB	2	0	00:00:28
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		12	68	70.5 KB	2	4	00:02:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.res.spectrum.com	8	68	70.5 KB	2	0	00:00:28
2	*.stretchoid.com	4	0	0.0 KB	0	0	00:00:20
3	*.man.lodz.pl	2884	0	0.0 KB	0	4	00:00:22
4	unresolved	590	0	0.0 KB	0	0	00:00:45
5	*.ch1.censys-scanner.com	6	0	0.0 KB	0	0	00:00:08
TOTAL: 5		3492	68	70.5 KB	2	4	00:02:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.272	3.172	00:00:32
syn-066-061-098-095.res.spectrum.com	0.024	0.046	00:00:28
nms1.man.lodz.pl	6.040	14.854	00:00:22
azpdsgbhg8mp.stretchoid.com	0.010	0.022	00:00:20
scanner-207.ch1.censys-scanner.com	0.074	0.032	00:00:08
80.111.142.213	0.024	0.048	00:00:06
205.210.31.55	0.002	0.012	00:00:03
198.235.24.208	0.008	0.008	00:00:02
86.54.31.42	0.004	0.012	00:00:00
isvcs01.man.lodz.pl	0.010	0.026	00:00:00
TOTAL: 10	7.468	18.232	00:02:05

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2880
172.16.2.4	576
80.111.142.213	8
scanner-207.ch1.censys-scanner.com	6
azpdsgbhg8mp.stretchoid.com	4
198.235.24.208	2
205.210.31.55	2
86.54.31.42	2
TOTAL: 8	3480

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

System	Conn
scanner-207.ch1.censys-scanner.com	2
azpdsgbhg8mp.stretchoid.com	2
TOTAL: 2	4

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
scanner-207.ch1.censys-scanner.com	2	4
TOTAL: 1	2	8

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	lodman	68	100.0%
TOTAL: 1		68	100.0%

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

Newsgroup		Count
1	lodman.info	68
TOTAL: 1		68

Daily Usenet report

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