Daily Usenet report: Sep 28 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 28 09:02:36 newsfeed nnrpd[575529]: SERVER perl filter not defined
Sep 28 13:22:09 newsfeed nnrpd[588600]: SERVER perl filter not defined
Sep 28 13:22:54 newsfeed nnrpd[588609]: SERVER perl filter not defined
Sep 28 13:41:18 newsfeed overchan[560486]: input line too long, skipping
Sep 28 14:18:55 newsfeed overchan[560486]: input line too long, skipping
Sep 28 14:20:16 newsfeed overchan[560486]: input line too long, skipping
Sep 28 14:21:19 newsfeed overchan[560486]: input line too long, skipping
Sep 28 16:33:00 newsfeed overchan[560486]: input line too long, skipping
Sep 28 19:17:00 newsfeed overchan[560486]: input line too long, skipping
Sep 28 20:29:14 newsfeed overchan[560486]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	669279	94.8%	73.7 MB	94.2%
innfeed	23544	3.3%	2.8 MB	3.6%
innd	9602	1.4%	1.5 MB	1.9%
nnrpd	3387	0.5%	317.8 KB	0.4%
overchan	8	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	705821	100.0%	78.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	667239	99.7%
Negative hits	1859	0.3%
Positive hits	173	0.0%
Cache misses	40	0.0%
TOTAL: 4	669311	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.763	0.0%	90	0.000	8.478	50.000
article cleanup	00:00:04.416	0.0%	668990	0.004	0.007	0.012
article logging	00:00:13.983	0.0%	669285	0.014	0.021	0.029
article parse	00:08:55.930	0.6%	106027239	0.003	0.005	0.008
article write	00:05:08.065	0.4%	668842	0.265	0.461	0.585
artlog/artcncl	00:00:00.002	0.0%	90	0.000	0.022	0.125
data move	00:00:30.579	0.0%	111532234	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.172	0.0%	89	0.000	1.933	21.000
hishave/artcncl	00:00:00.009	0.0%	90	0.000	0.100	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:04.412	0.2%	671442	0.161	0.275	0.850
history sync	00:00:00.009	0.0%	290	0.000	0.031	0.500
history write	00:08:44.933	0.6%	668979	0.246	0.785	3.304
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
idle	22:22:16.665	93.2%	105342502	0.483	0.765	1.212
nntp read	00:24:20.094	1.7%	105602034	0.011	0.014	0.018
overview write	00:00:01.754	0.0%	668842	0.001	0.003	0.005
perl filter	00:35:51.865	2.5%	668979	2.539	3.217	3.798
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:17.858	0.0%	1347317	0.009	0.013	0.017
TOTAL: 24:00:02.702	23:49:31.510	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	31809	0.000	0.001	0.009
article prepare	00:00:00.002	0.0%	16232	0.000	0.000	0.019
article read	00:00:09.448	0.0%	4087	0.000	2.312	20.167
backlog stats	00:00:00.043	0.0%	85372	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	23	0.000	0.000	0.000
data read	00:00:00.399	0.0%	41129	0.000	0.010	0.029
data write	00:00:00.857	0.0%	31815	0.000	0.027	0.061
idle	23:55:08.280	100.0%	85372	658.514	1008.624	2526.697
status file	00:00:04.025	0.0%	7723	0.383	0.521	1.436
TOTAL: 23:55:25.113	23:55:23.094	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:36:12.748	99.7%	2799	0.000	776.259	93709.400
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.076	0.0%	6021	0.000	0.013	1.000
readart	00:00:00.025	0.0%	33	0.000	0.758	8.000
TOTAL: 00:36:18.593	00:36:12.849	99.7%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
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	253	668741	668630	0	111	99%	98:52:02
2	news.icm.edu.pl	51	163	15	111	37	9%	13:06:20
3	localhost	1444	3	3	0	0	100%	00:00:00
TOTAL: 3		1748	668907	668648	111	148	99%	111:58:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	332.2 GB	738.3 KB	995.9 KB	332.2 GB	99%	520.8 KB
2	news.icm.edu.pl	776.6 KB	0.0 KB	1.1 MB	1.8 MB	41%	35.8 KB
3	localhost	7.1 KB	0.0 KB	0.0 KB	7.1 KB	100%	2.4 KB
TOTAL: 3		332.2 GB	738.3 KB	2.0 MB	332.2 GB	99%	520.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 28 04:15:02 - 04:59:59	22967	3.4%	8.51	11.6 GB	3.5%	4491.82
Sep 28 05:00:00 - 05:59:59	25821	3.9%	7.17	13.8 GB	4.2%	4025.50
Sep 28 06:00:00 - 06:59:59	25397	3.8%	7.05	13.2 GB	4.0%	3835.21
Sep 28 07:00:00 - 07:59:59	23669	3.5%	6.57	12.8 GB	3.8%	3722.20
Sep 28 08:00:00 - 08:59:59	26494	4.0%	7.36	13.8 GB	4.2%	4033.73
Sep 28 09:00:00 - 09:59:59	28318	4.2%	7.87	13.8 GB	4.2%	4027.30
Sep 28 10:00:00 - 10:59:59	27402	4.1%	7.61	14.5 GB	4.4%	4229.93
Sep 28 11:00:00 - 11:59:59	30596	4.6%	8.50	15.4 GB	4.6%	4490.78
Sep 28 12:00:00 - 12:59:59	27301	4.1%	7.58	13.8 GB	4.1%	4008.06
Sep 28 13:00:00 - 13:59:59	28457	4.3%	7.90	14.1 GB	4.2%	4093.42
Sep 28 14:00:00 - 14:59:59	27625	4.1%	7.67	14.0 GB	4.2%	4085.98
Sep 28 15:00:00 - 15:59:59	27446	4.1%	7.62	13.9 GB	4.2%	4038.32
Sep 28 16:00:00 - 16:59:59	30901	4.6%	8.58	14.9 GB	4.5%	4341.22
Sep 28 17:00:00 - 17:59:59	32453	4.9%	9.01	13.1 GB	3.9%	3812.07
Sep 28 18:00:00 - 18:59:59	26531	4.0%	7.37	13.4 GB	4.0%	3905.22
Sep 28 19:00:00 - 19:59:59	28899	4.3%	8.03	12.7 GB	3.8%	3702.83
Sep 28 20:00:00 - 20:59:59	27814	4.2%	7.73	13.6 GB	4.1%	3966.64
Sep 28 21:00:00 - 21:59:59	29025	4.3%	8.06	14.0 GB	4.2%	4090.78
Sep 28 22:00:00 - 22:59:59	27598	4.1%	7.67	13.6 GB	4.1%	3965.04
Sep 28 23:00:00 - 23:59:59	26287	3.9%	7.30	13.1 GB	4.0%	3825.55
Sep 29 00:00:00 - 00:59:59	30406	4.5%	8.45	13.6 GB	4.1%	3955.51
Sep 29 01:00:00 - 01:59:59	26203	3.9%	7.28	13.8 GB	4.2%	4026.89
Sep 29 02:00:00 - 02:59:59	25467	3.8%	7.07	13.9 GB	4.2%	4036.25
Sep 29 03:00:00 - 03:59:59	28426	4.3%	7.90	14.0 GB	4.2%	4091.46
Sep 29 04:00:00 - 04:15:02	7243	1.1%	8.03	3.9 GB	1.2%	4536.76
TOTAL: 24:00:00	668746	100.0%	7.74	332.4 GB	100.0%	4033.87

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	406	96	11	4	295
2	news.icm.edu.pl	37	0	0	0	37
TOTAL: 2		443	96	11	4	332

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	35
comp.mobile.android	20
it.comp.os.win.windows10	13
alt.comp.software.seamonkey	9
nl.comp.os.linux.techniek	6
it.tlc.cellulari.android	5
alt.comp.software.firefox	3
comp.mobile.ipad	2
it.tlc.cellulari.iphone	1
de.rec.sf.startrek	1
it.discussioni.energia	1
TOTAL: 11	96

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

Groups	Count
pl.praca.oferowana	2
soc.culture.pakistan.education	2
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16
Too many newsgroups	12
Binary in non-binary group	7
Too many newsgroups (low_xpost)	1
Scoring filter (9)	1
TOTAL: 5	37

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	15178	3101	6033	214	2	0	20%	23:59:56
2	panorama	22853	247	9722	173	57	21708	1%	23:59:28
3	poznan	13	0	10	0	0	0	0%	01:28:31
4	okapi	0	0	0	0	0	677	0%	23:57:48
5	nask	0	0	0	0	0	6310	0%	23:57:46
TOTAL: 5		38044	3348	15765	387	59	28695	8%	97:23:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	10.4 MB	1.2 MB	11.6 MB	0.1 KB/s	3.6 KB	23:59:56
2	panorama	744.7 KB	957.3 KB	1.7 MB	0.0 KB/s	4.1 KB	23:59:28
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:28:31
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:46
TOTAL: 5		11.1 MB	2.2 MB	13.3 MB	0.0 KB/s	3.6 KB	97:23:29

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	294	30	30.5 KB	14	0	00:00:09
2	laskomex.com.pl	2	3	4.5 KB	1	0	00:36:04
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		299	33	35.0 KB	15	3	00:36:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	297	30	30.5 KB	14	3	00:00:09
2	*.com.pl	2	3	4.5 KB	1	0	00:36:04
3	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	0	00:00:01
4	unresolved	103	0	0.0 KB	0	0	00:00:03
TOTAL: 4		403	33	35.0 KB	15	3	00:36:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
laskomex.com.pl	0.109	0.029	00:36:04
web.man.lodz.pl	0.858	1.527	00:00:09
soulful.monitoring.internet-measurement.com	0.000	0.007	00:00:01
80.111.142.213	0.020	0.013	00:00:01
212.51.192.185	0.284	0.425	00:00:01
192.241.213.72	0.000	0.007	00:00:00
isvcs01.man.lodz.pl	0.012	0.025	00:00:00
92.255.85.183	0.007	0.007	00:00:00
TOTAL: 8	1.290	2.040	00:36:18

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
92.255.85.183	2
soulful.monitoring.internet-measurement.com	1
192.241.213.72	1
TOTAL: 5	104

NNRP unrecognized commands (by host):

System	Conn
92.255.85.183	2
192.241.213.72	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
92.255.85.183	2	2
TOTAL: 1	2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	30	90.9%
2	lodman	3	9.1%
TOTAL: 2		33	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	26
2	pl.comp.pecet	4
3	lodman.test	3
TOTAL: 3		33

Daily Usenet report

Sep 28 04:15:02 -- Sep 29 04:15:02