Daily Usenet report: Aug 24 04:15:02

Unknown entries from news log file:

First 24 / 24 lines (100.0%)

Aug 24 11:04:09 newsfeed innfeed[1053]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Aug 24 11:04:09 newsfeed innfeed[1053]: SM: storage method 'cnfs' failed initialization
Aug 24 11:04:09 newsfeed innfeed[1053]: SM: one or more storage methods failed initialization
Aug 24 11:04:09 newsfeed innfeed[1053]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 24 11:04:09 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Aug 24 11:04:09 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 24 11:04:09 newsfeed innd: SM: one or more storage methods failed initialization
Aug 24 12:09:36 newsfeed nnrpd[5889]: SERVER perl filter not defined
Aug 24 13:00:11 newsfeed nnrpd[7812]: SERVER perl filter not defined
Aug 24 13:00:43 newsfeed nnrpd[7820]: SERVER perl filter not defined
Aug 24 15:44:37 newsfeed overchan[1359]: input line too long, skipping
Aug 24 16:27:12 newsfeed overchan[1359]: input line too long, skipping
Aug 24 16:51:13 newsfeed overchan[1359]: input line too long, skipping
Aug 24 17:21:03 newsfeed overchan[1359]: input line too long, skipping
Aug 24 17:29:09 newsfeed overchan[1359]: input line too long, skipping
Aug 24 17:47:50 newsfeed overchan[1359]: input line too long, skipping
Aug 24 17:54:22 newsfeed overchan[1359]: input line too long, skipping
Aug 24 20:17:52 newsfeed overchan[1359]: input line too long, skipping
Aug 24 20:18:23 newsfeed overchan[1359]: input line too long, skipping
Aug 24 20:28:54 newsfeed overchan[1359]: input line too long, skipping
Aug 24 20:34:46 newsfeed overchan[1359]: input line too long, skipping
Aug 25 02:44:11 newsfeed overchan[1359]: input line too long, skipping
Aug 25 02:44:53 newsfeed overchan[1359]: input line too long, skipping
Aug 25 03:46:37 newsfeed overchan[1359]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2195599	96.9%	253.6 MB	96.6%
innfeed	47474	2.1%	5.6 MB	2.1%
innd	17869	0.8%	2.9 MB	1.1%
nnrpd	4818	0.2%	505.9 KB	0.2%
overchan	16	0.0%	2.6 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
TOTAL: 6	2265779	100.0%	262.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2159318	91.7%
Negative hits	98551	4.2%
Positive hits	96979	4.1%
Cache misses	1153	0.0%
TOTAL: 4	2356001	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.320	0.0%	107	0.000	12.336	101.000
article cleanup	00:00:26.599	0.0%	2182206	0.008	0.012	0.018
article logging	00:01:33.617	0.1%	2182755	0.028	0.043	0.133
article parse	00:38:43.044	2.7%	91764643	0.014	0.025	0.036
article write	00:15:05.994	1.1%	2176645	0.203	0.416	0.658
artlog/artcncl	00:00:00.005	0.0%	107	0.000	0.047	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:06.433	0.2%	104816593	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.184	0.0%	103	0.000	1.786	19.000
hishave/artcncl	00:00:00.025	0.0%	107	0.000	0.234	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:31.594	2.0%	2376497	0.215	0.720	3.655
history sync	00:00:00.024	0.0%	288	0.000	0.083	1.000
history write	01:02:58.342	4.4%	2178251	0.357	1.735	6.694
hiswrite/artcncl	00:00:00.003	0.0%	4	0.667	0.750	1.000
idle	16:12:13.067	68.0%	76269740	0.514	0.765	1.970
nntp read	00:57:54.593	4.0%	90322390	0.027	0.038	0.060
overview write	00:00:10.817	0.0%	2176645	0.003	0.005	0.007
perl filter	03:45:40.119	15.8%	2178245	4.021	6.216	8.513
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:04.040	0.3%	4563217	0.041	0.053	0.072
TOTAL: 23:50:03.899	23:30:29.820	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.110	0.0%	414539	0.000	0.003	0.005
article prepare	00:00:00.110	0.0%	475988	0.000	0.000	0.002
article read	00:03:52.076	0.3%	108283	0.317	2.143	25.048
backlog stats	00:00:01.860	0.0%	2062041	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:13.145	0.0%	827553	0.010	0.016	0.024
data write	00:04:03.166	0.3%	1226163	0.059	0.198	0.372
idle	23:30:54.254	99.3%	2062041	22.752	41.054	152.350
status file	00:00:14.218	0.0%	17248	0.488	0.824	4.884
TOTAL: 23:40:16.047	23:39:19.939	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:45:59.125	99.4%	3243	0.000	850.794	72286.179
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.305	0.0%	7504	0.000	0.041	1.158
readart	00:00:04.106	0.1%	224	0.000	18.330	37.000
TOTAL: 00:46:16.011	00:46:03.536	99.6%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	155
name	8
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	205

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1252	2187248	2181273	3721	2254	99%	403:46:59
2	news.nask.pl	4	99255	6794	89234	3227	6%	23:34:53
3	news.icm.edu.pl	40	265	125	131	9	47%	17:38:49
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1300	2286771	2188195	93086	5490	95%	445:00:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	619.6 GB	30.0 MB	37.6 MB	619.7 GB	99%	297.6 KB
2	news.nask.pl	567.6 MB	177.0 MB	189.4 MB	934.1 MB	60%	95.4 KB
3	news.icm.edu.pl	2.2 MB	9.5 KB	401.5 KB	2.6 MB	84%	19.6 KB
4	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	1.4 KB
TOTAL: 4		620.2 GB	207.1 MB	227.4 MB	620.6 GB	99%	296.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 24 04:15:02 - 04:59:59	77236	3.5%	28.63	24.1 GB	3.9%	9360.99
Aug 24 05:00:00 - 05:59:59	97658	4.5%	27.13	28.2 GB	4.5%	8204.44
Aug 24 06:00:00 - 06:59:59	82525	3.8%	22.92	26.2 GB	4.2%	7645.09
Aug 24 07:00:00 - 07:59:59	67525	3.1%	18.76	20.0 GB	3.2%	5827.09
Aug 24 08:00:00 - 08:59:59	71113	3.2%	19.75	21.9 GB	3.5%	6389.01
Aug 24 09:00:00 - 09:59:59	84627	3.9%	23.51	21.9 GB	3.5%	6390.54
Aug 24 10:00:00 - 10:59:59	112458	5.1%	31.24	29.1 GB	4.7%	8473.23
Aug 24 11:00:00 - 11:59:59	78601	3.6%	21.83	24.5 GB	4.0%	7143.82
Aug 24 12:00:00 - 12:59:59	101458	4.6%	28.18	32.2 GB	5.2%	9369.86
Aug 24 13:00:00 - 13:59:59	97739	4.5%	27.15	26.9 GB	4.3%	7840.57
Aug 24 14:00:00 - 14:59:59	83329	3.8%	23.15	26.1 GB	4.2%	7592.74
Aug 24 15:00:00 - 15:59:59	103057	4.7%	28.63	29.6 GB	4.8%	8629.13
Aug 24 16:00:00 - 16:59:59	129222	5.9%	35.90	34.2 GB	5.5%	9970.99
Aug 24 17:00:00 - 17:59:59	113611	5.2%	31.56	34.7 GB	5.6%	10109.15
Aug 24 18:00:00 - 18:59:59	119993	5.5%	33.33	36.4 GB	5.9%	10604.62
Aug 24 19:00:00 - 19:59:59	77230	3.5%	21.45	24.0 GB	3.9%	7000.67
Aug 24 20:00:00 - 20:59:59	66294	3.0%	18.41	16.3 GB	2.6%	4742.55
Aug 24 21:00:00 - 21:59:59	72293	3.3%	20.08	16.1 GB	2.6%	4694.20
Aug 24 22:00:00 - 22:59:59	84933	3.9%	23.59	24.5 GB	4.0%	7149.21
Aug 24 23:00:00 - 23:59:59	103274	4.7%	28.69	30.8 GB	5.0%	8985.70
Aug 25 00:00:00 - 00:59:59	75148	3.4%	20.87	17.3 GB	2.8%	5033.62
Aug 25 01:00:00 - 01:59:59	89170	4.1%	24.77	21.7 GB	3.5%	6322.29
Aug 25 02:00:00 - 02:59:59	85647	3.9%	23.79	22.2 GB	3.6%	6472.36
Aug 25 03:00:00 - 03:59:59	90513	4.1%	25.14	23.7 GB	3.8%	6912.32
Aug 25 04:00:00 - 04:15:02	24734	1.1%	27.42	7.3 GB	1.2%	8532.58
TOTAL: 24:00:00	2189388	100.0%	25.34	620.2 GB	100.0%	7527.36

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3461	12	2015	5	1429
2	newsfeed.pionier.net.pl	2634	226	1943	0	465
3	news.icm.edu.pl	9	0	1	0	8
TOTAL: 3		6104	238	3959	5	1902

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	149
it.tlc.cellulari.android	31
it.comp.os.win.windows10	22
comp.mobile.android	12
uk.net.providers.gradwell	6
comp.mobile.ipad	4
de.comp.office-pakete.ms-office.outlook	4
de.comm.software.mailreader	3
hr.ponuda.hardver.komponente	2
it.comp.os.win.windows7	2
de.comp.lang.php	2
de.comp.lang.vbclassic	1
TOTAL: 12	238

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

Groups	Count
pl.praca.oferowana	3
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1086
Binary in non-binary group	174
Scoring filter (8)	20
Scoring filter (9)	17
EMP (phl)	16
Scoring filter (16)	13
Scoring filter (18)	7
Scoring filter (10)	7
Scoring filter (12)	1
Too many newsgroups	1
Scoring filter (13)	1
Angle-bracket bot	1
TOTAL: 12	1344

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	7
	TOTAL: 1	7
Bad command received
	news.nask.pl	18
	TOTAL: 1	18
Including strange strings
	newsfeed.pionier.net.pl	374
	news.nask.pl	176
	news.icm.edu.pl	1
	TOTAL: 3	551
TOTAL: 3		576

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	263723	102261	94176	25	0	4913	38%	23:48:33
2	panorama	214734	360	185796	73	2	229791	0%	23:56:26
3	poznan	8650	225	5815	18	0	0	2%	23:48:33
4	icm	267380	17	192989	16378	0	0	0%	23:48:33
5	okapi	0	0	0	0	0	1201	0%	23:46:26
TOTAL: 5		754487	102863	478776	16494	2	235905	13%	119:08:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	61.0 GB	14.9 MB	61.0 GB	746.5 KB/s	625.5 KB	23:48:33
2	panorama	32.3 MB	9.2 MB	41.5 MB	0.5 KB/s	98.1 KB	23:56:26
3	icm	1.0 MB	9.1 GB	9.1 GB	111.2 KB/s	581.5 KB	23:48:33
4	poznan	835.6 KB	58.8 KB	894.5 KB	0.0 KB/s	3.7 KB	23:48:33
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:46:26
TOTAL: 5		61.0 GB	9.1 GB	70.2 GB	171.5 KB/s	616.3 KB	119:08:31

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-mstarzak.cm.p.lodz.pl	6	176	274.1 KB	9	0	00:45:38
2	web.man.lodz.pl	287	48	83.9 KB	23	0	00:00:22
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		296	224	358.0 KB	32	3	00:46:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	6	176	274.1 KB	9	0	00:45:38
2	*.man.lodz.pl	674	48	83.9 KB	23	3	00:00:26
3	unresolved	1	0	0.0 KB	0	0	00:00:09
TOTAL: 3		681	224	358.0 KB	32	3	00:46:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-mstarzak.cm.p.lodz.pl	0.056	0.096	00:45:38
web.man.lodz.pl	1.568	3.548	00:00:22
107.170.211.232	0.004	0.016	00:00:09
mon.man.lodz.pl	1.148	2.592	00:00:03
david.man.lodz.pl	0.360	0.880	00:00:01
isvcs01.man.lodz.pl	0.012	0.020	00:00:00
TOTAL: 6	3.148	7.152	00:46:15

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
107.170.211.232	1
TOTAL: 3	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-mstarzak.cm.p.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	132	58.9%
2	pl	92	41.1%
TOTAL: 2		224	100.0%

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

Newsgroup		Count
1	lodman.info	132
2	pl.comp.os.ms-windows.winnt	30
3	pl.rec.telewizja	22
4	pl.comp.pecet	18
5	pl.regionalne.lodz	18
6	pl.rec.radio	4
TOTAL: 6		224

Daily Usenet report

Aug 24 04:15:02 -- Aug 25 04:15:02