Daily Usenet report: Sep 3 04:15:04

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 3 10:34:28 newsfeed overchan[16018]: input line too long, skipping
Sep 3 10:50:37 newsfeed overchan[16018]: input line too long, skipping
Sep 3 12:38:08 newsfeed overchan[1042]: input line too long, skipping
Sep 3 12:38:27 newsfeed overchan[1042]: input line too long, skipping
Sep 3 15:14:51 newsfeed overchan[1042]: input line too long, skipping
Sep 3 15:41:20 newsfeed overchan[1042]: input line too long, skipping
Sep 3 17:51:32 newsfeed overchan[1042]: input line too long, skipping
Sep 3 20:52:06 newsfeed overchan[1042]: input line too long, skipping
Sep 4 02:13:35 newsfeed overchan[1042]: input line too long, skipping
Sep 4 04:03:09 newsfeed overchan[1042]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2518943	97.8%	294.2 MB	97.5%
innfeed	30664	1.2%	3.6 MB	1.2%
innd	21265	0.8%	3.3 MB	1.1%
nnrpd	4818	0.2%	503.8 KB	0.2%
overchan	11	0.0%	1.8 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	2575703	100.0%	301.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2479780	96.5%
Negative hits	77700	3.0%
Positive hits	11065	0.4%
Cache misses	112	0.0%
TOTAL: 4	2568657	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.005	0.0%	180	0.000	11.139	72.000
article cleanup	00:00:37.401	0.0%	2502370	0.012	0.015	0.019
article logging	00:01:41.091	0.1%	2502752	0.031	0.040	0.167
article parse	01:08:04.858	4.8%	190361459	0.015	0.021	0.038
article write	00:21:50.694	1.5%	2495610	0.373	0.525	0.937
artlog/artcncl	00:00:00.005	0.0%	177	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:05:10.968	0.4%	206545745	0.001	0.002	0.002
hisgrep/artcncl	00:00:00.459	0.0%	176	0.000	2.608	21.000
hishave/artcncl	00:00:00.156	0.0%	180	0.000	0.867	6.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:34.368	2.3%	2590247	0.057	0.778	3.736
history sync	00:00:00.013	0.0%	288	0.000	0.045	1.000
history write	01:18:50.686	5.5%	2499004	0.194	1.893	8.032
hiswrite/artcncl	00:00:00.004	0.0%	4	0.000	1.000	3.000
idle	12:36:21.765	52.9%	137403629	0.216	0.330	0.615
nntp read	01:38:20.471	6.9%	188436223	0.019	0.031	0.046
overview write	00:00:14.795	0.0%	2495610	0.004	0.006	0.008
perl filter	05:28:48.026	23.0%	2499003	6.515	7.894	9.194
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:22.987	0.3%	5010115	0.039	0.052	0.070
TOTAL: 23:50:01.425	23:18:00.752	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.157	0.0%	44485	0.000	0.004	0.015
article prepare	00:00:00.003	0.0%	10198	0.000	0.000	0.036
article read	00:00:53.604	0.1%	2555	0.000	20.980	761.833
backlog stats	00:00:00.147	0.0%	148490	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:01.974	0.0%	78505	0.005	0.025	0.061
data write	00:00:04.649	0.0%	62270	0.013	0.075	0.418
idle	23:42:42.982	99.9%	148490	282.949	574.874	1076.421
status file	00:00:10.987	0.0%	10463	0.542	1.050	8.583
TOTAL: 23:44:06.963	23:43:54.503	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.169	59.0%	3120	0.000	5.823	42.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.185	0.6%	7104	0.000	0.026	0.667
readart	00:00:00.009	0.0%	144	0.000	0.062	0.500
TOTAL: 00:00:30.772	00:00:18.363	59.7%	-	-	-	-

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	1199	2509775	2500493	5957	3325	99%	367:33:43
2	news.nask.pl	3	14631	9744	1452	3435	66%	23:49:17
3	news.icm.edu.pl	44	369	32	333	4	8%	19:39:08
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1247	2524775	2510269	7742	6764	99%	411:02:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	807.8 GB	43.7 MB	44.0 MB	807.9 GB	99%	338.4 KB
2	news.nask.pl	70.2 MB	529.3 KB	24.1 MB	94.8 MB	74%	7.4 KB
3	news.icm.edu.pl	1.5 MB	0.0 KB	115.3 KB	1.6 MB	93%	46.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		807.9 GB	44.2 MB	68.3 MB	808.0 GB	99%	336.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 3 04:15:04 - 04:59:59	89785	3.6%	33.30	29.3 GB	3.6%	11396.55
Sep 3 05:00:00 - 05:59:59	101144	4.0%	28.10	37.3 GB	4.6%	10855.00
Sep 3 06:00:00 - 06:59:59	88048	3.5%	24.46	33.8 GB	4.2%	9849.92
Sep 3 07:00:00 - 07:59:59	93000	3.7%	25.83	32.4 GB	4.0%	9440.34
Sep 3 08:00:00 - 08:59:59	97298	3.9%	27.03	35.1 GB	4.3%	10213.77
Sep 3 09:00:00 - 09:59:59	115406	4.6%	32.06	35.7 GB	4.4%	10400.02
Sep 3 10:00:00 - 10:59:59	71255	2.8%	19.79	23.8 GB	2.9%	6929.34
Sep 3 11:00:00 - 11:59:59	116749	4.6%	32.43	36.2 GB	4.5%	10546.25
Sep 3 12:00:00 - 12:59:59	103130	4.1%	28.65	33.1 GB	4.1%	9644.97
Sep 3 13:00:00 - 13:59:59	50431	2.0%	14.01	18.1 GB	2.2%	5274.28
Sep 3 14:00:00 - 14:59:59	79049	3.1%	21.96	24.7 GB	3.1%	7207.01
Sep 3 15:00:00 - 15:59:59	115402	4.6%	32.06	35.8 GB	4.4%	10434.44
Sep 3 16:00:00 - 16:59:59	129723	5.2%	36.03	41.4 GB	5.1%	12070.44
Sep 3 17:00:00 - 17:59:59	108457	4.3%	30.13	32.6 GB	4.0%	9482.30
Sep 3 18:00:00 - 18:59:59	110822	4.4%	30.78	33.1 GB	4.1%	9649.07
Sep 3 19:00:00 - 19:59:59	130061	5.2%	36.13	37.0 GB	4.6%	10780.39
Sep 3 20:00:00 - 20:59:59	124551	5.0%	34.60	40.4 GB	5.0%	11758.77
Sep 3 21:00:00 - 21:59:59	127187	5.1%	35.33	39.5 GB	4.9%	11515.43
Sep 3 22:00:00 - 22:59:59	109754	4.4%	30.49	35.6 GB	4.4%	10372.55
Sep 3 23:00:00 - 23:59:59	118979	4.7%	33.05	34.7 GB	4.3%	10106.21
Sep 4 00:00:00 - 00:59:59	88442	3.5%	24.57	28.4 GB	3.5%	8282.38
Sep 4 01:00:00 - 01:59:59	90264	3.6%	25.07	29.1 GB	3.6%	8478.25
Sep 4 02:00:00 - 02:59:59	118091	4.7%	32.80	36.9 GB	4.6%	10760.14
Sep 4 03:00:00 - 03:59:59	105722	4.2%	29.37	33.9 GB	4.2%	9870.20
Sep 4 04:00:00 - 04:15:03	28846	1.1%	31.94	9.0 GB	1.1%	10475.07
TOTAL: 23:59:59	2511596	100.0%	29.07	807.1 GB	100.0%	9795.27

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3585	108	3202	0	275
2	news.nask.pl	3579	53	189	5	3332
3	news.icm.edu.pl	6	0	0	0	6
TOTAL: 3		7170	161	3391	5	3613

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	56
comp.mobile.android	49
it.tlc.cellulari.android	36
nl.comp.sys.android	10
de.comp.lang.php	6
hr.ponuda.hardver.komponente	1
it.comp.os.win.windows8	1
de.comp.office-pakete.ms-office.excel	1
it.comp.os.linux.ubuntu	1
TOTAL: 9	161

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

Groups	Count
pl.sci.matematyka	4
bit.listserv.catholic	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3029
Binary in non-binary group	85
Scoring filter (8)	40
Scoring filter (9)	19
Poison newsgroup	17
EMP (phl)	14
Scoring filter (10)	9
Scoring filter (12)	5
Scoring filter (11)	5
Too many newsgroups	2
HTML post	1
Too many newsgroups (low_xpost)	1
TOTAL: 12	3227

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	256
	news.nask.pl	127
	news.icm.edu.pl	2
	TOTAL: 3	385
TOTAL: 1		385

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	27813	317	9885	95	26	34402	1%	23:59:08
2	poznan	15920	288	9413	31	0	1	1%	23:50:29
3	icm	24006	77	16661	1386	0	0	0%	23:50:29
4	nask	3141	9	2210	0	0	0	0%	23:55:03
5	okapi	0	0	0	0	0	1472	0%	23:48:22
TOTAL: 5		70880	691	38169	1512	26	35875	0%	119:23:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	3.9 MB	288.4 KB	4.2 MB	0.1 KB/s	10.5 KB	23:59:08
2	poznan	1.4 MB	273.4 KB	1.7 MB	0.0 KB/s	5.4 KB	23:50:29
3	icm	362.4 KB	597.4 MB	597.7 MB	7.1 KB/s	418.4 KB	23:50:29
4	nask	29.4 KB	0.0 KB	29.4 KB	0.0 KB/s	3.3 KB	23:55:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:48:22
TOTAL: 5		5.7 MB	597.9 MB	603.6 MB	1.4 KB/s	280.6 KB	119:23:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	144	184.8 KB	62	0	0	00:00:24
TOTAL: 1		288	144	184.8 KB	62	0	0	00:00:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	672	144	184.8 KB	62	0	0	00:00:30
TOTAL: 1		672	144	184.8 KB	62	0	0	00:00:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.328	3.208	00:00:24
mon.man.lodz.pl	1.164	3.536	00:00:04
david.man.lodz.pl	0.408	1.112	00:00:01
TOTAL: 3	2.900	7.856	00:00:30

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	144	100.0%
TOTAL: 1		144	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	96
2	pl.comp.os.ms-windows.winnt	48
TOTAL: 2		144

Daily Usenet report

Sep 3 04:15:04 -- Sep 4 04:15:03