Daily Usenet report: May 2 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

May 2 07:54:22 newsfeed overchan[2689]: input line too long, skipping
May 2 07:55:03 newsfeed overchan[2689]: input line too long, skipping
May 2 07:56:39 newsfeed overchan[2689]: input line too long, skipping
May 2 10:31:51 newsfeed nnrpd[17154]: SERVER perl filter not defined
May 2 18:30:53 newsfeed overchan[2689]: input line too long, skipping
May 2 18:33:41 newsfeed overchan[2689]: input line too long, skipping
May 2 18:50:03 newsfeed overchan[2689]: input line too long, skipping
May 2 18:50:20 newsfeed overchan[2689]: input line too long, skipping
May 2 18:53:20 newsfeed overchan[2689]: input line too long, skipping
May 2 18:54:34 newsfeed overchan[2689]: input line too long, skipping
May 2 18:55:55 newsfeed overchan[2689]: input line too long, skipping
May 2 18:56:59 newsfeed overchan[2689]: input line too long, skipping
May 2 18:59:26 newsfeed overchan[2689]: input line too long, skipping
May 2 19:00:02 newsfeed overchan[2689]: input line too long, skipping
May 2 21:19:13 newsfeed overchan[2689]: input line too long, skipping
May 2 23:37:53 newsfeed overchan[2689]: input line too long, skipping
May 3 00:37:41 newsfeed overchan[2689]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2843623	97.2%	333.7 MB	97.0%
innfeed	45073	1.5%	5.3 MB	1.5%
innd	33477	1.1%	4.7 MB	1.4%
nnrpd	4738	0.2%	494.8 KB	0.1%
overchan	17	0.0%	2.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2926929	100.0%	344.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2819006	96.4%
Negative hits	92714	3.2%
Positive hits	13604	0.5%
Cache misses	113	0.0%
TOTAL: 4	2925437	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.102	0.0%	536	0.000	7.653	985.000
article cleanup	00:00:36.890	0.0%	2843151	0.009	0.013	0.018
article logging	00:01:52.506	0.1%	2843757	0.030	0.040	0.142
article parse	01:07:09.843	4.7%	222202588	0.013	0.018	0.032
article write	00:40:30.123	2.8%	2822340	0.410	0.861	4.686
artlog/artcncl	00:00:00.010	0.0%	517	0.000	0.019	0.500
data move	00:03:00.976	0.2%	240299459	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.355	0.0%	227	0.000	1.564	96.000
hishave/artcncl	00:00:00.642	0.0%	536	0.000	1.198	19.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:08:26.191	4.8%	2946128	0.184	1.394	5.642
history sync	00:00:00.018	0.0%	290	0.000	0.062	1.000
history write	01:45:58.586	7.4%	2836951	0.474	2.241	8.031
hiswrite/artcncl	00:00:00.869	0.0%	309	0.000	2.812	5.500
idle	12:00:17.991	50.0%	154558785	0.175	0.280	0.407
nntp read	01:19:18.612	5.5%	219977413	0.016	0.022	0.029
overview write	00:00:15.053	0.0%	2822340	0.004	0.005	0.008
perl filter	05:14:39.971	21.9%	2836951	5.875	6.655	7.702
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:30.908	0.3%	5733050	0.034	0.047	0.085
TOTAL: 24:00:04.215	23:26:43.646	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.442	0.0%	160599	0.000	0.003	0.012
article prepare	00:00:00.077	0.0%	281286	0.000	0.000	0.005
article read	00:01:40.157	0.1%	70284	0.162	1.425	90.704
backlog stats	00:00:00.404	0.0%	461941	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	6	0.000	0.000	0.000
data read	00:00:05.469	0.0%	254445	0.011	0.021	0.047
data write	00:00:53.901	0.1%	209568	0.027	0.257	0.475
idle	23:50:15.920	99.8%	461941	19.027	185.772	724.255
status file	00:00:16.657	0.0%	15862	0.544	1.050	10.033
TOTAL: 23:53:35.625	23:53:13.027	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.655	57.2%	3018	0.000	6.181	101.222
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.188	0.6%	6674	0.000	0.028	4.500
readart	00:00:00.004	0.0%	34	0.000	0.118	0.500
TOTAL: 00:00:32.625	00:00:18.847	57.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
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	1056	2822762	2812425	3870	6467	99%	470:47:57
2	news.nask.pl	1	26994	9464	3256	14274	35%	23:54:39
3	news.icm.edu.pl	47	241	13	225	3	5%	17:16:34
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1106	2849998	2821903	7351	20744	99%	511:59:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	874.0 GB	61.8 MB	209.8 MB	874.3 GB	99%	325.2 KB
2	news.nask.pl	52.0 MB	2.5 MB	170.4 MB	224.8 MB	23%	9.7 KB
3	news.icm.edu.pl	230.2 KB	0.0 KB	66.3 KB	296.6 KB	77%	18.5 KB
4	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 4		874.1 GB	64.2 MB	380.2 MB	874.5 GB	99%	322.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 2 04:15:02 - 04:59:59	87558	3.1%	32.45	30.0 GB	3.4%	11647.68
May 2 05:00:00 - 05:59:59	97750	3.5%	27.15	33.9 GB	3.9%	9883.69
May 2 06:00:00 - 06:59:59	105622	3.7%	29.34	34.8 GB	4.0%	10135.55
May 2 07:00:00 - 07:59:59	100738	3.6%	27.98	28.9 GB	3.3%	8430.84
May 2 08:00:00 - 08:59:59	107176	3.8%	29.77	30.4 GB	3.5%	8857.29
May 2 09:00:00 - 09:59:59	133554	4.7%	37.10	38.7 GB	4.4%	11259.03
May 2 10:00:00 - 10:59:59	141144	5.0%	39.21	40.4 GB	4.6%	11764.56
May 2 11:00:00 - 11:59:59	116249	4.1%	32.29	34.1 GB	3.9%	9930.72
May 2 12:00:00 - 12:59:59	115691	4.1%	32.14	37.8 GB	4.3%	11003.96
May 2 13:00:00 - 13:59:59	109578	3.9%	30.44	38.2 GB	4.4%	11117.98
May 2 14:00:00 - 14:59:59	117518	4.2%	32.64	39.7 GB	4.6%	11576.30
May 2 15:00:00 - 15:59:59	131514	4.7%	36.53	42.5 GB	4.9%	12365.27
May 2 16:00:00 - 16:59:59	120264	4.3%	33.41	37.8 GB	4.3%	11001.57
May 2 17:00:00 - 17:59:59	132001	4.7%	36.67	39.3 GB	4.5%	11438.34
May 2 18:00:00 - 18:59:59	108571	3.8%	30.16	31.9 GB	3.6%	9277.24
May 2 19:00:00 - 19:59:59	118146	4.2%	32.82	33.2 GB	3.8%	9664.01
May 2 20:00:00 - 20:59:59	139336	4.9%	38.70	39.5 GB	4.5%	11510.48
May 2 21:00:00 - 21:59:59	130523	4.6%	36.26	39.2 GB	4.5%	11414.68
May 2 22:00:00 - 22:59:59	130342	4.6%	36.21	38.6 GB	4.4%	11250.74
May 2 23:00:00 - 23:59:59	124035	4.4%	34.45	38.2 GB	4.4%	11139.70
May 3 00:00:00 - 00:59:59	102612	3.6%	28.50	30.9 GB	3.5%	8997.96
May 3 01:00:00 - 01:59:59	94025	3.3%	26.12	33.6 GB	3.9%	9791.23
May 3 02:00:00 - 02:59:59	120857	4.3%	33.57	40.2 GB	4.6%	11695.51
May 3 03:00:00 - 03:59:59	111510	4.0%	30.98	33.6 GB	3.9%	9797.09
May 3 04:00:00 - 04:15:02	25389	0.9%	28.15	7.7 GB	0.9%	8940.65
TOTAL: 24:00:00	2821703	100.0%	32.66	873.0 GB	100.0%	10594.72

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14550	16	823	1	13710
2	newsfeed.pionier.net.pl	6848	196	5383	0	1269
3	news.icm.edu.pl	5	0	0	0	5
TOTAL: 3		21403	212	6206	1	14984

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	88
it.tlc.cellulari.android	51
it.comp.os.win.windows10	34
comp.mobile.android	24
it.comp.os.win.windows7	6
nl.comp.os.linux.techniek	3
alt.sex.telephones	2
de.comp.lang.vbclassic	1
it.discussioni.energia	1
de.comm.software.mailreader	1
comp.sys.raspberry-pi	1
TOTAL: 11	212

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13099
Binary in non-binary group	1101
Scoring filter (8)	87
Scoring filter (9)	40
EMP (phl)	13
Scoring filter (10)	9
Too many newsgroups	8
Scoring filter (12)	6
Scoring filter (16)	5
Excessively crossposted test article	4
Poison newsgroup	2
Scoring filter (15)	2
Scoring filter (11)	1
HTML post	1
TOTAL: 14	14378

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	392
	news.nask.pl	212
	news.icm.edu.pl	2
	TOTAL: 3	606
TOTAL: 1		606

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	91739	60471	19978	7181	0	157	65%	23:59:57
2	panorama	83024	1373	54836	437	21	103715	1%	23:59:57
3	poznan	14395	95	9317	20	0	0	0%	23:59:52
4	nask	7080	19	4707	0	0	0	0%	23:59:36
5	okapi	0	0	0	0	0	1623	0%	23:57:50
TOTAL: 5		196238	61958	88838	7638	21	105495	31%	119:57:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	10.8 GB	1.2 GB	12.0 GB	145.8 KB/s	186.2 KB	23:59:57
2	panorama	7.8 MB	3.9 MB	11.7 MB	0.1 KB/s	6.6 KB	23:59:57
3	poznan	639.4 KB	65.1 KB	704.5 KB	0.0 KB/s	6.1 KB	23:59:52
4	nask	34.1 KB	0.0 KB	34.1 KB	0.0 KB/s	1.8 KB	23:59:36
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		10.8 GB	1.2 GB	12.0 GB	29.2 KB/s	181.2 KB	119:57:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	34	33.3 KB	15	0	00:00:26
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		289	34	33.3 KB	15	1	00:00:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	673	34	33.3 KB	15	1	0	00:00:32
TOTAL: 1		673	34	33.3 KB	15	1	0	00:00:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.240	3.192	00:00:26
mon.man.lodz.pl	1.180	3.236	00:00:04
david.man.lodz.pl	0.308	1.164	00:00:01
isvcs01.man.lodz.pl	0.008	0.012	00:00:00
TOTAL: 4	2.736	7.604	00:00:32

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	28
2	pl.comp.os.ms-windows.winnt	6
TOTAL: 2		34

Daily Usenet report

May 2 04:15:02 -- May 3 04:15:02