Daily Usenet report: Dec 13 04:15:01

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 13 06:27:55 newsfeed overchan[9036]: input line too long, skipping
Dec 13 13:33:51 newsfeed nnrpd[28615]: SERVER perl filter not defined
Dec 13 13:36:17 newsfeed nnrpd[28681]: SERVER perl filter not defined
Dec 13 13:41:34 newsfeed nnrpd[28921]: SERVER perl filter not defined
Dec 13 13:48:58 newsfeed innfeed[1448]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Dec 13 13:48:58 newsfeed innfeed[1448]: SM: storage method 'cnfs' failed initialization
Dec 13 13:48:58 newsfeed innfeed[1448]: SM: one or more storage methods failed initialization
Dec 13 13:48:58 newsfeed innfeed[1448]: Storage manager initialization failed -- it is OK after a change in storage methods
Dec 13 13:48:58 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Dec 13 13:48:58 newsfeed innd: SM: storage method 'cnfs' failed initialization
Dec 13 13:48:58 newsfeed innd: SM: one or more storage methods failed initialization
Dec 13 14:55:53 newsfeed nnrpd[4590]: SERVER perl filter not defined
Dec 13 18:52:17 newsfeed overchan[996]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	751772	93.3%	82.7 MB	92.7%
innfeed	43469	5.4%	5.0 MB	5.6%
innd	5471	0.7%	931.3 KB	1.0%
nnrpd	4999	0.6%	538.4 KB	0.6%
overchan	4	0.0%	0.6 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
TOTAL: 6	805718	100.0%	89.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	750580	99.2%
Negative hits	6019	0.8%
Positive hits	213	0.0%
Cache misses	44	0.0%
TOTAL: 4	756856	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.501	0.0%	84	0.000	17.869	87.000
article cleanup	00:00:08.814	0.0%	751694	0.004	0.012	0.018
article logging	00:00:30.585	0.0%	752165	0.030	0.041	0.062
article parse	00:03:16.786	0.2%	8276469	0.013	0.024	0.032
article write	00:02:07.093	0.1%	751456	0.128	0.169	0.556
artlog/artcncl	00:00:00.004	0.0%	84	0.000	0.048	0.333
data move	00:00:12.856	0.0%	11683735	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.831	0.0%	84	0.000	9.893	41.000
hishave/artcncl	00:00:00.217	0.0%	84	0.000	2.583	14.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:14.584	1.9%	757947	0.130	2.157	14.534
history sync	00:00:00.024	0.0%	332	0.000	0.072	0.500
history write	00:36:10.426	2.5%	751668	0.167	2.887	14.087
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	21:44:31.290	90.6%	8172884	1.298	9.577	98.969
nntp read	00:05:38.200	0.4%	8058326	0.030	0.042	0.054
overview write	00:00:03.559	0.0%	751456	0.000	0.005	0.009
perl filter	00:55:51.022	3.9%	751668	2.184	4.458	7.451
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:41.139	0.0%	1517819	0.018	0.027	0.038
TOTAL: 24:00:35.143	23:56:28.931	99.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.147	0.0%	50884	0.000	0.003	0.012
article prepare	00:00:00.000	0.0%	3494	0.000	0.000	0.000
article read	00:00:11.129	0.0%	876	0.000	12.704	265.375
backlog stats	00:00:00.110	0.0%	123605	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:01.132	0.0%	60907	0.000	0.019	0.033
data write	00:00:03.059	0.0%	46111	0.017	0.066	0.110
idle	23:52:47.044	100.0%	123605	451.945	695.498	1181.249
status file	00:00:16.310	0.0%	16903	0.618	0.965	4.329
TOTAL: 23:53:24.797	23:53:18.931	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:20.349	99.5%	3166	0.000	650.774	125341.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.238	0.0%	7074	0.000	0.034	0.500
readart	00:00:00.031	0.0%	83	0.000	0.373	9.667
TOTAL: 00:34:30.555	00:34:20.618	99.5%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	151
name	4
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	197

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	157	750905	750691	3	211	99%	81:40:26
2	news.icm.edu.pl	46	191	19	145	27	9%	15:14:15
3	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 3		208	751100	750714	148	238	99%	96:54:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	47.7 GB	3.5 MB	3.9 MB	47.7 GB	99%	66.6 KB
2	news.icm.edu.pl	287.5 KB	56.2 KB	828.2 KB	1.1 MB	24%	25.5 KB
3	localhost	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	1.5 KB
TOTAL: 3		47.7 GB	3.5 MB	4.7 MB	47.7 GB	99%	66.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 13 04:15:01 - 04:59:59	4249	0.6%	1.57	163.2 MB	0.3%	61.92
Dec 13 05:00:00 - 05:59:59	6653	0.9%	1.85	251.3 MB	0.5%	71.48
Dec 13 06:00:00 - 06:59:59	9972	1.3%	2.77	485.3 MB	1.0%	138.05
Dec 13 07:00:00 - 07:59:59	11517	1.5%	3.20	695.7 MB	1.4%	197.90
Dec 13 08:00:00 - 08:59:59	14538	1.9%	4.04	860.1 MB	1.8%	244.64
Dec 13 09:00:00 - 09:59:59	28551	3.8%	7.93	1.6 GB	3.4%	474.60
Dec 13 10:00:00 - 10:59:59	55367	7.4%	15.38	3.3 GB	7.0%	965.30
Dec 13 11:00:00 - 11:59:59	117802	15.7%	32.72	7.2 GB	15.1%	2097.52
Dec 13 12:00:00 - 12:59:59	58780	7.8%	16.33	3.6 GB	7.5%	1036.02
Dec 13 13:00:00 - 13:59:59	24017	3.2%	6.67	1.4 GB	2.9%	399.38
Dec 13 14:00:00 - 14:59:59	35890	4.8%	9.97	2.1 GB	4.4%	608.22
Dec 13 15:00:00 - 15:59:59	19653	2.6%	5.46	1.0 GB	2.2%	302.15
Dec 13 16:00:00 - 16:59:59	21876	2.9%	6.08	1.2 GB	2.5%	340.75
Dec 13 17:00:00 - 17:59:59	18025	2.4%	5.01	931.5 MB	1.9%	264.97
Dec 13 18:00:00 - 18:59:59	27313	3.6%	7.59	1.6 GB	3.3%	464.46
Dec 13 19:00:00 - 19:59:59	20271	2.7%	5.63	1.1 GB	2.4%	333.03
Dec 13 20:00:00 - 20:59:59	17572	2.3%	4.88	1010.4 MB	2.1%	287.39
Dec 13 21:00:00 - 21:59:59	11308	1.5%	3.14	603.9 MB	1.2%	171.77
Dec 13 22:00:00 - 22:59:59	69090	9.2%	19.19	4.2 GB	8.8%	1217.14
Dec 13 23:00:00 - 23:59:59	123116	16.4%	34.20	7.6 GB	15.9%	2203.84
Dec 14 00:00:00 - 00:59:59	18954	2.5%	5.26	2.3 GB	4.8%	671.74
Dec 14 01:00:00 - 01:59:59	8753	1.2%	2.43	1.2 GB	2.5%	352.73
Dec 14 02:00:00 - 02:59:59	16167	2.2%	4.49	1.2 GB	2.5%	347.11
Dec 14 03:00:00 - 03:59:59	9580	1.3%	2.66	2.1 GB	4.5%	622.01
Dec 14 04:00:00 - 04:15:01	1965	0.3%	2.18	100.5 MB	0.2%	114.17
TOTAL: 24:00:00	750979	100.0%	8.69	47.7 GB	100.0%	578.63

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	682	183	22	6	471
2	news.icm.edu.pl	27	0	4	0	23
TOTAL: 2		709	183	26	6	494

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	52
it.comp.os.win.windows10	45
it.tlc.cellulari.android	44
comp.mobile.android	21
comp.sys.raspberry-pi	15
it.comp.os.win.windows7	4
it.tlc.cellulari.iphone	1
de.rec.sf.startrek	1
TOTAL: 8	183

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	19
Binary in non-binary group	4
TOTAL: 2	23

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	41077	305	12742	160	5	37751	0%	23:55:27
2	icm	23169	61	14696	1	0	24	0%	23:48:45
3	nask	0	0	0	0	0	9677	0%	23:50:07
4	okapi	0	0	0	0	0	892	0%	23:50:07
5	poznan	27	0	18	0	0	0	0%	02:46:50
TOTAL: 5		64273	366	27456	161	5	48344	0%	98:11:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	panorama	854.1 KB	575.3 KB	1.4 MB	3.1 KB	23:55:27
2	icm	256.2 KB	2.5 KB	258.7 KB	4.2 KB	23:48:45
3	nask	0.0 KB	0.0 KB	0.0 KB	0	23:50:07
4	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:50:07
5	poznan	0.0 KB	0.0 KB	0.0 KB	0	02:46:50
TOTAL: 5		1.1 MB	577.9 KB	1.6 MB	3.2 KB	98:11:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	286	80	78.3 KB	31	0	00:00:15
2	public-gprs647012.centertel.pl	3	3	6.1 KB	1	0	00:12:38
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		293	83	84.4 KB	32	4	00:34:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	80	78.3 KB	31	4	00:00:18
2	*.centertel.pl	13	3	6.1 KB	1	0	00:34:07
3	unresolved	100	0	0.0 KB	0	0	00:00:03
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:01
TOTAL: 4		692	83	84.4 KB	32	4	00:34:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
public-gprs650676.centertel.pl	0.036	0.116	00:21:12
public-gprs647012.centertel.pl	0.004	0.052	00:12:38
public-gprs254447.centertel.pl	0.012	0.012	00:00:16
web.man.lodz.pl	1.508	2.496	00:00:15
mon.man.lodz.pl	1.436	2.152	00:00:02
80.111.142.213	0.020	0.048	00:00:02
scan-141.security.ipip.net	0.000	0.012	00:00:01
212.51.192.185	0.396	0.804	00:00:00
isvcs01.man.lodz.pl	0.048	0.052	00:00:00
TOTAL: 9	3.460	5.744	00:34:30

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
public-gprs650676.centertel.pl	8
80.111.142.213	4
public-gprs254447.centertel.pl	2
scan-141.security.ipip.net	1
TOTAL: 6	399

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
TOTAL: 4	4

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	80	96.4%
2	lodman	3	3.6%
TOTAL: 2		83	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	74
2	pl.comp.os.ms-windows.winnt	6
3	lodman.info	3
TOTAL: 3		83

Daily Usenet report

Dec 13 04:15:01 -- Dec 14 04:15:01