Daily Usenet report: Sep 13 04:15:03

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Sep 13 09:02:01 newsfeed overchan[25562]: input line too long, skipping
Sep 13 11:33:15 newsfeed nnrpd[9742]: SERVER perl filter not defined
Sep 13 12:08:54 newsfeed nnrpd[11103]: SERVER perl filter not defined
Sep 13 14:58:55 newsfeed overchan[25562]: input line too long, skipping
Sep 13 15:00:49 newsfeed overchan[25562]: input line too long, skipping
Sep 13 15:03:46 newsfeed overchan[25562]: input line too long, skipping
Sep 13 15:08:16 newsfeed overchan[25562]: input line too long, skipping
Sep 13 15:21:44 newsfeed overchan[25562]: input line too long, skipping
Sep 13 16:13:21 newsfeed overchan[25562]: input line too long, skipping
Sep 13 18:55:46 newsfeed overchan[25562]: input line too long, skipping
Sep 13 18:58:27 newsfeed overchan[25562]: input line too long, skipping
Sep 13 19:01:59 newsfeed overchan[25562]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2304097	96.3%	267.5 MB	96.0%
innfeed	45045	1.9%	5.3 MB	1.9%
innd	38033	1.6%	5.4 MB	1.9%
nnrpd	4791	0.2%	497.8 KB	0.2%
overchan	11	0.0%	1.7 KB	0.0%
controlchan	6	0.0%	0.7 KB	0.0%
TOTAL: 6	2391983	100.0%	278.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2240700	75.9%
Negative hits	601761	20.4%
Positive hits	110342	3.7%
Cache misses	735	0.0%
TOTAL: 4	2953538	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.515	0.0%	213	0.000	39.977	352.000
article cleanup	00:00:37.178	0.0%	2303487	0.000	0.016	0.040
article logging	00:01:34.646	0.1%	2304028	0.031	0.041	0.119
article parse	01:02:16.071	4.3%	123290203	0.000	0.030	0.061
article write	00:19:00.137	1.3%	2233803	0.211	0.510	2.045
artlog/artcncl	00:00:00.008	0.0%	205	0.000	0.039	0.500
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:04:31.252	0.3%	137500168	0.000	0.002	0.004
hisgrep/artcncl	00:00:01.338	0.0%	198	0.000	6.758	55.300
hishave/artcncl	00:00:00.148	0.0%	213	0.000	0.695	6.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:48:57.965	3.4%	2973711	0.168	0.988	4.631
history sync	00:00:00.027	0.0%	297	0.000	0.091	1.000
history write	02:06:26.006	8.8%	2257899	1.412	3.360	15.604
hiswrite/artcncl	00:00:00.055	0.0%	15	0.000	3.667	12.000
idle	12:33:13.688	52.3%	91657950	0.240	0.493	4754.468
nntp read	01:22:39.725	5.7%	121637471	0.019	0.041	0.087
overview write	00:00:13.810	0.0%	2233803	0.000	0.006	0.045
perl filter	04:59:49.893	20.8%	2257897	1.518	7.968	16.754
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:13.589	0.3%	4616673	0.035	0.055	0.107
TOTAL: 24:00:02.242	23:23:44.051	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.764	0.0%	239318	0.000	0.003	0.013
article prepare	00:00:00.011	0.0%	39704	0.000	0.000	0.016
article read	00:01:20.599	0.1%	9538	0.303	8.450	167.842
backlog stats	00:00:00.592	0.0%	728226	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:08.725	0.0%	463621	0.007	0.019	0.047
data write	00:00:29.825	0.0%	320812	0.020	0.093	0.143
idle	24:00:08.604	99.8%	728226	34.849	118.656	918.670
status file	00:00:18.559	0.0%	16154	0.658	1.149	21.400
TOTAL: 24:02:59.515	24:02:27.679	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:34.818	71.8%	3075	0.000	11.323	1780.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.447	0.9%	6892	0.000	0.065	6.325
readart	00:00:00.003	0.0%	76	0.000	0.039	0.500
TOTAL: 00:00:48.515	00:00:35.268	72.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
tanguy@ortolo.eu	5	0	0	0	0	5
TOTAL	5	0	0	0	0	5

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	453	2244188	2156124	32457	55607	96%	253:38:24
2	news.nask.pl	8	123990	77363	32607	14020	62%	24:27:20
3	news.icm.edu.pl	42	243	25	213	5	10%	16:59:26
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		506	2368423	2233514	65277	69632	94%	295:05:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	647.3 GB	12.7 GB	14.9 GB	674.9 GB	95%	319.9 KB
2	news.nask.pl	23.1 GB	556.3 MB	1.9 GB	25.5 GB	90%	292.7 KB
3	news.icm.edu.pl	381.1 KB	0.0 KB	82.8 KB	463.8 KB	82%	15.5 KB
4	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.4 KB
TOTAL: 4		670.4 GB	13.2 GB	16.8 GB	700.4 GB	95%	318.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 13 04:15:03 - 04:59:59	90569	4.1%	33.58	27.6 GB	4.1%	10738.11
Sep 13 05:00:00 - 05:59:59	119675	5.4%	33.24	37.5 GB	5.6%	10933.34
Sep 13 06:00:00 - 06:59:59	102369	4.6%	28.44	33.8 GB	5.0%	9837.38
Sep 13 07:00:00 - 07:59:59	33500	1.5%	9.31	11.6 GB	1.7%	3366.77
Sep 13 08:00:00 - 08:59:59	14567	0.7%	4.05	3.6 GB	0.5%	1057.39
Sep 13 09:00:00 - 09:59:59	109442	4.9%	30.40	32.6 GB	4.9%	9505.46
Sep 13 10:00:00 - 10:59:59	125165	5.6%	34.77	37.0 GB	5.5%	10773.68
Sep 13 11:00:00 - 11:59:59	101231	4.5%	28.12	30.5 GB	4.6%	8895.67
Sep 13 12:00:00 - 12:59:59	119599	5.4%	33.22	35.6 GB	5.3%	10375.59
Sep 13 13:00:00 - 13:59:59	112656	5.0%	31.29	33.4 GB	5.0%	9721.40
Sep 13 14:00:00 - 14:59:59	113153	5.1%	31.43	33.8 GB	5.0%	9846.41
Sep 13 15:00:00 - 15:59:59	68232	3.1%	18.95	19.8 GB	3.0%	5768.51
Sep 13 16:00:00 - 16:59:59	47399	2.1%	13.17	13.6 GB	2.0%	3953.05
Sep 13 17:00:00 - 17:59:59	48397	2.2%	13.44	13.5 GB	2.0%	3923.05
Sep 13 18:00:00 - 18:59:59	47607	2.1%	13.22	13.7 GB	2.0%	3978.18
Sep 13 19:00:00 - 19:59:59	60007	2.7%	16.67	18.2 GB	2.7%	5293.50
Sep 13 20:00:00 - 20:59:59	109576	4.9%	30.44	34.6 GB	5.2%	10087.32
Sep 13 21:00:00 - 21:59:59	108197	4.8%	30.05	32.6 GB	4.9%	9495.68
Sep 13 22:00:00 - 22:59:59	138604	6.2%	38.50	32.8 GB	4.9%	9552.75
Sep 13 23:00:00 - 23:59:59	122275	5.5%	33.97	28.2 GB	4.2%	8200.45
Sep 14 00:00:00 - 00:59:59	88622	4.0%	24.62	25.8 GB	3.9%	7527.43
Sep 14 01:00:00 - 01:59:59	112832	5.1%	31.34	37.9 GB	5.6%	11026.86
Sep 14 02:00:00 - 02:59:59	114982	5.1%	31.94	39.7 GB	5.9%	11571.65
Sep 14 03:00:00 - 03:59:59	100484	4.5%	27.91	34.5 GB	5.2%	10056.14
Sep 14 04:00:00 - 04:15:05	24525	1.1%	27.10	8.4 GB	1.2%	9686.10
TOTAL: 24:00:02	2233665	100.0%	25.85	670.3 GB	100.0%	8134.28

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	55979	203	43278	2	12496
2	news.nask.pl	14243	14	2312	2	11915
3	news.icm.edu.pl	5	0	0	0	5
TOTAL: 3		70227	217	45590	4	24416

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	111
it.tlc.cellulari.android	44
it.comp.os.win.windows10	37
comp.mobile.android	10
de.comp.lang.perl	4
de.comp.lang.php	3
hr.ponuda.hardver.komponente	2
nl.comp.os.linux.techniek	2
comp.sys.raspberry-pi	2
it.discussioni.energia	1
nl.comp.sys.android	1
TOTAL: 11	217

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

Groups	Count
pl.praca.oferowana	3
bit.listserv.catholic	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	19687
EMP (md5)	4063
EMP (phl)	32
Scoring filter (9)	30
Scoring filter (8)	24
Scoring filter (10)	20
Scoring filter (12)	6
Scoring filter (14)	5
Scoring filter (11)	3
Too many newsgroups	2
HTML post	1
TOTAL: 11	23873

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	372
	news.nask.pl	169
	TOTAL: 2	541
TOTAL: 1		541

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	86136	5709	57218	0	0	0	6%	22:52:40
2	panorama	104875	1829	76013	458	10	160602	1%	23:59:58
3	poznan	14585	227	8735	30	0	649	1%	23:59:58
4	icm	160995	57	147072	775	0	1	0%	23:59:58
5	okapi	0	0	0	0	0	1817	0%	23:57:51
TOTAL: 5		366591	7822	289038	1263	10	163069	2%	118:50:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	1.9 GB	0.0 KB	1.9 GB	24.4 KB/s	352.7 KB	22:52:40
2	panorama	6.7 MB	3.7 MB	10.4 MB	0.1 KB/s	4.7 KB	23:59:58
3	icm	1.4 MB	228.3 MB	229.7 MB	2.7 KB/s	282.7 KB	23:59:58
4	poznan	929.6 KB	130.6 KB	1.0 MB	0.0 KB/s	4.1 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		1.9 GB	232.1 MB	2.2 GB	5.3 KB/s	248.8 KB	118:50:25

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
okapi	488.3 KB
TOTAL: 2	2.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	76	69.3 KB	37	0	00:00:25
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	76	69.3 KB	37	2	00:00:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	76	69.3 KB	37	2	00:00:31
2	*.mydevil.net	1	0	0.0 KB	0	0	00:00:16
TOTAL: 2		675	76	69.3 KB	37	2	00:00:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.480	5.068	00:00:25
s0.mydevil.net	0.068	0.052	00:00:16
mon.man.lodz.pl	1.308	5.496	00:00:03
david.man.lodz.pl	0.420	1.696	00:00:01
isvcs01.man.lodz.pl	0.012	0.048	00:00:00
TOTAL: 5	3.288	12.360	00:00:47

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
s0.mydevil.net	1
TOTAL: 3	385

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	42
2	pl.comp.pecet	34
TOTAL: 2		76

Daily Usenet report

Sep 13 04:15:03 -- Sep 14 04:15:05