Daily Usenet report: Sep 7 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Sep 7 07:24:36 newsfeed overchan[907926]: input line too long, skipping
Sep 7 07:43:28 newsfeed overchan[907926]: input line too long, skipping
Sep 7 09:02:58 newsfeed nnrpd[922788]: SERVER perl filter not defined
Sep 7 10:04:42 newsfeed overchan[907926]: input line too long, skipping
Sep 7 14:17:16 newsfeed nnrpd[938985]: SERVER perl filter not defined
Sep 7 16:08:03 newsfeed nnrpd[944480]: SERVER perl filter not defined
Sep 7 19:17:21 newsfeed overchan[907926]: input line too long, skipping
Sep 7 19:46:45 newsfeed overchan[907926]: input line too long, skipping
Sep 7 20:46:01 newsfeed overchan[907926]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	592802	94.3%	65.9 MB	93.7%
innfeed	23916	3.8%	2.8 MB	4.0%
innd	8639	1.4%	1.3 MB	1.9%
nnrpd	3350	0.5%	315.7 KB	0.4%
overchan	7	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	628715	100.0%	70.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	588288	99.6%
Negative hits	1919	0.3%
Positive hits	210	0.0%
Cache misses	38	0.0%
TOTAL: 4	590455	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.205	0.0%	85	0.000	14.176	99.000
article cleanup	00:00:04.073	0.0%	592432	0.004	0.007	0.011
article logging	00:00:12.900	0.0%	592893	0.013	0.022	0.032
article parse	00:06:58.312	0.5%	112549908	0.003	0.004	0.005
article write	00:04:18.818	0.3%	592260	0.263	0.437	0.612
artlog/artcncl	00:00:00.001	0.0%	85	0.000	0.012	0.083
data move	00:00:32.727	0.0%	117334041	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.190	0.0%	83	0.000	2.289	16.000
hishave/artcncl	00:00:00.026	0.0%	85	0.000	0.306	1.600
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:23.948	0.4%	594617	0.205	0.545	2.435
history sync	00:00:00.009	0.0%	290	0.000	0.031	0.500
history write	00:19:30.740	1.4%	592422	0.460	1.976	7.291
hiswrite/artcncl	00:00:00.002	0.0%	2	1.000	1.000	1.000
idle	22:19:03.883	93.0%	112360757	0.488	0.715	1.009
nntp read	00:21:57.395	1.5%	112239164	0.011	0.012	0.014
overview write	00:00:01.533	0.0%	592260	0.001	0.003	0.005
perl filter	00:30:05.407	2.1%	592422	2.323	3.048	3.522
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:16.097	0.0%	1195378	0.010	0.013	0.017
TOTAL: 24:00:04.698	23:48:27.266	99.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	31541	0.000	0.001	0.015
article prepare	00:00:00.001	0.0%	6674	0.000	0.000	0.023
article read	00:00:06.253	0.0%	1685	0.000	3.711	41.000
backlog stats	00:00:00.061	0.0%	91969	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	17	0.000	0.000	0.000
data read	00:00:00.439	0.0%	43563	0.000	0.010	0.047
data write	00:00:01.099	0.0%	33146	0.005	0.033	0.078
idle	23:55:06.625	100.0%	91969	595.806	936.257	2166.805
status file	00:00:04.216	0.0%	8038	0.367	0.525	1.865
TOTAL: 23:55:20.838	23:55:18.729	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:02:52.668	99.9%	2909	0.000	2534.434	300048.833
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.098	0.0%	6485	0.000	0.015	0.500
readart	00:00:02.282	0.0%	142	0.000	16.070	17.554
TOTAL: 02:03:01.421	02:02:55.048	99.9%	-	-	-	-

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	127	592339	592175	0	164	99%	71:55:58
2	news.icm.edu.pl	52	165	10	148	7	6%	13:58:30
3	localhost	1444	3	3	0	0	100%	00:00:00
TOTAL: 3		1623	592507	592188	148	171	99%	85:54:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	270.6 GB	921.6 KB	1.3 MB	270.6 GB	99%	479.0 KB
2	news.icm.edu.pl	881.7 KB	0.0 KB	183.3 KB	1.0 MB	82%	62.6 KB
3	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	1.4 KB
TOTAL: 3		270.6 GB	921.6 KB	1.5 MB	270.6 GB	99%	479.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 04:15:03 - 04:59:59	14975	2.5%	5.55	7.5 GB	2.8%	2910.20
Sep 7 05:00:00 - 05:59:59	21368	3.6%	5.94	10.7 GB	3.9%	3110.48
Sep 7 06:00:00 - 06:59:59	21628	3.7%	6.01	11.2 GB	4.1%	3255.91
Sep 7 07:00:00 - 07:59:59	21878	3.7%	6.08	10.3 GB	3.8%	3005.45
Sep 7 08:00:00 - 08:59:59	21168	3.6%	5.88	11.2 GB	4.1%	3274.19
Sep 7 09:00:00 - 09:59:59	23566	4.0%	6.55	11.5 GB	4.2%	3350.31
Sep 7 10:00:00 - 10:59:59	27192	4.6%	7.55	10.8 GB	4.0%	3132.99
Sep 7 11:00:00 - 11:59:59	25267	4.3%	7.02	11.3 GB	4.2%	3287.81
Sep 7 12:00:00 - 12:59:59	25644	4.3%	7.12	11.8 GB	4.4%	3439.18
Sep 7 13:00:00 - 13:59:59	27741	4.7%	7.71	12.0 GB	4.4%	3508.86
Sep 7 14:00:00 - 14:59:59	27986	4.7%	7.77	13.1 GB	4.8%	3819.50
Sep 7 15:00:00 - 15:59:59	23500	4.0%	6.53	11.2 GB	4.1%	3252.34
Sep 7 16:00:00 - 16:59:59	26654	4.5%	7.40	11.4 GB	4.2%	3321.42
Sep 7 17:00:00 - 17:59:59	28418	4.8%	7.89	10.3 GB	3.8%	3011.42
Sep 7 18:00:00 - 18:59:59	28284	4.8%	7.86	10.7 GB	3.9%	3118.20
Sep 7 19:00:00 - 19:59:59	22117	3.7%	6.14	9.8 GB	3.6%	2850.15
Sep 7 20:00:00 - 20:59:59	22676	3.8%	6.30	10.7 GB	3.9%	3116.41
Sep 7 21:00:00 - 21:59:59	24850	4.2%	6.90	11.2 GB	4.1%	3268.70
Sep 7 22:00:00 - 22:59:59	22050	3.7%	6.12	10.9 GB	4.0%	3161.13
Sep 7 23:00:00 - 23:59:59	22711	3.8%	6.31	10.8 GB	4.0%	3157.32
Sep 8 00:00:00 - 00:59:59	23983	4.1%	6.66	12.5 GB	4.6%	3643.65
Sep 8 01:00:00 - 01:59:59	28328	4.8%	7.87	12.8 GB	4.7%	3728.44
Sep 8 02:00:00 - 02:59:59	31751	5.4%	8.82	13.0 GB	4.8%	3786.25
Sep 8 03:00:00 - 03:59:59	22980	3.9%	6.38	11.6 GB	4.3%	3372.09
Sep 8 04:00:00 - 04:15:01	5371	0.9%	5.96	2.9 GB	1.1%	3387.18
TOTAL: 23:59:58	592086	100.0%	6.85	271.2 GB	100.0%	3291.75

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	623	150	10	4	459
2	news.icm.edu.pl	8	0	0	0	8
TOTAL: 2		631	150	10	4	467

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
it.comp.os.win.windows10	13
alt.comp.software.seamonkey	11
nl.comp.os.linux.techniek	2
comp.mobile.android	2
it.discussioni.energia	1
it.comp.os.win.windows8	1
TOTAL: 7	150

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7
Scoring filter (8)	1
TOTAL: 2	8

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	19580	855	9355	283	0	1	4%	23:59:54
2	panorama	21603	144	8075	151	34	23225	0%	23:59:56
3	poznan	10	0	7	0	0	0	0%	00:42:31
4	okapi	0	0	0	0	0	819	0%	23:57:45
5	nask	0	0	0	0	0	7175	0%	23:57:45
TOTAL: 5		41193	999	17437	434	34	31220	2%	96:37:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.5 MB	57.5 MB	62.0 MB	0.7 KB/s	55.8 KB	23:59:54
2	panorama	370.1 KB	951.3 KB	1.3 MB	0.0 KB/s	4.5 KB	23:59:56
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:42:31
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:45
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:45
TOTAL: 5		4.9 MB	58.4 MB	63.3 MB	0.2 KB/s	45.2 KB	96:37:51

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	msqa.csk.umed.lodz.pl	2	130	271.1 KB	1	0	02:02:21
2	web.man.lodz.pl	288	12	10.2 KB	5	0	00:00:08
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		293	142	281.3 KB	6	3	02:03:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.csk.umed.lodz.pl	2	130	271.1 KB	1	0	02:02:21
2	*.man.lodz.pl	291	12	10.2 KB	5	3	00:00:09
3	*.ae1x302.dhiblang.lubman.net.pl	2	0	0.0 KB	0	0	00:00:26
4	*.ip.linodeusercontent.com	1	0	0.0 KB	0	0	00:00:00
5	unresolved	101	0	0.0 KB	0	0	00:00:03
6	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:00
7	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
TOTAL: 7		399	142	281.3 KB	6	3	02:03:01

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
msqa.csk.umed.lodz.pl	0.020	0.036	02:02:21
vikom.ae1x302.dhiblang.lubman.net.pl	0.039	0.020	00:00:26
web.man.lodz.pl	0.842	1.466	00:00:08
80.111.142.213	0.007	0.027	00:00:01
212.51.192.185	0.222	0.458	00:00:01
45-33-80-243.ip.linodeusercontent.com	0.000	0.006	00:00:00
192.241.212.140	0.000	0.006	00:00:00
ec2-35-86-189-150.us-west-2.compute.amazonaws.com	0.004	0.004	00:00:00
dojo.census.shodan.io	0.006	0.000	00:00:00
isvcs01.man.lodz.pl	0.015	0.021	00:00:00
TOTAL: 10	1.155	2.044	02:03:01

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
vikom.ae1x302.dhiblang.lubman.net.pl	2
45-33-80-243.ip.linodeusercontent.com	1
ec2-35-86-189-150.us-west-2.compute.amazonaws.com	1
192.241.212.140	1
dojo.census.shodan.io	1
TOTAL: 7	106

NNRP unrecognized commands (by host):

System	Conn
45-33-80-243.ip.linodeusercontent.com	2
192.241.212.140	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

Command	Count
#026#003#001	1
MGLNDD_212.191.2.21_119	1
#001	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
msqa.csk.umed.lodz.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	130	91.5%
2	pl	12	8.5%
TOTAL: 2		142	100.0%

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

Newsgroup		Count
1	comp.mail.sendmail	130
2	pl.comp.os.ms-windows.winnt	8
3	pl.comp.pecet	4
TOTAL: 3		142

Daily Usenet report

Sep 7 04:15:03 -- Sep 8 04:15:01