Daily Usenet report: Nov 10 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Nov 10 07:31:33 newsfeed controlchan[28017]: skipping newgroup free.hillary.clinton.uranium-sales y irs@sjrb.ca (would be created): Empty description
Nov 10 15:58:07 newsfeed overchan[1159]: input line too long, skipping
Nov 10 16:30:33 newsfeed overchan[1159]: input line too long, skipping
Nov 10 16:47:39 newsfeed overchan[1159]: input line too long, skipping
Nov 10 17:05:02 newsfeed nnrpd[7569]: SERVER perl filter not defined
Nov 10 17:31:26 newsfeed overchan[1159]: input line too long, skipping
Nov 10 17:40:45 newsfeed overchan[1159]: input line too long, skipping
Nov 10 22:31:22 newsfeed overchan[1159]: input line too long, skipping
Nov 10 23:33:27 newsfeed overchan[1159]: input line too long, skipping
Nov 11 00:13:47 newsfeed nnrpd[23638]: SERVER perl filter not defined
Nov 11 03:01:27 newsfeed overchan[1159]: input line too long, skipping
Nov 11 04:02:20 newsfeed overchan[1159]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3316153	97.2%	376.5 MB	96.9%
innd	47287	1.4%	6.6 MB	1.7%
innfeed	44068	1.3%	5.1 MB	1.3%
nnrpd	4774	0.1%	494.4 KB	0.1%
overchan	12	0.0%	1.7 KB	0.0%
controlchan	8	0.0%	1.0 KB	0.0%
TOTAL: 6	3412302	100.0%	388.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3246030	93.7%
Negative hits	187590	5.4%
Positive hits	26322	0.8%
Cache misses	4348	0.1%
TOTAL: 4	3464290	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.570	0.0%	424	0.000	6.061	508.500
article cleanup	00:00:46.371	0.1%	3283780	0.010	0.014	0.021
article logging	00:02:22.343	0.2%	3284387	0.028	0.043	0.102
article parse	00:58:00.452	4.1%	121568304	0.019	0.029	0.045
article write	00:23:03.787	1.6%	3243187	0.176	0.427	2.507
artlog/artcncl	00:00:00.004	0.0%	287	0.000	0.014	0.200
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:55.382	0.3%	137463452	0.001	0.002	0.005
hisgrep/artcncl	00:00:00.470	0.0%	415	0.000	1.133	20.500
hishave/artcncl	00:00:00.125	0.0%	424	0.000	0.295	7.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:00:24.983	4.2%	3492629	0.086	1.038	6.159
history sync	00:00:00.014	0.0%	287	0.000	0.049	0.500
history write	02:23:57.044	10.1%	3271957	0.239	2.640	11.034
hiswrite/artcncl	00:00:00.003	0.0%	9	0.000	0.333	1.000
idle	11:11:33.687	47.0%	91796421	0.198	0.439	0.983
nntp read	01:07:40.633	4.7%	118923054	0.020	0.034	0.068
overview write	00:00:18.690	0.0%	3243187	0.005	0.006	0.008
perl filter	06:05:10.581	25.5%	3271940	5.371	6.697	9.605
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:40.057	0.4%	6510113	0.037	0.052	0.081
TOTAL: 23:50:05.099	23:22:57.196	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.208	0.0%	65151	0.000	0.003	0.018
article prepare	00:00:00.001	0.0%	21296	0.000	0.000	0.006
article read	00:01:10.896	0.1%	5342	0.000	13.271	448.333
backlog stats	00:00:00.168	0.0%	182155	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	20	0.000	0.000	0.000
data read	00:00:02.010	0.0%	99252	0.006	0.020	0.048
data write	00:00:05.742	0.0%	76038	0.017	0.076	0.512
idle	23:32:21.400	99.9%	182155	67.672	465.216	864.713
status file	00:00:13.093	0.0%	16105	0.567	0.813	1.744
TOTAL: 23:34:12.888	23:33:53.518	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:21.089	57.8%	3050	0.000	6.914	1647.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.236	0.6%	6781	0.000	0.035	0.667
readart	00:00:00.003	0.0%	58	0.000	0.052	0.500
TOTAL: 00:00:36.484	00:00:21.328	58.5%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	3
newgroup	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	196

Newsgroups created:

Group	Mode
free.hillary.clinton.uranium-sales	y
free.jail.hillary	y
TOTAL	2

Control Channel:

Sender	newgroup	DoIt	OK
irs@sjrb.ca	2	2	2
irs@usa.guv	1	1	1
TOTAL	3	3	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	316	3297069	3263202	13722	20145	98%	259:34:21
2	news.nask.pl	3	35714	10407	4755	20552	29%	24:01:02
3	news.icm.edu.pl	50	166	9	143	14	5%	13:57:28
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		372	3332951	3273620	18620	40711	98%	297:32:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	745.2 GB	1.6 GB	3.0 GB	749.9 GB	99%	239.5 KB
2	news.nask.pl	72.3 MB	16.5 MB	2.0 GB	2.1 GB	3%	72.2 KB
3	news.icm.edu.pl	226.6 KB	18.2 KB	1.1 MB	1.3 MB	16%	59.0 KB
4	localhost	2.9 KB	0.0 KB	0.0 KB	2.9 KB	100%	1.4 KB
TOTAL: 4		745.2 GB	1.7 GB	5.1 GB	752.0 GB	99%	237.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 10 04:15:02 - 04:59:59	97034	3.0%	35.97	31.0 GB	4.2%	12034.09
Nov 10 05:00:00 - 05:59:59	110622	3.4%	30.73	36.4 GB	4.9%	10599.20
Nov 10 06:00:00 - 06:59:59	96179	2.9%	26.72	28.3 GB	3.8%	8244.36
Nov 10 07:00:00 - 07:59:59	122453	3.7%	34.01	36.3 GB	4.9%	10559.01
Nov 10 08:00:00 - 08:59:59	114348	3.5%	31.76	37.7 GB	5.0%	10967.22
Nov 10 09:00:00 - 09:59:59	94385	2.9%	26.22	30.2 GB	4.0%	8784.55
Nov 10 10:00:00 - 10:59:59	76062	2.3%	21.13	23.8 GB	3.2%	6936.74
Nov 10 11:00:00 - 11:59:59	77730	2.4%	21.59	22.5 GB	3.0%	6566.84
Nov 10 12:00:00 - 12:59:59	64389	2.0%	17.89	18.7 GB	2.5%	5448.62
Nov 10 13:00:00 - 13:59:59	129780	4.0%	36.05	39.5 GB	5.3%	11510.24
Nov 10 14:00:00 - 14:59:59	124801	3.8%	34.67	43.6 GB	5.8%	12689.68
Nov 10 15:00:00 - 15:59:59	106801	3.3%	29.67	32.5 GB	4.4%	9459.35
Nov 10 16:00:00 - 16:59:59	87422	2.7%	24.28	25.8 GB	3.5%	7506.59
Nov 10 17:00:00 - 17:59:59	58378	1.8%	16.22	22.1 GB	3.0%	6445.25
Nov 10 18:00:00 - 18:59:59	96839	3.0%	26.90	21.0 GB	2.8%	6106.21
Nov 10 19:00:00 - 19:59:59	117481	3.6%	32.63	22.2 GB	3.0%	6471.01
Nov 10 20:00:00 - 20:59:59	203019	6.2%	56.39	31.3 GB	4.2%	9103.93
Nov 10 21:00:00 - 21:59:59	259198	7.9%	72.00	37.9 GB	5.1%	11044.95
Nov 10 22:00:00 - 22:59:59	312821	9.6%	86.89	37.8 GB	5.1%	11001.92
Nov 10 23:00:00 - 23:59:59	166589	5.1%	46.27	37.8 GB	5.1%	11020.65
Nov 11 00:00:00 - 00:59:59	90985	2.8%	25.27	23.5 GB	3.2%	6848.81
Nov 11 01:00:00 - 01:59:59	138085	4.2%	38.36	35.3 GB	4.7%	10271.85
Nov 11 02:00:00 - 02:59:59	188872	5.8%	52.46	36.0 GB	4.8%	10480.07
Nov 11 03:00:00 - 03:59:59	266786	8.1%	74.11	27.8 GB	3.7%	8084.40
Nov 11 04:00:00 - 04:15:03	73482	2.2%	81.38	7.0 GB	0.9%	8177.50
TOTAL: 24:00:01	3274541	100.0%	37.90	745.8 GB	100.0%	9050.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	20745	5	305	11	20424
2	newsfeed.pionier.net.pl	20556	230	11533	7	8786
3	news.icm.edu.pl	24	0	1	0	23
TOTAL: 3		41325	235	11839	18	29233

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	162
it.comp.os.win.windows10	34
it.tlc.cellulari.android	24
it.comp.os.win.windows7	4
nl.comp.sys.android	2
comp.mobile.android	2
de.comp.lang.vbclassic	2
nl.comp.os.linux.techniek	2
hr.sci.med	1
uk.net.providers.gradwell	1
comp.sys.raspberry-pi	1
TOTAL: 11	235

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

Groups	Count
pl.praca.oferowana	17
bit.listserv.catholic	1
TOTAL: 2	18

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	20469
EMP (md5)	7809
EMP (phl)	118
Excessively crossposted test article	88
Too many test groups in crosspost	25
Scoring filter (8)	24
Scoring filter (9)	22
HTML post	17
Poison newsgroup	13
Scoring filter (11)	9
Scoring filter (10)	7
Scoring filter (12)	2
Scoring filter (14)	2
Too many newsgroups	1
Scoring filter (13)	1
hierarchy violation - crosspost outside cl	1
TOTAL: 16	28608

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	411
	news.nask.pl	195
	news.icm.edu.pl	2
	TOTAL: 3	608
TOTAL: 1		608

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	26594	2588	19704	114	0	1	9%	23:48:54
2	panorama	47468	1332	18676	313	74	45259	2%	23:57:56
3	poznan	16408	164	9996	39	0	0	0%	23:48:51
4	nask	5084	3	3718	0	0	0	0%	23:48:54
5	okapi	0	0	0	0	0	1282	0%	23:42:33
TOTAL: 5		95554	4087	52094	466	74	46542	4%	119:07:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	467.6 MB	16.2 MB	483.8 MB	5.8 KB/s	183.4 KB	23:48:54
2	panorama	6.0 MB	1.2 MB	7.1 MB	0.1 KB/s	4.4 KB	23:57:56
3	poznan	558.9 KB	369.5 KB	928.3 KB	0.0 KB/s	4.6 KB	23:48:51
4	nask	5.4 KB	0.0 KB	5.4 KB	0.0 KB/s	1.8 KB	23:48:54
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:42:33
TOTAL: 5		474.1 MB	17.7 MB	491.9 MB	1.2 KB/s	110.6 KB	119:07:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	58	72.3 KB	27	0	00:00:26
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	58	72.3 KB	27	2	00:00:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	673	58	72.3 KB	27	2	00:00:32
2	*.binaryedge.ninja	2	0	0.0 KB	0	0	00:00:03
TOTAL: 2		675	58	72.3 KB	27	2	00:00:35

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.604	5.256	00:00:26
mon.man.lodz.pl	1.204	4.260	00:00:04
min-extra-grab-37-ustx-prod.binaryedge.ninja	0.004	0.008	00:00:01
min-extra-grab-11-uk-prod.binaryedge.ninja	0.000	0.012	00:00:01
david.man.lodz.pl	0.356	1.352	00:00:01
isvcs01.man.lodz.pl	0.008	0.036	00:00:00
TOTAL: 6	3.176	10.924	00:00:35

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	287
david.man.lodz.pl	96
min-extra-grab-37-ustx-prod.binaryedge.ninja	1
min-extra-grab-11-uk-prod.binaryedge.ninja	1
TOTAL: 4	385

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Nov 10 04:15:02 -- Nov 11 04:15:03