Daily Usenet report: Apr 18 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Apr 18 08:19:18 newsfeed overchan[21571]: input line too long, skipping
Apr 18 09:36:31 newsfeed nnrpd[1621]: SERVER perl filter not defined
Apr 18 09:50:48 newsfeed nnrpd[2092]: SERVER perl filter not defined
Apr 18 11:54:25 newsfeed nnrpd[7486]: SERVER perl filter not defined
Apr 18 13:45:52 newsfeed nnrpd[11802]: SERVER perl filter not defined
Apr 18 13:46:16 newsfeed nnrpd[11809]: SERVER perl filter not defined
Apr 18 15:55:15 newsfeed overchan[21571]: input line too long, skipping
Apr 18 15:55:30 newsfeed overchan[21571]: input line too long, skipping
Apr 18 15:55:45 newsfeed overchan[21571]: input line too long, skipping
Apr 18 15:56:06 newsfeed overchan[21571]: input line too long, skipping
Apr 18 15:56:23 newsfeed overchan[21571]: input line too long, skipping
Apr 18 15:57:01 newsfeed overchan[21571]: input line too long, skipping
Apr 18 23:48:22 newsfeed overchan[21571]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2606623	97.0%	303.7 MB	96.8%
innfeed	45176	1.7%	5.3 MB	1.7%
innd	30800	1.1%	4.3 MB	1.4%
nnrpd	4678	0.2%	490.8 KB	0.2%
overchan	9	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2687287	100.0%	313.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2587271	96.5%
Negative hits	81858	3.1%
Positive hits	12290	0.5%
Cache misses	115	0.0%
TOTAL: 4	2681534	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.211	0.0%	207	0.000	20.343	2220.000
article cleanup	00:00:34.158	0.0%	2605876	0.009	0.013	0.017
article logging	00:01:38.293	0.1%	2606406	0.028	0.038	0.099
article parse	01:05:57.326	4.6%	212949210	0.008	0.019	0.029
article write	00:24:23.565	1.7%	2588182	0.378	0.565	2.904
artlog/artcncl	00:00:00.005	0.0%	205	0.000	0.024	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:25.837	0.2%	230040713	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.385	0.0%	189	0.000	2.037	170.000
hishave/artcncl	00:00:00.639	0.0%	207	0.000	3.087	561.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:02:55.464	4.4%	2697713	0.123	1.400	6.153
history sync	00:00:00.017	0.0%	290	0.000	0.059	1.000
history write	02:09:05.308	9.0%	2601347	0.393	2.977	9.493
hiswrite/artcncl	00:00:00.038	0.0%	18	0.500	2.111	3.333
idle	11:45:07.443	49.0%	146458105	0.198	0.289	0.440
nntp read	01:19:39.753	5.5%	210915405	0.013	0.023	0.034
overview write	00:00:13.865	0.0%	2588182	0.004	0.005	0.008
perl filter	05:13:12.670	21.8%	2601347	4.778	7.224	9.093
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:10.248	0.3%	5220537	0.033	0.048	0.080
TOTAL: 24:00:01.924	23:10:29.225	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.310	0.0%	125295	0.000	0.002	0.016
article prepare	00:00:00.024	0.0%	101044	0.000	0.000	0.013
article read	00:00:40.424	0.0%	25248	0.000	1.601	205.520
backlog stats	00:00:00.273	0.0%	296698	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:02.902	0.0%	161245	0.006	0.018	0.042
data write	00:00:23.779	0.0%	135873	0.018	0.175	0.485
idle	23:50:36.906	99.8%	296698	27.286	289.307	826.985
status file	00:01:14.230	0.1%	16280	0.625	4.560	69.567
TOTAL: 23:53:14.733	23:52:58.848	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.647	57.6%	3020	0.000	6.506	1170.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.177	0.5%	6615	0.000	0.027	1.333
readart	00:00:00.002	0.0%	24	0.000	0.083	0.250
TOTAL: 00:00:34.121	00:00:19.826	58.1%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1031	2588238	2580345	3517	4376	99%	426:06:59
2	news.nask.pl	2	25297	7908	4002	13387	31%	24:02:49
3	news.icm.edu.pl	42	270	13	242	15	4%	15:41:01
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1081	2613810	2588271	7761	17778	99%	465:50:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	859.8 GB	27.4 MB	32.8 MB	859.9 GB	99%	348.8 KB
2	news.nask.pl	33.2 MB	1.2 MB	148.7 MB	183.0 MB	18%	8.8 KB
3	news.icm.edu.pl	282.5 KB	124.1 KB	358.5 KB	765.1 KB	36%	27.3 KB
4	localhost	9.5 KB	0.0 KB	0.0 KB	9.5 KB	100%	1.9 KB
TOTAL: 4		859.9 GB	28.7 MB	181.9 MB	860.1 GB	99%	346.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 18 04:15:02 - 04:59:59	84503	3.3%	31.32	28.7 GB	3.3%	11169.34
Apr 18 05:00:00 - 05:59:59	89578	3.5%	24.88	31.9 GB	3.7%	9286.67
Apr 18 06:00:00 - 06:59:59	112525	4.3%	31.26	38.8 GB	4.5%	11288.26
Apr 18 07:00:00 - 07:59:59	113714	4.4%	31.59	40.1 GB	4.7%	11687.46
Apr 18 08:00:00 - 08:59:59	114404	4.4%	31.78	39.5 GB	4.6%	11499.96
Apr 18 09:00:00 - 09:59:59	118309	4.6%	32.86	40.2 GB	4.7%	11714.07
Apr 18 10:00:00 - 10:59:59	99541	3.8%	27.65	34.4 GB	4.0%	10027.61
Apr 18 11:00:00 - 11:59:59	112384	4.3%	31.22	37.7 GB	4.4%	10973.41
Apr 18 12:00:00 - 12:59:59	112948	4.4%	31.37	39.6 GB	4.6%	11534.66
Apr 18 13:00:00 - 13:59:59	114619	4.4%	31.84	38.8 GB	4.5%	11313.34
Apr 18 14:00:00 - 14:59:59	109139	4.2%	30.32	39.1 GB	4.6%	11384.79
Apr 18 15:00:00 - 15:59:59	106631	4.1%	29.62	37.3 GB	4.3%	10853.06
Apr 18 16:00:00 - 16:59:59	107019	4.1%	29.73	37.4 GB	4.3%	10881.23
Apr 18 17:00:00 - 17:59:59	94159	3.6%	26.16	33.4 GB	3.9%	9728.24
Apr 18 18:00:00 - 18:59:59	86837	3.4%	24.12	28.9 GB	3.4%	8431.54
Apr 18 19:00:00 - 19:59:59	100969	3.9%	28.05	32.9 GB	3.8%	9575.81
Apr 18 20:00:00 - 20:59:59	93288	3.6%	25.91	32.6 GB	3.8%	9508.86
Apr 18 21:00:00 - 21:59:59	105244	4.1%	29.23	34.0 GB	4.0%	9915.47
Apr 18 22:00:00 - 22:59:59	123993	4.8%	34.44	37.6 GB	4.4%	10947.89
Apr 18 23:00:00 - 23:59:59	125565	4.9%	34.88	37.4 GB	4.4%	10905.32
Apr 19 00:00:00 - 00:59:59	108763	4.2%	30.21	30.7 GB	3.6%	8947.34
Apr 19 01:00:00 - 01:59:59	106337	4.1%	29.54	32.9 GB	3.8%	9590.79
Apr 19 02:00:00 - 02:59:59	110425	4.3%	30.67	33.5 GB	3.9%	9765.84
Apr 19 03:00:00 - 03:59:59	110363	4.3%	30.66	33.2 GB	3.9%	9670.41
Apr 19 04:00:00 - 04:15:03	26938	1.0%	29.83	8.3 GB	1.0%	9590.00
TOTAL: 24:00:01	2588195	100.0%	29.96	859.0 GB	100.0%	10425.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13464	40	401	1	13022
2	newsfeed.pionier.net.pl	4745	162	4125	0	458
3	news.icm.edu.pl	14	0	3	0	11
TOTAL: 3		18223	202	4529	1	13491

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	55
it.comp.os.win.windows10	42
it.tlc.cellulari.android	35
comp.mobile.ipad	26
comp.mobile.android	18
comp.sys.raspberry-pi	12
it.comp.os.win.windows7	6
nl.comp.sys.android	3
alt.sex.telephones	2
de.comp.lang.vbclassic	1
it.comp.os.linux.ubuntu	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 12	202

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)	12648
Binary in non-binary group	188
Scoring filter (8)	53
Scoring filter (9)	22
Scoring filter (10)	10
Scoring filter (17)	8
Scoring filter (12)	7
HTML post	6
Scoring filter (11)	5
Scoring filter (18)	5
Scoring filter (14)	4
Excessively crossposted test article	3
EMP (fsl)	1
Scoring filter (22)	1
TOTAL: 14	12961

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	369
	news.nask.pl	160
	news.icm.edu.pl	1
	TOTAL: 3	530
TOTAL: 1		530

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	49104	21269	20441	577	1	0	43%	23:59:59
2	panorama	89242	613	61231	249	37	63407	0%	23:59:59
3	poznan	11847	228	7602	45	0	0	1%	23:59:58
4	nask	9513	9	5380	1755	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1827	0%	23:57:51
TOTAL: 5		159706	22119	94654	2626	38	65234	13%	119:57:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	3.8 GB	2.8 MB	3.8 GB	46.2 KB/s	182.5 KB	23:59:59
2	panorama	2.0 MB	921.0 KB	2.9 MB	0.0 KB/s	3.4 KB	23:59:59
3	poznan	749.8 KB	159.5 KB	909.4 KB	0.0 KB/s	3.3 KB	23:59:58
4	nask	25.7 KB	657.0 MB	657.1 MB	7.8 KB/s	381.4 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		3.8 GB	660.9 MB	4.4 GB	10.8 KB/s	188.5 KB	119:57:44

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	24	25.1 KB	12	0	00:00:24
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:01
TOTAL: 2		293	24	25.1 KB	12	5	00:00:33

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	654	24	25.1 KB	12	5	00:00:31
2	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	00:00:01
TOTAL: 2		656	24	25.1 KB	12	5	00:00:33

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.272	4.276	00:00:24
mon.man.lodz.pl	1.064	3.456	00:00:04
isvcs01.man.lodz.pl	0.032	0.128	00:00:01
ip231.208-100-26.static.steadfastdns.net	0.004	0.032	00:00:01
david.man.lodz.pl	0.316	1.268	00:00:01
TOTAL: 5	2.688	9.160	00:00:33

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	271
david.man.lodz.pl	90
ip231.208-100-26.static.steadfastdns.net	2
TOTAL: 3	363

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	16
2	pl.comp.os.ms-windows.winnt	8
TOTAL: 2		24

Daily Usenet report

Apr 18 04:15:02 -- Apr 19 04:15:03