Daily Usenet report: May 22 04:15:05

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

May 22 09:24:55 newsfeed nnrpd[10015]: SERVER perl filter not defined
May 22 09:28:03 newsfeed nnrpd[10235]: SERVER perl filter not defined
May 22 10:23:57 newsfeed overchan[10512]: input line too long, skipping
May 22 12:24:18 newsfeed nnrpd[16734]: SERVER perl filter not defined
May 22 12:26:08 newsfeed nnrpd[16928]: SERVER perl filter not defined
May 22 15:11:26 newsfeed overchan[10512]: input line too long, skipping
May 22 15:25:21 newsfeed overchan[10512]: input line too long, skipping
May 22 15:35:43 newsfeed overchan[10512]: input line too long, skipping
May 22 15:42:39 newsfeed overchan[10512]: input line too long, skipping
May 22 16:41:24 newsfeed overchan[10512]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2178126	97.1%	245.6 MB	96.7%
innfeed	45480	2.0%	5.4 MB	2.1%
innd	15101	0.7%	2.6 MB	1.0%
nnrpd	4964	0.2%	533.8 KB	0.2%
overchan	7	0.0%	1.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2243679	100.0%	254.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2140025	92.7%
Negative hits	126663	5.5%
Positive hits	40649	1.8%
Cache misses	389	0.0%
TOTAL: 4	2307726	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.528	0.0%	154	0.000	16.416	623.000
article cleanup	00:00:33.689	0.0%	2179645	0.011	0.015	0.039
article logging	00:01:47.536	0.1%	2180232	0.033	0.049	0.080
article parse	00:42:03.078	2.9%	79456647	0.015	0.032	0.044
article write	00:16:18.867	1.1%	2148411	0.187	0.456	1.327
artlog/artcncl	00:00:00.002	0.0%	152	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:52.717	0.1%	91212141	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.221	0.0%	139	0.000	1.590	29.000
hishave/artcncl	00:00:00.113	0.0%	154	0.000	0.734	4.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:20:24.143	1.4%	2319257	0.030	0.528	2.915
history sync	00:00:00.032	0.0%	293	0.000	0.109	4.000
history write	01:34:50.055	6.6%	2151029	0.343	2.645	19.079
hiswrite/artcncl	00:00:00.435	0.0%	15	0.000	29.000	72.333
idle	15:23:47.570	64.1%	65557193	0.368	0.845	2482.564
nntp read	00:58:24.391	4.1%	77828026	0.033	0.045	0.062
overview write	00:00:13.001	0.0%	2148411	0.000	0.006	0.009
perl filter	04:06:21.014	17.1%	2151029	1.855	6.872	10.565
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:08.354	0.4%	4356068	0.051	0.071	0.109
TOTAL: 24:00:03.508	23:31:47.746	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.321	0.0%	117531	0.000	0.003	0.012
article prepare	00:00:00.004	0.0%	11768	0.000	0.000	0.012
article read	00:00:23.658	0.0%	2929	0.000	8.077	430.000
backlog stats	00:00:00.269	0.0%	334174	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:03.579	0.0%	191537	0.005	0.019	0.036
data write	00:00:08.843	0.0%	132684	0.034	0.067	0.106
idle	23:51:09.823	99.9%	334174	133.099	256.961	987.509
status file	00:00:17.717	0.0%	16715	0.767	1.060	1.799
TOTAL: 23:52:20.653	23:52:04.214	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:40:37.841	99.3%	3323	0.000	733.627	238589.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.324	0.0%	7539	0.000	0.043	1.000
readart	00:00:01.179	0.0%	154	0.000	7.656	11.943
TOTAL: 00:40:55.824	00:40:39.344	99.3%	-	-	-	-

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	216	2138506	2102783	7888	27835	98%	173:13:20
2	news.nask.pl	34	50552	42915	4219	3418	84%	27:27:17
3	news.icm.edu.pl	45	250	36	205	9	14%	17:27:05
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		300	2189312	2145738	12312	31262	98%	218:07:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	519.3 GB	8.8 GB	8.8 GB	536.9 GB	96%	264.2 KB
2	news.nask.pl	12.2 GB	111.9 MB	125.8 MB	12.4 GB	98%	280.3 KB
3	news.icm.edu.pl	566.9 KB	37.2 KB	123.8 KB	728.0 KB	77%	16.2 KB
4	localhost	6.7 KB	0.0 KB	0.0 KB	6.7 KB	100%	1.7 KB
TOTAL: 4		531.4 GB	8.9 GB	8.9 GB	549.3 GB	96%	264.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 22 04:15:05 - 04:59:59	84810	4.0%	31.47	22.3 GB	4.2%	8661.46
May 22 05:00:00 - 05:59:59	110407	5.1%	30.67	31.6 GB	5.9%	9193.17
May 22 06:00:00 - 06:59:59	117260	5.5%	32.57	33.1 GB	6.2%	9639.52
May 22 07:00:00 - 07:59:59	117138	5.5%	32.54	32.5 GB	6.1%	9465.67
May 22 08:00:00 - 08:59:59	57158	2.7%	15.88	16.7 GB	3.1%	4859.42
May 22 09:00:00 - 09:59:59	127392	5.9%	35.39	30.9 GB	5.8%	9012.64
May 22 10:00:00 - 10:59:59	122505	5.7%	34.03	32.7 GB	6.1%	9523.50
May 22 11:00:00 - 11:59:59	113169	5.3%	31.44	32.0 GB	6.0%	9326.16
May 22 12:00:00 - 12:59:59	76440	3.6%	21.23	20.5 GB	3.8%	5961.39
May 22 13:00:00 - 13:59:59	51396	2.4%	14.28	13.7 GB	2.6%	3977.55
May 22 14:00:00 - 14:59:59	51574	2.4%	14.33	12.2 GB	2.3%	3547.98
May 22 15:00:00 - 15:59:59	21797	1.0%	6.05	7.2 GB	1.4%	2094.78
May 22 16:00:00 - 16:59:59	73127	3.4%	20.31	12.0 GB	2.3%	3490.43
May 22 17:00:00 - 17:59:59	53377	2.5%	14.83	14.3 GB	2.7%	4170.25
May 22 18:00:00 - 18:59:59	62883	2.9%	17.47	15.4 GB	2.9%	4492.01
May 22 19:00:00 - 19:59:59	56494	2.6%	15.69	15.4 GB	2.9%	4473.52
May 22 20:00:00 - 20:59:59	65935	3.1%	18.32	14.8 GB	2.8%	4315.23
May 22 21:00:00 - 21:59:59	62737	2.9%	17.43	15.2 GB	2.9%	4414.37
May 22 22:00:00 - 22:59:59	60655	2.8%	16.85	15.3 GB	2.9%	4453.90
May 22 23:00:00 - 23:59:59	108586	5.1%	30.16	26.6 GB	5.0%	7756.65
May 23 00:00:00 - 00:59:59	78714	3.7%	21.86	18.6 GB	3.5%	5410.05
May 23 01:00:00 - 01:59:59	124442	5.8%	34.57	27.2 GB	5.1%	7911.07
May 23 02:00:00 - 02:59:59	177742	8.3%	49.37	34.8 GB	6.5%	10137.19
May 23 03:00:00 - 03:59:59	143697	6.7%	39.92	30.3 GB	5.7%	8825.99
May 23 04:00:00 - 04:15:02	26672	1.2%	29.57	6.6 GB	1.3%	7730.50
TOTAL: 23:59:57	2146107	100.0%	24.84	531.7 GB	100.0%	6453.28

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	28247	172	27571	2	502
2	news.nask.pl	3604	27	1086	5	2486
3	news.icm.edu.pl	16	0	3	0	13
TOTAL: 3		31867	199	28660	7	3001

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	90
it.comp.os.win.windows10	49
comp.sys.raspberry-pi	25
it.tlc.cellulari.android	10
comp.mobile.android	9
uk.rec.cycling.moderated	8
de.rec.sf.startrek	4
it.comp.os.win.windows8	3
it.tlc.cellulari.iphone	1
TOTAL: 9	199

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

Groups	Count
pl.sci.matematyka	5
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 3	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2217
Binary in non-binary group	104
EMP (phl)	42
Scoring filter (8)	28
HTML post	7
EMP (fsl)	6
Scoring filter (9)	5
Scoring filter (18)	2
Scoring filter (10)	1
Scoring filter (11)	1
Scoring filter (13)	1
TOTAL: 11	2414

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	395
	news.nask.pl	187
	news.icm.edu.pl	5
	TOTAL: 3	587
TOTAL: 1		587

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	64924	1384	35395	330	22	80641	2%	23:59:55
2	poznan	14715	427	8500	20	0	178	2%	23:59:55
3	icm	71957	83	58776	148	0	0	0%	23:59:52
4	nask	12493	44	8864	1	0	0	0%	23:55:11
5	okapi	0	0	0	0	0	993	0%	23:57:48
TOTAL: 5		164089	1938	111535	499	22	81812	1%	119:52:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	15.2 MB	51.1 KB	15.2 MB	0.2 KB/s	345.9 KB	23:55:11
2	icm	4.9 MB	50.8 MB	55.7 MB	0.7 KB/s	246.8 KB	23:59:52
3	panorama	4.1 MB	1.1 MB	5.3 MB	0.1 KB/s	3.1 KB	23:59:55
4	poznan	1.4 MB	79.4 KB	1.5 MB	0.0 KB/s	3.4 KB	23:59:55
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
TOTAL: 5		25.5 MB	52.1 MB	77.6 MB	0.2 KB/s	32.6 KB	119:52:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	77-253-109-201.adsl.inetia.pl	10	118	114.2 KB	3	0	5	00:24:05
2	web.man.lodz.pl	288	28	27.2 KB	12	0	0	00:00:22
3	0890788407.static.corbina.ru	10	4	6.7 KB	1	0	0	00:01:28
4	net134-46.4web.pl	1	0	0.0 KB	0	0	1	00:02:53
5	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	0	00:00:00
TOTAL: 5		313	150	148.1 KB	16	4	6	00:40:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.adsl.inetia.pl	10	118	114.2 KB	3	0	5	00:24:05
2	*.man.lodz.pl	676	28	27.2 KB	12	4	0	00:00:28
3	*.static.corbina.ru	10	4	6.7 KB	1	0	0	00:01:28
4	*.4web.pl	1	0	0.0 KB	0	0	1	00:02:53
5	*.binaryedge.ninja	1	0	0.0 KB	0	0	0	00:00:02
6	*.internetdsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:11:56
TOTAL: 6		699	150	148.1 KB	16	4	6	00:40:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
77-253-109-201.adsl.inetia.pl	0.104	0.224	00:24:05
owo22.internetdsl.tpnet.pl	0.008	0.016	00:11:56
net134-46.4web.pl	0.032	0.028	00:02:53
0890788407.static.corbina.ru	0.072	0.232	00:01:28
web.man.lodz.pl	1.764	6.164	00:00:22
mon.man.lodz.pl	1.556	5.688	00:00:04
min-extra-grab-25-usnj-prod.binaryedge.ninja	0.004	0.020	00:00:02
david.man.lodz.pl	0.404	1.564	00:00:01
isvcs01.man.lodz.pl	0.056	0.108	00:00:00
TOTAL: 9	4.000	14.044	00:40:54

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
owo22.internetdsl.tpnet.pl	1
min-extra-grab-25-usnj-prod.binaryedge.ninja	1
TOTAL: 4	386

Newsgroup request counts (by category):

Category		Count	Pct
1	lodz	118	78.7%
2	pl	28	18.7%
3	lodman	4	2.7%
TOTAL: 3		150	100.0%

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

Newsgroup		Count
1	lodz.test	118
2	pl.comp.pecet	18
3	pl.comp.os.ms-windows.winnt	10
4	lodman.info	4
TOTAL: 4		150

Daily Usenet report

May 22 04:15:05 -- May 23 04:15:02