Daily Usenet report: Nov 22 04:15:02

Unknown entries from news log file:

First 50 / 5127 lines (1.0%)

Nov 22 10:39:09 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:40:46 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:48:37 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:49:43 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:50:46 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:51:03 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:51:15 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:51:38 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:54:25 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:54:42 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:55:03 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:56:33 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:59:11 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 10:59:38 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:00:57 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:01:33 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:09:56 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:11:30 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:12:49 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:14:02 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:15:06 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:18:41 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:20:21 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:20:53 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:21:16 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:24:53 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 11:26:50 newsfeed overchan[1160107]: input line too long, skipping
Nov 22 12:25:28 newsfeed nnrpd[1191418]: SERVER perl filter not defined
Nov 22 12:56:45 newsfeed innfeed[1453]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Nov 22 12:56:45 newsfeed innfeed[1453]: SM: storage method 'cnfs' failed initialization
Nov 22 12:56:45 newsfeed innfeed[1453]: SM: one or more storage methods failed initialization
Nov 22 12:56:45 newsfeed innfeed[1453]: Storage manager initialization failed -- it is OK after a change in storage methods
Nov 22 12:56:45 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Nov 22 12:56:45 newsfeed innd: SM: storage method 'cnfs' failed initialization
Nov 22 12:56:45 newsfeed innd: SM: one or more storage methods failed initialization
Nov 22 12:57:53 newsfeed nnrpd[1460]: news.icm.edu.pl noperm ihave without permission
Nov 22 12:57:54 newsfeed nnrpd[1462]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:57:54 newsfeed nnrpd[1461]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:58:23 newsfeed nnrpd[1481]: news.icm.edu.pl noperm ihave without permission
Nov 22 12:58:26 newsfeed nnrpd[1483]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:58:26 newsfeed nnrpd[1482]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:58:53 newsfeed nnrpd[1651]: news.icm.edu.pl noperm ihave without permission
Nov 22 12:58:57 newsfeed nnrpd[1654]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:58:57 newsfeed nnrpd[1653]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:59:23 newsfeed nnrpd[2287]: news.icm.edu.pl noperm ihave without permission
Nov 22 12:59:27 newsfeed nnrpd[2289]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:59:27 newsfeed nnrpd[2288]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:59:53 newsfeed nnrpd[2390]: news.icm.edu.pl noperm ihave without permission
Nov 22 12:59:57 newsfeed nnrpd[2391]: newsfeed.pionier.net.pl noperm ihave without permission
Nov 22 12:59:57 newsfeed nnrpd[2392]: newsfeed.pionier.net.pl noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	65762	62.6%	7.8 MB	66.0%
nnrpd	28024	26.7%	2.6 MB	22.3%
innd	10088	9.6%	1.3 MB	10.7%
innfeed	1165	1.1%	119.2 KB	1.0%
overchan	36	0.0%	5.0 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
TOTAL: 6	105078	100.0%	11.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	63875	81.2%
Negative hits	12576	16.0%
Positive hits	2222	2.8%
Cache misses	17	0.0%
TOTAL: 4	78690	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.417	0.0%	18	0.000	23.167	107.000
article cleanup	00:00:00.466	0.0%	64707	0.000	0.007	0.016
article logging	00:00:01.552	0.0%	64708	0.000	0.024	0.037
article parse	00:00:02.351	0.0%	227364	0.000	0.010	0.016
article write	00:00:18.959	0.0%	55670	0.107	0.341	27.000
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:00:00.334	0.0%	318033	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.183	0.0%	17	0.000	10.765	87.000
hishave/artcncl	00:00:00.121	0.0%	18	0.000	6.722	13.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:06:38.622	0.5%	79442	2.200	5.018	15.905
history sync	00:00:00.011	0.0%	397	0.000	0.028	0.500
history write	00:06:21.687	0.4%	64498	3.000	5.918	14.331
hiswrite/artcncl	00:00:00.005	0.0%	1	5.000	5.000	5.000
idle	23:36:13.918	99.0%	286523	30.253	296.569	4288.279
nntp read	00:00:04.870	0.0%	229103	0.000	0.021	0.031
overview write	00:00:00.159	0.0%	55670	0.000	0.003	0.008
perl filter	00:01:03.564	0.1%	64498	0.527	0.986	34.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.832	0.0%	128946	0.000	0.014	0.019
TOTAL: 23:51:08.432	23:50:49.051	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	17443	0.000	0.002	0.014
article prepare	00:00:00.000	0.0%	468	0.000	0.000	0.000
article read	00:00:00.007	0.0%	117	0.000	0.060	0.667
backlog stats	00:00:00.029	0.0%	63993	0.000	0.000	0.034
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.340	0.0%	36666	0.000	0.009	0.020
data write	00:00:00.775	0.0%	19363	0.000	0.040	0.107
idle	23:44:56.693	100.0%	63993	141.543	1336.032	20998.333
status file	00:00:00.180	0.0%	377	0.000	0.477	2.000
TOTAL: 23:44:59.237	23:44:58.064	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	09:14:50.565	99.8%	19487	0.000	1708.347	303181.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.673	0.0%	40705	0.000	0.017	0.500
readart	00:00:00.000	0.0%	2	0.000	0.000	0.000
TOTAL: 09:15:43.866	09:14:51.238	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
mode	151
name	3
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	196

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	40	53718	53520	3	195	99%	17:53:29
2	news.icm.edu.pl	4	13587	2926	1968	8693	21%	09:31:24
3	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 3		50	67310	56451	1971	8888	83%	27:24:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	647.9 MB	3.5 MB	3.6 MB	655.0 MB	98%	12.5 KB
2	news.icm.edu.pl	56.5 MB	581.2 KB	129.8 MB	186.9 MB	30%	16.5 KB
3	localhost	7.6 KB	0.0 KB	0.0 KB	7.6 KB	100%	1.5 KB
TOTAL: 3		704.5 MB	4.1 MB	133.4 MB	841.9 MB	83%	13.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 22 04:15:02 - 04:59:59	3582	6.3%	1.33	37.7 MB	0.0%	14.32
Nov 22 05:00:00 - 05:59:59	6246	11.0%	1.74	58.5 MB	0.0%	16.63
Nov 22 06:00:00 - 06:59:59	5072	8.9%	1.41	55.3 MB	0.0%	15.72
Nov 22 07:00:00 - 07:59:59	6511	11.5%	1.81	81.2 MB	0.0%	23.09
Nov 22 08:00:00 - 08:59:59	5104	9.0%	1.42	51.4 MB	0.0%	14.62
Nov 22 09:00:00 - 09:59:59	6133	10.8%	1.70	59.0 MB	0.0%	16.78
Nov 22 10:00:00 - 10:59:59	5880	10.4%	1.63	80.4 MB	0.0%	22.86
Nov 22 11:00:00 - 11:59:59	5284	9.3%	1.47	54.9 MB	0.0%	15.61
Nov 22 12:00:00 - 12:59:59	6055	10.7%	1.68	138.9 MB	0.0%	39.50
Nov 22 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 18:00:00 - 18:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 19:00:00 - 19:59:59	3	0.0%	0.00	4.8 KB	0.0%	0.00
Nov 22 20:00:00 - 20:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 21:00:00 - 21:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 22 22:00:00 - 22:59:59	1	0.0%	0.00	1.2 KB	0.0%	0.00
Nov 22 23:00:00 - 23:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 23 00:00:00 - 00:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 23 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 23 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 23 03:00:00 - 03:59:59	6277	11.1%	1.74	87.8 MB	232.8%	24.97
Nov 23 04:00:00 - 04:15:02	534	0.9%	0.59	6.1 MB	16.2%	6.92
TOTAL: 24:00:00	56682	100.0%	0.66	37.7 MB	100.0%	0.45

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.icm.edu.pl	8866	1	45	8820
2	newsfeed.pionier.net.pl	196	29	166	1
TOTAL: 2		9062	30	211	8821

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.mobile.android	15
comp.sys.raspberry-pi	4
eternal-september.support	2
uk.railway	2
alt.comp.software.firefox	2
de.rec.sf.startrek	1
it.tlc.cellulari.iphone	1
eternal-september.test	1
fido7.english-tutor	1
de.comm.software.mailreader	1
TOTAL: 10	30

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	8323
Scoring filter (9)	361
Scoring filter (10)	36
Scoring filter (8)	35
EMP (phl)	28
Scoring filter (12)	21
Scoring filter (13)	8
EMP (md5)	4
Scoring filter (14)	2
Scoring filter (11)	2
TOTAL: 10	8820

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	18759	114	14568	5	0	0%	10:02:40
2	okapi	0	0	0	0	230	0%	23:45:13
3	panorama	0	0	0	0	17527	0%	23:45:13
4	poznan	788	0	756	0	0	0%	09:39:04
5	nask	0	0	0	0	16092	0%	23:45:13
TOTAL: 5		19547	114	15324	5	33849	0%	90:57:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	306.8 KB	20.9 KB	327.7 KB	2.8 KB	10:02:40
2	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:45:13
3	panorama	0.0 KB	0.0 KB	0.0 KB	0	23:45:13
4	poznan	0.0 KB	0.0 KB	0.0 KB	0	09:39:04
5	nask	0.0 KB	0.0 KB	0.0 KB	0	23:45:13
TOTAL: 5		306.8 KB	20.9 KB	327.7 KB	2.8 KB	90:57:23

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
TOTAL: 1	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	2	14.8 KB	1	0	00:00:08
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	2	14.8 KB	1	5	09:15:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	1724	2	14.8 KB	1	5	00:00:22
2	unresolved	100	0	0.0 KB	0	0	00:00:03
3	*.icm.edu.pl	1687	0	0.0 KB	0	0	00:00:47
4	*.pionier.net.pl	3424	0	0.0 KB	0	0	09:14:30
TOTAL: 4		6935	2	14.8 KB	1	5	09:15:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
newsfeed.pionier.net.pl	12.537	16.068	09:14:30
news.icm.edu.pl	6.176	7.773	00:00:47
nms2.man.lodz.pl	3.527	4.250	00:00:09
web.man.lodz.pl	1.063	1.204	00:00:08
nms1.man.lodz.pl	1.487	2.304	00:00:04
80.111.142.213	0.010	0.025	00:00:02
212.51.192.185	0.378	0.426	00:00:01
isvcs01.man.lodz.pl	0.021	0.029	00:00:00
TOTAL: 8	25.199	32.079	09:15:43

Curious NNRP server explorers [Top 20]:

System	Conn
newsfeed.pionier.net.pl	3424
news.icm.edu.pl	1687
nms2.man.lodz.pl	971
nms1.man.lodz.pl	460
212.51.192.185	96
80.111.142.213	4
TOTAL: 6	6642

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	2
TOTAL: 1		2

Daily Usenet report

Nov 22 04:15:02 -- Nov 23 04:15:02