Daily Usenet report: Aug 24 04:15:04

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 24 10:00:05 newsfeed nnrpd[31201]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	977051	96.9%	109.0 MB	96.2%
innd	17873	1.8%	2.7 MB	2.4%
nnrpd	7026	0.7%	740.7 KB	0.6%
innfeed	6335	0.6%	936.1 KB	0.8%
TOTAL: 4	1008285	100.0%	113.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	973108	92.4%
Negative hits	63159	6.0%
Positive hits	17145	1.6%
Cache misses	121	0.0%
TOTAL: 4	1053533	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.955	0.0%	271	0.000	29.354	570.000
article cleanup	00:00:17.256	0.0%	976938	0.002	0.018	0.044
article logging	00:00:29.514	0.0%	977019	0.005	0.030	0.059
article parse	00:43:29.320	3.0%	128036018	0.011	0.020	0.050
article write	00:10:59.598	0.8%	965672	0.353	0.683	2.119
artlog/artcncl	00:00:00.003	0.0%	270	0.000	0.011	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:01:53.949	0.1%	134932011	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.124	0.0%	256	0.000	4.391	155.000
hishave/artcncl	00:00:00.260	0.0%	271	0.000	0.959	69.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:48:44.626	3.4%	1054442	0.009	2.774	8.195
history sync	00:00:00.014	0.0%	291	0.000	0.048	1.000
history write	01:33:17.200	6.5%	974064	0.072	5.746	27.901
hiswrite/artcncl	00:00:00.064	0.0%	15	0.000	4.267	16.000
idle	16:46:20.329	69.9%	100168511	0.332	0.603	2.023
nntp read	00:51:28.714	3.6%	127277642	0.015	0.024	0.041
overview write	00:00:06.141	0.0%	965672	0.000	0.006	0.021
perl filter	02:20:34.070	9.8%	974059	5.331	8.659	11.828
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:14.355	0.2%	1963110	0.017	0.068	0.105
TOTAL: 24:00:13.863	23:20:04.492	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.104	0.0%	31795	0.000	0.003	0.250
article prepare	00:00:00.002	0.0%	13868	0.000	0.000	0.125
article read	00:00:00.255	0.0%	3424	0.000	0.074	1.000
backlog stats	00:00:00.136	0.0%	203400	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.454	0.0%	128707	0.000	0.011	1.000
data write	00:00:03.904	0.0%	100524	0.000	0.039	4.000
idle	24:00:13.275	100.0%	203400	0.000	424.844	19162.000
status file	00:00:00.142	0.0%	168	0.000	0.845	4.500
TOTAL: 24:00:23.407	24:00:19.272	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:37:17.078	99.2%	5192	0.000	430.870	600074.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.240	0.0%	12109	0.000	0.020	0.737
readart	00:00:00.269	0.0%	373	0.000	0.721	84.000
TOTAL: 00:37:34.612	00:37:17.587	99.2%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	405	972356	961417	680	10259	98%	197:15:12
2	news.nask.pl	3	17655	3346	13448	861	18%	24:06:29
3	news.icm.edu.pl	45	336	27	176	133	8%	17:29:48
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		455	990348	964791	14304	11253	97%	238:51:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	355.6 GB	16.0 MB	1.0 GB	356.6 GB	99%	384.8 KB
2	news.nask.pl	19.6 MB	2.3 MB	2.7 MB	24.6 MB	79%	6.0 KB
3	news.icm.edu.pl	825.8 KB	37.0 KB	10.1 MB	10.9 MB	7%	69.9 KB
4	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 4		355.6 GB	18.4 MB	1.0 GB	356.7 GB	99%	383.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 24 04:15:04 - 04:59:59	34741	3.6%	12.89	14.4 GB	4.1%	5620.10
Aug 24 05:00:00 - 05:59:59	25565	2.6%	7.10	12.1 GB	3.4%	3517.83
Aug 24 06:00:00 - 06:59:59	22506	2.3%	6.25	10.9 GB	3.1%	3188.69
Aug 24 07:00:00 - 07:59:59	22067	2.3%	6.13	10.8 GB	3.0%	3135.23
Aug 24 08:00:00 - 08:59:59	27005	2.8%	7.50	12.1 GB	3.4%	3513.04
Aug 24 09:00:00 - 09:59:59	29427	3.0%	8.17	12.2 GB	3.4%	3541.38
Aug 24 10:00:00 - 10:59:59	33846	3.5%	9.40	12.1 GB	3.4%	3535.01
Aug 24 11:00:00 - 11:59:59	38290	4.0%	10.64	12.9 GB	3.6%	3764.49
Aug 24 12:00:00 - 12:59:59	37264	3.9%	10.35	12.6 GB	3.6%	3683.75
Aug 24 13:00:00 - 13:59:59	36735	3.8%	10.20	12.9 GB	3.6%	3770.86
Aug 24 14:00:00 - 14:59:59	34769	3.6%	9.66	12.6 GB	3.5%	3658.44
Aug 24 15:00:00 - 15:59:59	34893	3.6%	9.69	12.5 GB	3.5%	3640.51
Aug 24 16:00:00 - 16:59:59	49789	5.2%	13.83	12.5 GB	3.5%	3635.66
Aug 24 17:00:00 - 17:59:59	54075	5.6%	15.02	16.0 GB	4.5%	4649.17
Aug 24 18:00:00 - 18:59:59	36117	3.7%	10.03	13.0 GB	3.7%	3784.91
Aug 24 19:00:00 - 19:59:59	40281	4.2%	11.19	14.1 GB	4.0%	4099.78
Aug 24 20:00:00 - 20:59:59	41645	4.3%	11.57	14.2 GB	4.0%	4124.26
Aug 24 21:00:00 - 21:59:59	38313	4.0%	10.64	13.3 GB	3.7%	3865.04
Aug 24 22:00:00 - 22:59:59	37299	3.9%	10.36	13.3 GB	3.7%	3869.18
Aug 24 23:00:00 - 23:59:59	37468	3.9%	10.41	13.8 GB	3.9%	4014.28
Aug 25 00:00:00 - 00:59:59	57813	6.0%	16.06	21.7 GB	6.1%	6320.39
Aug 25 01:00:00 - 01:59:59	56435	5.8%	15.68	22.8 GB	6.4%	6633.26
Aug 25 02:00:00 - 02:59:59	64043	6.6%	17.79	24.2 GB	6.8%	7052.37
Aug 25 03:00:00 - 03:59:59	59238	6.1%	16.45	22.8 GB	6.4%	6633.15
Aug 25 04:00:00 - 04:15:02	15804	1.6%	17.52	6.0 GB	1.7%	6971.21
TOTAL: 23:59:58	965428	100.0%	11.17	355.6 GB	100.0%	4316.19

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	10334	272	2070	0	7992
2	news.nask.pl	886	61	809	4	12
3	news.icm.edu.pl	133	0	1	0	132
TOTAL: 3		11353	333	2880	4	8136

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	159
alt.sex.telephones	48
it.comp.os.win.windows8	31
comp.mobile.ipad	20
it.tlc.cellulari.android	14
comp.mobile.android	13
de.comp.office-pakete.ms-office.excel	8
nl.comp.sys.android	8
de.comm.provider.telefonie	8
hr.ponuda.hardver.konfiguracije	5
comp.sys.raspberry-pi	4
hr.alt.cellular.gsm.android	4
de.comp.lang.php	3
nl.comp.os.linux.techniek	3
hr.ponuda.hardver.komponente	2
de.rec.sf.startrek	1
it.comp.os.linux.ubuntu	1
it.comp.os.win.windows7	1
TOTAL: 18	333

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

Groups	Count
pl.sci.historia	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	7915
EMP (md5)	134
Excessively crossposted test article	1
TOTAL: 3	8050

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	32657	1349	30241	197	0	4%	24:00:00
2	nask	26468	714	15225	233	1	2%	24:01:12
3	icm	31909	353	25888	609	17	1%	23:56:06
4	poznan	4339	9	3142	6	0	0%	24:02:11
5	okapi	0	0	0	0	1695	0%	24:00:02
TOTAL: 5		95373	2425	74496	1045	1713	2%	119:59:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	72.7 MB	59.7 MB	132.4 MB	1.6 KB/s	143.1 KB	24:01:12
2	icm	25.4 MB	55.2 MB	80.6 MB	1.0 KB/s	85.8 KB	23:56:06
3	panorama	6.9 MB	939.8 KB	7.8 MB	0.1 KB/s	5.2 KB	24:00:00
4	poznan	35.7 KB	18.3 KB	54.0 KB	0.0 KB/s	3.6 KB	24:02:11
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		105.0 MB	115.9 MB	220.8 MB	0.5 KB/s	65.2 KB	119:59:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	300	398.0 KB	288	0	00:00:06
2	web.man.lodz.pl	288	70	73.0 KB	32	0	00:00:32
3	194.33.77.50	2	3	3.8 KB	1	0	00:04:13
4	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 4		579	373	474.8 KB	321	1	00:37:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	398.0 KB	288	0	00:00:06
2	*.man.lodz.pl	673	70	73.0 KB	32	1	00:00:37
3	unresolved	2	3	3.8 KB	1	0	00:04:13
4	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	00:02:29
5	*.centertel.pl	1	0	0.0 KB	0	0	00:30:00
6	*.toya.net.pl	52	0	0.0 KB	0	0	00:00:06
TOTAL: 6		1018	373	474.8 KB	321	1	00:37:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
public-gprs514595.centertel.pl	0.034	0.018	00:30:00
194.33.77.50	0.010	0.030	00:04:13
42.191.14.62.static.jazztel.es	0.054	0.100	00:02:29
web.man.lodz.pl	1.337	4.279	00:00:32
pc-skrzep2.cm.p.lodz.pl	1.357	4.135	00:00:06
staticline41435.toya.net.pl	0.256	0.796	00:00:06
mon.man.lodz.pl	1.338	4.088	00:00:03
david.man.lodz.pl	0.452	1.368	00:00:01
isvcs01.man.lodz.pl	0.008	0.016	00:00:00
TOTAL: 9	4.846	14.830	00:37:34

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	52
42.191.14.62.static.jazztel.es	2
public-gprs514595.centertel.pl	1
TOTAL: 5	439

NNRP client timeouts [Top 20]:

System	Conn	Peer
public-gprs514595.centertel.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	303	81.2%
2	pl	70	18.8%
TOTAL: 2		373	100.0%

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

Newsgroup		Count
1	lodman.info	303
2	pl.comp.pecet	52
3	pl.comp.os.ms-windows.winnt	18
TOTAL: 3		373

Daily Usenet report

Aug 24 04:15:04 -- Aug 25 04:15:02