Daily Usenet report: May 18 04:15:05

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

May 18 07:51:58 newsfeed overchan[8027]: input line too long, skipping
May 18 11:58:58 newsfeed nnrpd[28767]: SERVER perl filter not defined
May 18 16:00:58 newsfeed overchan[8027]: input line too long, skipping
May 18 16:02:23 newsfeed overchan[8027]: input line too long, skipping
May 18 16:02:43 newsfeed overchan[8027]: input line too long, skipping
May 18 16:04:03 newsfeed overchan[8027]: input line too long, skipping
May 18 16:08:40 newsfeed overchan[8027]: input line too long, skipping
May 18 17:18:18 newsfeed nnrpd[13745]: SERVER perl filter not defined
May 18 20:40:30 newsfeed overchan[8027]: input line too long, skipping
May 18 20:40:51 newsfeed overchan[8027]: input line too long, skipping
May 18 20:41:57 newsfeed overchan[8027]: input line too long, skipping
May 18 20:42:27 newsfeed overchan[8027]: input line too long, skipping
May 18 20:42:46 newsfeed overchan[8027]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2826508	97.5%	327.6 MB	97.2%
innfeed	44136	1.5%	5.2 MB	1.5%
innd	23875	0.8%	3.8 MB	1.1%
nnrpd	4746	0.2%	500.7 KB	0.1%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2899278	100.0%	337.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2805927	96.7%
Negative hits	81470	2.8%
Positive hits	14067	0.5%
Cache misses	116	0.0%
TOTAL: 4	2901580	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.522	0.0%	195	0.000	23.190	1415.000
article cleanup	00:00:37.590	0.0%	2826305	0.010	0.013	0.018
article logging	00:01:50.581	0.1%	2826796	0.029	0.039	0.155
article parse	01:08:28.552	4.8%	196252953	0.016	0.021	0.035
article write	00:33:12.047	2.3%	2818480	0.357	0.707	4.279
artlog/artcncl	00:00:00.002	0.0%	194	0.000	0.010	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:39.220	0.2%	214173287	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.307	0.0%	176	0.000	1.744	19.000
hishave/artcncl	00:00:00.116	0.0%	195	0.000	0.595	6.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:58.744	2.6%	2921482	0.153	0.759	5.102
history sync	00:00:00.018	0.0%	290	0.000	0.062	1.000
history write	01:42:21.272	7.1%	2822898	0.286	2.176	7.868
hiswrite/artcncl	00:00:00.084	0.0%	19	0.000	4.421	7.000
idle	12:33:17.143	52.3%	134350602	0.189	0.336	0.618
nntp read	01:19:48.253	5.5%	194055598	0.018	0.025	0.036
overview write	00:00:14.422	0.0%	2818480	0.004	0.005	0.007
perl filter	05:06:14.112	21.3%	2822898	5.132	6.509	8.059
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:17.766	0.3%	5686798	0.036	0.045	0.073
TOTAL: 24:00:02.516	23:10:04.751	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.290	0.0%	99061	0.000	0.003	0.017
article prepare	00:00:00.040	0.0%	117812	0.000	0.000	0.028
article read	00:01:07.003	0.1%	29440	0.192	2.276	259.125
backlog stats	00:00:00.279	0.0%	317359	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:03.888	0.0%	176235	0.007	0.022	0.047
data write	00:00:34.085	0.0%	135263	0.019	0.252	0.577
idle	23:51:31.555	99.8%	317359	43.730	270.645	905.169
status file	00:00:14.899	0.0%	16364	0.591	0.910	4.017
TOTAL: 23:53:48.379	23:53:32.039	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.360	60.6%	3014	0.000	6.092	173.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.171	0.6%	6638	0.000	0.026	2.667
readart	00:00:00.003	0.0%	22	0.000	0.136	0.500
TOTAL: 00:00:30.286	00:00:18.534	61.2%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1212	2817904	2808181	6422	3301	99%	434:18:03
2	news.nask.pl	2	18271	9852	3946	4473	53%	23:55:38
3	news.icm.edu.pl	45	303	19	246	38	6%	17:20:12
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1262	2836480	2818054	10614	7812	99%	475:33:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	871.8 GB	22.3 MB	27.5 MB	871.9 GB	99%	325.2 KB
2	news.nask.pl	46.4 MB	755.6 KB	31.6 MB	78.7 MB	58%	5.6 KB
3	news.icm.edu.pl	423.0 KB	62.2 KB	370.9 KB	856.0 KB	49%	15.0 KB
4	localhost	3.4 KB	0.0 KB	0.0 KB	3.4 KB	100%	1.7 KB
TOTAL: 4		871.9 GB	23.1 MB	59.4 MB	871.9 GB	99%	323.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 18 04:15:05 - 04:59:59	97961	3.5%	36.35	31.1 GB	3.6%	12119.47
May 18 05:00:00 - 05:59:59	122105	4.3%	33.92	40.2 GB	4.6%	11702.48
May 18 06:00:00 - 06:59:59	121435	4.3%	33.73	38.6 GB	4.4%	11249.62
May 18 07:00:00 - 07:59:59	126551	4.5%	35.15	39.0 GB	4.5%	11355.42
May 18 08:00:00 - 08:59:59	103025	3.7%	28.62	32.7 GB	3.8%	9524.42
May 18 09:00:00 - 09:59:59	105392	3.7%	29.28	33.6 GB	3.9%	9773.14
May 18 10:00:00 - 10:59:59	128272	4.6%	35.63	38.8 GB	4.5%	11307.84
May 18 11:00:00 - 11:59:59	126509	4.5%	35.14	38.8 GB	4.5%	11306.73
May 18 12:00:00 - 12:59:59	120196	4.3%	33.39	37.3 GB	4.3%	10876.75
May 18 13:00:00 - 13:59:59	117887	4.2%	32.75	38.4 GB	4.4%	11193.00
May 18 14:00:00 - 14:59:59	125168	4.4%	34.77	40.5 GB	4.7%	11787.22
May 18 15:00:00 - 15:59:59	126348	4.5%	35.10	41.0 GB	4.7%	11931.68
May 18 16:00:00 - 16:59:59	124727	4.4%	34.65	40.4 GB	4.7%	11780.71
May 18 17:00:00 - 17:59:59	108066	3.8%	30.02	35.2 GB	4.0%	10246.88
May 18 18:00:00 - 18:59:59	131060	4.7%	36.41	37.1 GB	4.3%	10792.89
May 18 19:00:00 - 19:59:59	133034	4.7%	36.95	36.6 GB	4.2%	10663.07
May 18 20:00:00 - 20:59:59	132688	4.7%	36.86	36.9 GB	4.2%	10750.33
May 18 21:00:00 - 21:59:59	122986	4.4%	34.16	33.3 GB	3.8%	9712.47
May 18 22:00:00 - 22:59:59	71027	2.5%	19.73	20.0 GB	2.3%	5810.99
May 18 23:00:00 - 23:59:59	66478	2.4%	18.47	19.1 GB	2.2%	5564.90
May 19 00:00:00 - 00:59:59	114192	4.1%	31.72	34.1 GB	3.9%	9923.43
May 19 01:00:00 - 01:59:59	128980	4.6%	35.83	39.7 GB	4.6%	11577.84
May 19 02:00:00 - 02:59:59	120116	4.3%	33.37	40.3 GB	4.6%	11727.33
May 19 03:00:00 - 03:59:59	115902	4.1%	32.20	37.2 GB	4.3%	10825.17
May 19 04:00:00 - 04:15:02	27895	1.0%	30.93	9.3 GB	1.1%	10793.93
TOTAL: 23:59:57	2818000	100.0%	32.62	869.2 GB	100.0%	10548.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4648	65	298	2	4283
2	newsfeed.pionier.net.pl	3625	127	3104	0	394
3	news.icm.edu.pl	41	0	3	0	38
TOTAL: 3		8314	192	3405	2	4715

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	68
comp.mobile.android	63
it.tlc.cellulari.android	18
it.comp.os.win.windows10	16
de.rec.kunst	15
comp.sys.raspberry-pi	5
de.rec.sf.startrek	4
uk.rec.cycling.moderated	1
comp.mobile.ipad	1
it.comp.os.win.windows7	1
TOTAL: 10	192

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3726
EMP (phl)	225
Binary in non-binary group	134
Scoring filter (8)	45
Scoring filter (9)	27
MID-Bot	27
Scoring filter (10)	15
Scoring filter (13)	10
Too many newsgroups	8
Scoring filter (12)	4
HTML post	2
Scoring filter (11)	1
TOTAL: 12	4224

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	326
	news.nask.pl	161
	news.icm.edu.pl	4
	TOTAL: 3	491
TOTAL: 1		491

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	52191	20484	21539	3581	0	0	39%	23:59:52
2	panorama	57518	712	29253	279	10	68985	1%	23:59:54
3	poznan	15224	292	9599	40	0	0	1%	23:59:54
4	nask	11393	8	5368	3507	0	0	0%	23:59:40
5	okapi	0	0	0	0	0	2477	0%	23:57:47
TOTAL: 5		136326	21496	65759	7407	10	71462	15%	119:57:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	3.6 GB	1.1 GB	4.7 GB	57.3 KB/s	205.8 KB	23:59:52
2	panorama	1.9 MB	1.1 MB	3.0 MB	0.0 KB/s	3.1 KB	23:59:54
3	poznan	1.5 MB	158.3 KB	1.7 MB	0.0 KB/s	5.2 KB	23:59:54
4	nask	12.9 KB	861.6 MB	861.6 MB	10.2 KB/s	251.0 KB	23:59:40
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
TOTAL: 5		3.7 GB	1.9 GB	5.6 GB	13.5 KB/s	202.0 KB	119:57:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	22	25.8 KB	11	0	00:00:23
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	22	25.8 KB	11	2	00:00:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	22	25.8 KB	11	2	00:00:29
2	*.shodan.io	1	0	0.0 KB	0	0	00:00:00
TOTAL: 2		675	22	25.8 KB	11	2	00:00:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.256	4.980	00:00:23
mon.man.lodz.pl	1.120	4.792	00:00:04
david.man.lodz.pl	0.356	1.608	00:00:01
isvcs01.man.lodz.pl	0.004	0.052	00:00:00
census8.shodan.io	0.012	0.024	00:00:00
TOTAL: 5	2.748	11.456	00:00:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
census8.shodan.io	1
TOTAL: 3	385

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

May 18 04:15:05 -- May 19 04:15:02