Daily Usenet report: Mar 16 04:15:01

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Mar 16 09:27:12 newsfeed pgpverify[3267406]: pgpverify: verification failed
Mar 16 09:27:12 newsfeed pgpverify[3267406]: pgpverify: verification failed
Mar 16 13:29:35 newsfeed nnrpd[21816]: SERVER perl filter not defined
Mar 16 13:29:35 newsfeed nnrpd[21816]: SERVER perl filter not defined
Mar 16 13:32:13 newsfeed nnrpd[22150]: SERVER perl filter not defined
Mar 16 13:32:13 newsfeed nnrpd[22150]: SERVER perl filter not defined
Mar 16 17:09:23 newsfeed nnrpd[48205]: SERVER perl filter not defined
Mar 16 17:09:23 newsfeed nnrpd[48205]: SERVER perl filter not defined
Mar 16 17:10:38 newsfeed nnrpd[48275]: SERVER perl filter not defined
Mar 16 17:10:38 newsfeed nnrpd[48275]: SERVER perl filter not defined
Mar 16 21:46:08 newsfeed nnrpd[81065]: SERVER perl filter not defined
Mar 16 21:46:08 newsfeed nnrpd[81065]: SERVER perl filter not defined
Mar 16 21:46:38 newsfeed nnrpd[81205]: SERVER perl filter not defined
Mar 16 21:46:38 newsfeed nnrpd[81205]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	132904	78.0%	14.7 MB	80.8%
innd	20028	11.8%	1.9 MB	10.3%
nnrpd	16070	9.4%	1.5 MB	8.0%
innfeed	1296	0.8%	158.2 KB	0.8%
controlchan	26	0.0%	5.9 KB	0.0%
overchan	2	0.0%	0.5 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
TOTAL: 7	170328	100.0%	18.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	263410	99.6%
Negative hits	944	0.4%
Positive hits	132	0.0%
Cache misses	2	0.0%
TOTAL: 4	264488	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.008	0.0%	56	0.000	0.143	3.000
article cleanup	00:00:00.750	0.0%	263918	0.000	0.003	0.011
article logging	00:00:03.160	0.0%	263982	0.004	0.012	0.026
article parse	00:00:04.354	0.0%	854708	0.002	0.005	0.011
article write	00:00:30.180	0.0%	263902	0.018	0.114	10.678
artlog/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
data move	00:00:00.532	0.0%	1175962	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.004	0.0%	68	0.000	0.059	2.000
hishave/artcncl	00:00:00.000	0.0%	12	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.198	0.0%	264972	0.000	0.035	0.619
history sync	00:00:00.028	0.0%	724	0.000	0.039	0.500
history write	00:00:28.108	0.0%	263906	0.027	0.107	0.699
idle	47:35:31.170	99.8%	1048522	76.152	163.403	513.443
nntp read	00:00:06.838	0.0%	812492	0.005	0.008	0.017
overview write	00:00:00.382	0.0%	263902	0.000	0.001	0.007
perl filter	00:02:04.160	0.1%	263906	0.264	0.470	0.725
python filter	00:00:00.054	0.0%	263918	0.000	0.000	0.003
site send	00:00:03.278	0.0%	545762	0.001	0.006	0.012
TOTAL: 47:41:43.208	47:39:02.204	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.014	0.0%	19208	0.000	0.001	0.037
article prepare	00:00:00.000	0.0%	608	0.000	0.000	0.000
article read	00:00:00.004	0.0%	152	0.000	0.026	0.500
backlog stats	00:00:00.016	0.0%	89422	0.000	0.000	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.260	0.0%	44714	0.000	0.006	0.029
data write	00:00:00.642	0.0%	26724	0.000	0.024	0.100
idle	47:38:50.932	100.0%	89422	184.683	1918.218	6533.402
status file	00:00:07.862	0.0%	350	0.000	22.463	3842.000
TOTAL: 47:39:00.448	47:38:59.730	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:03:59.244	88.4%	9000	1.000	26.583	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.286	0.1%	30464	0.000	0.009	0.333
readart	00:00:00.000	0.0%	28	0.000	0.000	0.000
TOTAL: 00:04:30.606	00:03:59.530	88.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	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet-fr.news.eu.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	46	265162	265154	0	8	99%	52:46:34
2	localhost	14	12	12	0	0	100%	00:00:00
3	news.icm.edu.pl	78	126	8	110	8	6%	14:55:40
TOTAL: 3		138	265300	265174	110	16	99%	67:42:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	2.3 GB	104.0 KB	0.0 KB	2.3 GB	99%	9.1 KB
2	news.icm.edu.pl	103.8 KB	51.6 KB	32.7 KB	188.1 KB	55%	11.8 KB
3	localhost	18.6 KB	0.0 KB	0.0 KB	18.6 KB	100%	1.6 KB
TOTAL: 3		2.3 GB	155.6 KB	32.7 KB	2.3 GB	99%	9.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 16 04:15:01 - 04:59:59	2870	2.2%	1.06	25.4 MB	2.2%	9.63
Mar 16 05:00:00 - 05:59:59	3878	2.9%	1.08	38.3 MB	3.3%	10.90
Mar 16 06:00:00 - 06:59:59	3204	2.4%	0.89	26.4 MB	2.2%	7.52
Mar 16 07:00:00 - 07:59:59	5911	4.4%	1.64	42.4 MB	3.6%	12.06
Mar 16 08:00:00 - 08:59:59	7184	5.4%	2.00	55.8 MB	4.7%	15.88
Mar 16 09:00:00 - 09:59:59	4348	3.3%	1.21	42.0 MB	3.6%	11.95
Mar 16 10:00:00 - 10:59:59	5512	4.1%	1.53	58.8 MB	5.0%	16.72
Mar 16 11:00:00 - 11:59:59	5571	4.2%	1.55	52.5 MB	4.5%	14.93
Mar 16 12:00:00 - 12:59:59	4132	3.1%	1.15	37.2 MB	3.2%	10.58
Mar 16 13:00:00 - 13:59:59	4990	3.8%	1.39	49.8 MB	4.2%	14.16
Mar 16 14:00:00 - 14:59:59	4356	3.3%	1.21	49.1 MB	4.2%	13.95
Mar 16 15:00:00 - 15:59:59	5493	4.1%	1.53	58.6 MB	5.0%	16.67
Mar 16 16:00:00 - 16:59:59	4486	3.4%	1.25	48.5 MB	4.1%	13.81
Mar 16 17:00:00 - 17:59:59	5445	4.1%	1.51	49.1 MB	4.2%	13.97
Mar 16 18:00:00 - 18:59:59	4427	3.3%	1.23	44.8 MB	3.8%	12.73
Mar 16 19:00:00 - 19:59:59	4857	3.7%	1.35	48.1 MB	4.1%	13.69
Mar 16 20:00:00 - 20:59:59	4790	3.6%	1.33	48.4 MB	4.1%	13.76
Mar 16 21:00:00 - 21:59:59	6908	5.2%	1.92	58.5 MB	5.0%	16.64
Mar 16 22:00:00 - 22:59:59	8638	6.5%	2.40	65.8 MB	5.6%	18.72
Mar 16 23:00:00 - 23:59:59	6381	4.8%	1.77	59.0 MB	5.0%	16.79
Mar 17 00:00:00 - 00:59:59	6841	5.1%	1.90	37.0 MB	3.1%	10.53
Mar 17 01:00:00 - 01:59:59	5913	4.5%	1.64	46.8 MB	4.0%	13.30
Mar 17 02:00:00 - 02:59:59	6295	4.7%	1.75	51.1 MB	4.3%	14.54
Mar 17 03:00:00 - 03:59:59	9200	6.9%	2.56	69.1 MB	5.9%	19.67
Mar 17 04:00:00 - 04:15:01	1228	0.9%	1.36	13.2 MB	1.1%	14.98
TOTAL: 24:00:00	132858	100.0%	1.54	1.1 GB	100.0%	13.93

Sites sending bad articles:

Server		Total	Duplic	Other
1	newsfeed.pionier.net.pl	36	4	32
2	news.icm.edu.pl	4	2	2
TOTAL: 2		40	6	34

INND Perl filter [Top 20]:

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

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	28648	116	17770	36	1278	0%	47:39:34
2	poznan	10	0	8	0	0	0%	02:00:10
TOTAL: 2		28658	116	17778	36	1278	0%	49:39:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	277.6 KB	513.8 KB	791.4 KB	5.2 KB	47:39:34
2	poznan	0.0 KB	0.0 KB	0.0 KB	0	02:00:10
TOTAL: 2		277.6 KB	513.8 KB	791.4 KB	5.2 KB	49:39:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	66.61.98.95	36	24	26.7 KB	6	0	00:00:44
2	172.16.2.4	576	4	6.7 KB	2	0	00:00:33
3	isvcs01.man.lodz.pl	12	0	0.0 KB	0	12	00:00:00
TOTAL: 3		624	28	33.4 KB	8	12	00:04:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	628	28	33.4 KB	8	0	00:02:06
2	*.105.73.148.socradar-curiosity-wide-scan-project.socradar.com	2	0	0.0 KB	0	0	00:00:20
3	*.man.lodz.pl	2890	0	0.0 KB	0	12	00:00:24
4	*.internet-census.org	16	0	0.0 KB	0	0	00:01:07
5	*.scanner.modat.io	2	0	0.0 KB	0	0	00:00:00
6	*.138.94.167.censys-scanner.com	10	0	0.0 KB	0	0	00:00:29
7	*.monitoring.internet-measurement.com	2	0	0.0 KB	0	0	00:00:03
TOTAL: 7		3550	28	33.4 KB	8	12	00:04:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
66.61.98.95	0.072	0.170	00:00:44
80.82.77.202	0.012	0.016	00:00:40
172.16.2.4	1.414	2.564	00:00:33
51.138.94.167.censys-scanner.com	0.018	0.040	00:00:26
nms1.man.lodz.pl	6.264	12.224	00:00:23
zl-dfwc-us-gp1-wk109c.internet-census.org	0.004	0.010	00:00:21
zl-amsc-nl-gp1-wk146a.internet-census.org	0.004	0.010	00:00:20
172.105.73.148.socradar-curiosity-wide-scan-project.socradar.com	0.002	0.010	00:00:20
sh-ams-nl-cpp-wk103.internet-census.org	0.006	0.020	00:00:08
80.111.142.213	0.018	0.038	00:00:05
zl-laxc-us-cpp-wk109.internet-census.org	0.006	0.006	00:00:04
zl-laxd-us-cpp-wk111.internet-census.org	0.004	0.008	00:00:04
sh-chi-us-cpp-wk107.internet-census.org	0.006	0.008	00:00:04
sh-chi-us-cpp-wk105.internet-census.org	0.000	0.012	00:00:04
sacred.monitoring.internet-measurement.com	0.008	0.006	00:00:03
187.138.94.167.censys-scanner.com	0.062	0.014	00:00:02
198.235.24.86	0.002	0.010	00:00:01
147.185.132.132	0.004	0.010	00:00:01
isvcs01.man.lodz.pl	0.034	0.064	00:00:00
o352.scanner.modat.io	0.002	0.012	00:00:00
TOTAL: 20	7.942	15.252	00:04:31

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	2878
80.111.142.213	8
51.138.94.167.censys-scanner.com	8
80.82.77.202	4
sh-ams-nl-cpp-wk103.internet-census.org	4
o352.scanner.modat.io	2
198.235.24.86	2
sacred.monitoring.internet-measurement.com	2
sh-chi-us-cpp-wk107.internet-census.org	2
zl-dfwc-us-gp1-wk109c.internet-census.org	2
187.138.94.167.censys-scanner.com	2
172.105.73.148.socradar-curiosity-wide-scan-project.socradar.com	2
147.185.132.132	2
zl-laxc-us-cpp-wk109.internet-census.org	2
zl-laxd-us-cpp-wk111.internet-census.org	2
sh-chi-us-cpp-wk105.internet-census.org	2
zl-amsc-nl-gp1-wk146a.internet-census.org	2
TOTAL: 17	2926

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

System	Conn
187.138.94.167.censys-scanner.com	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
51.138.94.167.censys-scanner.com	2	8
TOTAL: 1	2	12

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	lodman	24	85.7%
2	pl	4	14.3%
TOTAL: 2		28	100.0%

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

Newsgroup		Count
1	lodman.info	24
2	pl.comp.os.ms-windows.winnt	4
TOTAL: 2		28

Daily Usenet report

Mar 16 04:15:01 -- Mar 17 04:15:01