Daily Usenet report: Aug 9 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Aug 9 08:55:56 newsfeed nnrpd[15073]: SERVER perl filter not defined
Aug 9 11:54:32 newsfeed nnrpd[20766]: SERVER perl filter not defined
Aug 9 15:04:24 newsfeed nnrpd[26761]: SERVER perl filter not defined
Aug 9 15:05:14 newsfeed nnrpd[26807]: SERVER perl filter not defined
Aug 9 15:05:28 newsfeed nnrpd[26808]: SERVER perl filter not defined
Aug 10 00:29:58 newsfeed nnrpd[12258]: SERVER perl filter not defined
Aug 10 01:41:42 newsfeed nnrpd[14502]: SERVER perl filter not defined
Aug 10 02:30:41 newsfeed nnrpd[16083]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2260918	98.6%	257.0 MB	98.2%
innd	18096	0.8%	3.0 MB	1.2%
innfeed	7163	0.3%	1.1 MB	0.4%
nnrpd	6911	0.3%	727.4 KB	0.3%
controlchan	4	0.0%	0.6 KB	0.0%
TOTAL: 5	2293092	100.0%	261.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2254857	95.9%
Negative hits	57915	2.5%
Positive hits	37516	1.6%
Cache misses	401	0.0%
TOTAL: 4	2350689	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.854	0.0%	347	0.000	19.752	338.000
article cleanup	00:00:27.607	0.0%	2259607	0.001	0.012	0.025
article logging	00:01:02.714	0.1%	2259832	0.005	0.028	0.869
article parse	01:06:16.364	4.6%	131849058	0.007	0.030	0.084
article write	00:21:06.634	1.5%	2253579	0.275	0.562	1.247
artlog/artcncl	00:00:00.007	0.0%	313	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:10.665	0.2%	146598782	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.612	0.0%	248	0.000	6.500	73.333
hishave/artcncl	00:00:01.083	0.0%	347	0.000	3.121	129.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:22:22.628	9.9%	2351304	0.417	3.633	24.645
history sync	00:00:00.010	0.0%	292	0.000	0.034	1.000
history write	03:42:23.040	15.4%	2255565	0.513	5.916	52.160
hiswrite/artcncl	00:00:00.300	0.0%	99	0.000	3.030	17.000
idle	10:15:30.428	42.8%	94021265	0.168	0.393	1.134
nntp read	00:42:56.730	3.0%	130052561	0.007	0.020	0.039
overview write	00:00:11.814	0.0%	2253579	0.000	0.005	0.014
perl filter	04:52:28.175	20.3%	2255565	3.936	7.780	10.926
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:26.966	0.2%	4544973	0.019	0.046	0.080
TOTAL: 23:59:36.767	23:30:33.631	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.135	0.0%	37827	0.000	0.004	0.154
article prepare	00:00:00.010	0.0%	35750	0.000	0.000	0.250
article read	00:00:00.716	0.0%	8692	0.000	0.082	1.000
backlog stats	00:00:00.195	0.0%	287675	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.664	0.0%	170731	0.000	0.010	0.065
data write	00:00:03.916	0.0%	141108	0.000	0.028	0.333
idle	23:59:23.939	100.0%	287675	0.000	300.214	11114.000
status file	00:00:01.874	0.0%	243	0.000	7.712	142.000
TOTAL: 23:59:38.071	23:59:32.449	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:06:15.004	99.4%	4153	0.000	957.140	241660.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.171	0.0%	10084	0.000	0.017	0.500
readart	00:00:00.952	0.0%	459	0.000	2.074	21.000
TOTAL: 01:06:38.009	01:06:16.127	99.5%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
usenet@film.rlss.okayama-u.ac.jp	0	0	1	0	1	1
TOTAL	0	0	1	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	674	2250142	2243668	2353	4121	99%	316:51:55
2	news.nask.pl	2	16192	5420	10121	651	33%	23:47:14
3	news.task.gda.pl	21	26796	4945	20625	1226	18%	45:18:08
4	news.icm.edu.pl	39	395	10	328	57	2%	21:22:20
5	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 5		745	2293533	2254051	33427	6055	98%	407:19:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	815.8 GB	306.3 MB	379.5 MB	816.4 GB	99%	380.9 KB
2	news.nask.pl	19.7 MB	1.7 MB	1.8 MB	23.3 MB	84%	3.9 KB
3	news.task.gda.pl	10.8 MB	21.9 KB	3.8 MB	14.6 MB	74%	2.4 KB
4	news.icm.edu.pl	619.4 KB	241.4 KB	3.0 MB	3.8 MB	15%	58.5 KB
5	localhost	12.5 KB	0.0 KB	0.0 KB	12.5 KB	100%	1.6 KB
TOTAL: 5		815.8 GB	308.3 MB	388.1 MB	816.5 GB	99%	378.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 9 04:15:03 - 04:59:59	54415	2.4%	20.18	19.8 GB	2.4%	7686.79
Aug 9 05:00:00 - 05:59:59	99948	4.4%	27.76	36.8 GB	4.5%	10722.48
Aug 9 06:00:00 - 06:59:59	90182	4.0%	25.05	34.5 GB	4.2%	10048.96
Aug 9 07:00:00 - 07:59:59	82001	3.6%	22.78	31.2 GB	3.8%	9084.14
Aug 9 08:00:00 - 08:59:59	82917	3.7%	23.03	30.7 GB	3.8%	8955.92
Aug 9 09:00:00 - 09:59:59	107879	4.8%	29.97	38.3 GB	4.7%	11153.13
Aug 9 10:00:00 - 10:59:59	99653	4.4%	27.68	31.2 GB	3.8%	9078.40
Aug 9 11:00:00 - 11:59:59	74480	3.3%	20.69	27.4 GB	3.4%	7988.26
Aug 9 12:00:00 - 12:59:59	97072	4.3%	26.96	34.4 GB	4.2%	10031.69
Aug 9 13:00:00 - 13:59:59	103920	4.6%	28.87	36.7 GB	4.5%	10686.13
Aug 9 14:00:00 - 14:59:59	97531	4.3%	27.09	35.9 GB	4.4%	10454.13
Aug 9 15:00:00 - 15:59:59	98715	4.4%	27.42	35.2 GB	4.3%	10240.21
Aug 9 16:00:00 - 16:59:59	87674	3.9%	24.35	31.1 GB	3.8%	9051.75
Aug 9 17:00:00 - 17:59:59	74853	3.3%	20.79	27.8 GB	3.4%	8097.90
Aug 9 18:00:00 - 18:59:59	101615	4.5%	28.23	37.8 GB	4.6%	11012.18
Aug 9 19:00:00 - 19:59:59	99977	4.4%	27.77	35.9 GB	4.4%	10461.68
Aug 9 20:00:00 - 20:59:59	77304	3.4%	21.47	28.0 GB	3.4%	8169.16
Aug 9 21:00:00 - 21:59:59	76634	3.4%	21.29	28.3 GB	3.5%	8235.10
Aug 9 22:00:00 - 22:59:59	104982	4.7%	29.16	37.6 GB	4.6%	10958.45
Aug 9 23:00:00 - 23:59:59	104613	4.6%	29.06	37.4 GB	4.6%	10882.19
Aug 10 00:00:00 - 00:59:59	88429	3.9%	24.56	32.1 GB	3.9%	9346.54
Aug 10 01:00:00 - 01:59:59	111707	5.0%	31.03	40.3 GB	4.9%	11750.35
Aug 10 02:00:00 - 02:59:59	107912	4.8%	29.98	39.7 GB	4.9%	11572.79
Aug 10 03:00:00 - 03:59:59	103427	4.6%	28.73	37.3 GB	4.6%	10869.11
Aug 10 04:00:00 - 04:15:03	26512	1.2%	29.36	9.8 GB	1.2%	11408.39
TOTAL: 24:00:00	2254352	100.0%	26.09	815.3 GB	100.0%	9894.62

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4289	203	3401	0	685
2	news.task.gda.pl	1234	1047	11	1	175
3	news.nask.pl	705	20	626	1	58
4	news.icm.edu.pl	25	0	4	0	21
TOTAL: 4		6253	1270	4042	2	939

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	117
uk.railway	87
mailing.freebsd.svn-src-all	68
fa.freebsd.cvs-all	68
mailing.database.pgsql-general	60
mailing.freebsd.svn-ports-all	51
mailing.freebsd.svn-ports-head	51
mozilla.dev.tree-management	48
mailing.freebsd.svn-src-head	44
mailing.openbsd.misc	35
uk.rec.cycling.moderated	33
mailing.openbsd.ports-changes	32
mailing.gcc.gcc-cvs	30
gmane.linux.alsa.devel	25
mailing.freebsd.current	25
comp.mobile.android	24
fr.usenet.abus.nocem	23
mozilla.dev.platform	22
hr.alt.cellular.gsm.android	22
alt.windows7.general	22
TOTAL: 78	1270

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

Groups	Count
bit.listserv.christia	1
bit.listserv.catholic	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	454
EMP (fsl)	207
HTML post	28
EMP (md5)	21
Attached HTML file	3
Scoring filter (8)	1
TOTAL: 6	714

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	39753	3322	33627	865	0	0	8%	23:59:48
2	icm	55386	603	30135	3862	1	1	1%	23:56:39
3	poznan	12511	107	10194	59	0	0	0%	23:59:21
4	task	1911	81	972	7	0	11	4%	22:27:25
5	nask	19393	17	12890	1	0	0	0%	24:06:13
6	okapi	0	0	0	0	0	2541	0%	23:50:00
TOTAL: 6		128954	4130	87818	4794	1	2553	3%	142:19:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	17.2 MB	699.2 MB	716.3 MB	8.5 KB/s	164.3 KB	23:56:39
2	panorama	9.6 MB	3.4 MB	13.0 MB	0.2 KB/s	3.2 KB	23:59:48
3	poznan	357.2 KB	93.6 KB	450.7 KB	0.0 KB/s	2.7 KB	23:59:21
4	task	156.0 KB	13.5 KB	169.5 KB	0.0 KB/s	1.9 KB	22:27:25
5	nask	50.6 KB	1.5 KB	52.1 KB	0.0 KB/s	2.9 KB	24:06:13
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 6		27.4 MB	702.6 MB	730.0 MB	1.5 KB/s	83.8 KB	142:19:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	374	599.3 KB	288	0	00:00:09
2	web.man.lodz.pl	287	48	43.8 KB	21	0	00:00:23
3	lze200.lze.lodz.pl	4	22	42.8 KB	5	0	00:40:16
4	212.191.196.1	2	15	30.3 KB	4	0	00:24:09
5	kujawiak.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 5		589	459	716.2 KB	318	8	01:06:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	374	599.3 KB	288	0	00:00:09
2	*.man.lodz.pl	679	48	43.8 KB	21	8	00:00:35
3	*.lze.lodz.pl	4	22	42.8 KB	5	0	00:40:16
4	unresolved	2	15	30.3 KB	4	0	00:24:09
5	*.adsl.inetia.pl	1	0	0.0 KB	0	0	00:00:01
6	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:01:25
TOTAL: 6		975	459	716.2 KB	318	8	01:06:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.008	0.084	00:40:16
212.191.196.1	0.008	0.036	00:24:09
eci108.neoplus.adsl.tpnet.pl	0.004	0.012	00:01:25
web.man.lodz.pl	0.556	3.672	00:00:23
vegas.cm.p.lodz.pl	0.552	3.896	00:00:09
f-oper-v100.man.lodz.pl	0.560	3.896	00:00:08
178-36-245-249.adsl.inetia.pl	0.000	0.008	00:00:01
david.man.lodz.pl	0.180	1.252	00:00:01
kujawiak.man.lodz.pl	0.032	0.132	00:00:01
TOTAL: 9	1.900	12.988	01:06:37

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
178-36-245-249.adsl.inetia.pl	1
eci108.neoplus.adsl.tpnet.pl	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	374	81.5%
2	pl	82	17.9%
3	alt	3	0.7%
TOTAL: 3		459	100.0%

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

Newsgroup		Count
1	lodman.info	374
2	pl.comp.pecet	45
3	pl.regionalne.lodz	12
4	pl.rec.gory	8
5	pl.comp.os.ms-windows.winnt	6
6	pl.rec.kuchnia	4
7	pl.sci.filozofia	4
8	pl.comp.bazy-danych	3
9	alt.pl.matematyka	3
TOTAL: 9		459

Daily Usenet report

Aug 9 04:15:03 -- Aug 10 04:15:03