Daily Usenet report: Oct 7 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Oct 7 08:43:09 newsfeed nnrpd[1238320]: SERVER perl filter not defined
Oct 7 08:44:24 newsfeed nnrpd[1238331]: SERVER perl filter not defined
Oct 7 09:20:09 newsfeed overchan[1224215]: input line too long, skipping
Oct 7 09:26:12 newsfeed nnrpd[1240504]: SERVER perl filter not defined
Oct 7 11:31:56 newsfeed overchan[1224215]: input line too long, skipping
Oct 7 13:04:41 newsfeed nnrpd[1252161]: SERVER perl filter not defined
Oct 7 14:35:07 newsfeed nnrpd[1256827]: SERVER perl filter not defined
Oct 7 15:44:35 newsfeed overchan[1224215]: input line too long, skipping
Oct 7 16:28:10 newsfeed overchan[1224215]: input line too long, skipping
Oct 7 19:23:37 newsfeed overchan[1224215]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	727922	95.2%	79.8 MB	94.5%
innfeed	23800	3.1%	2.8 MB	3.4%
innd	9496	1.2%	1.5 MB	1.7%
nnrpd	3394	0.4%	324.0 KB	0.4%
overchan	6	0.0%	1.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	764619	100.0%	84.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	726439	99.8%
Negative hits	1548	0.2%
Positive hits	179	0.0%
Cache misses	37	0.0%
TOTAL: 4	728203	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.369	0.0%	69	0.000	19.841	88.000
article cleanup	00:00:05.348	0.0%	727618	0.005	0.007	0.011
article logging	00:00:16.590	0.0%	727869	0.016	0.023	0.033
article parse	00:09:30.341	0.7%	87484321	0.004	0.007	0.010
article write	00:05:54.707	0.4%	727329	0.323	0.488	0.609
artlog/artcncl	00:00:00.002	0.0%	68	0.000	0.029	1.000
data move	00:01:23.051	0.1%	93188108	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.308	0.0%	68	0.000	4.529	27.000
hishave/artcncl	00:00:00.010	0.0%	69	0.000	0.145	1.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:59.565	0.4%	729448	0.202	0.493	4.870
history sync	00:00:00.020	0.0%	290	0.000	0.069	0.500
history write	00:13:14.358	0.9%	727602	0.306	1.092	9.978
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	22:03:45.691	91.9%	86697594	0.564	0.916	1.343
nntp read	00:28:32.621	2.0%	87026813	0.015	0.020	0.026
overview write	00:00:02.073	0.0%	727329	0.001	0.003	0.005
perl filter	00:41:49.149	2.9%	727602	2.696	3.449	4.260
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:21.051	0.0%	1464058	0.012	0.014	0.018
TOTAL: 24:00:03.252	23:50:56.254	99.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	30184	0.000	0.002	0.016
article prepare	00:00:00.002	0.0%	16940	0.000	0.000	0.012
article read	00:00:09.012	0.0%	4265	0.000	2.113	13.143
backlog stats	00:00:00.038	0.0%	82039	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	34	0.000	0.000	0.000
data read	00:00:00.372	0.0%	39622	0.000	0.009	0.037
data write	00:00:00.923	0.0%	30512	0.000	0.030	0.067
idle	23:56:06.738	100.0%	82039	706.173	1050.314	2076.227
status file	00:00:04.682	0.0%	7829	0.400	0.598	1.983
TOTAL: 23:56:24.496	23:56:21.815	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:20.981	75.4%	2786	0.000	7.531	9759.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.086	0.3%	5926	0.000	0.015	0.500
readart	00:00:00.000	0.0%	38	0.000	0.000	0.000
TOTAL: 00:00:27.809	00:00:21.067	75.8%	-	-	-	-

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	164	727722	727474	0	248	99%	87:13:51
2	news.icm.edu.pl	47	193	18	134	41	9%	13:26:57
3	localhost	1446	5	5	0	0	100%	00:00:00
TOTAL: 3		1657	727920	727497	134	289	99%	100:40:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	345.7 GB	1.1 MB	1.8 MB	345.7 GB	99%	498.1 KB
2	news.icm.edu.pl	1.2 MB	0.0 KB	2.2 MB	3.4 MB	35%	58.5 KB
3	localhost	7.5 KB	0.0 KB	0.0 KB	7.5 KB	100%	1.5 KB
TOTAL: 3		345.7 GB	1.1 MB	4.0 MB	345.7 GB	99%	498.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 04:15:02 - 04:59:59	18320	2.5%	6.79	10.3 GB	3.0%	3986.68
Oct 7 05:00:00 - 05:59:59	31673	4.4%	8.80	14.6 GB	4.2%	4240.70
Oct 7 06:00:00 - 06:59:59	28133	3.9%	7.81	15.0 GB	4.3%	4354.76
Oct 7 07:00:00 - 07:59:59	26373	3.6%	7.33	13.8 GB	4.0%	4028.16
Oct 7 08:00:00 - 08:59:59	29111	4.0%	8.09	14.0 GB	4.0%	4066.88
Oct 7 09:00:00 - 09:59:59	27379	3.8%	7.61	13.7 GB	4.0%	3989.61
Oct 7 10:00:00 - 10:59:59	32575	4.5%	9.05	15.6 GB	4.5%	4535.08
Oct 7 11:00:00 - 11:59:59	28924	4.0%	8.03	14.5 GB	4.2%	4218.39
Oct 7 12:00:00 - 12:59:59	31847	4.4%	8.85	15.1 GB	4.4%	4405.93
Oct 7 13:00:00 - 13:59:59	36810	5.1%	10.22	15.0 GB	4.3%	4378.04
Oct 7 14:00:00 - 14:59:59	27825	3.8%	7.73	13.0 GB	3.8%	3800.29
Oct 7 15:00:00 - 15:59:59	34548	4.8%	9.60	13.8 GB	4.0%	4017.59
Oct 7 16:00:00 - 16:59:59	35583	4.9%	9.88	14.8 GB	4.3%	4318.90
Oct 7 17:00:00 - 17:59:59	31669	4.4%	8.80	15.2 GB	4.4%	4418.30
Oct 7 18:00:00 - 18:59:59	34770	4.8%	9.66	14.3 GB	4.1%	4171.34
Oct 7 19:00:00 - 19:59:59	32397	4.5%	9.00	14.3 GB	4.1%	4155.86
Oct 7 20:00:00 - 20:59:59	29747	4.1%	8.26	14.2 GB	4.1%	4121.76
Oct 7 21:00:00 - 21:59:59	30479	4.2%	8.47	15.1 GB	4.4%	4412.60
Oct 7 22:00:00 - 22:59:59	29316	4.0%	8.14	14.3 GB	4.1%	4162.64
Oct 7 23:00:00 - 23:59:59	29362	4.0%	8.16	13.7 GB	3.9%	3977.18
Oct 8 00:00:00 - 00:59:59	31705	4.4%	8.81	14.4 GB	4.2%	4187.37
Oct 8 01:00:00 - 01:59:59	28707	3.9%	7.97	14.7 GB	4.2%	4271.01
Oct 8 02:00:00 - 02:59:59	28175	3.9%	7.83	15.5 GB	4.5%	4510.09
Oct 8 03:00:00 - 03:59:59	25141	3.5%	6.98	13.5 GB	3.9%	3921.05
Oct 8 04:00:00 - 04:15:02	6745	0.9%	7.48	3.7 GB	1.1%	4328.34
TOTAL: 24:00:00	727314	100.0%	8.42	345.8 GB	100.0%	4197.33

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	499	226	16	6	251
2	news.icm.edu.pl	41	0	0	0	41
TOTAL: 2		540	226	16	6	292

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	160
it.tlc.cellulari.android	27
alt.comp.software.seamonkey	11
alt.comp.software.firefox	8
it.comp.os.win.windows10	6
nl.comp.os.linux.techniek	4
de.rec.sf.startrek	3
de.comp.office-pakete.ms-office.excel	2
it.comp.os.linux.ubuntu	2
it.discussioni.energia	1
comp.sys.raspberry-pi	1
it.comp.os.win.windows8	1
TOTAL: 12	226

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	32
Binary in non-binary group	3
Scoring filter (8)	3
Scoring filter (9)	2
Scoring filter (11)	1
TOTAL: 5	41

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	14278	3503	5386	223	0	0	24%	23:59:56
2	panorama	21383	94	8232	156	61	21710	0%	23:59:28
3	nask	0	0	0	0	0	6314	0%	23:57:47
4	poznan	9	0	7	0	0	0	0%	01:00:11
5	okapi	0	0	0	0	0	696	0%	23:57:47
TOTAL: 5		35670	3597	13625	379	61	28720	10%	96:55:09

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.1 MB	1.0 MB	15.1 MB	0.2 KB/s	4.2 KB	23:59:56
2	panorama	277.2 KB	3.7 MB	3.9 MB	0.0 KB/s	16.1 KB	23:59:28
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
4	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:00:11
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
TOTAL: 5		14.4 MB	4.7 MB	19.1 MB	0.1 KB/s	4.9 KB	96:55:09

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	294	38	39.7 KB	18	0	00:00:09
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		299	38	39.7 KB	18	5	00:00:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	299	38	39.7 KB	18	5	00:00:09
2	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	0	00:00:01
3	*.census.shodan.io	2	0	0.0 KB	0	0	00:00:01
4	unresolved	103	0	0.0 KB	0	0	00:00:14
TOTAL: 4		405	38	39.7 KB	18	5	00:00:27

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
192.241.206.46	0.004	0.004	00:00:09
web.man.lodz.pl	0.853	1.699	00:00:09
80.111.142.213	0.023	0.014	00:00:02
lovely.monitoring.internet-measurement.com	0.000	0.008	00:00:01
refrigerator.census.shodan.io	0.009	0.000	00:00:01
212.51.192.185	0.206	0.529	00:00:01
einstein.census.shodan.io	0.000	0.008	00:00:00
79.124.62.106	0.002	0.009	00:00:00
192.241.192.72	0.000	0.008	00:00:00
isvcs01.man.lodz.pl	0.024	0.037	00:00:00
TOTAL: 10	1.121	2.316	00:00:27

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
79.124.62.106	1
refrigerator.census.shodan.io	1
192.241.206.46	1
192.241.192.72	1
einstein.census.shodan.io	1
lovely.monitoring.internet-measurement.com	1
TOTAL: 8	106

NNRP unrecognized commands (by host):

System	Conn
79.124.62.106	1
192.241.192.72	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
#003	1
MGLNDD_212.191.2.21_119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
79.124.62.106	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	36	94.7%
2	lodman	2	5.3%
TOTAL: 2		38	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	22
2	pl.comp.pecet	14
3	lodman.test	2
TOTAL: 3		38

Daily Usenet report

Oct 7 04:15:02 -- Oct 8 04:15:02