Daily Usenet report: May 8 04:15:04

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

May 8 06:37:46 newsfeed overchan[28465]: input line too long, skipping
May 8 13:18:06 newsfeed overchan[28465]: input line too long, skipping
May 8 14:42:45 newsfeed overchan[28465]: input line too long, skipping
May 8 14:51:43 newsfeed overchan[28465]: input line too long, skipping
May 8 15:06:30 newsfeed overchan[28465]: input line too long, skipping
May 8 16:24:07 newsfeed overchan[28465]: input line too long, skipping
May 8 19:25:33 newsfeed overchan[28465]: input line too long, skipping
May 8 19:27:01 newsfeed overchan[28465]: input line too long, skipping
May 8 19:42:59 newsfeed overchan[28465]: input line too long, skipping
May 8 22:42:56 newsfeed overchan[28465]: input line too long, skipping
May 9 00:30:03 newsfeed nnrpd[2973]: SERVER perl filter not defined
May 9 01:31:00 newsfeed nnrpd[11954]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	983351	94.1%	109.0 MB	93.4%
innfeed	47592	4.6%	5.6 MB	4.8%
innd	11187	1.1%	1.8 MB	1.5%
nnrpd	3336	0.3%	314.5 KB	0.3%
overchan	10	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1045477	100.0%	116.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	977787	98.6%
Negative hits	13151	1.3%
Positive hits	250	0.0%
Cache misses	17	0.0%
TOTAL: 4	991205	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.216	0.0%	78	0.000	79.692	4614.000
article cleanup	00:00:06.554	0.0%	983194	0.004	0.007	0.010
article logging	00:00:22.932	0.0%	983523	0.019	0.023	0.033
article parse	00:10:46.458	0.7%	140044199	0.004	0.005	0.007
article write	00:05:48.597	0.4%	982983	0.211	0.355	0.502
artlog/artcncl	00:00:00.000	0.0%	78	0.000	0.000	0.000
data move	00:01:55.567	0.1%	146164570	0.001	0.001	0.001
hisgrep/artcncl	00:00:01.508	0.0%	74	0.000	20.378	1274.000
hishave/artcncl	00:00:00.230	0.0%	78	0.000	2.949	73.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:42:51.600	7.1%	997696	0.532	6.186	18.755
history sync	00:00:00.013	0.0%	291	0.000	0.045	0.500
history write	02:08:41.688	8.9%	983144	1.585	7.854	19.202
hiswrite/artcncl	00:00:00.014	0.0%	4	0.000	3.500	7.000
idle	18:08:53.128	75.6%	120276817	0.419	0.543	1.119
nntp read	00:29:37.601	2.1%	139348960	0.011	0.013	0.016
overview write	00:00:03.331	0.0%	982983	0.002	0.003	0.006
perl filter	00:49:27.621	3.4%	983144	2.005	3.019	3.961
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:25.221	0.0%	1975528	0.010	0.013	0.017
TOTAL: 24:00:04.507	23:39:08.279	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	43812	0.000	0.001	0.012
article prepare	00:00:00.008	0.0%	12684	0.000	0.001	0.012
article read	00:00:17.174	0.0%	3169	0.000	5.419	118.714
backlog stats	00:00:00.105	0.0%	101283	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:00.507	0.0%	47817	0.000	0.011	0.026
data write	00:00:00.980	0.0%	38554	0.003	0.025	0.058
idle	23:51:54.408	99.7%	101283	598.944	848.261	1368.064
status file	00:03:17.790	0.2%	16483	0.400	12.000	423.422
TOTAL: 23:55:34.520	23:55:31.034	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:59.690	89.4%	2743	0.000	21.761	10718.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.092	0.1%	5794	0.000	0.016	0.500
readart	00:00:00.001	0.0%	8	0.000	0.125	0.500
TOTAL: 00:01:06.747	00:00:59.783	89.6%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
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	269	982173	982010	1	162	99%	159:03:46
2	news.icm.edu.pl	42	235	46	140	49	19%	14:10:06
3	localhost	1442	2	2	0	0	100%	00:00:00
TOTAL: 3		1753	982410	982058	141	211	99%	173:13:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	357.9 GB	3.8 MB	4.1 MB	357.9 GB	99%	382.1 KB
2	news.icm.edu.pl	3.8 MB	40.0 KB	1.7 MB	5.5 MB	68%	59.3 KB
3	localhost	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	1.5 KB
TOTAL: 3		357.9 GB	3.9 MB	5.8 MB	357.9 GB	99%	382.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 8 04:15:04 - 04:59:59	31076	3.2%	11.53	12.4 GB	3.5%	4813.47
May 8 05:00:00 - 05:59:59	42804	4.4%	11.89	17.0 GB	4.7%	4953.99
May 8 06:00:00 - 06:59:59	36997	3.8%	10.28	14.2 GB	3.9%	4122.01
May 8 07:00:00 - 07:59:59	39596	4.0%	11.00	14.9 GB	4.2%	4337.82
May 8 08:00:00 - 08:59:59	37432	3.8%	10.40	14.2 GB	4.0%	4146.39
May 8 09:00:00 - 09:59:59	39270	4.0%	10.91	15.2 GB	4.3%	4439.95
May 8 10:00:00 - 10:59:59	38768	3.9%	10.77	14.2 GB	4.0%	4131.83
May 8 11:00:00 - 11:59:59	47202	4.8%	13.11	14.6 GB	4.1%	4267.10
May 8 12:00:00 - 12:59:59	40890	4.2%	11.36	13.9 GB	3.9%	4054.46
May 8 13:00:00 - 13:59:59	35931	3.7%	9.98	14.5 GB	4.0%	4225.59
May 8 14:00:00 - 14:59:59	47657	4.8%	13.24	15.0 GB	4.2%	4378.56
May 8 15:00:00 - 15:59:59	54794	5.6%	15.22	15.3 GB	4.3%	4444.98
May 8 16:00:00 - 16:59:59	39486	4.0%	10.97	14.6 GB	4.1%	4252.94
May 8 17:00:00 - 17:59:59	41897	4.3%	11.64	15.5 GB	4.3%	4508.85
May 8 18:00:00 - 18:59:59	48853	5.0%	13.57	13.6 GB	3.8%	3963.67
May 8 19:00:00 - 19:59:59	43315	4.4%	12.03	15.5 GB	4.3%	4513.53
May 8 20:00:00 - 20:59:59	46853	4.8%	13.01	16.1 GB	4.5%	4703.54
May 8 21:00:00 - 21:59:59	44535	4.5%	12.37	15.5 GB	4.3%	4511.48
May 8 22:00:00 - 22:59:59	38536	3.9%	10.70	15.5 GB	4.3%	4512.43
May 8 23:00:00 - 23:59:59	40113	4.1%	11.14	15.7 GB	4.4%	4571.83
May 9 00:00:00 - 00:59:59	29892	3.0%	8.30	14.8 GB	4.1%	4318.76
May 9 01:00:00 - 01:59:59	47276	4.8%	13.13	15.4 GB	4.3%	4493.99
May 9 02:00:00 - 02:59:59	31051	3.2%	8.63	14.0 GB	3.9%	4073.57
May 9 03:00:00 - 03:59:59	30478	3.1%	8.47	13.2 GB	3.7%	3836.38
May 9 04:00:00 - 04:15:01	8031	0.8%	8.91	3.5 GB	1.0%	4015.81
TOTAL: 23:59:57	982733	100.0%	11.37	358.3 GB	100.0%	4349.04

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	490	114	47	1	328
2	news.icm.edu.pl	50	0	3	0	47
TOTAL: 2		540	114	50	1	375

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	56
de.rec.sf.startrek	16
alt.comp.software.firefox	12
it.comp.os.win.windows10	12
it.tlc.cellulari.android	6
it.comp.os.linux.ubuntu	4
alt.comp.software.seamonkey	4
nl.comp.os.linux.techniek	3
comp.mobile.android	1
TOTAL: 9	114

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	39
Binary in non-binary group	3
Too many newsgroups	3
Scoring filter (8)	1
TOTAL: 4	46

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	328
	news.icm.edu.pl	1
	TOTAL: 2	329
TOTAL: 1		329

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	15007	2405	6946	122	0	0	16%	23:50:02
2	panorama	36949	165	9354	109	24	34294	0%	23:59:37
3	poznan	10	0	9	0	0	0	0%	01:13:20
4	nask	0	0	0	0	0	6672	0%	23:50:03
5	okapi	0	0	0	0	0	535	0%	23:50:03
TOTAL: 5		51966	2570	16309	231	24	41501	4%	96:43:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	7.2 MB	404.2 KB	7.6 MB	0.1 KB/s	3.1 KB	23:50:02
2	panorama	469.4 KB	853.8 KB	1.3 MB	0.0 KB/s	4.8 KB	23:59:37
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:13:20
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 5		7.7 MB	1.2 MB	8.9 MB	0.0 KB/s	3.3 KB	96:43:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	8	8.1 KB	4	0	00:00:21
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	8	8.1 KB	4	2	00:01:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	290	8	8.1 KB	4	2	00:00:21
2	unresolved	105	0	0.0 KB	0	0	00:00:45
TOTAL: 2		395	8	8.1 KB	4	2	00:01:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
40.113.202.73	0.010	0.007	00:00:31
web.man.lodz.pl	0.944	1.922	00:00:21
143.110.189.215	0.008	0.000	00:00:10
80.111.142.213	0.004	0.035	00:00:02
212.51.192.185	0.240	0.499	00:00:01
isvcs01.man.lodz.pl	0.019	0.012	00:00:00
194.165.16.11	0.000	0.014	00:00:00
?	0.000	0.007	00:00:00
TOTAL: 8	1.225	2.496	00:01:06

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
194.165.16.11	2
40.113.202.73	2
143.110.189.215	1
TOTAL: 5	105

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP unrecognized commands (by host):

System	Conn
40.113.202.73	3
194.165.16.11	2
TOTAL: 2	5

NNRP unrecognized commands (by command):

Command	Count
#003	2
Connection: Keep-Alive	1
Host: 212.191.2.21:119	1
GET / HTTP/1.1	1
TOTAL: 4	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.11	2	2
TOTAL: 1	2	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	6
2	pl.comp.os.ms-windows.winnt	2
TOTAL: 2		8

Daily Usenet report

May 8 04:15:04 -- May 9 04:15:01