Daily Usenet report: May 22 04:15:01

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

May 22 09:26:43 newsfeed nnrpd[1326832]: SERVER perl filter not defined
May 22 10:49:33 newsfeed nnrpd[1331780]: SERVER perl filter not defined
May 22 11:40:07 newsfeed nnrpd[1334089]: SERVER perl filter not defined
May 22 11:45:40 newsfeed overchan[1312519]: input line too long, skipping
May 22 13:14:42 newsfeed nnrpd[1338123]: SERVER perl filter not defined
May 22 13:18:20 newsfeed nnrpd[1338264]: SERVER perl filter not defined
May 22 14:58:39 newsfeed nnrpd[1342637]: SERVER perl filter not defined
May 22 15:33:20 newsfeed overchan[1312519]: input line too long, skipping
May 22 15:36:19 newsfeed overchan[1312519]: input line too long, skipping
May 22 15:38:58 newsfeed overchan[1312519]: input line too long, skipping
May 22 16:30:39 newsfeed overchan[1312519]: input line too long, skipping
May 22 20:47:53 newsfeed overchan[1312519]: input line too long, skipping
May 22 20:50:34 newsfeed overchan[1312519]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	64883	67.5%	7.5 MB	67.2%
innfeed	21943	22.8%	2.6 MB	23.1%
nnrpd	7685	8.0%	863.8 KB	7.6%
innd	1591	1.7%	231.3 KB	2.0%
overchan	7	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	96110	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	64415	97.4%
Negative hits	1399	2.1%
Positive hits	292	0.4%
Cache misses	11	0.0%
TOTAL: 4	66117	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.525	0.0%	26	0.000	20.192	108.000
article cleanup	00:00:00.341	0.0%	64706	0.000	0.005	0.016
article logging	00:00:01.566	0.0%	64945	0.009	0.024	0.068
article parse	00:00:02.062	0.0%	390425	0.001	0.005	0.012
article write	00:00:11.839	0.0%	64429	0.058	0.184	2.513
artlog/artcncl	00:00:00.000	0.0%	26	0.000	0.000	0.000
data move	00:00:00.415	0.0%	541209	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.161	0.0%	26	0.000	6.192	19.500
hishave/artcncl	00:00:00.060	0.0%	26	0.000	2.308	11.750
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:06:20.433	0.4%	66378	1.825	5.731	46.432
history sync	00:00:00.014	0.0%	309	0.000	0.045	0.500
history write	00:10:03.376	0.7%	64702	3.843	9.325	52.205
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:41:54.009	98.7%	451558	59.103	188.933	692.385
nntp read	00:00:05.432	0.0%	389555	0.009	0.014	0.023
overview write	00:00:00.200	0.0%	64429	0.000	0.003	0.017
perl filter	00:01:13.886	0.1%	64702	0.857	1.142	1.493
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.948	0.0%	137454	0.000	0.014	0.023
TOTAL: 24:00:17.857	23:59:56.267	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	27289	0.000	0.001	0.013
article prepare	00:00:00.001	0.0%	2794	0.000	0.000	0.125
article read	00:00:13.219	0.0%	711	0.000	18.592	69.750
backlog stats	00:00:00.032	0.0%	62334	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	14	0.000	0.000	0.000
data read	00:00:00.269	0.0%	30760	0.000	0.009	0.028
data write	00:00:00.591	0.0%	22321	0.000	0.026	0.075
idle	23:57:18.484	100.0%	62334	840.149	1383.490	3244.151
status file	00:00:05.825	0.0%	7085	0.300	0.822	33.947
TOTAL: 23:57:40.216	23:57:38.456	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:50.923	75.1%	4215	0.000	12.081	12329.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.135	0.2%	10171	0.000	0.013	0.500
TOTAL: 00:01:07.782	00:00:51.058	75.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	77	64776	64500	11	265	99%	41:14:56
2	news.icm.edu.pl	45	298	12	273	13	4%	16:56:30
3	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 3		129	65080	64518	284	278	99%	58:11:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	975.1 MB	1.5 KB	2.3 MB	977.4 MB	99%	15.5 KB
2	news.icm.edu.pl	1.0 MB	40.9 KB	142.4 KB	1.2 MB	85%	50.0 KB
3	localhost	8.1 KB	0.0 KB	0.0 KB	8.1 KB	100%	1.4 KB
TOTAL: 3		976.1 MB	42.3 KB	2.4 MB	978.6 MB	99%	15.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 22 04:15:01 - 04:59:59	1404	2.2%	0.52	21.2 MB	2.2%	8.05
May 22 05:00:00 - 05:59:59	1835	2.9%	0.51	25.0 MB	2.6%	7.11
May 22 06:00:00 - 06:59:59	2096	3.3%	0.58	33.3 MB	3.4%	9.47
May 22 07:00:00 - 07:59:59	1701	2.6%	0.47	30.6 MB	3.1%	8.71
May 22 08:00:00 - 08:59:59	3028	4.7%	0.84	51.4 MB	5.3%	14.63
May 22 09:00:00 - 09:59:59	2625	4.1%	0.73	44.9 MB	4.6%	12.78
May 22 10:00:00 - 10:59:59	2675	4.2%	0.74	35.8 MB	3.7%	10.19
May 22 11:00:00 - 11:59:59	3464	5.4%	0.96	53.8 MB	5.5%	15.32
May 22 12:00:00 - 12:59:59	3148	4.9%	0.87	81.7 MB	8.4%	23.24
May 22 13:00:00 - 13:59:59	2947	4.6%	0.82	43.1 MB	4.4%	12.26
May 22 14:00:00 - 14:59:59	3036	4.7%	0.84	48.1 MB	4.9%	13.68
May 22 15:00:00 - 15:59:59	3025	4.7%	0.84	48.6 MB	5.0%	13.83
May 22 16:00:00 - 16:59:59	2899	4.5%	0.81	51.7 MB	5.3%	14.69
May 22 17:00:00 - 17:59:59	2501	3.9%	0.69	37.1 MB	3.8%	10.55
May 22 18:00:00 - 18:59:59	2244	3.5%	0.62	31.0 MB	3.2%	8.81
May 22 19:00:00 - 19:59:59	2196	3.4%	0.61	32.0 MB	3.3%	9.10
May 22 20:00:00 - 20:59:59	3215	5.0%	0.89	48.9 MB	5.0%	13.90
May 22 21:00:00 - 21:59:59	3172	4.9%	0.88	42.3 MB	4.3%	12.03
May 22 22:00:00 - 22:59:59	3386	5.3%	0.94	47.2 MB	4.8%	13.42
May 22 23:00:00 - 23:59:59	4251	6.6%	1.18	48.1 MB	4.9%	13.68
May 23 00:00:00 - 00:59:59	2608	4.1%	0.72	26.9 MB	2.8%	7.65
May 23 01:00:00 - 01:59:59	2361	3.7%	0.66	30.7 MB	3.2%	8.74
May 23 02:00:00 - 02:59:59	2108	3.3%	0.59	25.9 MB	2.7%	7.38
May 23 03:00:00 - 03:59:59	2105	3.3%	0.58	31.2 MB	3.2%	8.87
May 23 04:00:00 - 04:15:02	310	0.5%	0.34	3.1 MB	0.3%	3.54
TOTAL: 24:00:01	64340	100.0%	0.74	973.6 MB	100.0%	11.54

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	505	193	1	3	308
2	news.icm.edu.pl	12	0	3	0	9
TOTAL: 2		517	193	4	3	317

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	157
it.comp.os.win.windows10	12
alt.comp.software.seamonkey	10
comp.sys.raspberry-pi	6
de.rec.sf.startrek	4
it.tlc.cellulari.android	3
de.comp.office-pakete.ms-office.excel	1
TOTAL: 7	193

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

Groups	Count
pl.sci.matematyka	3
TOTAL: 1	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	69
Binary in non-binary group	7
HTML post	1
TOTAL: 3	77

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	18813	146	6891	192	25	20085	0%	23:59:40
2	icm	11051	16	8493	1	0	0	0%	23:53:55
3	poznan	18	1	11	0	0	0	5%	01:41:27
4	nask	0	0	0	0	0	6273	0%	23:50:01
5	okapi	0	0	0	0	0	604	0%	23:50:01
TOTAL: 5		29882	163	15395	193	25	26962	0%	97:15:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	panorama	439.0 KB	3.3 MB	3.7 MB	11.3 KB	23:59:40
2	icm	75.7 KB	13.8 KB	89.5 KB	5.3 KB	23:53:55
3	poznan	12.3 KB	0.0 KB	12.3 KB	12.3 KB	01:41:27
4	nask	0.0 KB	0.0 KB	0.0 KB	0	23:50:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:50:01
TOTAL: 5		527.0 KB	3.3 MB	3.8 MB	11.1 KB	97:15:04

NNRP readership statistics:

System		Conn	Post	Elapsed
1	isvcs01.man.lodz.pl	6	6	00:00:00
2	courageous.monitoring.internet-measurement.com	1	0	00:00:01
3	118.123.105.85	1	0	00:00:24
4	162.243.91.8	1	0	00:00:05
5	scanner-25.ch1.censys-scanner.com	1	0	00:00:00
6	nms1.man.lodz.pl	1440	0	00:00:13
7	92.255.85.152	1	0	00:00:00
8	80.111.142.213	4	0	00:00:02
9	198.199.95.14	1	0	00:00:09
10	212.51.192.185	96	0	00:00:00
11	45.227.254.55	1	0	00:00:00
12	web.man.lodz.pl	289	0	00:00:09
TOTAL: 12		1842	6	00:01:08

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.man.lodz.pl	1735	6	00:00:22
2	*.monitoring.internet-measurement.com	1	0	00:00:01
3	*.ch1.censys-scanner.com	1	0	00:00:00
4	unresolved	105	0	00:00:43
TOTAL: 4		1842	6	00:01:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
118.123.105.85	0.007	0.007	00:00:24
nms1.man.lodz.pl	2.879	10.207	00:00:13
198.199.95.14	0.000	0.008	00:00:09
web.man.lodz.pl	0.615	2.222	00:00:09
162.243.91.8	0.004	0.005	00:00:05
80.111.142.213	0.002	0.038	00:00:02
courageous.monitoring.internet-measurement.com	0.000	0.007	00:00:01
212.51.192.185	0.221	0.710	00:00:00
scanner-25.ch1.censys-scanner.com	0.005	0.010	00:00:00
isvcs01.man.lodz.pl	0.010	0.071	00:00:00
92.255.85.152	0.005	0.010	00:00:00
45.227.254.55	0.000	0.008	00:00:00
TOTAL: 12	3.748	13.303	00:01:08

NNRP unrecognized commands (by host):

System	Conn
92.255.85.152	1
45.227.254.55	1
scanner-25.ch1.censys-scanner.com	1
TOTAL: 3	3

NNRP unrecognized commands (by command):

Command	Count
#003	2
EHLO www.censys.io	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.55	1	1
92.255.85.152	1	1
TOTAL: 2	2	2

Daily Usenet report

May 22 04:15:01 -- May 23 04:15:02