Daily Usenet report: Nov 17 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Nov 17 09:57:27 newsfeed nnrpd[21094]: SERVER perl filter not defined
Nov 17 13:10:02 newsfeed pgpverify[28322]: pgpverify: verification failed
Nov 17 16:02:41 newsfeed overchan[6416]: input line too long, skipping
Nov 17 16:04:53 newsfeed overchan[6416]: input line too long, skipping
Nov 17 16:06:38 newsfeed overchan[6416]: input line too long, skipping
Nov 17 16:30:44 newsfeed overchan[6416]: input line too long, skipping
Nov 17 16:39:30 newsfeed overchan[6416]: input line too long, skipping
Nov 17 16:40:29 newsfeed overchan[6416]: input line too long, skipping
Nov 17 17:17:24 newsfeed overchan[6416]: input line too long, skipping
Nov 17 17:18:29 newsfeed overchan[6416]: input line too long, skipping
Nov 17 17:34:37 newsfeed overchan[6416]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3912052	97.4%	445.3 MB	97.1%
innd	56964	1.4%	8.1 MB	1.8%
innfeed	40727	1.0%	4.8 MB	1.0%
nnrpd	4817	0.1%	501.4 KB	0.1%
overchan	10	0.0%	1.2 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	4014575	100.0%	458.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3868012	93.9%
Negative hits	219674	5.3%
Positive hits	31312	0.8%
Cache misses	178	0.0%
TOTAL: 4	4119176	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.971	0.0%	185	0.000	32.276	2298.000
article cleanup	00:00:45.910	0.1%	3912173	0.008	0.012	0.017
article logging	00:02:34.926	0.2%	3912691	0.024	0.040	0.124
article parse	00:52:59.100	3.7%	98941704	0.023	0.032	0.040
article write	00:22:05.182	1.5%	3860798	0.129	0.343	0.683
artlog/artcncl	00:00:00.005	0.0%	184	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:23.561	0.2%	117829964	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.345	0.0%	178	0.000	7.556	642.000
hishave/artcncl	00:00:00.216	0.0%	185	0.000	1.168	5.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:46:55.375	3.3%	4146993	0.046	0.679	4.926
history sync	00:00:00.019	0.0%	290	0.000	0.066	0.500
history write	02:16:37.290	9.5%	3893958	0.206	2.105	8.491
hiswrite/artcncl	00:00:00.026	0.0%	7	0.000	3.714	5.000
idle	12:32:13.552	52.2%	68817542	0.328	0.656	1.553
nntp read	00:51:17.649	3.6%	95923660	0.025	0.032	0.048
overview write	00:00:18.484	0.0%	3860798	0.003	0.005	0.007
perl filter	05:36:49.953	23.4%	3893933	2.508	5.190	6.977
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:43.239	0.4%	7740840	0.033	0.044	0.059
TOTAL: 24:00:03.605	23:30:51.803	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.134	0.0%	54688	0.000	0.002	0.013
article prepare	00:00:00.006	0.0%	15634	0.000	0.000	0.012
article read	00:02:12.761	0.2%	3904	1.880	34.006	348.250
backlog stats	00:00:00.128	0.0%	163768	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	37	0.000	0.000	0.000
data read	00:00:01.108	0.0%	86367	0.002	0.013	0.034
data write	00:00:03.567	0.0%	67781	0.022	0.053	0.200
idle	23:51:54.152	99.8%	163768	303.210	524.609	1073.532
status file	00:00:11.903	0.0%	15182	0.447	0.784	1.119
TOTAL: 23:54:32.728	23:54:23.759	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:16.840	61.7%	3084	0.000	5.460	78.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.205	0.8%	6935	0.000	0.030	0.500
readart	00:00:00.009	0.0%	96	0.000	0.094	0.500
TOTAL: 00:00:27.281	00:00:17.054	62.5%	-	-	-	-

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
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	967	3888420	3850334	7389	30697	99%	440:20:10
2	news.nask.pl	2	35943	9718	5560	20665	27%	23:54:02
3	news.icm.edu.pl	47	205	12	193	0	5%	17:05:51
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1018	3924569	3860065	13142	51362	98%	481:20:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	845.8 GB	2.9 GB	5.3 GB	853.9 GB	99%	230.7 KB
2	news.nask.pl	50.3 MB	37.1 MB	3.3 GB	3.4 GB	1%	118.4 KB
3	news.icm.edu.pl	328.2 KB	0.0 KB	0.0 KB	328.2 KB	100%	27.4 KB
4	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 4		845.8 GB	2.9 GB	8.6 GB	857.4 GB	98%	229.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:02 - 04:59:59	102284	2.6%	37.91	24.9 GB	2.9%	9690.62
Nov 17 05:00:00 - 05:59:59	129169	3.3%	35.88	40.4 GB	4.8%	11760.35
Nov 17 06:00:00 - 06:59:59	145332	3.8%	40.37	42.9 GB	5.1%	12486.81
Nov 17 07:00:00 - 07:59:59	138616	3.6%	38.50	40.3 GB	4.8%	11745.78
Nov 17 08:00:00 - 08:59:59	126730	3.3%	35.20	40.2 GB	4.8%	11708.47
Nov 17 09:00:00 - 09:59:59	153281	4.0%	42.58	39.7 GB	4.7%	11566.52
Nov 17 10:00:00 - 10:59:59	169244	4.4%	47.01	42.7 GB	5.1%	12446.93
Nov 17 11:00:00 - 11:59:59	159522	4.1%	44.31	42.6 GB	5.0%	12408.50
Nov 17 12:00:00 - 12:59:59	192402	5.0%	53.45	41.3 GB	4.9%	12020.22
Nov 17 13:00:00 - 13:59:59	191814	5.0%	53.28	40.6 GB	4.8%	11813.80
Nov 17 14:00:00 - 14:59:59	229799	6.0%	63.83	38.7 GB	4.6%	11272.83
Nov 17 15:00:00 - 15:59:59	277877	7.2%	77.19	34.4 GB	4.1%	10021.92
Nov 17 16:00:00 - 16:59:59	128731	3.3%	35.76	35.9 GB	4.2%	10457.40
Nov 17 17:00:00 - 17:59:59	80412	2.1%	22.34	23.7 GB	2.8%	6913.28
Nov 17 18:00:00 - 18:59:59	82969	2.1%	23.05	19.2 GB	2.3%	5588.06
Nov 17 19:00:00 - 19:59:59	70972	1.8%	19.71	18.7 GB	2.2%	5449.46
Nov 17 20:00:00 - 20:59:59	121662	3.2%	33.80	33.5 GB	4.0%	9752.44
Nov 17 21:00:00 - 21:59:59	139937	3.6%	38.87	34.6 GB	4.1%	10070.08
Nov 17 22:00:00 - 22:59:59	134218	3.5%	37.28	29.3 GB	3.5%	8521.32
Nov 17 23:00:00 - 23:59:59	348201	9.0%	96.72	36.8 GB	4.4%	10720.27
Nov 18 00:00:00 - 00:59:59	209133	5.4%	58.09	30.5 GB	3.6%	8890.77
Nov 18 01:00:00 - 01:59:59	190155	4.9%	52.82	37.2 GB	4.4%	10832.16
Nov 18 02:00:00 - 02:59:59	167941	4.4%	46.65	36.8 GB	4.3%	10709.31
Nov 18 03:00:00 - 03:59:59	139877	3.6%	38.85	32.2 GB	3.8%	9387.25
Nov 18 04:00:00 - 04:15:04	29695	0.8%	32.85	9.0 GB	1.1%	10490.33
TOTAL: 24:00:02	3859973	100.0%	44.67	846.1 GB	100.0%	10268.13

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	30994	239	17813	6	12936
2	news.nask.pl	20900	15	408	12	20465
3	news.icm.edu.pl	1	0	0	0	1
TOTAL: 3		51895	254	18221	18	33402

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	128
it.comp.os.win.windows10	85
it.tlc.cellulari.android	15
comp.mobile.android	11
it.comp.os.win.windows7	4
comp.sys.raspberry-pi	4
de.rec.sf.startrek	3
comp.mobile.ipad	2
alt.sex.telephones	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 10	254

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

Groups	Count
pl.praca.oferowana	17
bit.listserv.catholic	1
TOTAL: 2	18

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	28942
EMP (md5)	3732
Scoring filter (8)	73
EMP (phl)	65
Scoring filter (9)	33
Too many newsgroups	7
Scoring filter (10)	5
HTML post	5
Scoring filter (12)	1
Scoring filter (11)	1
TOTAL: 10	32864

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	308
	news.nask.pl	204
	news.icm.edu.pl	1
	TOTAL: 3	513
TOTAL: 1		513

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42650	1583	15409	345	83	37339	3%	23:59:59
2	poznan	16438	490	9107	52	0	0	2%	23:59:47
3	icm	22992	53	17793	344	0	0	0%	23:59:59
4	nask	2857	3	2102	0	0	0	0%	23:51:36
5	okapi	0	0	0	0	0	1203	0%	23:57:52
TOTAL: 5		84937	2129	44411	741	83	38542	2%	119:49:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.7 MB	2.3 MB	11.0 MB	0.1 KB/s	5.9 KB	23:59:59
2	icm	3.3 MB	92.0 MB	95.3 MB	1.1 KB/s	245.9 KB	23:59:59
3	poznan	1.5 MB	348.6 KB	1.9 MB	0.0 KB/s	3.5 KB	23:59:47
4	nask	4.5 KB	0.0 KB	4.5 KB	0.0 KB/s	1.5 KB	23:51:36
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:52
TOTAL: 5		13.5 MB	94.7 MB	108.2 MB	0.3 KB/s	38.6 KB	119:49:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	291	96	93.7 KB	41	0	00:00:21
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		292	96	93.7 KB	41	1	00:00:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	96	93.7 KB	41	1	00:00:25
2	unresolved	1	0	0.0 KB	0	0	00:00:01
TOTAL: 2		677	96	93.7 KB	41	1	00:00:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.248	5.944	00:00:21
mon.man.lodz.pl	0.888	5.580	00:00:03
195.19.10.135	0.000	0.016	00:00:01
david.man.lodz.pl	0.324	1.680	00:00:00
isvcs01.man.lodz.pl	0.004	0.028	00:00:00
TOTAL: 5	2.464	13.248	00:00:26

Curious NNRP server explorers [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
195.19.10.135	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
195.19.10.135	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

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