Daily Usenet report: May 30 04:15:04

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 30 11:50:41 newsfeed nnrpd[5837]: SERVER perl filter not defined
May 30 15:44:14 newsfeed nnrpd[13616]: SERVER perl filter not defined
May 30 15:49:52 newsfeed nnrpd[13859]: SERVER perl filter not defined
May 30 19:32:33 newsfeed nnrpd[21302]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1615090	98.1%	182.0 MB	97.6%
innd	15757	1.0%	2.6 MB	1.4%
nnrpd	8913	0.5%	980.7 KB	0.5%
innfeed	6749	0.4%	1.0 MB	0.5%
TOTAL: 4	1646509	100.0%	186.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1609636	93.5%
Negative hits	63886	3.7%
Positive hits	40075	2.3%
Cache misses	7893	0.5%
TOTAL: 4	1721490	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.004	0.0%	361	0.000	36.022	1372.000
article cleanup	00:00:25.792	0.0%	1614561	0.003	0.016	0.034
article logging	00:00:50.546	0.1%	1614646	0.010	0.031	0.488
article parse	01:04:57.579	4.5%	163690328	0.007	0.024	0.091
article write	00:19:36.693	1.4%	1608183	0.403	0.732	2.788
artlog/artcncl	00:00:00.401	0.0%	359	0.000	1.117	393.000
artlog/artparse	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:04:51.991	0.3%	174580802	0.000	0.002	0.081
hisgrep/artcncl	00:00:02.142	0.0%	303	0.000	7.069	166.000
hishave/artcncl	00:00:00.966	0.0%	361	0.000	2.676	69.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:27:10.880	14.4%	1722288	0.011	7.218	13.227
history sync	00:00:00.016	0.0%	291	0.000	0.055	1.000
history write	04:36:09.067	19.2%	1610330	0.059	10.289	22.940
hiswrite/artcncl	00:00:00.679	0.0%	58	0.000	11.707	252.000
idle	08:44:22.646	36.4%	115276595	0.140	0.273	9.310
nntp read	00:59:56.120	4.2%	162482265	0.009	0.022	0.104
overview write	00:00:09.718	0.0%	1608183	0.000	0.006	0.020
perl filter	03:54:17.420	16.3%	1610322	6.048	8.730	12.031
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:15.630	0.2%	3277268	0.017	0.060	0.114
TOTAL: 23:59:44.096	23:16:21.290	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.213	0.0%	61149	0.000	0.003	0.143
article prepare	00:00:00.021	0.0%	59278	0.000	0.000	0.250
article read	00:00:21.569	0.0%	14647	0.000	1.473	118.667
backlog stats	00:00:00.378	0.0%	445250	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.459	0.0%	221464	0.000	0.011	0.065
data write	00:00:22.018	0.0%	249576	0.000	0.088	1.837
idle	23:58:43.277	99.9%	445250	0.000	193.876	9045.000
status file	00:00:00.935	0.0%	212	0.000	4.410	277.000
TOTAL: 23:59:40.214	23:59:30.870	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:40:15.825	99.9%	5874	0.000	10217.199	207432.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:11.952	0.0%	24802	0.000	0.482	48.167
readart	00:00:00.485	0.0%	395	0.000	1.228	20.000
TOTAL: 16:41:12.699	16:40:28.262	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	953	1610257	1597754	8888	3615	99%	320:38:15
2	news.task.gda.pl	13	28626	5438	21788	1400	18%	45:14:52
3	news.nask.pl	2	18557	4739	12476	1342	25%	24:08:19
4	localhost	5	4	4	0	0	100%	00:00:00
5	news.icm.edu.pl	42	237	2	219	16	0%	16:45:01
TOTAL: 5		1015	1657681	1607937	43371	6373	96%	406:46:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	559.3 GB	14.3 MB	64.6 MB	559.4 GB	99%	366.3 KB
2	news.nask.pl	21.3 MB	4.4 MB	4.6 MB	30.2 MB	70%	5.1 KB
3	news.task.gda.pl	11.9 MB	28.4 KB	4.5 MB	16.4 MB	72%	2.5 KB
4	news.icm.edu.pl	55.2 KB	18.9 KB	361.4 KB	435.5 KB	12%	24.2 KB
5	localhost	8.7 KB	0.0 KB	0.0 KB	8.7 KB	100%	2.2 KB
TOTAL: 5		559.3 GB	18.8 MB	74.0 MB	559.4 GB	99%	363.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 30 04:15:04 - 04:59:59	55694	3.5%	20.66	21.3 GB	3.8%	8296.90
May 30 05:00:00 - 05:59:59	78405	4.9%	21.78	28.8 GB	5.1%	8388.52
May 30 06:00:00 - 06:59:59	55529	3.5%	15.42	21.4 GB	3.8%	6234.67
May 30 07:00:00 - 07:59:59	42094	2.6%	11.69	16.2 GB	2.9%	4706.98
May 30 08:00:00 - 08:59:59	54950	3.4%	15.26	20.5 GB	3.7%	5965.68
May 30 09:00:00 - 09:59:59	60551	3.8%	16.82	21.5 GB	3.8%	6256.84
May 30 10:00:00 - 10:59:59	71773	4.5%	19.94	25.1 GB	4.5%	7304.38
May 30 11:00:00 - 11:59:59	59373	3.7%	16.49	21.3 GB	3.8%	6202.45
May 30 12:00:00 - 12:59:59	83163	5.2%	23.10	28.2 GB	5.0%	8207.66
May 30 13:00:00 - 13:59:59	92640	5.8%	25.73	30.7 GB	5.5%	8933.90
May 30 14:00:00 - 14:59:59	98660	6.1%	27.41	32.0 GB	5.7%	9330.85
May 30 15:00:00 - 15:59:59	92316	5.7%	25.64	28.8 GB	5.1%	8376.06
May 30 16:00:00 - 16:59:59	56038	3.5%	15.57	17.4 GB	3.1%	5077.28
May 30 17:00:00 - 17:59:59	61977	3.9%	17.22	19.6 GB	3.5%	5719.35
May 30 18:00:00 - 18:59:59	49641	3.1%	13.79	16.7 GB	3.0%	4872.51
May 30 19:00:00 - 19:59:59	50078	3.1%	13.91	17.4 GB	3.1%	5071.55
May 30 20:00:00 - 20:59:59	69350	4.3%	19.26	23.4 GB	4.2%	6817.18
May 30 21:00:00 - 21:59:59	53259	3.3%	14.79	18.1 GB	3.2%	5259.66
May 30 22:00:00 - 22:59:59	45252	2.8%	12.57	16.0 GB	2.9%	4660.21
May 30 23:00:00 - 23:59:59	42405	2.6%	11.78	15.5 GB	2.8%	4503.68
May 31 00:00:00 - 00:59:59	70559	4.4%	19.60	24.3 GB	4.3%	7065.37
May 31 01:00:00 - 01:59:59	79793	5.0%	22.16	28.3 GB	5.1%	8244.39
May 31 02:00:00 - 02:59:59	87551	5.4%	24.32	31.1 GB	5.6%	9058.91
May 31 03:00:00 - 03:59:59	83252	5.2%	23.13	30.7 GB	5.5%	8948.67
May 31 04:00:00 - 04:15:02	13956	0.9%	15.47	5.2 GB	0.9%	6065.47
TOTAL: 23:59:58	1608259	100.0%	18.61	559.4 GB	100.0%	6789.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3693	246	2910	3	534
2	news.task.gda.pl	1405	1261	19	1	124
3	news.nask.pl	1358	19	1310	6	23
4	news.icm.edu.pl	16	0	2	0	14
TOTAL: 4		6472	1526	4241	10	695

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	162
mailing.freebsd.svn-ports-head	160
mailing.freebsd.ports-bugs	96
uk.railway	67
mailing.freebsd.svn-src-all	60
fa.freebsd.cvs-all	59
alt.windows7.general	58
fr.usenet.abus.nocem	54
comp.mobile.ipad	53
mailing.freebsd.svn-src-head	53
mozilla.dev.tree-management	50
mailing.unix.samba	47
it.tlc.cellulari.android	44
mailing.openbsd.source-changes	40
mozilla.dev.platform	35
mailing.database.pgsql-general	32
mailing.gcc.gcc-cvs	30
mailing.postfix.users	29
gmane.linux.alsa.devel	25
comp.sys.raspberry-pi	25
TOTAL: 73	1526

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

Groups	Count
pl.sci.matematyka	7
fr.comp.lang.php	1
pl.sci.historia	1
bit.listserv.catholic	1
TOTAL: 4	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	437
EMP (fsl)	124
EMP (md5)	30
HTML post	6
Attached HTML file	2
Scoring filter (9)	1
Scoring filter (11)	1
TOTAL: 7	601

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	63623	1567	59099	328	0	0	2%	24:03:50
2	icm	60826	1066	33141	11509	5	234	1%	24:00:00
3	task	2061	75	1046	15	0	0	3%	21:59:09
4	poznan	11721	45	9967	9	0	0	0%	24:06:11
5	nask	22446	25	15227	214	0	0	0%	23:59:00
6	okapi	0	0	0	0	0	2754	0%	24:00:01
TOTAL: 6		160677	2778	118480	12075	5	2988	1%	142:08:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	194.9 MB	5.7 GB	5.9 GB	71.8 KB/s	493.0 KB	24:00:00
2	panorama	6.9 MB	2.4 MB	9.3 MB	0.1 KB/s	5.0 KB	24:03:50
3	task	188.5 KB	27.1 KB	215.6 KB	0.0 KB/s	2.4 KB	21:59:09
4	poznan	121.9 KB	21.6 KB	143.5 KB	0.0 KB/s	2.7 KB	24:06:11
5	nask	102.3 KB	81.8 MB	81.9 MB	1.0 KB/s	350.9 KB	23:59:00
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		202.2 MB	5.8 GB	6.0 GB	12.3 KB/s	423.7 KB	142:08:11

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	336	700.5 KB	288	0	00:00:11
2	web.man.lodz.pl	288	48	41.8 KB	19	0	00:00:28
3	proxy.filmschool.lodz.pl	1	6	14.4 KB	1	0	00:01:28
4	212.191.5.118	2	5	8.8 KB	1	0	00:08:42
5	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 5		583	395	765.5 KB	309	4	16:41:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	700.5 KB	288	0	00:00:11
2	*.man.lodz.pl	676	48	41.8 KB	19	4	00:00:37
3	*.filmschool.lodz.pl	1	6	14.4 KB	1	0	00:01:28
4	unresolved	5	5	8.8 KB	1	0	00:20:49
5	*.pa.nsw.optusnet.com.au	378	0	0.0 KB	0	0	00:09:46
6	*.wroclaw.mm.pl	100	0	0.0 KB	0	0	16:08:15
7	*.toya.net.pl	19	0	0.0 KB	0	0	00:00:02
TOTAL: 7		1467	395	765.5 KB	309	4	16:41:11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.248	1.224	16:08:15
91.90.78.15	0.036	0.020	00:10:27
pa49-181-247-86.pa.nsw.optusnet.com.au	0.964	4.872	00:09:46
212.191.5.118	0.064	0.052	00:08:42
194.33.77.50	0.012	0.020	00:01:40
proxy.filmschool.lodz.pl	0.004	0.016	00:01:28
web.man.lodz.pl	0.656	4.164	00:00:28
pc-skrzep2.cm.p.lodz.pl	0.652	4.136	00:00:11
mon.man.lodz.pl	0.652	3.752	00:00:06
staticline41435.toya.net.pl	0.048	0.272	00:00:02
isvcs01.man.lodz.pl	0.020	0.060	00:00:01
david.man.lodz.pl	0.192	1.224	00:00:01
TOTAL: 12	3.548	19.812	16:41:11

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-247-86.pa.nsw.optusnet.com.au	378
mon.man.lodz.pl	288
host-89-231-50-15.wroclaw.mm.pl	100
david.man.lodz.pl	96
staticline41435.toya.net.pl	19
194.33.77.50	2
91.90.78.15	1
TOTAL: 7	884

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	336	85.1%
2	pl	59	14.9%
TOTAL: 2		395	100.0%

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

Newsgroup		Count
1	lodman.info	336
2	pl.comp.pecet	42
3	pl.rec.foto.cyfrowa	6
4	pl.comp.os.ms-windows.winnt	6
5	pl.rec.rowery	5
TOTAL: 5		395

Daily Usenet report

May 30 04:15:04 -- May 31 04:15:02