Daily Usenet report: Sep 18 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Sep 18 14:47:05 newsfeed overchan[7886]: input line too long, skipping
Sep 18 14:48:54 newsfeed overchan[7886]: input line too long, skipping
Sep 18 15:43:15 newsfeed overchan[7886]: input line too long, skipping
Sep 18 16:34:34 newsfeed overchan[7886]: input line too long, skipping
Sep 18 18:19:42 newsfeed overchan[7886]: input line too long, skipping
Sep 18 19:40:00 newsfeed overchan[7886]: input line too long, skipping
Sep 18 19:44:07 newsfeed overchan[7886]: input line too long, skipping
Sep 18 19:51:50 newsfeed overchan[7886]: input line too long, skipping
Sep 18 19:53:36 newsfeed overchan[7886]: input line too long, skipping
Sep 18 21:27:22 newsfeed overchan[7886]: input line too long, skipping
Sep 18 23:14:06 newsfeed nnrpd[19148]: SERVER perl filter not defined
Sep 18 23:21:02 newsfeed nnrpd[19477]: SERVER perl filter not defined
Sep 19 00:41:51 newsfeed nnrpd[22533]: SERVER perl filter not defined
Sep 19 01:05:40 newsfeed nnrpd[23417]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2376165	97.3%	278.4 MB	97.1%
innfeed	41158	1.7%	4.9 MB	1.7%
innd	19246	0.8%	3.1 MB	1.1%
nnrpd	4768	0.2%	506.6 KB	0.2%
overchan	11	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2441349	100.0%	286.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2361353	86.6%
Positive hits	264531	9.7%
Negative hits	96419	3.5%
Cache misses	2886	0.1%
TOTAL: 4	2725189	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.719	0.0%	246	0.000	35.443	94.600
article cleanup	00:00:30.958	0.0%	2378466	0.010	0.013	0.018
article logging	00:01:45.825	0.1%	2378916	0.030	0.044	0.083
article parse	00:47:11.115	3.3%	91518212	0.015	0.031	0.047
article write	00:18:07.077	1.3%	2365721	0.203	0.460	0.879
artlog/artcncl	00:00:00.016	0.0%	230	0.000	0.070	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:02.701	0.1%	105801334	0.001	0.001	0.002
hisgrep/artcncl	00:00:04.220	0.0%	238	0.000	17.731	31.333
hishave/artcncl	00:00:00.639	0.0%	246	0.000	2.598	18.800
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:34:48.407	6.6%	2733481	0.438	2.081	5.412
history sync	00:00:00.028	0.0%	290	0.000	0.097	1.000
history write	03:22:14.737	14.0%	2368602	1.135	5.123	11.838
hiswrite/artcncl	00:00:00.047	0.0%	8	0.000	5.875	11.000
idle	11:49:13.712	49.3%	65589846	0.273	0.649	1.697
nntp read	01:07:39.268	4.7%	89919936	0.032	0.045	0.060
overview write	00:00:11.535	0.0%	2365721	0.003	0.005	0.007
perl filter	04:24:45.017	18.4%	2368601	4.109	6.706	9.566
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:58.602	0.3%	5076209	0.042	0.059	0.078
TOTAL: 24:00:02.145	23:33:42.623	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.872	0.0%	639624	0.001	0.003	0.006
article prepare	00:00:00.095	0.0%	370912	0.000	0.000	0.004
article read	00:04:10.595	0.3%	86253	0.284	2.905	23.495
backlog stats	00:00:02.109	0.0%	2298483	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:25.445	0.0%	1235106	0.011	0.021	0.028
data write	00:04:47.914	0.3%	1095029	0.085	0.263	0.498
idle	23:39:26.740	99.2%	2298483	15.974	37.053	163.498
status file	00:00:20.942	0.0%	16530	0.703	1.267	6.446
TOTAL: 23:50:23.365	23:49:15.712	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:14.462	54.3%	3042	0.000	4.754	73.556
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.330	1.2%	6704	0.000	0.049	1.947
readart	00:00:00.003	0.0%	34	0.000	0.088	0.500
TOTAL: 00:00:26.656	00:00:14.795	55.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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1038	2362056	2356617	2225	3214	99%	475:00:52
2	news.nask.pl	2	271768	5633	256610	9525	2%	23:57:29
3	news.icm.edu.pl	49	258	138	109	11	53%	16:35:15
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1094	2634086	2362392	258944	12750	89%	515:33:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	736.8 GB	486.7 MB	499.9 MB	737.8 GB	99%	327.8 KB
2	news.nask.pl	760.8 MB	2.7 GB	2.7 GB	6.1 GB	12%	419.9 KB
3	news.icm.edu.pl	2.0 MB	28.2 KB	676.7 KB	2.7 MB	74%	18.7 KB
4	localhost	6.3 KB	0.0 KB	0.0 KB	6.3 KB	100%	1.6 KB
TOTAL: 4		737.6 GB	3.1 GB	3.2 GB	743.9 GB	99%	328.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 18 04:15:02 - 04:59:59	58717	2.5%	21.76	20.3 GB	2.7%	7881.15
Sep 18 05:00:00 - 05:59:59	104346	4.4%	28.98	36.1 GB	4.9%	10522.56
Sep 18 06:00:00 - 06:59:59	107944	4.6%	29.98	35.9 GB	4.9%	10464.49
Sep 18 07:00:00 - 07:59:59	79508	3.4%	22.09	26.1 GB	3.5%	7609.57
Sep 18 08:00:00 - 08:59:59	79949	3.4%	22.21	24.8 GB	3.4%	7211.73
Sep 18 09:00:00 - 09:59:59	69826	3.0%	19.40	21.5 GB	2.9%	6267.82
Sep 18 10:00:00 - 10:59:59	92411	3.9%	25.67	32.8 GB	4.5%	9563.29
Sep 18 11:00:00 - 11:59:59	90025	3.8%	25.01	33.2 GB	4.5%	9665.71
Sep 18 12:00:00 - 12:59:59	73585	3.1%	20.44	26.1 GB	3.5%	7589.02
Sep 18 13:00:00 - 13:59:59	63899	2.7%	17.75	21.1 GB	2.9%	6137.28
Sep 18 14:00:00 - 14:59:59	77770	3.3%	21.60	27.7 GB	3.7%	8055.21
Sep 18 15:00:00 - 15:59:59	100207	4.2%	27.84	36.1 GB	4.9%	10512.66
Sep 18 16:00:00 - 16:59:59	146983	6.2%	40.83	38.9 GB	5.3%	11319.99
Sep 18 17:00:00 - 17:59:59	106573	4.5%	29.60	35.5 GB	4.8%	10346.65
Sep 18 18:00:00 - 18:59:59	122543	5.2%	34.04	36.5 GB	4.9%	10618.97
Sep 18 19:00:00 - 19:59:59	109919	4.7%	30.53	38.7 GB	5.3%	11285.98
Sep 18 20:00:00 - 20:59:59	98003	4.1%	27.22	35.9 GB	4.9%	10443.46
Sep 18 21:00:00 - 21:59:59	124309	5.3%	34.53	37.5 GB	5.1%	10922.77
Sep 18 22:00:00 - 22:59:59	138166	5.8%	38.38	35.7 GB	4.8%	10390.95
Sep 18 23:00:00 - 23:59:59	176250	7.5%	48.96	35.5 GB	4.8%	10335.08
Sep 19 00:00:00 - 00:59:59	63519	2.7%	17.64	19.7 GB	2.7%	5741.13
Sep 19 01:00:00 - 01:59:59	90778	3.8%	25.22	26.4 GB	3.6%	7695.91
Sep 19 02:00:00 - 02:59:59	81005	3.4%	22.50	20.0 GB	2.7%	5830.63
Sep 19 03:00:00 - 03:59:59	95332	4.0%	26.48	31.8 GB	4.3%	9260.38
Sep 19 04:00:00 - 04:15:03	11196	0.5%	12.40	3.9 GB	0.5%	4504.73
TOTAL: 24:00:01	2362763	100.0%	27.35	737.6 GB	100.0%	8951.05

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	9579	4	6967	8	2600
2	newsfeed.pionier.net.pl	3574	228	2872	0	474
3	news.icm.edu.pl	19	0	2	0	17
TOTAL: 3		13172	232	9841	8	3091

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	142
it.tlc.cellulari.android	30
it.comp.os.win.windows10	19
comp.mobile.ipad	10
uk.rec.cycling.moderated	9
uk.net.providers.gradwell	6
comp.sys.raspberry-pi	4
de.rec.sf.startrek	4
de.comm.software.mailreader	3
it.comp.os.linux.ubuntu	2
nl.comp.os.linux.techniek	2
it.comp.os.win.windows7	1
TOTAL: 12	232

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

Groups	Count
clari.usa.law.supreme	7
bit.listserv.catholic	1
TOTAL: 2	8

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2356
Binary in non-binary group	210
EMP (phl)	34
Scoring filter (8)	19
Scoring filter (9)	5
Scoring filter (16)	5
HTML post	4
Scoring filter (18)	2
Scoring filter (10)	2
Too many newsgroups	2
Scoring filter (11)	1
TOTAL: 11	2640

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	371
	news.nask.pl	78
	news.icm.edu.pl	1
	TOTAL: 3	450
TOTAL: 1		450

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	491424	58872	269526	1706	0	11787	11%	23:59:58
2	panorama	317345	10174	277089	714	6	342105	3%	23:59:58
3	poznan	6035	32	4281	15	0	0	0%	23:59:58
4	icm	413002	10	322603	21314	0	11	0%	23:59:58
5	okapi	0	0	0	0	0	1037	0%	23:57:51
TOTAL: 5		1227806	69088	873499	23749	6	354940	5%	119:57:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	30.3 GB	878.0 MB	31.2 GB	378.5 KB/s	539.8 KB	23:59:58
2	panorama	4.2 GB	175.1 MB	4.4 GB	53.1 KB/s	421.2 KB	23:59:58
3	poznan	136.1 KB	29.9 KB	166.0 KB	0.0 KB/s	3.5 KB	23:59:58
4	icm	21.9 KB	10.0 GB	10.0 GB	121.8 KB/s	493.6 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		34.5 GB	11.1 GB	45.6 GB	110.7 KB/s	515.0 KB	119:57:43

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
TOTAL: 1	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	34	33.4 KB	16	0	00:00:20
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	34	33.4 KB	16	4	00:00:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	676	34	33.4 KB	16	4	0	00:00:25
TOTAL: 1		676	34	33.4 KB	16	4	0	00:00:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.224	5.180	00:00:20
mon.man.lodz.pl	1.336	4.920	00:00:03
david.man.lodz.pl	0.400	1.504	00:00:01
isvcs01.man.lodz.pl	0.028	0.048	00:00:00
TOTAL: 4	2.988	11.652	00:00:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	34
TOTAL: 1		34

Daily Usenet report

Sep 18 04:15:02 -- Sep 19 04:15:03