Daily Usenet report: Oct 1 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 1 07:15:01 newsfeed pgpverify[993690]: pgpverify: /usr/bin/gpg1: no such file
Oct 1 07:15:01 newsfeed pgpverify[993690]: pgpverify: /usr/bin/gpg1: no such file
Oct 1 11:33:26 newsfeed nnrpd[1024757]: SERVER perl filter not defined
Oct 1 11:33:26 newsfeed nnrpd[1024757]: SERVER perl filter not defined
Oct 1 11:33:33 newsfeed nnrpd[1024758]: SERVER perl filter not defined
Oct 1 11:33:33 newsfeed nnrpd[1024758]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	193543	81.9%	21.3 MB	84.2%
innd	25654	10.9%	2.4 MB	9.4%
nnrpd	15724	6.7%	1.4 MB	5.7%
innfeed	1284	0.5%	167.1 KB	0.6%
controlchan	44	0.0%	10.4 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	236253	100.0%	25.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	386638	99.7%
Negative hits	954	0.2%
Positive hits	192	0.0%
Cache misses	8	0.0%
TOTAL: 4	387792	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.018	0.0%	188	0.000	0.096	2.000
article cleanup	00:00:01.114	0.0%	387290	0.000	0.003	0.012
article logging	00:00:04.784	0.0%	387354	0.005	0.012	0.026
article parse	00:00:07.978	0.0%	1465500	0.003	0.005	0.029
article write	00:00:11.856	0.0%	387276	0.014	0.031	0.076
artlog/artcncl	00:00:00.000	0.0%	24	0.000	0.000	0.000
data move	00:00:01.194	0.0%	2194516	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.002	0.0%	212	0.000	0.009	0.091
hishave/artcncl	00:00:00.000	0.0%	24	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.148	0.0%	388406	0.000	0.003	0.015
history sync	00:00:00.034	0.0%	606	0.000	0.056	0.667
history write	00:00:14.462	0.0%	387276	0.018	0.037	0.205
idle	47:55:37.952	99.8%	1748814	48.891	98.660	2197.575
nntp read	00:00:11.468	0.0%	1411142	0.005	0.008	0.015
overview write	00:00:00.594	0.0%	387276	0.000	0.002	0.006
perl filter	00:03:24.972	0.1%	387276	0.435	0.529	0.981
python filter	00:00:00.068	0.0%	387280	0.000	0.000	0.003
site send	00:00:05.300	0.0%	793434	0.003	0.007	0.016
TOTAL: 48:00:35.218	48:00:02.944	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	18860	0.000	0.001	0.018
article prepare	00:00:00.000	0.0%	1600	0.000	0.000	0.000
article read	00:00:00.012	0.0%	400	0.000	0.030	0.500
backlog stats	00:00:00.024	0.0%	106488	0.000	0.000	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.466	0.0%	53044	0.000	0.009	0.042
data write	00:00:01.074	0.0%	33230	0.000	0.032	0.082
idle	47:53:12.140	100.0%	106488	849.041	1618.888	3470.925
status file	00:00:00.166	0.0%	338	0.000	0.491	4.000
TOTAL: 47:53:14.744	47:53:13.900	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:39.830	79.4%	8748	1.000	11.412	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.194	0.2%	29700	0.000	0.007	0.333
readart	00:00:00.000	0.0%	8	0.000	0.000	0.000
TOTAL: 00:02:05.800	00:01:40.024	79.5%	-	-	-	-

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	Other	Bad PGP	OK
control@eternal-september.org	2	0	2
moderator@dana.de	2	2	2
TOTAL	4	2	4

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	134	386968	386954	2	12	99%	62:53:54
2	news.icm.edu.pl	50	176	14	160	2	7%	13:55:24
3	localhost	6	4	4	0	0	100%	00:00:00
TOTAL: 3		190	387148	386972	162	14	99%	76:49:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	4.4 GB	355.1 KB	4.4 GB	99%	11.9 KB
2	news.icm.edu.pl	525.2 KB	26.8 KB	552.0 KB	95%	34.5 KB
3	localhost	5.4 KB	0.0 KB	5.4 KB	100%	1.4 KB
TOTAL: 3		4.4 GB	381.9 KB	4.4 GB	99%	11.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:01 - 04:59:59	6151	3.2%	2.28	64.0 MB	2.8%	24.30
Oct 1 05:00:00 - 05:59:59	6794	3.5%	1.89	81.4 MB	3.6%	23.14
Oct 1 06:00:00 - 06:59:59	6203	3.2%	1.72	72.9 MB	3.2%	20.75
Oct 1 07:00:00 - 07:59:59	7335	3.8%	2.04	90.8 MB	4.0%	25.83
Oct 1 08:00:00 - 08:59:59	6479	3.3%	1.80	75.6 MB	3.4%	21.49
Oct 1 09:00:00 - 09:59:59	8006	4.1%	2.22	93.6 MB	4.2%	26.62
Oct 1 10:00:00 - 10:59:59	9767	5.0%	2.71	117.5 MB	5.2%	33.43
Oct 1 11:00:00 - 11:59:59	8252	4.3%	2.29	98.3 MB	4.4%	27.96
Oct 1 12:00:00 - 12:59:59	8578	4.4%	2.38	112.9 MB	5.0%	32.11
Oct 1 13:00:00 - 13:59:59	8891	4.6%	2.47	103.1 MB	4.6%	29.31
Oct 1 14:00:00 - 14:59:59	7446	3.8%	2.07	97.0 MB	4.3%	27.60
Oct 1 15:00:00 - 15:59:59	7944	4.1%	2.21	105.6 MB	4.7%	30.05
Oct 1 16:00:00 - 16:59:59	7265	3.8%	2.02	88.7 MB	3.9%	25.23
Oct 1 17:00:00 - 17:59:59	9610	5.0%	2.67	96.9 MB	4.3%	27.57
Oct 1 18:00:00 - 18:59:59	10008	5.2%	2.78	103.6 MB	4.6%	29.46
Oct 1 19:00:00 - 19:59:59	8618	4.5%	2.39	106.2 MB	4.7%	30.21
Oct 1 20:00:00 - 20:59:59	10884	5.6%	3.02	136.7 MB	6.1%	38.89
Oct 1 21:00:00 - 21:59:59	8691	4.5%	2.41	106.2 MB	4.7%	30.22
Oct 1 22:00:00 - 22:59:59	6712	3.5%	1.86	75.3 MB	3.3%	21.42
Oct 1 23:00:00 - 23:59:59	6728	3.5%	1.87	80.2 MB	3.6%	22.82
Oct 2 00:00:00 - 00:59:59	5960	3.1%	1.66	62.4 MB	2.8%	17.74
Oct 2 01:00:00 - 01:59:59	11133	5.8%	3.09	124.0 MB	5.5%	35.26
Oct 2 02:00:00 - 02:59:59	9221	4.8%	2.56	83.8 MB	3.7%	23.82
Oct 2 03:00:00 - 03:59:59	5042	2.6%	1.40	59.5 MB	2.6%	16.92
Oct 2 04:00:00 - 04:15:01	1774	0.9%	1.97	16.7 MB	0.7%	19.02
TOTAL: 24:00:00	193492	100.0%	2.24	2.2 GB	100.0%	26.70

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	38	6	32
2	news.icm.edu.pl	1	1	0
TOTAL: 2		39	7	32

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	35386	398	18466	2	2	1%	47:59:56
2	poznan	22	0	14	0	0	0%	02:15:30
TOTAL: 2		35408	398	18480	2	2	1%	50:15:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	842.1 KB	25.0 KB	867.2 KB	2.2 KB	47:59:56
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	02:15:30
TOTAL: 2		842.1 KB	25.0 KB	867.2 KB	2.2 KB	50:15:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	172.16.2.4	576	8	11.2 KB	4	0	00:00:33
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		580	8	11.2 KB	4	4	00:02:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	600	8	11.2 KB	4	0	00:01:29
2	*.monitoring.internet-measurement.com	2	0	0.0 KB	0	0	00:00:03
3	*.man.lodz.pl	2884	0	0.0 KB	0	4	00:00:24
4	*.shodan.io	2	0	0.0 KB	0	0	00:00:07
TOTAL: 4		3488	8	11.2 KB	4	4	00:02:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
172.16.2.4	1.264	2.852	00:00:33
nms1.man.lodz.pl	6.172	13.028	00:00:24
167.94.138.160	0.014	0.038	00:00:22
45.14.225.27	0.004	0.010	00:00:20
census8.shodan.io	0.010	0.002	00:00:07
205.210.31.163	0.002	0.012	00:00:05
80.111.142.213	0.016	0.040	00:00:04
masterful.monitoring.internet-measurement.com	0.006	0.008	00:00:03
66.132.153.135	0.062	0.016	00:00:02
205.210.31.168	0.004	0.010	00:00:01
isvcs01.man.lodz.pl	0.012	0.022	00:00:00
TOTAL: 11	7.566	16.038	00:02:06

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2880
80.111.142.213	8
167.94.138.160	8
census8.shodan.io	2
masterful.monitoring.internet-measurement.com	2
66.132.153.135	2
45.14.225.27	2
205.210.31.163	2
205.210.31.168	2
TOTAL: 9	2908

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

System	Conn
66.132.153.135	2
TOTAL: 1	2

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.160	2	8
TOTAL: 1	2	12

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	4
2	pl.comp.pecet	4
TOTAL: 2		8

Daily Usenet report

Oct 1 04:15:01 -- Oct 2 04:15:01