Daily Usenet report: Sep 9 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 9 06:26:08 newsfeed nnrpd[20542]: SERVER perl filter not defined
Sep 9 07:35:25 newsfeed nnrpd[30732]: SERVER perl filter not defined
Sep 9 07:41:50 newsfeed nnrpd[31825]: SERVER perl filter not defined
Sep 9 07:42:27 newsfeed nnrpd[31828]: SERVER perl filter not defined
Sep 9 07:42:41 newsfeed nnrpd[31894]: SERVER perl filter not defined
Sep 9 10:56:00 newsfeed nnrpd[28322]: SERVER perl filter not defined
Sep 9 13:16:07 newsfeed overchan[1139]: input line too long, skipping
Sep 10 01:00:16 newsfeed nnrpd[31894]: SERVER perl filter not defined
Sep 10 02:15:49 newsfeed nnrpd[10929]: unable to get certificate from '/etc/news/cert.pem'
Sep 10 02:15:49 newsfeed nnrpd[10929]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	560994	90.5%	61.9 MB	89.6%
innfeed	45944	7.4%	5.4 MB	7.9%
innd	9447	1.5%	1.4 MB	2.1%
nnrpd	3437	0.6%	323.9 KB	0.5%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	619825	100.0%	69.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	557119	98.9%
Negative hits	5958	1.1%
Positive hits	245	0.0%
Cache misses	34	0.0%
TOTAL: 4	563356	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.165	0.0%	58	0.000	20.086	139.000
article cleanup	00:00:04.547	0.0%	562438	0.004	0.008	0.014
article logging	00:00:13.415	0.0%	562852	0.015	0.024	0.032
article parse	00:07:24.760	0.5%	86804460	0.004	0.005	0.007
article write	00:03:35.859	0.2%	562269	0.236	0.384	0.770
artlog/artcncl	00:00:00.001	0.0%	58	0.000	0.017	0.077
data move	00:00:32.284	0.0%	91027482	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.204	0.0%	57	0.000	3.579	98.000
hishave/artcncl	00:00:00.072	0.0%	58	0.000	1.241	5.571
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:08:12.262	0.6%	568678	0.155	0.866	4.286
history sync	00:00:00.017	0.0%	306	0.000	0.056	1.000
history write	00:09:16.001	0.6%	562396	0.277	0.989	4.788
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	22:31:11.954	93.8%	82689876	0.708	0.980	1.807
nntp read	00:19:33.943	1.4%	86424821	0.012	0.014	0.017
overview write	00:00:01.780	0.0%	562269	0.001	0.003	0.006
perl filter	00:33:25.732	2.3%	562396	2.836	3.566	4.330
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:13.694	0.0%	1135784	0.009	0.012	0.017
TOTAL: 24:00:09.242	23:53:47.690	99.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.086	0.0%	71811	0.000	0.001	0.025
article prepare	00:00:00.000	0.0%	29334	0.000	0.000	0.000
article read	00:00:33.582	0.0%	7333	0.000	4.580	316.571
backlog stats	00:00:00.055	0.0%	142997	0.000	0.000	0.006
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:00.565	0.0%	69817	0.000	0.008	0.020
data write	00:00:01.372	0.0%	58790	0.000	0.023	0.080
idle	23:43:24.876	99.9%	142997	15.912	597.249	2068.588
status file	00:00:51.741	0.1%	16042	0.000	3.225	226.468
TOTAL: 23:44:55.942	23:44:52.277	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:08.357	54.6%	2819	0.000	2.965	127.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.077	0.5%	6030	0.000	0.013	0.500
readart	00:00:00.001	0.0%	38	0.000	0.026	0.500
TOTAL: 00:00:15.312	00:00:08.435	55.1%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	349	559929	559786	0	143	99%	127:11:03
2	news.icm.edu.pl	44	259	46	189	24	17%	16:32:40
3	localhost	1448	7	7	0	0	100%	00:00:00
TOTAL: 3		1841	560195	559839	189	167	99%	143:43:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	245.3 GB	3.6 MB	3.8 MB	245.3 GB	99%	459.3 KB
2	news.icm.edu.pl	4.2 MB	0.0 KB	506.1 KB	4.7 MB	89%	69.1 KB
3	localhost	13.1 KB	0.0 KB	0.0 KB	13.1 KB	100%	1.9 KB
TOTAL: 3		245.3 GB	3.6 MB	4.3 MB	245.3 GB	99%	459.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 9 04:15:02 - 04:59:59	16992	3.0%	6.30	7.4 GB	3.0%	2865.85
Sep 9 05:00:00 - 05:59:59	21293	3.8%	5.91	9.5 GB	3.9%	2764.80
Sep 9 06:00:00 - 06:59:59	24151	4.3%	6.71	10.7 GB	4.4%	3120.28
Sep 9 07:00:00 - 07:59:59	22128	3.9%	6.15	10.1 GB	4.1%	2942.44
Sep 9 08:00:00 - 08:59:59	22597	4.0%	6.28	10.8 GB	4.4%	3156.96
Sep 9 09:00:00 - 09:59:59	19037	3.4%	5.29	8.6 GB	3.5%	2513.90
Sep 9 10:00:00 - 10:59:59	27129	4.8%	7.54	8.0 GB	3.3%	2335.95
Sep 9 11:00:00 - 11:59:59	25976	4.6%	7.22	12.4 GB	5.1%	3621.16
Sep 9 12:00:00 - 12:59:59	23955	4.3%	6.65	11.3 GB	4.6%	3288.34
Sep 9 13:00:00 - 13:59:59	22104	3.9%	6.14	11.1 GB	4.5%	3247.46
Sep 9 14:00:00 - 14:59:59	20476	3.7%	5.69	9.9 GB	4.0%	2890.97
Sep 9 15:00:00 - 15:59:59	19355	3.5%	5.38	8.7 GB	3.6%	2547.79
Sep 9 16:00:00 - 16:59:59	27641	4.9%	7.68	10.1 GB	4.1%	2929.43
Sep 9 17:00:00 - 17:59:59	22615	4.0%	6.28	9.5 GB	3.9%	2767.07
Sep 9 18:00:00 - 18:59:59	22056	3.9%	6.13	10.2 GB	4.1%	2968.14
Sep 9 19:00:00 - 19:59:59	27760	5.0%	7.71	11.4 GB	4.7%	3332.80
Sep 9 20:00:00 - 20:59:59	30785	5.5%	8.55	11.0 GB	4.5%	3204.56
Sep 9 21:00:00 - 21:59:59	28992	5.2%	8.05	11.8 GB	4.8%	3438.30
Sep 9 22:00:00 - 22:59:59	26801	4.8%	7.44	10.6 GB	4.3%	3088.58
Sep 9 23:00:00 - 23:59:59	19972	3.6%	5.55	9.9 GB	4.0%	2873.68
Sep 10 00:00:00 - 00:59:59	22572	4.0%	6.27	10.1 GB	4.1%	2932.19
Sep 10 01:00:00 - 01:59:59	23392	4.2%	6.50	10.5 GB	4.3%	3066.14
Sep 10 02:00:00 - 02:59:59	21218	3.8%	5.89	10.6 GB	4.3%	3078.08
Sep 10 03:00:00 - 03:59:59	16656	3.0%	4.63	8.8 GB	3.6%	2570.24
Sep 10 04:00:00 - 04:15:01	4700	0.8%	5.22	2.5 GB	1.0%	2955.73
TOTAL: 23:59:59	560353	100.0%	6.49	245.7 GB	100.0%	2981.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	557	99	41	3	414
2	news.icm.edu.pl	25	0	0	0	25
TOTAL: 2		582	99	41	3	439

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	28
it.tlc.cellulari.android	16
comp.sys.raspberry-pi	12
comp.mobile.android	11
it.comp.os.win.windows10	10
de.comm.provider.telefonie	8
alt.comp.software.seamonkey	5
alt.comp.software.firefox	3
de.rec.sf.startrek	3
it.comp.os.win.windows7	2
it.comp.os.linux.ubuntu	1
TOTAL: 11	99

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
pl.praca.oferowana	3
TOTAL: 1	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	24
Binary in non-binary group	1
TOTAL: 2	25

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	63744	1785	31241	4232	9	34848	2%	23:59:35
2	icm	18321	767	10092	73	0	0	4%	24:00:01
3	nask	0	0	0	0	0	7791	0%	23:56:15
4	okapi	0	0	0	0	0	750	0%	23:56:15
5	poznan	53	0	34	0	0	0	0%	04:05:12
TOTAL: 5		82118	2552	41367	4305	9	43389	3%	99:57:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.6 MB	17.5 MB	22.0 MB	0.3 KB/s	3.8 KB	23:59:35
2	icm	2.3 MB	517.5 KB	2.8 MB	0.0 KB/s	3.4 KB	24:00:01
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:15
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:15
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:05:12
TOTAL: 5		6.9 MB	18.0 MB	24.8 MB	0.1 KB/s	3.7 KB	99:57:18

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	38	39.0 KB	17	0	00:00:10
2	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 2		295	38	39.0 KB	17	7	00:00:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	295	38	39.0 KB	17	7	00:00:10
2	*.ch1.censys-scanner.com	3	0	0.0 KB	0	0	00:00:00
3	unresolved	107	0	0.0 KB	0	0	00:00:03
TOTAL: 3		405	38	39.0 KB	17	7	00:00:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	0.783	1.449	00:00:10
80.111.142.213	0.029	0.004	00:00:02
212.51.192.185	0.267	0.405	00:00:00
scanner-05.ch1.censys-scanner.com	0.004	0.010	00:00:00
isvcs01.man.lodz.pl	0.021	0.046	00:00:00
scanner-21.ch1.censys-scanner.com	0.000	0.008	00:00:00
167.71.36.50	0.015	0.026	00:00:00
TOTAL: 7	1.119	1.948	00:00:15

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
167.71.36.50	7
80.111.142.213	4
scanner-05.ch1.censys-scanner.com	2
scanner-21.ch1.censys-scanner.com	1
TOTAL: 5	110

NNRP unrecognized commands (by host):

System	Conn
167.71.36.50	36
scanner-05.ch1.censys-scanner.com	1
TOTAL: 2	37

NNRP unrecognized commands (by command):

Command	Count
Accept-Encoding: gzip, deflate	6
Accept: /	6
Host: 212.191.2.21:119	6
Connection: keep-alive	6
User-Agent: Mozilla/5.0 (Windows NT 10.0...	5
GET /streaming/clients_live.php HTTP/1.1	1
EHLO www.censys.io	1
GET /flu/403.html HTTP/1.1	1
GET /c/version.js HTTP/1.1	1
GET /stalker_portal/c/version.js HTTP/1....	1
User-Agent: AlexaMediaPlayer/2.1.4676.0 ...	1
GET /stream/live.php HTTP/1.1	1
GET /system_api.php HTTP/1.1	1
TOTAL: 13	37

NNRP client timeouts [Top 20]:

System	Conn	Peer
scanner-21.ch1.censys-scanner.com	1	1
TOTAL: 1	1	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	38	100.0%
TOTAL: 1		38	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	38
TOTAL: 1		38

Daily Usenet report

Sep 9 04:15:02 -- Sep 10 04:15:01