Daily Usenet report: Dec 20 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Dec 20 06:47:02 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 06:50:06 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 06:52:19 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 10:03:03 newsfeed nnrpd[1039865]: SERVER perl filter not defined
Dec 20 10:17:11 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 10:17:46 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 11:30:47 newsfeed nnrpd[1044090]: SERVER perl filter not defined
Dec 20 11:31:56 newsfeed nnrpd[1044101]: SERVER perl filter not defined
Dec 20 15:08:14 newsfeed nnrpd[1054180]: SERVER perl filter not defined
Dec 20 15:31:35 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 15:45:49 newsfeed overchan[1022917]: input line too long, skipping
Dec 20 20:01:51 newsfeed overchan[1022917]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	812938	95.8%	88.4 MB	95.1%
innfeed	22711	2.7%	2.7 MB	2.9%
innd	9897	1.2%	1.5 MB	1.6%
nnrpd	3364	0.4%	321.1 KB	0.3%
overchan	9	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	848920	100.0%	92.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	807739	99.8%
Negative hits	1625	0.2%
Positive hits	184	0.0%
Cache misses	12	0.0%
TOTAL: 4	809560	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.440	0.0%	26	0.000	16.923	93.000
article cleanup	00:00:03.383	0.0%	813613	0.002	0.004	0.007
article logging	00:00:12.544	0.0%	813886	0.011	0.015	0.029
article parse	00:06:53.010	0.5%	180376784	0.002	0.002	0.003
article write	00:04:25.582	0.3%	813464	0.188	0.326	0.450
artlog/artcncl	00:00:00.000	0.0%	26	0.000	0.000	0.000
data move	00:00:26.905	0.0%	186731889	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.101	0.0%	26	0.000	3.885	21.000
hishave/artcncl	00:00:00.001	0.0%	26	0.000	0.038	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:59.062	0.4%	815879	0.109	0.440	7.712
history sync	00:00:00.005	0.0%	290	0.000	0.017	0.500
history write	00:18:47.453	1.3%	813605	0.168	1.386	13.526
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	22:30:52.693	93.8%	179417627	0.314	0.452	0.803
nntp read	00:17:01.297	1.2%	179900885	0.005	0.006	0.006
overview write	00:00:01.609	0.0%	813464	0.000	0.002	0.006
perl filter	00:24:38.139	1.7%	813605	1.496	1.817	2.207
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:13.148	0.0%	1637072	0.006	0.008	0.013
TOTAL: 24:00:02.928	23:49:35.372	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	29891	0.000	0.001	0.016
article prepare	00:00:00.000	0.0%	6018	0.000	0.000	0.000
article read	00:00:08.783	0.0%	1534	0.000	5.726	39.571
backlog stats	00:00:00.028	0.0%	84770	0.000	0.000	0.005
callbacks	00:00:00.000	0.0%	32	0.000	0.000	0.000
data read	00:00:00.276	0.0%	40041	0.000	0.007	0.024
data write	00:00:00.590	0.0%	30509	0.000	0.019	0.053
idle	23:56:18.633	100.0%	84770	598.222	1016.617	2317.124
status file	00:00:02.867	0.0%	7387	0.100	0.388	1.684
TOTAL: 23:56:32.956	23:56:31.211	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:14.736	71.3%	2756	0.000	5.347	5227.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.044	0.2%	5834	0.000	0.008	0.500
readart	00:00:00.000	0.0%	18	0.000	0.000	0.000
TOTAL: 00:00:20.664	00:00:14.780	71.5%	-	-	-	-

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	175	812351	812218	0	133	99%	86:58:01
2	news.icm.edu.pl	40	366	188	162	16	51%	13:30:28
3	localhost	1445	4	4	0	0	100%	00:00:00
TOTAL: 3		1660	812721	812410	162	149	99%	100:28:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	358.7 GB	504.6 KB	901.9 KB	358.7 GB	99%	463.0 KB
2	news.icm.edu.pl	5.4 MB	10.4 KB	470.9 KB	5.8 MB	91%	29.3 KB
3	localhost	5.8 KB	0.0 KB	0.0 KB	5.8 KB	100%	1.5 KB
TOTAL: 3		358.7 GB	514.9 KB	1.3 MB	358.7 GB	99%	462.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 20 04:15:02 - 04:59:59	25854	3.2%	9.58	10.4 GB	2.9%	4035.68
Dec 20 05:00:00 - 05:59:59	32222	4.0%	8.95	14.3 GB	4.0%	4159.42
Dec 20 06:00:00 - 06:59:59	32018	3.9%	8.89	14.6 GB	4.1%	4266.95
Dec 20 07:00:00 - 07:59:59	33474	4.1%	9.30	14.7 GB	4.1%	4288.68
Dec 20 08:00:00 - 08:59:59	28279	3.5%	7.86	14.0 GB	3.9%	4081.76
Dec 20 09:00:00 - 09:59:59	29977	3.7%	8.33	12.9 GB	3.6%	3763.50
Dec 20 10:00:00 - 10:59:59	26112	3.2%	7.25	12.6 GB	3.5%	3671.08
Dec 20 11:00:00 - 11:59:59	33876	4.2%	9.41	14.3 GB	4.0%	4157.73
Dec 20 12:00:00 - 12:59:59	34667	4.3%	9.63	16.4 GB	4.6%	4787.03
Dec 20 13:00:00 - 13:59:59	32462	4.0%	9.02	15.6 GB	4.3%	4536.49
Dec 20 14:00:00 - 14:59:59	36789	4.5%	10.22	16.1 GB	4.5%	4688.45
Dec 20 15:00:00 - 15:59:59	27310	3.4%	7.59	12.6 GB	3.5%	3682.78
Dec 20 16:00:00 - 16:59:59	36958	4.5%	10.27	14.0 GB	3.9%	4077.16
Dec 20 17:00:00 - 17:59:59	27818	3.4%	7.73	13.3 GB	3.7%	3875.73
Dec 20 18:00:00 - 18:59:59	31900	3.9%	8.86	14.5 GB	4.0%	4222.25
Dec 20 19:00:00 - 19:59:59	32796	4.0%	9.11	14.0 GB	3.9%	4068.78
Dec 20 20:00:00 - 20:59:59	24359	3.0%	6.77	12.7 GB	3.5%	3699.11
Dec 20 21:00:00 - 21:59:59	31980	3.9%	8.88	14.2 GB	3.9%	4126.00
Dec 20 22:00:00 - 22:59:59	36115	4.4%	10.03	14.1 GB	3.9%	4111.03
Dec 20 23:00:00 - 23:59:59	41583	5.1%	11.55	14.9 GB	4.2%	4353.12
Dec 21 00:00:00 - 00:59:59	48781	6.0%	13.55	19.7 GB	5.5%	5739.20
Dec 21 01:00:00 - 01:59:59	43802	5.4%	12.17	19.4 GB	5.4%	5636.94
Dec 21 02:00:00 - 02:59:59	43211	5.3%	12.00	20.4 GB	5.7%	5943.34
Dec 21 03:00:00 - 03:59:59	32417	4.0%	9.00	15.0 GB	4.2%	4361.28
Dec 21 04:00:00 - 04:15:01	7730	1.0%	8.58	4.2 GB	1.2%	4870.40
TOTAL: 23:59:59	812490	100.0%	9.40	358.9 GB	100.0%	4355.94

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	406	121	7	5	273
2	news.icm.edu.pl	16	0	1	0	15
TOTAL: 2		422	121	8	5	288

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	69
it.tlc.cellulari.android	15
comp.sys.raspberry-pi	12
alt.comp.software.firefox	6
it.comp.os.win.windows10	5
it.comp.os.linux.ubuntu	5
de.comp.office-pakete.ms-office.excel	3
comp.mobile.android	2
de.comp.lang.vbclassic	2
de.comm.software.mailreader	1
alt.comp.software.seamonkey	1
TOTAL: 11	121

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

Groups	Count
pl.praca.oferowana	3
misc.activism.militia	2
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11
Binary in non-binary group	3
Too many newsgroups	1
TOTAL: 3	15

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	17973	883	8754	52	0	0	4%	23:59:54
2	panorama	20050	116	7660	163	59	21952	0%	23:59:28
3	okapi	0	0	0	0	0	1092	0%	23:57:48
4	nask	0	0	0	0	0	7235	0%	23:57:48
5	poznan	213	0	182	3	0	0	0%	03:00:44
TOTAL: 5		38236	999	16596	218	59	30279	2%	98:55:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	7.2 MB	278.8 KB	7.4 MB	0.1 KB/s	8.1 KB	23:59:54
2	panorama	316.6 KB	2.4 MB	2.7 MB	0.0 KB/s	9.9 KB	23:59:28
3	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
5	poznan	0.0 KB	82.0 KB	82.0 KB	0.0 KB/s	27.3 KB	03:00:44
TOTAL: 5		7.5 MB	2.7 MB	10.2 MB	0.0 KB/s	8.6 KB	98:55:42

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	296	18	16.7 KB	7	0	00:00:08
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		300	18	16.7 KB	7	4	00:00:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	300	18	16.7 KB	7	4	00:00:09
2	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:05
3	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	0	00:00:02
4	unresolved	100	0	0.0 KB	0	0	00:00:03
5	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	00:00:00
TOTAL: 5		404	18	16.7 KB	7	4	00:00:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	0.547	1.766	00:00:08
refrigerator.census.shodan.io	0.007	0.000	00:00:05
agreeable.monitoring.internet-measurement.com	0.000	0.007	00:00:02
80.111.142.213	0.000	0.031	00:00:02
212.51.192.185	0.180	0.528	00:00:01
scanner-04.ch1.censys-scanner.com	0.009	0.006	00:00:00
isvcs01.man.lodz.pl	0.012	0.038	00:00:00
TOTAL: 7	0.755	2.376	00:00:20

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
scanner-04.ch1.censys-scanner.com	2
agreeable.monitoring.internet-measurement.com	1
refrigerator.census.shodan.io	1
TOTAL: 5	104

NNRP unrecognized commands (by host):

System	Conn
scanner-04.ch1.censys-scanner.com	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
TOTAL: 1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	14
2	pl.comp.os.ms-windows.winnt	4
TOTAL: 2		18

Daily Usenet report

Dec 20 04:15:02 -- Dec 21 04:15:01