Daily Usenet report: Aug 16 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Aug 16 10:15:24 newsfeed nnrpd[11094]: SERVER perl filter not defined
Aug 16 10:57:36 newsfeed overchan[29155]: input line too long, skipping
Aug 16 13:32:49 newsfeed nnrpd[18650]: SERVER perl filter not defined
Aug 16 14:30:49 newsfeed nnrpd[20849]: SERVER perl filter not defined
Aug 16 15:38:56 newsfeed nnrpd[23400]: SERVER perl filter not defined
Aug 16 16:44:00 newsfeed nnrpd[25711]: unable to get certificate from '/etc/news/cert.pem'
Aug 16 16:44:00 newsfeed nnrpd[25711]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Aug 16 21:01:12 newsfeed overchan[29155]: input line too long, skipping
Aug 16 21:03:02 newsfeed overchan[29155]: input line too long, skipping
Aug 16 21:04:33 newsfeed overchan[29155]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2258448	97.0%	262.5 MB	96.7%
innfeed	46140	2.0%	5.5 MB	2.0%
innd	18929	0.8%	3.0 MB	1.1%
nnrpd	4808	0.2%	507.7 KB	0.2%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2328331	100.0%	271.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2244301	96.1%
Negative hits	76840	3.3%
Positive hits	13283	0.6%
Cache misses	117	0.0%
TOTAL: 4	2334541	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.756	0.0%	229	0.000	20.769	263.000
article cleanup	00:00:31.805	0.0%	2257605	0.011	0.014	0.019
article logging	00:01:24.159	0.1%	2258196	0.029	0.037	0.107
article parse	00:57:02.061	4.0%	182926895	0.013	0.019	0.030
article write	00:19:21.835	1.3%	2250692	0.397	0.516	1.235
artlog/artcncl	00:00:00.005	0.0%	227	0.000	0.022	0.200
data move	00:03:41.849	0.3%	197325043	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.448	0.0%	220	0.000	2.036	12.125
hishave/artcncl	00:00:00.205	0.0%	229	0.000	0.895	5.889
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:29:38.492	2.1%	2344239	0.022	0.759	5.974
history sync	00:00:00.016	0.0%	290	0.000	0.055	0.500
history write	01:33:42.420	6.5%	2253372	0.177	2.495	10.052
hiswrite/artcncl	00:00:00.001	0.0%	9	0.000	0.111	0.500
idle	14:24:03.106	60.0%	144274491	0.236	0.359	0.796
nntp read	01:21:18.452	5.6%	181249765	0.017	0.027	0.049
overview write	00:00:11.491	0.0%	2250692	0.003	0.005	0.008
perl filter	04:15:42.355	17.8%	2253343	5.539	6.809	7.976
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:37.697	0.3%	4523885	0.035	0.048	0.062
TOTAL: 24:00:01.857	23:30:21.153	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.181	0.0%	62314	0.000	0.003	0.013
article prepare	00:00:00.003	0.0%	10384	0.000	0.000	0.042
article read	00:00:44.884	0.1%	2598	0.000	17.276	582.000
backlog stats	00:00:00.156	0.0%	189870	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:02.067	0.0%	100748	0.005	0.021	0.042
data write	00:00:03.947	0.0%	79569	0.009	0.050	0.207
idle	24:02:46.886	99.9%	189870	264.307	455.927	795.645
status file	00:00:13.976	0.0%	16903	0.567	0.827	4.292
TOTAL: 24:04:08.890	24:03:52.100	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:22.565	62.7%	3064	0.000	7.365	3268.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.160	0.4%	6794	0.000	0.024	0.333
readart	00:00:00.020	0.1%	52	0.000	0.385	9.500
TOTAL: 00:00:36.001	00:00:22.745	63.2%	-	-	-	-

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	1112	2250609	2240291	6193	4125	99%	353:46:42
2	news.nask.pl	2	16152	10721	2629	2802	66%	23:46:44
3	news.icm.edu.pl	45	199	12	186	1	6%	15:53:24
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1164	2266964	2251028	9008	6928	99%	393:26:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	672.2 GB	24.8 MB	25.4 MB	672.2 GB	99%	314.0 KB
2	news.nask.pl	56.0 MB	841.6 KB	15.3 MB	72.1 MB	77%	5.5 KB
3	news.icm.edu.pl	372.3 KB	0.0 KB	138.9 KB	511.2 KB	72%	39.3 KB
4	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.4 KB
TOTAL: 4		672.2 GB	25.6 MB	40.8 MB	672.3 GB	99%	312.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 16 04:15:02 - 04:59:59	59954	2.7%	22.22	17.6 GB	2.6%	6835.92
Aug 16 05:00:00 - 05:59:59	79265	3.5%	22.02	23.4 GB	3.5%	6802.91
Aug 16 06:00:00 - 06:59:59	78911	3.5%	21.92	23.1 GB	3.4%	6739.72
Aug 16 07:00:00 - 07:59:59	71889	3.2%	19.97	19.9 GB	3.0%	5792.31
Aug 16 08:00:00 - 08:59:59	80870	3.6%	22.46	21.7 GB	3.2%	6332.93
Aug 16 09:00:00 - 09:59:59	73890	3.3%	20.52	19.2 GB	2.9%	5605.64
Aug 16 10:00:00 - 10:59:59	70778	3.1%	19.66	19.6 GB	2.9%	5709.13
Aug 16 11:00:00 - 11:59:59	81000	3.6%	22.50	23.9 GB	3.6%	6960.42
Aug 16 12:00:00 - 12:59:59	130489	5.8%	36.25	38.4 GB	5.7%	11189.67
Aug 16 13:00:00 - 13:59:59	130569	5.8%	36.27	38.1 GB	5.7%	11084.01
Aug 16 14:00:00 - 14:59:59	127898	5.7%	35.53	37.5 GB	5.6%	10932.79
Aug 16 15:00:00 - 15:59:59	127796	5.7%	35.50	38.1 GB	5.7%	11100.43
Aug 16 16:00:00 - 16:59:59	93168	4.1%	25.88	29.3 GB	4.4%	8535.28
Aug 16 17:00:00 - 17:59:59	113194	5.0%	31.44	34.8 GB	5.2%	10142.33
Aug 16 18:00:00 - 18:59:59	97882	4.3%	27.19	30.3 GB	4.5%	8829.23
Aug 16 19:00:00 - 19:59:59	90142	4.0%	25.04	27.6 GB	4.1%	8028.73
Aug 16 20:00:00 - 20:59:59	98505	4.4%	27.36	30.2 GB	4.5%	8794.26
Aug 16 21:00:00 - 21:59:59	70629	3.1%	19.62	22.9 GB	3.4%	6667.67
Aug 16 22:00:00 - 22:59:59	61422	2.7%	17.06	17.7 GB	2.6%	5150.49
Aug 16 23:00:00 - 23:59:59	67622	3.0%	18.78	19.5 GB	2.9%	5673.63
Aug 17 00:00:00 - 00:59:59	110085	4.9%	30.58	31.8 GB	4.7%	9252.68
Aug 17 01:00:00 - 01:59:59	114877	5.1%	31.91	34.3 GB	5.1%	9977.83
Aug 17 02:00:00 - 02:59:59	105143	4.7%	29.21	33.4 GB	5.0%	9731.21
Aug 17 03:00:00 - 03:59:59	94794	4.2%	26.33	31.5 GB	4.7%	9175.44
Aug 17 04:00:00 - 04:15:04	19951	0.9%	22.07	7.1 GB	1.1%	8188.65
TOTAL: 24:00:02	2250723	100.0%	26.05	670.8 GB	100.0%	8140.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4500	147	3920	4	429
2	news.nask.pl	2997	26	313	3	2655
3	news.icm.edu.pl	1	0	0	0	1
TOTAL: 3		7498	173	4233	7	3085

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	107
comp.sys.raspberry-pi	17
it.tlc.cellulari.android	17
comp.mobile.ipad	11
it.comp.os.win.windows10	8
comp.sys.mac.vintage	5
comp.mobile.android	2
de.comp.lang.vbclassic	2
it.comp.os.win.windows7	2
it.comp.os.linux.ubuntu	2
TOTAL: 10	173

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

Groups	Count
pl.praca.oferowana	6
bit.listserv.catholic	1
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2304
Binary in non-binary group	90
Scoring filter (9)	24
Scoring filter (8)	19
Scoring filter (10)	10
Scoring filter (11)	6
Scoring filter (14)	5
HTML post	3
Too many newsgroups	3
Scoring filter (12)	1
TOTAL: 10	2465

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	380
	news.nask.pl	211
	TOTAL: 2	591
TOTAL: 1		591

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43789	725	14679	369	25	46212	1%	24:00:00
2	poznan	17906	315	10442	31	0	0	1%	24:00:00
3	icm	29680	77	20723	612	1	1	0%	24:00:00
4	nask	5368	10	3764	0	0	0	0%	23:59:51
5	okapi	0	0	0	0	0	2224	0%	23:57:53
TOTAL: 5		96743	1127	49608	1012	26	48437	1%	119:57:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.3 MB	1.4 MB	3.7 MB	0.0 KB/s	3.4 KB	24:00:00
2	poznan	1.4 MB	180.2 KB	1.5 MB	0.0 KB/s	4.6 KB	24:00:00
3	icm	325.6 KB	190.9 MB	191.2 MB	2.3 KB/s	284.1 KB	24:00:00
4	nask	122.2 KB	0.0 KB	122.2 KB	0.0 KB/s	12.2 KB	23:59:51
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:53
TOTAL: 5		4.1 MB	192.4 MB	196.5 MB	0.5 KB/s	94.1 KB	119:57:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	52	65.9 KB	23	0	00:00:23
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:02
TOTAL: 2		292	52	65.9 KB	23	4	00:00:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	52	65.9 KB	23	4	00:00:30
2	unresolved	3	0	0.0 KB	0	0	00:00:04
TOTAL: 2		679	52	65.9 KB	23	4	00:00:35

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.244	2.404	00:00:23
164.52.25.68	0.016	0.024	00:00:04
mon.man.lodz.pl	1.244	2.408	00:00:03
isvcs01.man.lodz.pl	0.024	0.036	00:00:02
david.man.lodz.pl	0.368	0.740	00:00:01
TOTAL: 5	2.896	5.612	00:00:35

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
164.52.25.68	3
TOTAL: 3	387

NNRP unrecognized commands (by host):

System	Conn
164.52.25.68	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
#026#003#001#001"#001	1
ÅÐ˜—VÒÌc3#010)ëí#006Šàz	1
À/À+À'À#À#023À	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
164.52.25.68	2	3
TOTAL: 1	2	3

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	28
2	pl.comp.os.ms-windows.winnt	24
TOTAL: 2		52

Daily Usenet report

Aug 16 04:15:02 -- Aug 17 04:15:04