Daily Usenet report: Nov 17 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Nov 17 10:22:35 newsfeed innfeed[2615]: ME time -39 idle 18446744073709551391(43) blstats 0(43) stsfile 1(9) newart 0(21) readart 62(5) prepart 0(20) read 0(24) write 0(26)
Nov 17 13:10:02 newsfeed pgpverify[8905]: pgpverify: verification failed
Nov 17 14:39:14 newsfeed overchan[2616]: input line too long, skipping
Nov 17 15:00:21 newsfeed nnrpd[12732]: SERVER perl filter not defined
Nov 17 15:01:05 newsfeed nnrpd[12733]: SERVER perl filter not defined
Nov 17 15:04:48 newsfeed nnrpd[12925]: SERVER perl filter not defined
Nov 17 15:05:29 newsfeed nnrpd[12975]: SERVER perl filter not defined
Nov 17 15:06:09 newsfeed nnrpd[12976]: SERVER perl filter not defined
Nov 17 15:08:59 newsfeed nnrpd[13007]: SERVER perl filter not defined
Nov 17 15:09:57 newsfeed nnrpd[13031]: SERVER perl filter not defined
Nov 17 15:12:23 newsfeed nnrpd[13087]: SERVER perl filter not defined
Nov 17 15:15:21 newsfeed nnrpd[13318]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2962895	98.8%	344.4 MB	98.5%
innd	27139	0.9%	4.1 MB	1.2%
innfeed	4889	0.2%	701.1 KB	0.2%
nnrpd	4876	0.2%	505.4 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	2999805	100.0%	349.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2955595	96.3%
Negative hits	99935	3.3%
Positive hits	13399	0.4%
Cache misses	122	0.0%
TOTAL: 4	3069051	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.405	0.0%	214	0.000	20.584	216.000
article cleanup	00:00:48.684	0.1%	2960272	0.007	0.016	0.032
article logging	00:01:39.810	0.1%	2960923	0.017	0.034	0.583
article parse	01:33:16.521	6.5%	157998266	0.017	0.035	0.071
article write	00:28:04.783	2.0%	2950675	0.342	0.571	3.887
artlog/artcncl	00:00:00.421	0.0%	212	0.000	1.986	13.452
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:46.068	0.3%	175617206	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.120	0.0%	144	0.000	14.722	216.000
hishave/artcncl	00:00:00.066	0.0%	214	0.000	0.308	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:45:42.244	3.2%	3070449	0.145	0.893	8.962
history sync	00:00:00.015	0.0%	289	0.000	0.052	2.000
history write	01:08:37.067	4.8%	2956973	0.293	1.392	10.048
hiswrite/artcncl	00:00:00.073	0.0%	70	0.000	1.043	28.000
idle	11:39:35.958	48.6%	87033607	0.341	0.482	1.527
nntp read	01:05:57.406	4.6%	155478215	0.016	0.025	0.038
overview write	00:00:19.181	0.0%	2950675	0.000	0.007	0.015
perl filter	06:36:49.792	27.6%	2956973	6.423	8.052	9.718
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:31.420	0.4%	5946636	0.036	0.056	0.083
TOTAL: 23:58:23.437	23:30:16.034	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.219	0.0%	63920	0.000	0.003	0.250
article prepare	00:00:00.015	0.0%	83980	0.000	0.000	0.024
article read	00:00:43.253	0.1%	21019	0.000	2.058	620.000
backlog stats	00:00:00.257	0.0%	297211	0.000	0.001	0.042
callbacks	00:00:00.000	0.0%	23	0.000	0.000	0.000
data read	00:00:02.050	0.0%	147605	0.000	0.014	0.250
data write	00:00:15.531	0.0%	152100	0.000	0.102	0.500
idle	23:57:19.510	99.9%	297211	17.933	290.163	5473.727
status file	00:00:00.738	0.0%	925	0.000	0.798	5.667
TOTAL: 23:58:29.279	23:58:21.573	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:45:05.499	99.4%	3153	0.000	858.071	100316.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.138	0.0%	6964	0.000	0.020	0.667
readart	00:00:00.551	0.0%	63	0.000	8.746	69.600
TOTAL: 00:45:21.181	00:45:06.188	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	750	2948835	2937776	6646	4413	99%	486:34:49
2	news.nask.pl	3	21644	13201	3281	5162	60%	23:54:37
3	news.icm.edu.pl	47	169	9	159	1	5%	15:27:21
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		810	2970657	2950995	10086	9576	99%	525:56:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	922.9 GB	35.3 MB	230.4 MB	923.1 GB	99%	329.0 KB
2	news.nask.pl	96.7 MB	1.1 MB	58.0 MB	155.8 MB	62%	8.7 KB
3	news.icm.edu.pl	180.3 KB	12.5 KB	12.5 KB	205.4 KB	87%	20.5 KB
4	localhost	15.4 KB	0.0 KB	0.0 KB	15.4 KB	100%	1.7 KB
TOTAL: 4		923.0 GB	36.4 MB	288.4 MB	923.3 GB	99%	327.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:02 - 04:59:59	103683	3.5%	38.43	30.4 GB	3.3%	11817.16
Nov 17 05:00:00 - 05:59:59	143957	4.9%	39.99	42.7 GB	4.6%	12436.01
Nov 17 06:00:00 - 06:59:59	147637	5.0%	41.01	42.9 GB	4.7%	12508.34
Nov 17 07:00:00 - 07:59:59	153698	5.2%	42.69	44.1 GB	4.8%	12842.85
Nov 17 08:00:00 - 08:59:59	157119	5.3%	43.64	44.3 GB	4.8%	12912.70
Nov 17 09:00:00 - 09:59:59	153088	5.2%	42.52	44.1 GB	4.8%	12847.95
Nov 17 10:00:00 - 10:59:59	135060	4.6%	37.52	39.8 GB	4.3%	11583.99
Nov 17 11:00:00 - 11:59:59	111095	3.8%	30.86	31.2 GB	3.4%	9076.44
Nov 17 12:00:00 - 12:59:59	78397	2.7%	21.78	21.8 GB	2.4%	6351.45
Nov 17 13:00:00 - 13:59:59	88955	3.0%	24.71	28.9 GB	3.1%	8405.19
Nov 17 14:00:00 - 14:59:59	95111	3.2%	26.42	31.4 GB	3.4%	9136.56
Nov 17 15:00:00 - 15:59:59	125686	4.3%	34.91	43.3 GB	4.7%	12601.32
Nov 17 16:00:00 - 16:59:59	130694	4.4%	36.30	44.1 GB	4.8%	12843.07
Nov 17 17:00:00 - 17:59:59	121614	4.1%	33.78	40.6 GB	4.4%	11819.71
Nov 17 18:00:00 - 18:59:59	99735	3.4%	27.70	34.1 GB	3.7%	9943.15
Nov 17 19:00:00 - 19:59:59	127887	4.3%	35.52	43.4 GB	4.7%	12628.10
Nov 17 20:00:00 - 20:59:59	123359	4.2%	34.27	42.3 GB	4.6%	12308.09
Nov 17 21:00:00 - 21:59:59	130203	4.4%	36.17	40.1 GB	4.3%	11668.16
Nov 17 22:00:00 - 22:59:59	125982	4.3%	34.99	40.4 GB	4.4%	11770.83
Nov 17 23:00:00 - 23:59:59	126531	4.3%	35.15	40.4 GB	4.4%	11762.99
Nov 18 00:00:00 - 00:59:59	106350	3.6%	29.54	32.5 GB	3.5%	9472.78
Nov 18 01:00:00 - 01:59:59	109841	3.7%	30.51	34.8 GB	3.8%	10137.60
Nov 18 02:00:00 - 02:59:59	123658	4.2%	34.35	41.7 GB	4.5%	12136.63
Nov 18 03:00:00 - 03:59:59	106897	3.6%	29.69	35.0 GB	3.8%	10193.04
Nov 18 04:00:00 - 04:15:02	26187	0.9%	29.03	9.2 GB	1.0%	10672.98
TOTAL: 24:00:00	2952424	100.0%	34.17	923.3 GB	100.0%	11204.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5433	14	316	4	5099
2	newsfeed.pionier.net.pl	4827	206	2982	0	1639
3	news.icm.edu.pl	1	0	1	0	0
TOTAL: 3		10261	220	3299	4	6738

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	156
it.comp.os.win.windows10	24
comp.mobile.android	15
it.tlc.cellulari.android	11
it.comp.os.win.windows7	6
hr.ponuda.hardver.periferije	2
uk.net.providers.gradwell	2
alt.sex.telephones	2
hr.comp.os.linux.ubuntu	1
it.discussioni.energia	1
TOTAL: 10	220

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4457
Binary in non-binary group	1341
Scoring filter (8)	164
Scoring filter (9)	33
EMP (phl)	24
Scoring filter (10)	20
Scoring filter (13)	13
EMP (fsl)	13
Poison newsgroup	6
Scoring filter (11)	6
Scoring filter (12)	4
HTML post	3
Too many newsgroups	1
Scoring filter (14)	1
Attached HTML file	1
TOTAL: 15	6087

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	20234	1388	16817	547	50	44911	6%	23:58:56
2	poznan	18695	172	12838	31	0	0	0%	23:50:02
3	icm	52332	111	25882	18524	0	0	0%	24:00:02
4	nask	7614	62	5621	0	0	0	0%	24:00:02
5	okapi	0	0	0	0	0	2328	0%	23:50:01
TOTAL: 5		98875	1733	61158	19102	50	47239	1%	119:39:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.7 MB	2.7 MB	8.4 MB	0.1 KB/s	4.4 KB	23:58:56
2	icm	2.7 MB	4.5 GB	4.5 GB	54.8 KB/s	254.1 KB	24:00:02
3	poznan	1.2 MB	200.4 KB	1.4 MB	0.0 KB/s	7.1 KB	23:50:02
4	nask	188.9 KB	0.0 KB	188.9 KB	0.0 KB/s	3.0 KB	24:00:02
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		9.7 MB	4.5 GB	4.5 GB	11.0 KB/s	227.8 KB	119:39:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	44	40.3 KB	20	0	00:00:23
2	lze200.lze.lodz.pl	8	19	31.0 KB	5	0	00:44:50
3	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 3		305	63	71.3 KB	25	9	00:45:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	681	44	40.3 KB	20	9	00:00:30
2	*.lze.lodz.pl	8	19	31.0 KB	5	0	00:44:50
TOTAL: 2		689	63	71.3 KB	25	9	00:45:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.022	0.200	00:44:50
web.man.lodz.pl	0.984	4.211	00:00:23
mon.man.lodz.pl	1.025	3.974	00:00:03
david.man.lodz.pl	0.307	1.250	00:00:01
isvcs01.man.lodz.pl	0.039	0.108	00:00:01
TOTAL: 5	2.377	9.743	00:45:20

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	63	100.0%
TOTAL: 1		63	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	30
2	pl.comp.os.ms-windows.winnt	12
3	pl.comp.os.linux	10
4	pl.misc.telefonia.gsm	7
5	pl.comp.os.linux.sieci	2
6	pl.internet.polip	2
TOTAL: 6		63

Daily Usenet report

Nov 17 04:15:02 -- Nov 18 04:15:02