Daily Usenet report: Feb 24 04:15:03

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

Feb 24 08:27:49 newsfeed overchan[2711]: input line too long, skipping
Feb 24 09:36:11 newsfeed overchan[2711]: input line too long, skipping
Feb 24 10:21:01 newsfeed overchan[2711]: input line too long, skipping
Feb 24 10:21:11 newsfeed overchan[2711]: input line too long, skipping
Feb 24 10:21:22 newsfeed overchan[2711]: input line too long, skipping
Feb 24 14:27:24 newsfeed overchan[2711]: input line too long, skipping
Feb 24 14:50:18 newsfeed overchan[2711]: input line too long, skipping
Feb 24 14:50:35 newsfeed overchan[2711]: input line too long, skipping
Feb 24 14:50:49 newsfeed overchan[2711]: input line too long, skipping
Feb 24 14:53:06 newsfeed overchan[2711]: input line too long, skipping
Feb 24 18:02:06 newsfeed overchan[2711]: input line too long, skipping
Feb 24 18:08:18 newsfeed innfeed[1071]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Feb 24 18:08:18 newsfeed innfeed[1071]: SM: storage method 'cnfs' failed initialization
Feb 24 18:08:18 newsfeed innfeed[1071]: SM: one or more storage methods failed initialization
Feb 24 18:08:18 newsfeed innfeed[1071]: Storage manager initialization failed -- it is OK after a change in storage methods
Feb 24 18:08:18 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Feb 24 18:08:18 newsfeed innd: SM: storage method 'cnfs' failed initialization
Feb 24 18:08:18 newsfeed innd: SM: one or more storage methods failed initialization
Feb 24 18:30:16 newsfeed innfeed[1046]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Feb 24 18:30:16 newsfeed innfeed[1046]: SM: storage method 'cnfs' failed initialization
Feb 24 18:30:16 newsfeed innfeed[1046]: SM: one or more storage methods failed initialization
Feb 24 18:30:16 newsfeed innfeed[1046]: Storage manager initialization failed -- it is OK after a change in storage methods
Feb 24 18:30:17 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Feb 24 18:30:17 newsfeed innd: SM: storage method 'cnfs' failed initialization
Feb 24 18:30:17 newsfeed innd: SM: one or more storage methods failed initialization

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2814759	97.6%	322.2 MB	97.3%
innfeed	43615	1.5%	5.1 MB	1.5%
innd	21552	0.7%	3.5 MB	1.0%
nnrpd	4661	0.2%	491.5 KB	0.1%
overchan	13	0.0%	1.9 KB	0.0%
controlchan	5	0.0%	0.3 KB	0.0%
TOTAL: 6	2884605	100.0%	331.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2752283	98.2%
Negative hits	38198	1.4%
Positive hits	11159	0.4%
Cache misses	113	0.0%
TOTAL: 4	2801753	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.504	0.0%	463	0.000	9.728	704.000
article cleanup	00:00:30.889	0.0%	2775854	0.007	0.011	0.017
article logging	00:01:59.797	0.1%	2776439	0.030	0.043	0.091
article parse	00:35:16.139	2.5%	53876287	0.021	0.039	0.048
article write	00:13:26.749	1.0%	2763543	0.119	0.292	0.526
artlog/artcncl	00:00:00.014	0.0%	449	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:43.358	0.1%	63393231	0.001	0.002	0.004
hisgrep/artcncl	00:00:00.578	0.0%	442	0.000	1.308	83.000
hishave/artcncl	00:00:00.064	0.0%	463	0.000	0.138	1.933
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:01.684	2.3%	2816222	0.031	0.682	6.185
history sync	00:00:00.024	0.0%	282	0.000	0.085	1.000
history write	01:17:21.041	5.5%	2766297	0.243	1.678	8.345
hiswrite/artcncl	00:00:00.002	0.0%	21	0.000	0.095	1.000
idle	15:53:12.401	68.1%	42873864	0.645	1.334	9.448
nntp read	00:41:29.107	3.0%	52629657	0.029	0.047	0.073
overview write	00:00:14.249	0.0%	2763543	0.002	0.005	0.007
perl filter	03:33:19.326	15.2%	2766297	1.982	4.627	7.579
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:50.924	0.4%	5544142	0.046	0.063	0.111
TOTAL: 23:20:03.080	22:56:30.850	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.123	0.0%	52164	0.000	0.002	0.023
article prepare	00:00:00.001	0.0%	10358	0.000	0.000	0.006
article read	00:01:19.966	0.1%	2569	1.050	31.127	464.222
backlog stats	00:00:00.141	0.0%	162796	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.644	0.0%	84012	0.007	0.020	0.084
data write	00:00:04.044	0.0%	67451	0.028	0.060	0.098
idle	23:22:03.680	99.9%	162796	337.469	516.743	839.362
status file	00:00:15.036	0.0%	15776	0.667	0.953	1.413
TOTAL: 23:23:58.655	23:23:44.635	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:16.699	60.3%	2966	0.000	5.630	52.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.288	1.0%	6579	0.000	0.044	1.105
readart	00:00:00.005	0.0%	40	0.000	0.125	0.500
TOTAL: 00:00:27.684	00:00:16.992	61.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	152
name	5
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	199

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1047	2798712	2788535	512	9665	99%	303:31:27
2	news.nask.pl	3	14692	11438	502	2752	77%	23:25:46
3	news.icm.edu.pl	43	335	15	313	7	4%	17:26:38
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1094	2813739	2799988	1327	12424	99%	344:23:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	472.5 GB	41.9 MB	49.2 MB	472.6 GB	99%	177.1 KB
2	news.nask.pl	51.6 MB	1.3 MB	18.5 MB	71.4 MB	72%	5.2 KB
3	news.icm.edu.pl	256.4 KB	123.4 KB	123.4 KB	503.3 KB	50%	22.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		472.6 GB	43.4 MB	67.8 MB	472.7 GB	99%	176.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 24 04:15:03 - 04:59:59	80047	2.9%	29.68	15.4 GB	3.3%	5972.08
Feb 24 05:00:00 - 05:59:59	127234	4.5%	35.34	26.9 GB	5.7%	7840.16
Feb 24 06:00:00 - 06:59:59	119947	4.3%	33.32	24.7 GB	5.2%	7208.62
Feb 24 07:00:00 - 07:59:59	127968	4.6%	35.55	27.4 GB	5.8%	7994.32
Feb 24 08:00:00 - 08:59:59	136128	4.9%	37.81	29.3 GB	6.2%	8531.00
Feb 24 09:00:00 - 09:59:59	61753	2.2%	17.15	4.6 GB	1.0%	1342.61
Feb 24 10:00:00 - 10:59:59	88950	3.2%	24.71	10.3 GB	2.2%	2988.57
Feb 24 11:00:00 - 11:59:59	86063	3.1%	23.91	11.9 GB	2.5%	3470.79
Feb 24 12:00:00 - 12:59:59	133147	4.8%	36.99	24.5 GB	5.2%	7145.20
Feb 24 13:00:00 - 13:59:59	111120	4.0%	30.87	29.8 GB	6.3%	8672.26
Feb 24 14:00:00 - 14:59:59	109071	3.9%	30.30	23.4 GB	4.9%	6810.44
Feb 24 15:00:00 - 15:59:59	160709	5.7%	44.64	28.5 GB	6.0%	8297.64
Feb 24 16:00:00 - 16:59:59	133985	4.8%	37.22	21.2 GB	4.5%	6176.58
Feb 24 17:00:00 - 17:59:59	60096	2.1%	16.69	2.6 GB	0.5%	743.76
Feb 24 18:00:00 - 18:59:59	72844	2.6%	20.23	10.9 GB	2.3%	3170.99
Feb 24 19:00:00 - 19:59:59	138017	4.9%	38.34	25.2 GB	5.3%	7334.64
Feb 24 20:00:00 - 20:59:59	152804	5.5%	42.45	25.8 GB	5.5%	7522.85
Feb 24 21:00:00 - 21:59:59	130759	4.7%	36.32	17.3 GB	3.7%	5031.97
Feb 24 22:00:00 - 22:59:59	71493	2.6%	19.86	3.3 GB	0.7%	961.44
Feb 24 23:00:00 - 23:59:59	177117	6.3%	49.20	27.2 GB	5.8%	7917.07
Feb 25 00:00:00 - 00:59:59	93102	3.3%	25.86	13.0 GB	2.7%	3773.91
Feb 25 01:00:00 - 01:59:59	113308	4.0%	31.47	19.7 GB	4.2%	5743.55
Feb 25 02:00:00 - 02:59:59	131181	4.7%	36.44	21.8 GB	4.6%	6336.43
Feb 25 03:00:00 - 03:59:59	151297	5.4%	42.03	21.8 GB	4.6%	6339.71
Feb 25 04:00:00 - 04:15:03	33125	1.2%	36.68	6.1 GB	1.3%	7119.14
TOTAL: 24:00:00	2801265	100.0%	32.42	472.5 GB	100.0%	5733.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9930	125	9474	0	331
2	news.nask.pl	3098	21	192	6	2879
3	news.icm.edu.pl	8	0	7	0	1
TOTAL: 3		13036	146	9673	6	3211

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	80
it.tlc.cellulari.android	32
comp.sys.raspberry-pi	10
it.comp.os.win.windows10	8
comp.mobile.android	6
comp.mobile.ipad	3
it.comp.os.win.windows8	2
de.comp.lang.vbclassic	2
it.comp.os.linux.ubuntu	1
it.politica.m5s	1
it.discussioni.energia	1
TOTAL: 11	146

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2374
Binary in non-binary group	146
EMP (phl)	55
Scoring filter (8)	12
Scoring filter (10)	7
Scoring filter (9)	7
Scoring filter (16)	6
HTML post	5
Too many newsgroups	3
Scoring filter (11)	2
Scoring filter (18)	1
Excessively crossposted test article	1
TOTAL: 12	2619

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	328
	newsfeed.pionier.net.pl	263
	news.icm.edu.pl	1
	TOTAL: 3	592
TOTAL: 1		592

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40293	794	13053	142	24	38679	1%	23:38:49
2	poznan	19579	736	10506	36	1	0	3%	23:29:00
3	icm	23492	44	16864	5	0	0	0%	23:29:00
4	nask	1274	3	854	0	0	0	0%	21:06:19
5	okapi	0	0	0	0	0	1039	0%	23:26:55
TOTAL: 5		84638	1577	41277	183	25	39718	1%	115:10:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.2 MB	559.1 KB	8.7 MB	0.1 KB/s	9.5 KB	23:38:49
2	icm	7.9 MB	60.3 KB	8.0 MB	0.1 KB/s	166.2 KB	23:29:00
3	poznan	2.5 MB	575.2 KB	3.1 MB	0.0 KB/s	4.1 KB	23:29:00
4	nask	7.8 KB	0.0 KB	7.8 KB	0.0 KB/s	2.6 KB	21:06:19
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:26:55
TOTAL: 5		18.6 MB	1.2 MB	19.7 MB	0.0 KB/s	11.5 KB	115:10:03

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	283	40	40.9 KB	16	0	0	00:00:22
TOTAL: 1		283	40	40.9 KB	16	0	0	00:00:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	662	40	40.9 KB	16	0	0	00:00:26
TOTAL: 1		662	40	40.9 KB	16	0	0	00:00:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.900	5.752	00:00:22
mon.man.lodz.pl	1.444	4.932	00:00:03
david.man.lodz.pl	0.416	1.720	00:00:01
TOTAL: 3	3.760	12.404	00:00:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	284
david.man.lodz.pl	95
TOTAL: 2	379

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Feb 24 04:15:03 -- Feb 25 04:15:03