Daily Usenet report: Mar 7 04:15:02

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Mar 7 07:04:26 newsfeed overchan[16589]: input line too long, skipping
Mar 7 07:05:28 newsfeed overchan[16589]: input line too long, skipping
Mar 7 07:05:54 newsfeed overchan[16589]: input line too long, skipping
Mar 7 09:07:45 newsfeed overchan[16589]: input line too long, skipping
Mar 7 09:36:42 newsfeed nnrpd[29325]: SERVER perl filter not defined
Mar 7 11:04:08 newsfeed nnrpd[32646]: SERVER perl filter not defined
Mar 7 11:32:19 newsfeed nnrpd[1312]: SERVER perl filter not defined
Mar 7 12:40:52 newsfeed nnrpd[3872]: SERVER perl filter not defined
Mar 7 13:35:18 newsfeed nnrpd[5798]: SERVER perl filter not defined
Mar 7 13:44:48 newsfeed nnrpd[6127]: SERVER perl filter not defined
Mar 7 13:54:34 newsfeed nnrpd[6474]: SERVER perl filter not defined
Mar 7 14:37:21 newsfeed overchan[16589]: input line too long, skipping
Mar 7 14:37:37 newsfeed overchan[16589]: input line too long, skipping
Mar 7 14:37:54 newsfeed overchan[16589]: input line too long, skipping
Mar 7 15:07:33 newsfeed overchan[16589]: input line too long, skipping
Mar 7 15:25:30 newsfeed overchan[16589]: input line too long, skipping
Mar 7 17:55:43 newsfeed nnrpd[15407]: SERVER perl filter not defined
Mar 7 17:56:37 newsfeed nnrpd[15420]: SERVER perl filter not defined
Mar 7 22:57:24 newsfeed overchan[16589]: input line too long, skipping
Mar 7 23:00:09 newsfeed overchan[16589]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3829227	98.5%	428.9 MB	98.1%
innd	29058	0.7%	4.6 MB	1.1%
innfeed	25758	0.7%	3.1 MB	0.7%
nnrpd	4829	0.1%	510.3 KB	0.1%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3888885	100.0%	437.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3784562	98.0%
Negative hits	64130	1.7%
Positive hits	12857	0.3%
Cache misses	283	0.0%
TOTAL: 4	3861832	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.365	0.0%	199	0.000	21.935	465.000
article cleanup	00:00:47.813	0.1%	3835903	0.007	0.012	0.017
article logging	00:02:54.756	0.2%	3836555	0.029	0.046	0.162
article parse	00:53:03.852	3.7%	85048497	0.022	0.037	0.049
article write	00:21:05.341	1.5%	3820770	0.175	0.331	0.467
artlog/artcncl	00:00:00.006	0.0%	194	0.000	0.031	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:07.636	0.1%	101803692	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.454	0.0%	198	0.000	2.293	24.000
hishave/artcncl	00:00:00.196	0.0%	199	0.000	0.985	15.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:35.261	2.4%	3903093	0.039	0.532	3.296
history sync	00:00:00.016	0.0%	290	0.000	0.055	0.500
history write	01:40:30.081	7.0%	3825004	0.195	1.576	6.545
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	13:00:24.249	54.2%	60664110	0.429	0.772	1.834
nntp read	00:58:04.345	4.0%	82354167	0.027	0.042	0.056
overview write	00:00:20.609	0.0%	3820770	0.003	0.005	0.007
perl filter	05:44:18.297	23.9%	3825003	3.099	5.401	6.566
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:36.885	0.5%	7661925	0.036	0.060	0.081
TOTAL: 24:00:06.233	23:25:54.162	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.116	0.0%	43038	0.000	0.003	0.014
article prepare	00:00:00.001	0.0%	8612	0.000	0.000	0.007
article read	00:01:15.904	0.1%	2185	0.000	34.739	400.000
backlog stats	00:00:00.269	0.0%	154928	0.000	0.002	0.108
callbacks	00:00:00.000	0.0%	33	0.000	0.000	0.000
data read	00:00:01.508	0.0%	82688	0.005	0.018	0.035
data write	00:00:03.797	0.0%	62691	0.016	0.061	0.158
idle	23:52:53.738	99.9%	154928	288.152	554.927	1051.372
status file	00:00:08.932	0.0%	8630	0.571	1.035	2.531
TOTAL: 23:54:37.060	23:54:24.265	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:22:18.629	99.9%	703610	0.000	124.698	363.655
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:42.201	0.0%	1407731	0.000	0.030	0.500
readart	00:00:00.004	0.0%	21	0.000	0.190	1.000
TOTAL: 24:23:58.012	24:23:00.834	99.9%	-	-	-	-

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	1324	3812962	3801525	559	10878	99%	448:01:14
2	news.nask.pl	3	16708	11407	1124	4177	68%	23:30:18
3	news.icm.edu.pl	32	517	27	456	34	5%	18:40:43
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1369	3830196	3812968	2139	15089	99%	490:12:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	754.7 GB	53.3 MB	57.5 MB	754.8 GB	99%	207.6 KB
2	news.nask.pl	45.9 MB	807.6 KB	29.7 MB	76.4 MB	60%	5.0 KB
3	news.icm.edu.pl	571.4 KB	57.0 KB	556.0 KB	1.2 MB	48%	19.4 KB
4	localhost	26.7 KB	0.0 KB	0.0 KB	26.7 KB	100%	3.0 KB
TOTAL: 4		754.8 GB	54.2 MB	87.8 MB	754.9 GB	99%	206.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 7 04:15:02 - 04:59:59	122595	3.2%	45.44	27.0 GB	3.6%	10475.63
Mar 7 05:00:00 - 05:59:59	170331	4.5%	47.31	34.1 GB	4.5%	9918.01
Mar 7 06:00:00 - 06:59:59	153581	4.0%	42.66	33.7 GB	4.5%	9821.37
Mar 7 07:00:00 - 07:59:59	128234	3.4%	35.62	22.5 GB	3.0%	6550.09
Mar 7 08:00:00 - 08:59:59	127888	3.4%	35.52	29.4 GB	3.9%	8564.78
Mar 7 09:00:00 - 09:59:59	166723	4.4%	46.31	33.1 GB	4.4%	9637.46
Mar 7 10:00:00 - 10:59:59	153652	4.0%	42.68	33.0 GB	4.4%	9600.72
Mar 7 11:00:00 - 11:59:59	126740	3.3%	35.21	28.6 GB	3.8%	8328.19
Mar 7 12:00:00 - 12:59:59	152867	4.0%	42.46	33.3 GB	4.4%	9705.02
Mar 7 13:00:00 - 13:59:59	148201	3.9%	41.17	38.4 GB	5.1%	11178.84
Mar 7 14:00:00 - 14:59:59	162189	4.3%	45.05	33.3 GB	4.4%	9699.43
Mar 7 15:00:00 - 15:59:59	204700	5.4%	56.86	34.2 GB	4.5%	9951.93
Mar 7 16:00:00 - 16:59:59	160973	4.2%	44.71	32.0 GB	4.2%	9313.06
Mar 7 17:00:00 - 17:59:59	162332	4.3%	45.09	33.7 GB	4.5%	9807.11
Mar 7 18:00:00 - 18:59:59	154854	4.1%	43.02	33.4 GB	4.4%	9730.56
Mar 7 19:00:00 - 19:59:59	152225	4.0%	42.28	36.2 GB	4.8%	10538.47
Mar 7 20:00:00 - 20:59:59	193113	5.1%	53.64	35.2 GB	4.7%	10262.25
Mar 7 21:00:00 - 21:59:59	189007	5.0%	52.50	31.0 GB	4.1%	9042.02
Mar 7 22:00:00 - 22:59:59	152740	4.0%	42.43	28.9 GB	3.8%	8416.31
Mar 7 23:00:00 - 23:59:59	183936	4.8%	51.09	34.5 GB	4.6%	10049.26
Mar 8 00:00:00 - 00:59:59	143755	3.8%	39.93	20.3 GB	2.7%	5913.88
Mar 8 01:00:00 - 01:59:59	186912	4.9%	51.92	30.2 GB	4.0%	8801.74
Mar 8 02:00:00 - 02:59:59	168644	4.4%	46.85	30.6 GB	4.1%	8918.12
Mar 8 03:00:00 - 03:59:59	116759	3.1%	32.43	21.2 GB	2.8%	6171.40
Mar 8 04:00:00 - 04:15:02	30323	0.8%	33.62	6.6 GB	0.9%	7705.44
TOTAL: 24:00:00	3813274	100.0%	44.14	754.3 GB	100.0%	9154.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	11276	178	10629	0	469
2	news.nask.pl	4443	14	258	7	4164
3	news.icm.edu.pl	35	0	3	0	32
TOTAL: 3		15754	192	10890	7	4665

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	157
it.comp.os.win.windows10	11
de.rec.sf.startrek	8
it.tlc.cellulari.android	5
comp.mobile.android	5
de.comp.lang.vbclassic	2
it.politica.m5s	2
comp.sys.raspberry-pi	1
comp.mobile.ipad	1
TOTAL: 9	192

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

Groups	Count
pl.praca.oferowana	4
pl.sci.matematyka	2
bit.listserv.catholic	1
TOTAL: 3	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3684
Binary in non-binary group	234
Scoring filter (8)	29
EMP (phl)	19
Scoring filter (10)	10
Scoring filter (18)	8
Scoring filter (9)	8
Scoring filter (16)	7
Scoring filter (15)	5
Scoring filter (11)	3
Scoring filter (12)	2
Excessively crossposted test article	1
Scoring filter (14)	1
Scoring filter (19)	1
Too many newsgroups	1
TOTAL: 15	4013

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	397
	news.nask.pl	253
	news.icm.edu.pl	1
	TOTAL: 3	651
TOTAL: 1		651

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	23259	965	7754	204	82	33516	4%	23:59:30
2	poznan	19921	217	11259	13	0	0	1%	23:48:17
3	icm	27064	157	19905	34	0	448	0%	23:59:58
4	nask	2182	2	1577	0	0	155	0%	23:53:53
5	okapi	0	0	0	0	0	1118	0%	23:57:51
TOTAL: 5		72426	1341	40495	251	82	35237	1%	119:39:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.2 MB	778.0 KB	7.0 MB	0.1 KB/s	6.1 KB	23:59:30
2	icm	6.1 MB	8.2 MB	14.2 MB	0.2 KB/s	76.2 KB	23:59:58
3	poznan	832.1 KB	45.0 KB	877.2 KB	0.0 KB/s	3.8 KB	23:48:17
4	nask	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	4.0 KB	23:53:53
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		13.1 MB	9.0 MB	22.1 MB	0.1 KB/s	14.2 KB	119:39:29

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
TOTAL: 1	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	289	21	25.1 KB	11	1	00:00:22
2	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 2		297	21	25.1 KB	11	9	24:23:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	681	21	25.1 KB	11	9	00:00:29
2	unresolved	2	0	0.0 KB	0	0	23:19:19
3	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
4	*.red-217-125-92.staticip.rima-tde.net	2	0	0.0 KB	0	0	01:04:07
TOTAL: 4		686	21	25.1 KB	11	9	24:23:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.99	40.276	66.188	23:19:19
242.red-217-125-92.staticip.rima-tde.net	1.960	3.188	01:04:07
web.man.lodz.pl	1.768	6.904	00:00:22
mon.man.lodz.pl	1.548	6.568	00:00:03
isvcs01.man.lodz.pl	0.076	0.188	00:00:01
david.man.lodz.pl	0.540	1.920	00:00:01
ec2-54-67-56-179.us-west-1.compute.amazonaws.com	0.004	0.052	00:00:00
TOTAL: 7	46.172	85.008	24:23:57

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.99	2
242.red-217-125-92.staticip.rima-tde.net	2
ec2-54-67-56-179.us-west-1.compute.amazonaws.com	1
TOTAL: 5	389

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.99	3	0
242.red-217-125-92.staticip.rima-tde.net	2	2
TOTAL: 2	5	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	16
2	pl.comp.os.ms-windows.winnt	4
3	pl.rec.mineraly	1
TOTAL: 3		21

Daily Usenet report

Mar 7 04:15:02 -- Mar 8 04:15:02