Daily Usenet report: May 18 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

May 18 09:09:09 newsfeed overchan[2456]: input line too long, skipping
May 18 10:30:19 newsfeed nnrpd[19726]: SERVER perl filter not defined
May 18 13:51:57 newsfeed nnrpd[26371]: SERVER perl filter not defined
May 18 14:55:29 newsfeed nnrpd[28481]: SERVER perl filter not defined
May 18 17:00:41 newsfeed nnrpd[32723]: SERVER perl filter not defined
May 18 17:54:54 newsfeed nnrpd[1974]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1565258	98.2%	179.5 MB	97.7%
innd	14309	0.9%	2.3 MB	1.3%
innfeed	7597	0.5%	1.1 MB	0.6%
nnrpd	7061	0.4%	738.7 KB	0.4%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	1594226	100.0%	183.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1560112	97.7%
Negative hits	19951	1.2%
Positive hits	16382	1.0%
Cache misses	142	0.0%
TOTAL: 4	1596587	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.548	0.0%	226	0.000	6.850	79.500
article cleanup	00:00:19.033	0.0%	1564020	0.000	0.012	0.044
article logging	00:00:49.778	0.1%	1564128	0.000	0.032	0.098
article parse	00:34:46.118	2.4%	73887384	0.018	0.028	0.055
article write	00:16:03.599	1.1%	1560266	0.212	0.618	1.551
artlog/artcncl	00:00:00.003	0.0%	220	0.000	0.014	1.000
data move	00:01:05.945	0.1%	83588378	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.358	0.0%	205	0.000	1.746	30.000
hishave/artcncl	00:00:00.055	0.0%	226	0.000	0.243	14.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:07:45.896	0.5%	1597571	0.008	0.292	4.976
history sync	00:00:00.024	0.0%	377	0.000	0.064	1.000
history write	00:13:54.707	1.0%	1561262	0.147	0.535	5.893
hiswrite/artcncl	00:00:00.017	0.0%	21	0.000	0.810	3.250
idle	18:21:50.633	76.5%	62249997	0.342	1.062	38.529
nntp read	00:31:30.460	2.2%	72792723	0.019	0.026	0.040
overview write	00:00:10.912	0.0%	1560266	0.000	0.007	0.035
perl filter	03:27:21.994	14.4%	1561262	4.652	7.969	10.586
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:23.256	0.2%	3165253	0.039	0.064	0.141
TOTAL: 23:59:36.363	23:39:04.336	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.185	0.0%	44781	0.000	0.004	1.000
article prepare	00:00:00.013	0.0%	43344	0.000	0.000	0.062
article read	00:00:01.207	0.0%	10486	0.000	0.115	1.000
backlog stats	00:00:00.213	0.0%	304435	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.334	0.0%	182035	0.000	0.013	0.062
data write	00:00:08.090	0.0%	138120	0.000	0.059	14.000
idle	23:59:28.592	100.0%	304435	0.000	283.701	15746.000
status file	00:00:00.157	0.0%	198	0.000	0.793	4.000
TOTAL: 23:59:48.750	23:59:40.791	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.685	0.0%	65122	0.074	0.087	0.800
idle	16:23:48.465	99.8%	440822	0.000	133.905	450056.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:17.563	0.0%	883740	0.000	0.020	1.111
readart	00:00:21.606	0.0%	65439	0.000	0.330	13.500
TOTAL: 16:25:17.462	16:24:33.319	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	487	1561339	1557200	599	3540	99%	151:19:56
2	news.nask.pl	2	16542	3761	12581	200	22%	23:47:13
3	news.icm.edu.pl	51	227	30	185	12	13%	17:02:49
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		546	1578113	1560996	13365	3752	98%	192:09:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	462.7 GB	15.4 MB	136.8 MB	462.9 GB	99%	311.0 KB
2	news.nask.pl	17.8 MB	505.9 KB	570.5 KB	18.9 MB	94%	4.9 KB
3	news.icm.edu.pl	2.5 MB	182.4 KB	473.8 KB	3.1 MB	79%	75.8 KB
4	localhost	8.3 KB	0.0 KB	0.0 KB	8.3 KB	100%	1.7 KB
TOTAL: 4		462.7 GB	16.0 MB	137.8 MB	462.9 GB	99%	310.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 18 04:15:02 - 04:59:59	62897	4.0%	23.31	20.4 GB	4.4%	7946.43
May 18 05:00:00 - 05:59:59	62063	4.0%	17.24	18.9 GB	4.1%	5509.26
May 18 06:00:00 - 06:59:59	24161	1.5%	6.71	6.7 GB	1.5%	1956.52
May 18 07:00:00 - 07:59:59	11090	0.7%	3.08	1.8 GB	0.4%	534.41
May 18 08:00:00 - 08:59:59	9496	0.6%	2.64	1.5 GB	0.3%	431.36
May 18 09:00:00 - 09:59:59	11474	0.7%	3.19	1.8 GB	0.4%	536.02
May 18 10:00:00 - 10:59:59	10592	0.7%	2.94	1.8 GB	0.4%	537.67
May 18 11:00:00 - 11:59:59	67629	4.3%	18.79	22.3 GB	4.8%	6494.08
May 18 12:00:00 - 12:59:59	61334	3.9%	17.04	20.2 GB	4.4%	5896.37
May 18 13:00:00 - 13:59:59	47420	3.0%	13.17	14.3 GB	3.1%	4155.80
May 18 14:00:00 - 14:59:59	66703	4.3%	18.53	17.3 GB	3.8%	5051.33
May 18 15:00:00 - 15:59:59	86217	5.5%	23.95	24.7 GB	5.3%	7182.91
May 18 16:00:00 - 16:59:59	75395	4.8%	20.94	22.5 GB	4.9%	6550.68
May 18 17:00:00 - 17:59:59	82898	5.3%	23.03	26.1 GB	5.7%	7608.69
May 18 18:00:00 - 18:59:59	56279	3.6%	15.63	15.9 GB	3.4%	4626.14
May 18 19:00:00 - 19:59:59	80162	5.1%	22.27	25.2 GB	5.5%	7336.69
May 18 20:00:00 - 20:59:59	77087	4.9%	21.41	24.6 GB	5.3%	7173.49
May 18 21:00:00 - 21:59:59	85417	5.5%	23.73	27.5 GB	5.9%	7998.30
May 18 22:00:00 - 22:59:59	93577	6.0%	25.99	28.2 GB	6.1%	8216.76
May 18 23:00:00 - 23:59:59	111107	7.1%	30.86	29.4 GB	6.4%	8555.00
May 19 00:00:00 - 00:59:59	61953	4.0%	17.21	19.5 GB	4.2%	5681.80
May 19 01:00:00 - 01:59:59	97909	6.3%	27.20	28.8 GB	6.2%	8401.61
May 19 02:00:00 - 02:59:59	100593	6.4%	27.94	28.8 GB	6.2%	8379.26
May 19 03:00:00 - 03:59:59	92722	5.9%	25.76	26.6 GB	5.8%	7741.02
May 19 04:00:00 - 04:15:02	24996	1.6%	27.71	7.2 GB	1.5%	8320.15
TOTAL: 24:00:00	1561171	100.0%	18.07	462.1 GB	100.0%	5608.14

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3646	191	2584	20	851
2	news.nask.pl	209	5	178	12	14
3	news.icm.edu.pl	12	0	1	0	11
TOTAL: 3		3867	196	2763	32	876

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	106
it.tlc.cellulari.android	48
uk.rec.cycling.moderated	10
hr.rec.glazba.hifi	6
comp.mobile.android	5
alt.sex.telephones	3
hr.ponuda.hardver.periferije	3
de.comm.provider.telefonie	3
hr.alt.cellular.gsm.android	2
comp.sys.mac.vintage	2
it.comp.os.linux.ubuntu	2
comp.sys.raspberry-pi	1
it.tlc.cellulari.iphone	1
de.comp.lang.vbclassic	1
hr.ponuda.hardver.komponente	1
hr.ponuda.hardver.konfiguracije	1
it.comp.os.win.windows8	1
TOTAL: 17	196

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

Groups	Count
pl.praca.oferowana	24
pl.sci.matematyka	7
pl.sci.historia	1
TOTAL: 3	32

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	746
EMP (md5)	16
HTML post	5
Scoring filter (9)	1
TOTAL: 4	768

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	45243	3014	25327	4222	26	6%	24:00:08
2	panorama	53107	2826	41537	351	0	5%	23:59:39
3	poznan	5829	217	3507	77	0	3%	23:55:33
4	nask	24208	52	13204	76	31	0%	23:55:01
5	okapi	0	0	0	0	2808	0%	24:00:01
TOTAL: 5		128387	6109	83575	4726	2865	4%	119:50:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	426.7 MB	760.3 MB	1.2 GB	14.1 KB/s	168.0 KB	24:00:08
2	panorama	10.3 MB	1.1 MB	11.4 MB	0.1 KB/s	3.7 KB	23:59:39
3	poznan	1.5 MB	481.9 KB	1.9 MB	0.0 KB/s	6.8 KB	23:55:33
4	nask	1.0 MB	20.1 MB	21.1 MB	0.3 KB/s	168.5 KB	23:55:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		439.4 MB	782.0 MB	1.2 GB	2.9 KB/s	115.4 KB	119:50:22

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	348	641.0 KB	288	0	00:00:07
2	dmcs2.p.lodz.pl	4	168	363.9 KB	6	0	01:38:45
3	web.man.lodz.pl	288	64	59.1 KB	29	0	00:00:32
4	pc-212-51-218-225.p.lodz.pl	4	8	21.1 KB	5	0	01:27:57
5	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 5		589	588	1.1 MB	328	5	16:25:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	641.0 KB	288	0	00:00:07
2	*.p.lodz.pl	8	176	385.0 KB	11	0	03:06:42
3	*.man.lodz.pl	677	64	59.1 KB	29	5	00:00:37
4	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:29
5	unresolved	8	0	0.0 KB	0	0	13:17:18
6	*.toya.net.pl	28	0	0.0 KB	0	0	00:00:01
TOTAL: 6		1013	588	1.1 MB	328	5	16:25:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.96	28.197	59.833	13:17:18
dmcs2.p.lodz.pl	0.052	0.084	01:38:45
pc-212-51-218-225.p.lodz.pl	0.032	0.032	01:27:57
web.man.lodz.pl	1.284	3.783	00:00:32
host227.elsnet.pl	0.014	0.034	00:00:29
pc-skrzep2.cm.p.lodz.pl	1.088	3.625	00:00:07
mon.man.lodz.pl	1.021	3.141	00:00:03
staticline41435.toya.net.pl	0.087	0.359	00:00:01
david.man.lodz.pl	0.272	1.149	00:00:01
isvcs01.man.lodz.pl	0.037	0.061	00:00:00
TOTAL: 10	32.084	72.101	16:25:16

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	28
185.48.195.96	8
host227.elsnet.pl	4
TOTAL: 5	424

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	2	0
dmcs2.p.lodz.pl	1	0
185.48.195.96	1	1
TOTAL: 3	4	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	59.2%
2	pl	240	40.8%
TOTAL: 2		588	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.misc.elektronika	94
3	pl.misc.kolej	82
4	pl.comp.pecet	50
5	pl.comp.os.ms-windows.winnt	14
TOTAL: 5		588

Daily Usenet report

May 18 04:15:02 -- May 19 04:15:02