Daily Usenet report: Nov 2 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 2 04:22:34 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Nov 2 09:10:19 newsfeed overchan[8927]: input line too long, skipping
Nov 2 13:50:58 newsfeed nnrpd[30604]: SERVER perl filter not defined
Nov 2 16:08:30 newsfeed overchan[8927]: input line too long, skipping
Nov 2 16:23:36 newsfeed nnrpd[5861]: SERVER perl filter not defined
Nov 2 17:42:38 newsfeed overchan[8927]: input line too long, skipping
Nov 2 18:04:17 newsfeed overchan[8927]: input line too long, skipping
Nov 2 18:06:58 newsfeed overchan[8927]: input line too long, skipping
Nov 2 18:15:34 newsfeed overchan[8927]: input line too long, skipping
Nov 2 18:16:30 newsfeed overchan[8927]: input line too long, skipping
Nov 2 18:39:16 newsfeed nnrpd[10726]: SERVER perl filter not defined
Nov 2 20:26:49 newsfeed overchan[8927]: input line too long, skipping
Nov 2 22:49:57 newsfeed overchan[8927]: input line too long, skipping
Nov 2 22:52:27 newsfeed overchan[8927]: input line too long, skipping
Nov 2 23:18:05 newsfeed nnrpd[21043]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2859154	96.9%	333.8 MB	96.7%
innfeed	43876	1.5%	5.2 MB	1.5%
innd	41604	1.4%	5.9 MB	1.7%
nnrpd	4798	0.2%	502.7 KB	0.1%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2949445	100.0%	345.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2842174	96.7%
Negative hits	97771	3.3%
Cache misses	509	0.0%
Positive hits	105	0.0%
TOTAL: 4	2940559	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.117	0.0%	427	0.000	19.009	65.500
article cleanup	00:00:47.321	0.1%	2867158	0.011	0.017	0.021
article logging	00:02:07.839	0.1%	2867946	0.034	0.045	0.097
article parse	01:00:56.741	4.2%	114478938	0.019	0.032	0.050
article write	00:21:38.600	1.5%	2845211	0.230	0.456	0.802
artlog/artcncl	00:00:00.014	0.0%	427	0.000	0.033	0.100
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:23.337	0.2%	130744730	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.926	0.0%	406	0.000	4.744	14.500
hishave/artcncl	00:00:02.237	0.0%	427	0.000	5.239	7.021
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:08:42.810	4.8%	2965222	0.105	1.390	10.475
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	02:11:14.501	9.1%	2867077	0.258	2.747	9.771
hiswrite/artcncl	00:00:01.097	0.0%	21	0.500	52.238	60.833
idle	11:59:09.866	49.9%	84851617	0.153	0.509	2.340
nntp read	01:01:46.978	4.3%	112180073	0.020	0.033	0.051
overview write	00:00:15.779	0.0%	2845211	0.004	0.006	0.008
perl filter	05:24:10.533	22.5%	2867077	4.729	6.784	8.652
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:52.534	0.3%	5709087	0.039	0.051	0.067
TOTAL: 24:00:03.889	23:18:20.246	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.136	0.0%	59963	0.000	0.002	0.010
article prepare	00:00:00.006	0.0%	10652	0.000	0.001	0.036
article read	00:01:52.490	0.1%	2699	2.000	41.678	557.375
backlog stats	00:00:00.140	0.0%	143106	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	43	0.000	0.000	0.000
data read	00:00:01.603	0.0%	79035	0.002	0.020	0.049
data write	00:00:02.543	0.0%	60580	0.012	0.042	0.085
idle	23:52:13.205	99.8%	143106	404.405	600.486	2230.189
status file	00:00:16.253	0.0%	15651	0.582	1.038	8.331
TOTAL: 23:54:40.925	23:54:26.376	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:29.370	68.2%	3061	0.000	9.595	9757.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.226	0.5%	6799	0.000	0.033	1.000
readart	00:00:00.009	0.0%	60	0.000	0.150	1.500
TOTAL: 00:00:43.091	00:00:29.605	68.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	147
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	191

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	848	2857881	2835265	0	22616	99%	316:38:49
2	news.icm.edu.pl	72	198	54	127	17	27%	10:46:23
3	localhost	5	4	4	0	0	100%	00:00:00
4	news.nask.pl	2	0	0	0	0	0%	00:04:00
TOTAL: 4		927	2858083	2835323	127	22633	99%	327:29:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	789.1 GB	3.6 MB	3.4 GB	792.5 GB	99%	290.8 KB
2	news.icm.edu.pl	1.4 MB	29.1 KB	231.8 KB	1.6 MB	84%	23.2 KB
3	localhost	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	1.5 KB
4	news.nask.pl	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		789.1 GB	3.7 MB	3.4 GB	792.5 GB	99%	290.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 2 04:15:02 - 04:59:59	39338	1.4%	14.58	12.3 GB	1.6%	4791.34
Nov 2 05:00:00 - 05:59:59	102687	3.6%	28.52	35.8 GB	4.5%	10414.52
Nov 2 06:00:00 - 06:59:59	110371	3.9%	30.66	41.6 GB	5.3%	12107.06
Nov 2 07:00:00 - 07:59:59	122155	4.3%	33.93	43.6 GB	5.5%	12693.76
Nov 2 08:00:00 - 08:59:59	147869	5.2%	41.07	37.4 GB	4.7%	10899.23
Nov 2 09:00:00 - 09:59:59	135975	4.8%	37.77	37.8 GB	4.8%	10998.22
Nov 2 10:00:00 - 10:59:59	142014	5.0%	39.45	39.9 GB	5.1%	11629.50
Nov 2 11:00:00 - 11:59:59	117466	4.1%	32.63	34.9 GB	4.4%	10168.76
Nov 2 12:00:00 - 12:59:59	66142	2.3%	18.37	20.7 GB	2.6%	6037.50
Nov 2 13:00:00 - 13:59:59	67243	2.4%	18.68	19.8 GB	2.5%	5754.54
Nov 2 14:00:00 - 14:59:59	61466	2.2%	17.07	20.4 GB	2.6%	5943.86
Nov 2 15:00:00 - 15:59:59	61036	2.2%	16.95	19.5 GB	2.5%	5693.96
Nov 2 16:00:00 - 16:59:59	121265	4.3%	33.68	32.9 GB	4.2%	9572.81
Nov 2 17:00:00 - 17:59:59	125362	4.4%	34.82	38.1 GB	4.8%	11102.43
Nov 2 18:00:00 - 18:59:59	153024	5.4%	42.51	39.3 GB	5.0%	11444.25
Nov 2 19:00:00 - 19:59:59	124671	4.4%	34.63	38.8 GB	4.9%	11287.03
Nov 2 20:00:00 - 20:59:59	122754	4.3%	34.10	35.9 GB	4.5%	10442.35
Nov 2 21:00:00 - 21:59:59	120181	4.2%	33.38	33.4 GB	4.2%	9715.35
Nov 2 22:00:00 - 22:59:59	155644	5.5%	43.23	36.6 GB	4.6%	10648.18
Nov 2 23:00:00 - 23:59:59	143312	5.1%	39.81	34.3 GB	4.3%	9990.02
Nov 3 00:00:00 - 00:59:59	127545	4.5%	35.43	30.7 GB	3.9%	8942.45
Nov 3 01:00:00 - 01:59:59	124166	4.4%	34.49	35.1 GB	4.4%	10218.39
Nov 3 02:00:00 - 02:59:59	154385	5.4%	42.88	36.6 GB	4.6%	10674.71
Nov 3 03:00:00 - 03:59:59	169311	6.0%	47.03	29.1 GB	3.7%	8483.57
Nov 3 04:00:00 - 04:15:02	19920	0.7%	22.08	4.3 GB	0.6%	5048.80
TOTAL: 24:00:00	2835302	100.0%	32.82	788.7 GB	100.0%	9571.60

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	23406	262	78	4	23062
2	news.icm.edu.pl	19	0	3	0	16
TOTAL: 2		23425	262	81	4	23078

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	175
it.comp.os.win.windows10	48
comp.mobile.android	21
it.comp.os.win.windows7	4
de.comp.lang.vbclassic	3
comp.mobile.ipad	3
de.rec.sf.startrek	2
it.tlc.cellulari.android	2
de.comm.software.mailreader	2
it.comp.os.linux.ubuntu	1
it.comp.os.win.windows8	1
TOTAL: 11	262

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

Groups	Count
pl.praca.oferowana	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	22279
HTML post	7
Scoring filter (8)	3
TOTAL: 3	22289

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	787
	news.icm.edu.pl	2
	TOTAL: 2	789
TOTAL: 1		789

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46930	1398	19126	390	96	39134	2%	23:59:57
2	icm	22020	25	17942	15	0	0	0%	22:59:45
3	nask	12263	14	10844	0	0	0	0%	22:59:38
4	poznan	75	0	54	0	0	0	0%	04:17:38
5	okapi	0	0	0	0	0	1234	0%	23:57:50
TOTAL: 5		81288	1437	47966	405	96	40368	1%	98:14:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	7.2 MB	1.3 MB	8.6 MB	0.1 KB/s	4.9 KB	23:59:57
2	icm	1.9 MB	3.9 MB	5.8 MB	0.1 KB/s	148.8 KB	22:59:45
3	nask	238.5 KB	0.0 KB	238.5 KB	0.0 KB/s	17.0 KB	22:59:38
4	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:17:38
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		9.4 MB	5.2 MB	14.6 MB	0.0 KB/s	8.1 KB	98:14:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	289	60	63.5 KB	26	0	00:00:25
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		293	60	63.5 KB	26	4	00:00:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	60	63.5 KB	26	4	00:00:32
2	unresolved	1	0	0.0 KB	0	0	00:00:09
3	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
4	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
TOTAL: 4		680	60	63.5 KB	26	4	00:00:42

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.440	12.792	00:00:25
45.55.31.60	0.004	0.020	00:00:09
mon.man.lodz.pl	1.272	12.948	00:00:04
david.man.lodz.pl	0.380	3.876	00:00:01
isvcs01.man.lodz.pl	0.028	0.200	00:00:00
ec2-54-187-241-217.us-west-2.compute.amazonaws.com	0.004	0.048	00:00:00
ec2-13-56-168-195.us-west-1.compute.amazonaws.com	0.008	0.036	00:00:00
TOTAL: 7	3.136	29.920	00:00:42

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ec2-13-56-168-195.us-west-1.compute.amazonaws.com	1
ec2-54-187-241-217.us-west-2.compute.amazonaws.com	1
45.55.31.60	1
TOTAL: 5	387

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	30
2	pl.comp.pecet	30
TOTAL: 2		60

Daily Usenet report

Nov 2 04:15:02 -- Nov 3 04:15:02