Daily Usenet report: Sep 15 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Sep 15 08:47:38 newsfeed nnrpd[13884]: SERVER perl filter not defined
Sep 15 08:58:18 newsfeed overchan[2722]: input line too long, skipping
Sep 15 11:54:13 newsfeed nnrpd[20605]: SERVER perl filter not defined
Sep 15 12:04:34 newsfeed nnrpd[20992]: SERVER perl filter not defined
Sep 15 14:52:45 newsfeed nnrpd[27217]: SERVER perl filter not defined
Sep 15 14:54:42 newsfeed nnrpd[27248]: SERVER perl filter not defined
Sep 15 17:00:04 newsfeed pgpverify[31927]: pgpverify: verification failed
Sep 15 18:06:48 newsfeed nnrpd[1848]: SERVER perl filter not defined
Sep 15 18:56:39 newsfeed overchan[2722]: input line too long, skipping
Sep 15 19:00:16 newsfeed overchan[2722]: input line too long, skipping
Sep 15 19:09:18 newsfeed overchan[2722]: input line too long, skipping
Sep 15 19:17:53 newsfeed overchan[2722]: input line too long, skipping
Sep 15 19:29:26 newsfeed overchan[2722]: input line too long, skipping
Sep 15 19:35:59 newsfeed overchan[2722]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2517908	95.9%	293.0 MB	95.6%
innd	56695	2.2%	7.8 MB	2.5%
innfeed	45578	1.7%	5.3 MB	1.7%
nnrpd	4800	0.2%	493.8 KB	0.2%
overchan	9	0.0%	1.4 KB	0.0%
controlchan	4	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2624995	100.0%	306.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2459746	82.3%
Negative hits	434533	14.5%
Positive hits	93416	3.1%
Cache misses	769	0.0%
TOTAL: 4	2988464	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.011	0.0%	231	0.000	30.351	429.778
article cleanup	00:00:42.561	0.0%	2517260	0.012	0.017	0.023
article logging	00:01:52.321	0.1%	2517742	0.034	0.045	0.159
article parse	01:17:04.779	5.4%	146420100	0.020	0.032	0.050
article write	00:28:53.698	2.0%	2435387	0.471	0.712	1.470
artlog/artcncl	00:00:00.005	0.0%	219	0.000	0.023	0.250
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:04:20.115	0.3%	162408357	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.506	0.0%	220	0.000	2.300	40.000
hishave/artcncl	00:00:00.329	0.0%	231	0.000	1.424	14.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:00.393	2.1%	3007870	0.053	0.599	3.715
history sync	00:00:00.023	0.0%	290	0.000	0.079	1.000
history write	01:27:49.407	6.1%	2474763	0.412	2.129	7.306
hiswrite/artcncl	00:00:00.737	0.0%	11	0.000	67.000	139.600
idle	11:53:40.591	49.6%	90541269	0.250	0.473	0.833
nntp read	01:31:54.324	6.4%	144535176	0.024	0.038	0.047
overview write	00:00:15.603	0.0%	2435387	0.004	0.006	0.010
perl filter	05:52:31.272	24.5%	2474761	7.379	8.547	9.957
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:31.211	0.3%	4987652	0.044	0.054	0.073
TOTAL: 24:00:02.768	23:13:44.886	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.650	0.0%	211436	0.000	0.003	0.013
article prepare	00:00:00.025	0.0%	63316	0.000	0.000	0.008
article read	00:00:54.535	0.1%	15590	0.000	3.498	797.000
backlog stats	00:00:00.589	0.0%	643044	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:08.641	0.0%	404861	0.006	0.021	0.071
data write	00:00:51.125	0.1%	315606	0.027	0.162	0.452
idle	23:50:27.985	99.8%	643044	28.875	133.471	864.696
status file	00:00:18.863	0.0%	16434	0.717	1.148	10.172
TOTAL: 23:53:17.189	23:52:42.413	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:20.740	98.6%	3068	0.000	593.462	450681.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.198	0.0%	6760	0.000	0.029	1.333
readart	00:00:00.011	0.0%	40	0.000	0.275	4.000
TOTAL: 00:30:47.354	00:30:20.949	98.6%	-	-	-	-

Control commands to INND:

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

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	1062	2470421	2401289	13994	55138	97%	403:57:22
2	news.nask.pl	3	98531	34319	37507	26705	34%	23:58:47
3	news.icm.edu.pl	41	292	36	239	17	12%	16:21:09
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1113	2569250	2435650	51740	81860	94%	444:17:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	809.6 GB	10.6 GB	13.4 GB	833.6 GB	97%	355.8 KB
2	news.nask.pl	8.6 GB	634.7 MB	4.3 GB	13.5 GB	63%	232.2 KB
3	news.icm.edu.pl	754.9 KB	21.9 KB	885.9 KB	1.6 MB	45%	31.4 KB
4	localhost	9.3 KB	0.0 KB	0.0 KB	9.3 KB	100%	1.6 KB
TOTAL: 4		818.2 GB	11.2 GB	17.7 GB	847.1 GB	96%	352.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:02 - 04:59:59	85961	3.5%	31.86	30.5 GB	3.7%	11844.14
Sep 15 05:00:00 - 05:59:59	118552	4.9%	32.93	42.9 GB	5.3%	12501.58
Sep 15 06:00:00 - 06:59:59	124259	5.1%	34.52	42.6 GB	5.2%	12414.58
Sep 15 07:00:00 - 07:59:59	121928	5.0%	33.87	43.8 GB	5.4%	12764.11
Sep 15 08:00:00 - 08:59:59	100397	4.1%	27.89	34.1 GB	4.2%	9945.18
Sep 15 09:00:00 - 09:59:59	72805	3.0%	20.22	25.4 GB	3.1%	7391.56
Sep 15 10:00:00 - 10:59:59	54337	2.2%	15.09	19.8 GB	2.4%	5755.58
Sep 15 11:00:00 - 11:59:59	55218	2.3%	15.34	19.2 GB	2.4%	5603.52
Sep 15 12:00:00 - 12:59:59	57503	2.4%	15.97	19.8 GB	2.4%	5756.81
Sep 15 13:00:00 - 13:59:59	61091	2.5%	16.97	21.8 GB	2.7%	6335.26
Sep 15 14:00:00 - 14:59:59	122510	5.0%	34.03	40.1 GB	4.9%	11674.64
Sep 15 15:00:00 - 15:59:59	117649	4.8%	32.68	40.9 GB	5.0%	11900.57
Sep 15 16:00:00 - 16:59:59	110920	4.6%	30.81	37.4 GB	4.6%	10891.43
Sep 15 17:00:00 - 17:59:59	73756	3.0%	20.49	24.9 GB	3.0%	7243.42
Sep 15 18:00:00 - 18:59:59	98251	4.0%	27.29	32.3 GB	4.0%	9406.43
Sep 15 19:00:00 - 19:59:59	102630	4.2%	28.51	36.3 GB	4.4%	10587.27
Sep 15 20:00:00 - 20:59:59	100025	4.1%	27.78	34.6 GB	4.2%	10069.75
Sep 15 21:00:00 - 21:59:59	108794	4.5%	30.22	35.2 GB	4.3%	10266.93
Sep 15 22:00:00 - 22:59:59	113608	4.7%	31.56	35.8 GB	4.4%	10440.61
Sep 15 23:00:00 - 23:59:59	117963	4.8%	32.77	37.5 GB	4.6%	10930.33
Sep 16 00:00:00 - 00:59:59	105304	4.3%	29.25	32.9 GB	4.0%	9579.27
Sep 16 01:00:00 - 01:59:59	129311	5.3%	35.92	40.7 GB	5.0%	11857.77
Sep 16 02:00:00 - 02:59:59	135112	5.5%	37.53	41.6 GB	5.1%	12117.54
Sep 16 03:00:00 - 03:59:59	114327	4.7%	31.76	36.7 GB	4.5%	10685.16
Sep 16 04:00:00 - 04:15:03	33122	1.4%	36.68	10.2 GB	1.3%	11893.76
TOTAL: 24:00:01	2435333	100.0%	28.19	817.0 GB	100.0%	9915.68

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	55423	211	40123	1	15088
2	news.nask.pl	26916	6	2375	4	24531
3	news.icm.edu.pl	17	0	1	0	16
TOTAL: 3		82356	217	42499	5	39635

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	141
it.tlc.cellulari.android	34
it.comp.os.win.windows10	19
it.comp.os.win.windows7	7
comp.mobile.android	6
hr.rec.glazba.hifi	2
it.comp.os.linux.ubuntu	2
uk.rec.cycling.moderated	2
alt.sex.telephones	1
de.comp.lang.perl	1
hr.ponuda.hardver.komponente	1
it.tlc.cellulari.iphone	1
TOTAL: 12	217

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	34407
EMP (md5)	4583
Scoring filter (8)	68
Scoring filter (9)	24
EMP (phl)	17
Scoring filter (10)	14
Scoring filter (11)	13
HTML post	13
Poison newsgroup	4
Scoring filter (14)	3
Scoring filter (12)	3
Too many newsgroups	1
Scoring filter (13)	1
TOTAL: 13	39151

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	281
	news.nask.pl	201
	TOTAL: 2	482
TOTAL: 1		482

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nask	96816	7666	60097	8	0	7%	23:59:58
2	panorama	103422	481	75485	190	135982	0%	23:59:58
3	poznan	13617	160	8521	47	1	1%	23:59:50
4	icm	123947	63	108418	6948	58	0%	23:59:58
5	okapi	0	0	0	0	1478	0%	23:57:50
TOTAL: 5		337802	8370	252521	7193	137519	2%	119:57:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	2.6 GB	2.8 MB	2.6 GB	31.3 KB/s	352.4 KB	23:59:58
2	icm	7.9 MB	2.5 GB	2.5 GB	30.5 KB/s	375.3 KB	23:59:58
3	panorama	4.6 MB	5.0 MB	9.6 MB	0.1 KB/s	14.7 KB	23:59:58
4	poznan	607.9 KB	166.3 KB	774.2 KB	0.0 KB/s	3.7 KB	23:59:50
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		2.6 GB	2.5 GB	5.1 GB	12.4 KB/s	343.5 KB	119:57:34

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	40	32.6 KB	19	0	00:00:29
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 2		294	40	32.6 KB	19	6	00:30:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	679	40	32.6 KB	19	6	00:30:46
2	*.shodan.io	1	0	0.0 KB	0	0	00:00:00
TOTAL: 2		680	40	32.6 KB	19	6	00:30:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-tom.man.lodz.pl	0.012	0.024	00:30:02
web.man.lodz.pl	1.444	5.412	00:00:29
mon.man.lodz.pl	1.296	5.636	00:00:09
david.man.lodz.pl	0.444	1.688	00:00:03
isvcs01.man.lodz.pl	0.040	0.136	00:00:00
census8.shodan.io	0.008	0.012	00:00:00
TOTAL: 6	3.244	12.908	00:30:46

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
census8.shodan.io	1
pc-tom.man.lodz.pl	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-tom.man.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	34
2	pl.comp.pecet	6
TOTAL: 2		40

Daily Usenet report

Sep 15 04:15:02 -- Sep 16 04:15:03