Daily Usenet report: Nov 15 04:15:03

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Nov 15 09:43:07 newsfeed overchan[27163]: input line too long, skipping
Nov 15 10:10:38 newsfeed nnrpd[21822]: SERVER perl filter not defined
Nov 15 10:52:42 newsfeed nnrpd[23346]: SERVER perl filter not defined
Nov 15 10:54:11 newsfeed nnrpd[23354]: SERVER perl filter not defined
Nov 15 10:58:25 newsfeed overchan[27163]: input line too long, skipping
Nov 15 10:58:40 newsfeed overchan[27163]: input line too long, skipping
Nov 15 11:20:21 newsfeed nnrpd[24192]: SERVER perl filter not defined
Nov 15 11:24:33 newsfeed nnrpd[24389]: SERVER perl filter not defined
Nov 15 12:13:53 newsfeed nnrpd[26077]: SERVER perl filter not defined
Nov 15 12:43:10 newsfeed nnrpd[27089]: SERVER perl filter not defined
Nov 15 13:53:09 newsfeed nnrpd[29472]: SERVER perl filter not defined
Nov 15 15:00:40 newsfeed overchan[27163]: input line too long, skipping
Nov 15 15:25:12 newsfeed nnrpd[32576]: SERVER perl filter not defined
Nov 15 15:26:04 newsfeed nnrpd[32577]: SERVER perl filter not defined
Nov 15 15:58:45 newsfeed overchan[27163]: input line too long, skipping
Nov 15 17:00:05 newsfeed pgpverify[3381]: pgpverify: verification failed
Nov 15 17:51:07 newsfeed nnrpd[5055]: SERVER perl filter not defined
Nov 15 20:43:56 newsfeed nnrpd[10996]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2938574	97.5%	339.8 MB	97.1%
innfeed	51053	1.7%	6.1 MB	1.7%
innd	20354	0.7%	3.4 MB	1.0%
nnrpd	4936	0.2%	530.7 KB	0.1%
overchan	6	0.0%	0.7 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	3014928	100.0%	349.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2920979	96.8%
Negative hits	67230	2.2%
Positive hits	29067	1.0%
Cache misses	166	0.0%
TOTAL: 4	3017442	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.027	0.0%	117	0.000	42.966	200.000
article cleanup	00:00:36.174	0.0%	2935672	0.008	0.012	0.017
article logging	00:02:03.537	0.1%	2936239	0.026	0.042	0.151
article parse	00:50:14.446	3.5%	103478558	0.011	0.029	0.050
article write	00:19:44.551	1.4%	2926521	0.229	0.405	0.664
artlog/artcncl	00:00:00.001	0.0%	112	0.000	0.009	0.077
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:45.530	0.2%	119998687	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.098	0.0%	117	0.000	17.932	135.000
hishave/artcncl	00:00:00.398	0.0%	117	0.000	3.402	30.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:39:33.203	6.9%	3025202	0.159	1.974	7.693
history sync	00:00:00.016	0.0%	291	0.000	0.055	0.500
history write	02:53:41.742	12.1%	2928550	0.755	3.559	7.892
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	11:40:18.239	48.6%	71310475	0.305	0.589	1.227
nntp read	01:09:09.763	4.8%	101209960	0.026	0.041	0.056
overview write	00:00:14.371	0.0%	2926521	0.003	0.005	0.007
perl filter	04:49:43.382	20.1%	2928548	3.503	5.936	9.110
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:36.445	0.4%	5899436	0.040	0.057	0.078
TOTAL: 24:00:02.096	23:33:48.923	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.312	0.0%	117584	0.000	0.003	0.013
article prepare	00:00:00.014	0.0%	45176	0.000	0.000	0.017
article read	00:00:17.349	0.0%	10907	0.000	1.591	88.571
backlog stats	00:00:00.296	0.0%	340455	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:03.856	0.0%	188930	0.005	0.020	0.131
data write	00:00:23.064	0.0%	158711	0.025	0.145	0.279
idle	23:51:43.301	99.9%	340455	36.198	252.319	1013.678
status file	00:00:23.172	0.0%	16987	0.767	1.364	7.450
TOTAL: 23:53:08.217	23:52:51.364	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:15.281	51.2%	3158	0.000	4.839	49.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.543	1.8%	6828	0.000	0.080	3.368
readart	00:00:00.004	0.0%	46	0.000	0.087	0.500
TOTAL: 00:00:29.865	00:00:15.828	53.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	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	782	2928583	2923682	1343	3558	99%	500:10:29
2	news.nask.pl	2	31200	4909	20662	5629	15%	24:22:25
3	news.icm.edu.pl	53	204	66	134	4	32%	16:47:27
4	localhost	13	12	10	0	2	83%	00:00:00
TOTAL: 4		850	2959999	2928667	22139	9193	98%	541:20:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	838.2 GB	96.3 MB	98.8 MB	838.4 GB	99%	300.3 KB
2	news.nask.pl	142.2 MB	476.0 MB	489.2 MB	1.1 GB	12%	107.6 KB
3	news.icm.edu.pl	1.5 MB	28.6 KB	158.2 KB	1.7 MB	89%	24.5 KB
4	localhost	16.9 KB	0.0 KB	18.3 KB	35.1 KB	47%	2.9 KB
TOTAL: 4		838.4 GB	572.4 MB	588.2 MB	839.5 GB	99%	299.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 15 04:15:03 - 04:59:59	68339	2.3%	25.34	28.2 GB	3.4%	10962.44
Nov 15 05:00:00 - 05:59:59	110048	3.8%	30.57	41.8 GB	5.0%	12168.18
Nov 15 06:00:00 - 06:59:59	114464	3.9%	31.80	41.8 GB	5.0%	12165.66
Nov 15 07:00:00 - 07:59:59	125132	4.3%	34.76	42.9 GB	5.1%	12500.86
Nov 15 08:00:00 - 08:59:59	124767	4.3%	34.66	42.6 GB	5.1%	12421.02
Nov 15 09:00:00 - 09:59:59	141913	4.8%	39.42	38.5 GB	4.6%	11213.36
Nov 15 10:00:00 - 10:59:59	135406	4.6%	37.61	24.4 GB	2.9%	7119.01
Nov 15 11:00:00 - 11:59:59	87292	3.0%	24.25	24.0 GB	2.9%	7000.16
Nov 15 12:00:00 - 12:59:59	155251	5.3%	43.13	34.9 GB	4.2%	10175.32
Nov 15 13:00:00 - 13:59:59	161270	5.5%	44.80	37.8 GB	4.5%	10999.54
Nov 15 14:00:00 - 14:59:59	118197	4.0%	32.83	34.8 GB	4.2%	10141.70
Nov 15 15:00:00 - 15:59:59	125212	4.3%	34.78	34.6 GB	4.1%	10064.55
Nov 15 16:00:00 - 16:59:59	155162	5.3%	43.10	36.4 GB	4.3%	10601.81
Nov 15 17:00:00 - 17:59:59	135116	4.6%	37.53	33.7 GB	4.0%	9827.57
Nov 15 18:00:00 - 18:59:59	140544	4.8%	39.04	42.3 GB	5.0%	12319.57
Nov 15 19:00:00 - 19:59:59	139718	4.8%	38.81	42.8 GB	5.1%	12453.19
Nov 15 20:00:00 - 20:59:59	151146	5.2%	41.98	43.1 GB	5.1%	12564.43
Nov 15 21:00:00 - 21:59:59	144062	4.9%	40.02	28.4 GB	3.4%	8276.67
Nov 15 22:00:00 - 22:59:59	88807	3.0%	24.67	21.2 GB	2.5%	6168.51
Nov 15 23:00:00 - 23:59:59	105282	3.6%	29.25	30.8 GB	3.7%	8979.50
Nov 16 00:00:00 - 00:59:59	83228	2.8%	23.12	21.4 GB	2.6%	6247.40
Nov 16 01:00:00 - 01:59:59	96284	3.3%	26.75	33.3 GB	4.0%	9712.01
Nov 16 02:00:00 - 02:59:59	110193	3.8%	30.61	40.4 GB	4.8%	11766.92
Nov 16 03:00:00 - 03:59:59	85609	2.9%	23.78	28.9 GB	3.5%	8421.08
Nov 16 04:00:00 - 04:15:02	26296	0.9%	29.15	8.8 GB	1.1%	10244.31
TOTAL: 23:59:59	2928738	100.0%	33.90	838.0 GB	100.0%	10170.43

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5683	24	3770	2	1887
2	newsfeed.pionier.net.pl	4032	165	3341	2	524
3	news.icm.edu.pl	7	0	2	0	5
4	localhost	2	0	0	0	2
TOTAL: 4		9724	189	7113	4	2418

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	71
it.comp.os.win.windows10	40
comp.mobile.android	29
it.tlc.cellulari.android	22
de.comp.lang.php	12
comp.sys.raspberry-pi	6
de.comp.lang.vbclassic	3
de.comp.lang.perl	2
it.comp.os.win.windows7	2
nl.comp.os.linux.techniek	1
comp.mobile.ipad	1
TOTAL: 11	189

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)	1667
Binary in non-binary group	97
Scoring filter (16)	28
Scoring filter (9)	22
Scoring filter (10)	6
Scoring filter (8)	6
Scoring filter (18)	5
EMP (phl)	4
Too many newsgroups	4
Scoring filter (11)	3
Too many test groups in crosspost	2
EMP (fsl)	1
Scoring filter (12)	1
Scoring filter (14)	1
TOTAL: 14	1847

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	2
	TOTAL: 1	2
Including strange strings
	newsfeed.pionier.net.pl	471
	news.nask.pl	95
	news.icm.edu.pl	3
	TOTAL: 3	569
TOTAL: 2		571

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	48936	8833	25800	42	0	0	18%	23:59:54
2	icm	51782	645	44708	485	0	0	1%	23:59:55
3	panorama	73713	626	44685	195	2	72345	0%	23:59:57
4	poznan	6414	50	4500	16	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1087	0%	23:57:50
TOTAL: 5		180845	10154	119693	738	2	73432	5%	119:57:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	3.0 GB	14.2 MB	3.0 GB	36.3 KB/s	353.7 KB	23:59:54
2	icm	418.9 MB	161.7 MB	580.6 MB	6.9 KB/s	526.2 KB	23:59:55
3	panorama	1.9 MB	949.3 KB	2.8 MB	0.0 KB/s	3.5 KB	23:59:57
4	poznan	164.1 KB	53.2 KB	217.3 KB	0.0 KB/s	3.3 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		3.4 GB	176.8 MB	3.6 GB	8.7 KB/s	343.1 KB	119:57:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	290	46	42.7 KB	21	0	4	00:00:21
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	0	00:00:01
TOTAL: 2		300	46	42.7 KB	21	10	4	00:00:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	588	46	42.7 KB	21	10	4	00:00:27
2	unresolved	96	0	0.0 KB	0	0	0	00:00:01
TOTAL: 2		684	46	42.7 KB	21	10	4	00:00:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.392	6.700	00:00:21
mon.man.lodz.pl	1.564	7.964	00:00:04
212.51.192.185	0.436	2.012	00:00:01
isvcs01.man.lodz.pl	0.092	0.228	00:00:01
TOTAL: 4	3.484	16.904	00:00:28

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Nov 15 04:15:03 -- Nov 16 04:15:02