Daily Usenet report: Nov 6 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 6 11:00:25 newsfeed nnrpd[31588]: SERVER perl filter not defined
Nov 6 12:22:13 newsfeed nnrpd[3216]: SERVER perl filter not defined
Nov 6 17:31:20 newsfeed nnrpd[24377]: SERVER perl filter not defined
Nov 7 03:24:49 newsfeed overchan[2731]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2528735	97.6%	287.9 MB	97.1%
innfeed	38450	1.5%	4.6 MB	1.5%
innd	19984	0.8%	3.5 MB	1.2%
nnrpd	5040	0.2%	540.3 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2592211	100.0%	296.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2522128	98.3%
Negative hits	26230	1.0%
Positive hits	17628	0.7%
Cache misses	114	0.0%
TOTAL: 4	2566100	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.267	0.0%	233	0.000	9.730	329.000
article cleanup	00:00:39.501	0.0%	2527940	0.005	0.016	0.030
article logging	00:01:09.326	0.1%	2528448	0.015	0.027	0.314
article parse	01:16:07.568	5.3%	291000740	0.005	0.016	0.072
article write	00:24:39.280	1.7%	2522986	0.310	0.586	2.277
artlog/artcncl	00:00:00.006	0.0%	231	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:26.738	0.3%	307789261	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.335	0.0%	199	0.000	1.683	18.000
hishave/artcncl	00:00:00.040	0.0%	233	0.000	0.172	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:57.117	2.3%	2567708	0.084	0.770	11.320
history sync	00:00:00.017	0.0%	290	0.000	0.059	1.000
history write	00:47:05.673	3.3%	2523860	0.159	1.120	18.268
hiswrite/artcncl	00:00:00.016	0.0%	34	0.000	0.471	7.000
idle	13:44:09.852	57.3%	201204968	0.118	0.246	4.417
nntp read	01:20:12.169	5.6%	289046557	0.007	0.017	0.035
overview write	00:00:13.968	0.0%	2522986	0.000	0.006	0.012
perl filter	05:02:54.444	21.0%	2523860	4.178	7.201	9.947
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:41.073	0.3%	5107201	0.015	0.055	0.087
TOTAL: 23:59:11.165	23:19:19.390	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.293	0.0%	122414	0.000	0.002	0.083
article prepare	00:00:00.015	0.0%	38794	0.000	0.000	0.250
article read	00:00:27.269	0.0%	9683	0.000	2.816	523.000
backlog stats	00:00:00.203	0.0%	285292	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:01.767	0.0%	162587	0.000	0.011	0.111
data write	00:00:07.054	0.0%	103248	0.000	0.068	0.741
idle	23:57:36.605	99.9%	285292	75.300	302.345	2017.967
status file	00:00:09.876	0.0%	13498	0.000	0.732	15.417
TOTAL: 23:58:33.819	23:58:23.082	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.017	0.0%	19	0.895	0.895	0.895
idle	00:42:02.152	99.3%	17691	0.000	142.567	10048.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.678	0.0%	36248	0.000	0.019	0.500
readart	00:00:01.141	0.0%	104	0.000	10.971	19.448
TOTAL: 00:42:20.052	00:42:03.988	99.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	858	2522832	2517999	431	4402	99%	276:41:04
2	news.nask.pl	3	17194	4342	12358	494	25%	22:52:07
3	news.icm.edu.pl	43	289	39	202	48	13%	17:13:57
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		908	2540318	2522383	12991	4944	99%	316:47:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	779.7 GB	15.7 MB	132.6 MB	779.8 GB	99%	324.2 KB
2	news.nask.pl	16.3 MB	1.2 MB	1.3 MB	18.8 MB	86%	4.0 KB
3	news.icm.edu.pl	1.9 MB	17.5 KB	1.1 MB	3.0 MB	63%	35.4 KB
4	localhost	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	2.1 KB
TOTAL: 4		779.7 GB	16.9 MB	135.0 MB	779.8 GB	99%	323.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 6 04:15:02 - 04:59:59	98880	3.9%	36.65	29.8 GB	3.8%	11567.57
Nov 6 05:00:00 - 05:59:59	124882	4.9%	34.69	37.6 GB	4.8%	10949.40
Nov 6 06:00:00 - 06:59:59	119897	4.8%	33.30	35.4 GB	4.5%	10316.65
Nov 6 07:00:00 - 07:59:59	112391	4.5%	31.22	33.7 GB	4.3%	9803.13
Nov 6 08:00:00 - 08:59:59	104864	4.2%	29.13	31.9 GB	4.1%	9294.32
Nov 6 09:00:00 - 09:59:59	102283	4.1%	28.41	31.4 GB	4.0%	9136.53
Nov 6 10:00:00 - 10:59:59	93315	3.7%	25.92	30.8 GB	4.0%	8979.62
Nov 6 11:00:00 - 11:59:59	95097	3.8%	26.42	33.6 GB	4.3%	9784.20
Nov 6 12:00:00 - 12:59:59	80571	3.2%	22.38	30.0 GB	3.8%	8725.27
Nov 6 13:00:00 - 13:59:59	82692	3.3%	22.97	31.0 GB	4.0%	9024.98
Nov 6 14:00:00 - 14:59:59	87387	3.5%	24.27	30.8 GB	4.0%	8982.31
Nov 6 15:00:00 - 15:59:59	119964	4.8%	33.32	34.7 GB	4.5%	10109.87
Nov 6 16:00:00 - 16:59:59	117197	4.6%	32.55	32.9 GB	4.2%	9569.10
Nov 6 17:00:00 - 17:59:59	98324	3.9%	27.31	31.0 GB	4.0%	9017.18
Nov 6 18:00:00 - 18:59:59	106755	4.2%	29.65	33.2 GB	4.3%	9662.79
Nov 6 19:00:00 - 19:59:59	126099	5.0%	35.03	36.6 GB	4.7%	10662.05
Nov 6 20:00:00 - 20:59:59	127160	5.0%	35.32	36.1 GB	4.6%	10517.09
Nov 6 21:00:00 - 21:59:59	124170	4.9%	34.49	35.9 GB	4.6%	10463.73
Nov 6 22:00:00 - 22:59:59	116538	4.6%	32.37	33.0 GB	4.2%	9598.89
Nov 6 23:00:00 - 23:59:59	103116	4.1%	28.64	30.4 GB	3.9%	8851.21
Nov 7 00:00:00 - 00:59:59	83471	3.3%	23.19	25.6 GB	3.3%	7449.26
Nov 7 01:00:00 - 01:59:59	88512	3.5%	24.59	26.3 GB	3.4%	7666.49
Nov 7 02:00:00 - 02:59:59	100784	4.0%	28.00	30.8 GB	4.0%	8980.43
Nov 7 03:00:00 - 03:59:59	86359	3.4%	23.99	29.3 GB	3.8%	8520.99
Nov 7 04:00:00 - 04:15:03	22334	0.9%	24.73	7.5 GB	1.0%	8703.52
TOTAL: 24:00:01	2523042	100.0%	29.20	779.1 GB	100.0%	9454.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4850	235	3600	1	1014
2	news.nask.pl	561	14	478	2	67
3	news.icm.edu.pl	51	0	2	0	49
TOTAL: 3		5462	249	4080	3	1130

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	87
it.tlc.cellulari.android	48
comp.mobile.android	25
hr.alt.cellular.gsm.iphone	19
nl.comp.sys.android	13
it.comp.os.win.windows10	11
nl.comp.os.linux.techniek	10
it.comp.os.win.windows8	7
de.rec.sf.startrek	6
hr.comp.os.linux.ubuntu	4
de.comm.software.mailreader	3
alt.sex.telephones	3
it.comp.os.win.windows7	3
comp.mobile.ipad	3
hr.ponuda.hardver.komponente	3
de.comp.os.os2	2
de.comm.provider.telefonie	1
uk.rec.cycling.moderated	1
TOTAL: 18	249

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	470
Binary in non-binary group	94
EMP (md5)	50
Attached HTML file	3
Scoring filter (8)	3
Too many newsgroups	2
TOTAL: 6	622

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	53801	6144	29387	2333	0	15	11%	23:58:39
2	panorama	67450	509	44477	226	21	77126	0%	23:56:33
3	poznan	6856	124	4336	51	0	0	1%	23:50:03
4	nask	0	0	0	0	0	14479	0%	23:50:01
5	okapi	0	0	0	0	0	2571	0%	23:50:01
TOTAL: 5		128107	6777	78200	2610	21	94191	5%	119:25:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	998.6 MB	524.8 MB	1.5 GB	18.1 KB/s	184.0 KB	23:58:39
2	panorama	2.1 MB	1.0 MB	3.2 MB	0.0 KB/s	4.4 KB	23:56:33
3	poznan	418.7 KB	93.9 KB	512.7 KB	0.0 KB/s	2.9 KB	23:50:03
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		1001.2 MB	525.9 MB	1.5 GB	3.6 KB/s	166.6 KB	119:25:17

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
panorama	488.4 KB
TOTAL: 2	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	2	58	131.2 KB	5	0	00:10:44
2	web.man.lodz.pl	288	46	39.3 KB	21	0	00:00:27
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		293	104	170.5 KB	26	3	00:42:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	2	58	131.2 KB	5	0	00:10:44
2	*.man.lodz.pl	675	46	39.3 KB	21	3	00:00:32
3	*.toya.net.pl	47	0	0.0 KB	0	0	00:00:02
4	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:18
5	unresolved	7	0	0.0 KB	0	0	00:30:40
TOTAL: 5		735	104	170.5 KB	26	3	00:42:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.26	0.710	1.060	00:30:29
lze200.lze.lodz.pl	0.011	0.025	00:10:44
web.man.lodz.pl	0.936	3.251	00:00:27
host227.elsnet.pl	0.000	0.050	00:00:18
212.51.203.199	0.005	0.034	00:00:11
mon.man.lodz.pl	0.768	2.889	00:00:04
staticline41435.toya.net.pl	0.128	0.398	00:00:02
david.man.lodz.pl	0.309	0.926	00:00:00
isvcs01.man.lodz.pl	0.017	0.030	00:00:00
TOTAL: 9	2.884	8.663	00:42:19

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	47
185.48.193.26	5
host227.elsnet.pl	4
212.51.203.199	2
TOTAL: 6	442

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.26	2	2
TOTAL: 1	2	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	49
2	pl.rec.dom	17
3	pl.comp.os.linux	17
4	pl.rec.kuchnia	17
5	pl.misc.telefonia.gsm	9
6	pl.comp.os.ms-windows.winnt	4
TOTAL: 6		113

Daily Usenet report

Nov 6 04:15:02 -- Nov 7 04:15:03