Daily Usenet report: Mar 15 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Mar 15 08:53:25 newsfeed nnrpd[31938]: SERVER perl filter not defined
Mar 15 10:00:15 newsfeed overchan[21109]: input line too long, skipping
Mar 15 10:00:34 newsfeed overchan[21109]: input line too long, skipping
Mar 15 10:01:03 newsfeed overchan[21109]: input line too long, skipping
Mar 15 12:38:51 newsfeed nnrpd[7941]: SERVER perl filter not defined
Mar 15 16:00:05 newsfeed pgpverify[15420]: pgpverify: verification failed
Mar 15 16:04:33 newsfeed overchan[21109]: input line too long, skipping
Mar 15 18:25:30 newsfeed overchan[21109]: input line too long, skipping
Mar 15 19:03:32 newsfeed overchan[21109]: input line too long, skipping
Mar 15 20:21:55 newsfeed overchan[21109]: input line too long, skipping
Mar 15 20:22:14 newsfeed overchan[21109]: input line too long, skipping
Mar 15 20:22:35 newsfeed overchan[21109]: input line too long, skipping
Mar 16 04:05:21 newsfeed overchan[21109]: input line too long, skipping
Mar 16 04:05:37 newsfeed overchan[21109]: input line too long, skipping
Mar 16 04:05:53 newsfeed overchan[21109]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2835705	97.5%	317.9 MB	97.1%
innfeed	45182	1.6%	5.4 MB	1.6%
innd	21678	0.7%	3.5 MB	1.1%
nnrpd	4731	0.2%	503.4 KB	0.2%
overchan	13	0.0%	2.5 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2907314	100.0%	327.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2814189	95.6%
Negative hits	117212	4.0%
Positive hits	11270	0.4%
Cache misses	119	0.0%
TOTAL: 4	2942790	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.183	0.0%	202	0.000	15.757	339.000
article cleanup	00:00:26.224	0.0%	2837203	0.006	0.009	0.014
article logging	00:01:39.174	0.1%	2837877	0.024	0.035	0.301
article parse	00:31:54.419	2.2%	92165500	0.012	0.021	0.035
article write	00:14:33.908	1.0%	2832717	0.162	0.309	0.787
artlog/artcncl	00:00:00.003	0.0%	198	0.000	0.015	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:15.535	0.1%	107393259	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.856	0.0%	200	0.000	4.280	90.333
hishave/artcncl	00:00:00.214	0.0%	202	0.000	1.059	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:41:15.712	2.9%	2964910	0.063	0.835	5.476
history sync	00:00:00.010	0.0%	290	0.000	0.034	0.500
history write	01:55:16.693	8.0%	2835362	0.255	2.439	10.224
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	16:03:28.168	66.9%	76053995	0.479	0.760	2.288
nntp read	00:39:34.600	2.7%	90108236	0.019	0.026	0.046
overview write	00:00:11.308	0.0%	2832717	0.003	0.004	0.006
perl filter	03:22:49.616	14.1%	2835362	3.098	4.292	6.427
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:15.742	0.3%	5686516	0.033	0.045	0.070
TOTAL: 24:00:03.493	23:36:45.366	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.119	0.0%	60748	0.000	0.002	0.010
article prepare	00:00:00.005	0.0%	16760	0.000	0.000	0.011
article read	00:01:09.347	0.1%	4176	0.000	16.606	246.000
backlog stats	00:00:00.118	0.0%	184603	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	8	0.000	0.000	0.000
data read	00:00:01.143	0.0%	96341	0.002	0.012	0.026
data write	00:00:04.680	0.0%	77399	0.020	0.060	0.196
idle	23:52:00.749	99.9%	184603	273.230	465.435	838.077
status file	00:00:18.682	0.0%	16195	0.419	1.154	16.595
TOTAL: 23:53:42.580	23:53:34.843	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:21.616	72.3%	2999	0.000	7.208	3652.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.198	0.7%	6593	0.000	0.030	0.526
readart	00:00:00.000	0.0%	12	0.000	0.000	0.000
TOTAL: 00:00:29.889	00:00:21.814	73.0%	-	-	-	-

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	1128	2829583	2819499	8154	1930	99%	432:52:04
2	news.nask.pl	2	14747	11099	1067	2581	75%	24:29:20
3	news.icm.edu.pl	52	235	12	220	3	5%	18:05:44
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1185	2844567	2830612	9441	4514	99%	475:27:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	669.7 GB	21.2 MB	25.9 MB	669.7 GB	99%	248.9 KB
2	news.nask.pl	62.4 MB	1003.6 KB	17.1 MB	80.5 MB	77%	6.0 KB
3	news.icm.edu.pl	247.1 KB	10.8 KB	118.8 KB	376.7 KB	65%	25.1 KB
4	localhost	3.7 KB	0.0 KB	0.0 KB	3.7 KB	100%	1.9 KB
TOTAL: 4		669.8 GB	22.1 MB	43.1 MB	669.8 GB	99%	247.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 15 04:15:02 - 04:59:59	93250	3.3%	34.56	23.8 GB	3.6%	9268.22
Mar 15 05:00:00 - 05:59:59	110648	3.9%	30.74	28.8 GB	4.3%	8378.85
Mar 15 06:00:00 - 06:59:59	110612	3.9%	30.73	28.8 GB	4.3%	8377.05
Mar 15 07:00:00 - 07:59:59	82646	2.9%	22.96	21.2 GB	3.2%	6167.84
Mar 15 08:00:00 - 08:59:59	93237	3.3%	25.90	25.4 GB	3.8%	7407.52
Mar 15 09:00:00 - 09:59:59	132779	4.7%	36.88	36.0 GB	5.4%	10493.74
Mar 15 10:00:00 - 10:59:59	123222	4.4%	34.23	31.3 GB	4.7%	9109.77
Mar 15 11:00:00 - 11:59:59	143830	5.1%	39.95	36.0 GB	5.4%	10497.41
Mar 15 12:00:00 - 12:59:59	139206	4.9%	38.67	35.2 GB	5.3%	10241.66
Mar 15 13:00:00 - 13:59:59	126875	4.5%	35.24	32.6 GB	4.9%	9500.44
Mar 15 14:00:00 - 14:59:59	118257	4.2%	32.85	27.0 GB	4.0%	7869.85
Mar 15 15:00:00 - 15:59:59	102983	3.6%	28.61	23.2 GB	3.5%	6746.20
Mar 15 16:00:00 - 16:59:59	126190	4.5%	35.05	30.6 GB	4.6%	8910.43
Mar 15 17:00:00 - 17:59:59	141159	5.0%	39.21	31.2 GB	4.7%	9085.68
Mar 15 18:00:00 - 18:59:59	124623	4.4%	34.62	29.5 GB	4.4%	8591.50
Mar 15 19:00:00 - 19:59:59	104314	3.7%	28.98	26.1 GB	3.9%	7595.47
Mar 15 20:00:00 - 20:59:59	101285	3.6%	28.13	23.9 GB	3.6%	6951.64
Mar 15 21:00:00 - 21:59:59	145726	5.1%	40.48	29.9 GB	4.5%	8711.54
Mar 15 22:00:00 - 22:59:59	147811	5.2%	41.06	27.4 GB	4.1%	7972.44
Mar 15 23:00:00 - 23:59:59	114626	4.0%	31.84	24.8 GB	3.7%	7223.74
Mar 16 00:00:00 - 00:59:59	136615	4.8%	37.95	21.1 GB	3.2%	6148.36
Mar 16 01:00:00 - 01:59:59	97844	3.5%	27.18	21.4 GB	3.2%	6235.45
Mar 16 02:00:00 - 02:59:59	91014	3.2%	25.28	21.9 GB	3.3%	6370.65
Mar 16 03:00:00 - 03:59:59	96916	3.4%	26.92	26.0 GB	3.9%	7586.90
Mar 16 04:00:00 - 04:15:01	24650	0.9%	27.36	6.7 GB	1.0%	7771.67
TOTAL: 23:59:59	2830318	100.0%	32.76	669.7 GB	100.0%	8127.81

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2832	27	179	7	2619
2	newsfeed.pionier.net.pl	2346	204	1656	4	482
3	news.icm.edu.pl	3	0	1	0	2
TOTAL: 3		5181	231	1836	11	3103

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	147
it.tlc.cellulari.android	31
comp.sys.raspberry-pi	12
it.comp.os.win.windows10	10
de.comp.lang.perl	8
de.rec.sf.startrek	7
uk.rec.cycling.moderated	5
de.rec.kunst	3
it.comp.os.win.windows7	3
comp.mobile.android	3
de.comm.software.mailreader	1
it.comp.virtualizzazione	1
TOTAL: 12	231

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

Groups	Count
pl.praca.oferowana	6
pl.sci.matematyka	4
bit.listserv.catholic	1
TOTAL: 3	11

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2137
Binary in non-binary group	177
EMP (phl)	43
Scoring filter (9)	23
Scoring filter (8)	19
HTML post	13
Scoring filter (10)	6
Scoring filter (16)	3
Scoring filter (18)	3
Scoring filter (11)	1
Scoring filter (13)	1
Excessively crossposted test article	1
TOTAL: 12	2427

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	416
	news.nask.pl	260
	TOTAL: 2	676
TOTAL: 1		676

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44841	1275	16339	240	12	43177	2%	23:59:57
2	poznan	18307	530	10369	28	0	1	2%	23:59:56
3	icm	27810	44	19513	1240	0	0	0%	23:59:57
4	nask	2788	1	2022	0	0	0	0%	23:58:32
5	okapi	0	0	0	0	0	1193	0%	23:57:49
TOTAL: 5		93746	1850	48243	1508	12	44371	1%	119:56:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	10.7 MB	740.6 KB	11.4 MB	0.1 KB/s	7.7 KB	23:59:57
2	icm	5.1 MB	463.9 MB	469.0 MB	5.6 KB/s	374.1 KB	23:59:57
3	poznan	2.4 MB	609.7 KB	3.0 MB	0.0 KB/s	5.4 KB	23:59:56
4	nask	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:58:32
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		18.2 MB	465.2 MB	483.4 MB	1.1 KB/s	147.4 KB	119:56:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	12	11.7 KB	6	0	00:00:14
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	12	11.7 KB	6	2	00:00:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	12	11.7 KB	6	2	00:00:18
2	unresolved	1	0	0.0 KB	0	0	00:00:11
TOTAL: 2		675	12	11.7 KB	6	2	00:00:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.116	4.924	00:00:14
156.67.96.174	0.016	0.016	00:00:11
mon.man.lodz.pl	0.928	4.740	00:00:02
david.man.lodz.pl	0.332	1.508	00:00:00
isvcs01.man.lodz.pl	0.020	0.036	00:00:00
TOTAL: 5	2.412	11.224	00:00:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
156.67.96.174	1
TOTAL: 3	385

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Mar 15 04:15:02 -- Mar 16 04:15:01