Daily Usenet report: Sep 25 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 25 08:29:24 newsfeed nnrpd[15771]: SERVER perl filter not defined
Sep 25 10:27:10 newsfeed nnrpd[20210]: SERVER perl filter not defined
Sep 25 10:28:41 newsfeed nnrpd[20262]: SERVER perl filter not defined
Sep 25 14:27:55 newsfeed nnrpd[29214]: message repeated 2 times: [ 188.47.78.224.ipv4.supernova.orange.pl group lodz.test 0]
Sep 25 14:28:58 newsfeed nnrpd[29214]: message repeated 3 times: [ 188.47.78.224.ipv4.supernova.orange.pl group lodman.test 0]
Sep 25 14:38:30 newsfeed nnrpd[29539]: message repeated 2 times: [ 188.47.78.224.ipv4.supernova.orange.pl group lodz.test 0]
Sep 25 14:38:55 newsfeed nnrpd[29539]: message repeated 2 times: [ 188.47.78.224.ipv4.supernova.orange.pl group lodz.ogloszenia 0]
Sep 25 19:02:10 newsfeed overchan[5566]: input line too long, skipping
Sep 26 01:27:12 newsfeed nnrpd[21802]: SERVER perl filter not defined
Sep 26 02:08:37 newsfeed nnrpd[23529]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2353865	97.2%	277.6 MB	97.0%
innfeed	44418	1.8%	5.3 MB	1.8%
innd	17830	0.7%	2.9 MB	1.0%
nnrpd	5050	0.2%	540.1 KB	0.2%
overchan	2	0.0%	0.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2421166	100.0%	286.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2324061	88.0%
Positive hits	217398	8.2%
Negative hits	96484	3.7%
Cache misses	2754	0.1%
TOTAL: 4	2640697	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.287	0.0%	99	0.000	43.303	225.000
article cleanup	00:00:32.272	0.0%	2355638	0.009	0.014	0.021
article logging	00:02:03.460	0.1%	2356192	0.030	0.052	1.492
article parse	00:58:08.022	4.0%	93645448	0.018	0.037	0.057
article write	00:20:45.103	1.4%	2341817	0.220	0.532	1.819
artlog/artcncl	00:00:00.004	0.0%	98	0.000	0.041	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:49.556	0.2%	108406301	0.001	0.002	0.003
hisgrep/artcncl	00:00:01.373	0.0%	97	0.000	14.155	44.545
hishave/artcncl	00:00:00.318	0.0%	99	0.000	3.212	26.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:00:27.319	8.4%	2665585	0.424	2.711	10.981
history sync	00:00:00.022	0.0%	290	0.000	0.076	1.000
history write	04:16:06.473	17.8%	2344658	1.673	6.554	35.443
hiswrite/artcncl	00:00:00.007	0.0%	2	0.000	3.500	7.000
idle	09:20:04.478	38.9%	61345499	0.233	0.548	0.976
nntp read	01:15:20.507	5.2%	91988065	0.032	0.049	0.062
overview write	00:00:12.866	0.0%	2341817	0.004	0.005	0.008
perl filter	05:09:54.187	21.5%	2344657	4.809	7.930	10.678
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:11.198	0.4%	4988412	0.042	0.062	0.082
TOTAL: 24:00:02.302	23:31:41.452	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.902	0.0%	620193	0.001	0.003	0.007
article prepare	00:00:00.123	0.0%	451168	0.000	0.000	0.002
article read	00:13:17.874	0.9%	98854	0.268	8.071	48.099
backlog stats	00:00:02.229	0.0%	2260567	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:26.169	0.0%	1141336	0.014	0.023	0.036
data write	00:06:36.668	0.5%	1190699	0.055	0.333	0.585
idle	23:27:53.019	98.4%	2260567	5.390	37.368	624.098
status file	00:01:02.947	0.1%	16069	0.667	3.917	235.607
TOTAL: 23:50:32.837	23:49:20.931	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:27:41.432	99.6%	3462	0.000	1519.767	139482.714
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.411	0.0%	8089	0.000	0.174	4.585
readart	00:00:02.200	0.0%	217	0.000	10.138	155.000
TOTAL: 01:28:04.758	01:27:45.043	99.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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	702	2334533	2328889	2632	3012	99%	525:23:44
2	news.nask.pl	7	226172	8875	206463	10834	3%	24:01:24
3	news.icm.edu.pl	43	217	120	90	7	55%	15:33:25
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		758	2560927	2337889	209185	13853	91%	564:58:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	813.7 GB	687.5 MB	689.7 MB	815.0 GB	99%	366.5 KB
2	news.nask.pl	2.7 GB	3.7 GB	3.7 GB	10.2 GB	26%	541.2 KB
3	news.icm.edu.pl	2.2 MB	39.2 KB	197.0 KB	2.5 MB	90%	19.8 KB
4	localhost	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.3 KB
TOTAL: 4		816.3 GB	4.4 GB	4.4 GB	825.2 GB	98%	367.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 25 04:15:02 - 04:59:59	96930	4.1%	35.93	29.3 GB	3.6%	11388.50
Sep 25 05:00:00 - 05:59:59	115000	4.9%	31.94	34.7 GB	4.2%	10108.57
Sep 25 06:00:00 - 06:59:59	111277	4.8%	30.91	38.8 GB	4.8%	11302.79
Sep 25 07:00:00 - 07:59:59	81402	3.5%	22.61	30.8 GB	3.8%	8959.60
Sep 25 08:00:00 - 08:59:59	60215	2.6%	16.73	24.1 GB	2.9%	7015.04
Sep 25 09:00:00 - 09:59:59	90103	3.9%	25.03	31.6 GB	3.9%	9207.69
Sep 25 10:00:00 - 10:59:59	111708	4.8%	31.03	43.1 GB	5.3%	12553.36
Sep 25 11:00:00 - 11:59:59	106112	4.5%	29.48	41.7 GB	5.1%	12148.39
Sep 25 12:00:00 - 12:59:59	114837	4.9%	31.90	42.0 GB	5.1%	12223.14
Sep 25 13:00:00 - 13:59:59	103591	4.4%	28.78	41.2 GB	5.0%	12007.93
Sep 25 14:00:00 - 14:59:59	66055	2.8%	18.35	25.9 GB	3.2%	7546.60
Sep 25 15:00:00 - 15:59:59	85618	3.7%	23.78	33.5 GB	4.1%	9745.45
Sep 25 16:00:00 - 16:59:59	60873	2.6%	16.91	24.2 GB	3.0%	7043.65
Sep 25 17:00:00 - 17:59:59	105696	4.5%	29.36	41.2 GB	5.0%	12000.17
Sep 25 18:00:00 - 18:59:59	84819	3.6%	23.56	32.8 GB	4.0%	9558.86
Sep 25 19:00:00 - 19:59:59	94582	4.0%	26.27	32.7 GB	4.0%	9536.26
Sep 25 20:00:00 - 20:59:59	96887	4.1%	26.91	33.6 GB	4.1%	9793.49
Sep 25 21:00:00 - 21:59:59	131700	5.6%	36.58	40.0 GB	4.9%	11663.12
Sep 25 22:00:00 - 22:59:59	127013	5.4%	35.28	40.2 GB	4.9%	11700.85
Sep 25 23:00:00 - 23:59:59	147073	6.3%	40.85	31.5 GB	3.9%	9188.31
Sep 26 00:00:00 - 00:59:59	79455	3.4%	22.07	24.3 GB	3.0%	7071.80
Sep 26 01:00:00 - 01:59:59	98618	4.2%	27.39	34.4 GB	4.2%	10009.71
Sep 26 02:00:00 - 02:59:59	73858	3.2%	20.52	29.2 GB	3.6%	8500.44
Sep 26 03:00:00 - 03:59:59	75657	3.2%	21.02	29.9 GB	3.7%	8715.99
Sep 26 04:00:00 - 04:15:02	20334	0.9%	22.54	6.2 GB	0.8%	7159.58
TOTAL: 24:00:00	2339413	100.0%	27.08	816.9 GB	100.0%	9913.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10876	13	8245	6	2612
2	newsfeed.pionier.net.pl	3469	218	2725	6	520
3	news.icm.edu.pl	9	0	4	0	5
TOTAL: 3		14354	231	10974	12	3137

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	125
it.comp.os.win.windows10	30
it.tlc.cellulari.android	26
comp.sys.raspberry-pi	20
comp.mobile.android	11
it.comp.os.win.windows7	9
uk.rec.cycling.moderated	6
comp.mobile.ipad	2
nl.comp.os.linux.techniek	2
TOTAL: 9	231

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

Groups	Count
pl.praca.oferowana	6
pl.sci.matematyka	4
courts.usa.federal.supreme	1
clari.usa.law.supreme	1
TOTAL: 4	12

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2476
Binary in non-binary group	57
Scoring filter (8)	17
Scoring filter (9)	13
Scoring filter (10)	7
Scoring filter (16)	5
Scoring filter (18)	3
Too many newsgroups	2
Scoring filter (13)	1
TOTAL: 9	2581

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	news.nask.pl	56
	TOTAL: 1	56
Including strange strings
	newsfeed.pionier.net.pl	455
	news.nask.pl	98
	news.icm.edu.pl	2
	TOTAL: 3	555
TOTAL: 2		611

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	416206	64755	222698	2513	0	19144	15%	23:59:56
2	panorama	334336	12860	291826	1320	6	297009	3%	23:59:57
3	poznan	5800	72	4238	14	0	1	1%	23:50:47
4	icm	343785	20	272552	32045	0	39	0%	23:59:57
5	okapi	0	0	0	0	0	970	0%	23:57:50
TOTAL: 5		1100127	77707	791314	35892	6	317163	7%	119:48:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	35.6 GB	1.5 GB	37.0 GB	449.3 KB/s	577.1 KB	23:59:56
2	panorama	5.5 GB	61.0 MB	5.6 GB	67.6 KB/s	411.8 KB	23:59:57
3	icm	2.5 MB	16.6 GB	16.6 GB	201.9 KB/s	544.0 KB	23:59:57
4	poznan	263.9 KB	33.5 KB	297.4 KB	0.0 KB/s	3.5 KB	23:50:47
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		41.1 GB	18.2 GB	59.2 GB	144.0 KB/s	546.7 KB	119:48:27

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	Rej	Elapsed
1	188.47.78.224.ipv4.supernova.orange.pl	19	113	137.2 KB	23	0	9	01:27:29
2	web.man.lodz.pl	288	104	119.4 KB	39	0	0	00:00:25
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:02
TOTAL: 3		312	217	256.6 KB	62	5	9	01:28:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.47.78.224.ipv4.supernova.orange.pl	19	113	137.2 KB	23	0	9	01:27:29
2	*.man.lodz.pl	677	104	119.4 KB	39	5	0	00:00:33
3	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 3		697	217	256.6 KB	62	5	9	01:28:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
188.47.78.224.ipv4.supernova.orange.pl	0.712	0.720	01:27:29
web.man.lodz.pl	1.468	5.556	00:00:25
mon.man.lodz.pl	1.504	5.520	00:00:04
isvcs01.man.lodz.pl	0.052	0.148	00:00:02
david.man.lodz.pl	0.436	1.924	00:00:01
ec2-54-67-38-166.us-west-1.compute.amazonaws.com	0.008	0.016	00:00:00
TOTAL: 6	4.180	13.884	01:28:03

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ec2-54-67-38-166.us-west-1.compute.amazonaws.com	1
TOTAL: 3	385

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	104	47.9%
2	lodz	85	39.2%
3	lodman	17	7.8%
4	lodzman	11	5.1%
TOTAL: 4		217	100.0%

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

Newsgroup		Count
1	lodz.test	78
2	pl.comp.pecet	76
3	pl.comp.os.ms-windows.winnt	28
4	lodman.info	15
5	lodzman.test	11
6	lodz.ogloszenia	7
7	lodman.test	2
TOTAL: 7		217

Daily Usenet report

Sep 25 04:15:02 -- Sep 26 04:15:02