Daily Usenet report: Jun 5 04:15:04

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jun 5 04:15:04 newsfeed nnrpd[15162]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 04:15:04 newsfeed nnrpd[15166]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 04:15:04 newsfeed nnrpd[15167]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 04:15:04 newsfeed nnrpd[15168]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 04:15:04 newsfeed nnrpd[15170]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 14:12:44 newsfeed nnrpd[3552]: SERVER perl filter not defined
Jun 5 15:13:52 newsfeed nnrpd[5480]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2580536	98.1%	296.7 MB	97.7%
innd	32199	1.2%	4.8 MB	1.6%
nnrpd	9593	0.4%	1015.7 KB	0.3%
innfeed	7727	0.3%	1.2 MB	0.4%
TOTAL: 4	2630055	100.0%	303.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2566302	94.3%
Negative hits	102655	3.8%
Positive hits	52995	1.9%
Cache misses	327	0.0%
TOTAL: 4	2722279	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.257	0.0%	309	0.000	42.903	650.000
article cleanup	00:00:34.076	0.0%	2579834	0.003	0.013	0.128
article logging	00:01:37.299	0.1%	2580067	0.013	0.038	1.010
article parse	01:04:50.054	4.5%	63098022	0.021	0.062	0.181
article write	00:26:13.143	1.8%	2552237	0.211	0.616	3.801
artlog/artcncl	00:00:00.007	0.0%	296	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:21.868	0.2%	76015875	0.001	0.003	0.030
hisgrep/artcncl	00:00:03.034	0.0%	253	0.000	11.992	268.000
hishave/artcncl	00:00:01.169	0.0%	309	0.000	3.783	266.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:24:03.888	14.2%	2723599	0.011	4.495	18.300
history sync	00:00:00.022	0.0%	291	0.000	0.076	12.000
history write	05:03:50.380	21.1%	2567631	0.233	7.100	23.836
hiswrite/artcncl	00:00:00.774	0.0%	56	0.000	13.821	206.000
idle	06:47:08.208	28.3%	30646623	0.156	0.797	3.316
nntp read	00:38:12.156	2.7%	61060435	0.024	0.038	0.104
overview write	00:00:18.327	0.0%	2552237	0.000	0.007	0.196
perl filter	05:58:44.330	24.9%	2567623	4.705	8.383	19.992
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:39.940	0.5%	5172279	0.013	0.089	0.971
TOTAL: 23:59:57.727	23:36:51.932	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.334	0.0%	68758	0.000	0.005	0.433
article prepare	00:00:00.034	0.0%	110792	0.000	0.000	0.042
article read	00:00:16.749	0.0%	27270	0.000	0.614	25.583
backlog stats	00:00:00.732	0.0%	473345	0.000	0.002	0.461
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.363	0.0%	285785	0.000	0.015	1.421
data write	00:00:13.871	0.0%	236924	0.000	0.059	2.745
idle	23:58:29.556	99.9%	473345	0.000	182.340	8539.000
status file	00:00:04.734	0.0%	233	0.500	20.318	564.000
TOTAL: 23:59:26.718	23:59:10.373	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	44:21:17.622	100.0%	32570	0.000	4902.598	600056.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.754	0.0%	67527	0.000	0.041	4.500
readart	00:00:03.071	0.0%	530	0.000	5.794	152.000
TOTAL: 44:22:05.522	44:21:23.447	100.0%	-	-	-	-

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	531	2561252	2535374	1855	24023	98%	436:51:57
2	news.nask.pl	3	25338	11071	12066	2201	43%	23:56:40
3	news.task.gda.pl	12	33744	5782	26607	1355	17%	45:19:56
4	news.icm.edu.pl	34	320	3	295	22	0%	18:06:11
5	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 5		583	2620656	2552232	40823	27601	97%	524:14:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	680.7 GB	796.2 MB	2.9 GB	684.4 GB	99%	280.4 KB
2	news.nask.pl	292.0 MB	63.7 MB	63.8 MB	419.5 MB	69%	32.4 KB
3	news.task.gda.pl	13.0 MB	89.5 KB	4.3 MB	17.4 MB	75%	2.5 KB
4	news.icm.edu.pl	33.0 KB	34.2 KB	225.8 KB	292.9 KB	11%	11.7 KB
5	localhost	3.4 KB	0.0 KB	0.0 KB	3.4 KB	100%	1.7 KB
TOTAL: 5		681.0 GB	860.1 MB	3.0 GB	684.8 GB	99%	278.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 5 04:15:04 - 04:59:59	62920	2.5%	23.34	18.0 GB	2.6%	7010.65
Jun 5 05:00:00 - 05:59:59	113745	4.5%	31.60	34.3 GB	5.0%	10001.79
Jun 5 06:00:00 - 06:59:59	112037	4.4%	31.12	33.9 GB	5.0%	9863.53
Jun 5 07:00:00 - 07:59:59	121910	4.8%	33.86	38.2 GB	5.6%	11114.68
Jun 5 08:00:00 - 08:59:59	131320	5.1%	36.48	40.1 GB	5.9%	11676.46
Jun 5 09:00:00 - 09:59:59	133667	5.2%	37.13	40.6 GB	6.0%	11833.52
Jun 5 10:00:00 - 10:59:59	123477	4.8%	34.30	38.0 GB	5.6%	11056.60
Jun 5 11:00:00 - 11:59:59	119599	4.7%	33.22	33.6 GB	4.9%	9799.29
Jun 5 12:00:00 - 12:59:59	123953	4.9%	34.43	34.9 GB	5.1%	10176.30
Jun 5 13:00:00 - 13:59:59	121443	4.8%	33.73	30.3 GB	4.5%	8830.26
Jun 5 14:00:00 - 14:59:59	129458	5.1%	35.96	31.0 GB	4.5%	9021.48
Jun 5 15:00:00 - 15:59:59	82025	3.2%	22.78	17.7 GB	2.6%	5161.62
Jun 5 16:00:00 - 16:59:59	63053	2.5%	17.51	15.3 GB	2.2%	4444.81
Jun 5 17:00:00 - 17:59:59	79134	3.1%	21.98	18.5 GB	2.7%	5375.54
Jun 5 18:00:00 - 18:59:59	90832	3.6%	25.23	21.9 GB	3.2%	6373.49
Jun 5 19:00:00 - 19:59:59	86006	3.4%	23.89	19.6 GB	2.9%	5716.02
Jun 5 20:00:00 - 20:59:59	65282	2.6%	18.13	14.5 GB	2.1%	4237.20
Jun 5 21:00:00 - 21:59:59	67121	2.6%	18.64	10.8 GB	1.6%	3139.95
Jun 5 22:00:00 - 22:59:59	72274	2.8%	20.08	14.3 GB	2.1%	4154.58
Jun 5 23:00:00 - 23:59:59	77570	3.0%	21.55	19.7 GB	2.9%	5744.26
Jun 6 00:00:00 - 00:59:59	120460	4.7%	33.46	31.2 GB	4.6%	9101.05
Jun 6 01:00:00 - 01:59:59	137211	5.4%	38.11	37.3 GB	5.5%	10852.78
Jun 6 02:00:00 - 02:59:59	147003	5.8%	40.83	39.9 GB	5.9%	11628.37
Jun 6 03:00:00 - 03:59:59	137586	5.4%	38.22	37.4 GB	5.5%	10885.86
Jun 6 04:00:00 - 04:15:05	33309	1.3%	36.81	10.0 GB	1.5%	11590.21
TOTAL: 24:00:01	2552395	100.0%	29.54	681.0 GB	100.0%	8264.63

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	24175	353	9991	9	13822
2	news.nask.pl	2286	26	2167	2	91
3	news.task.gda.pl	1361	1104	45	1	211
4	news.icm.edu.pl	23	0	2	0	21
TOTAL: 4		27845	1483	12205	12	14145

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	110
mailing.freebsd.svn-ports-all	100
mailing.freebsd.svn-ports-head	95
alt.politics.scorched-earth	79
mailing.freebsd.ports-bugs	68
mailing.freebsd.svn-src-all	56
fa.freebsd.cvs-all	56
gmane.linux.alsa.devel	52
uk.rec.cycling.moderated	47
it.tlc.cellulari.android	43
mozilla.dev.tree-management	40
mailing.openbsd.source-changes	39
mailing.gcc.gcc-cvs	35
mailing.freebsd.svn-src-head	35
mailing.www.horde-bugs	30
alt.windows7.general	29
mailing.freebsd.current	28
mailing.database.pgsql-general	27
mailing.unix.samba	26
mailing.openbsd.misc	26
TOTAL: 92	1483

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

Groups	Count
pl.sci.matematyka	10
christnet.poetry	1
bit.listserv.catholic	1
TOTAL: 3	12

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	13565
EMP (fsl)	159
HTML post	92
Attached HTML file	41
EMP (md5)	40
Scoring filter (8)	7
TOTAL: 6	13904

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	72475	4648	61957	1241	0	6%	24:02:26
2	icm	77881	3842	41777	4132	962	4%	24:00:24
3	nask	37937	123	17030	13544	0	0%	24:00:03
4	poznan	17873	78	14826	47	0	0%	23:58:35
5	task	2578	16	1358	3	0	0%	21:27:50
6	okapi	0	0	0	0	3434	0%	24:00:01
TOTAL: 6		208744	8707	136948	18967	4396	4%	141:29:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	390.9 MB	574.1 MB	965.1 MB	11.4 KB/s	123.9 KB	24:00:24
2	panorama	53.5 MB	11.0 MB	64.5 MB	0.8 KB/s	11.2 KB	24:02:26
3	nask	24.6 MB	1.9 GB	1.9 GB	23.4 KB/s	148.2 KB	24:00:03
4	poznan	1.2 MB	1.2 MB	2.4 MB	0.0 KB/s	19.3 KB	23:58:35
5	task	27.0 KB	4.4 KB	31.3 KB	0.0 KB/s	1.6 KB	21:27:50
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		470.2 MB	2.5 GB	2.9 GB	6.1 KB/s	111.4 KB	141:29:19

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	312	726.7 KB	288	0	0	00:00:11
2	web.man.lodz.pl	292	96	95.7 KB	39	0	0	00:00:21
3	212.191.78.190	224	83	165.8 KB	41	0	0	32:49:51
4	lze200.lze.lodz.pl	2	15	28.7 KB	3	0	0	00:08:25
5	212.191.196.1	4	15	28.5 KB	5	0	0	00:40:36
6	ip-176.105.135.22.tvsat364.lodz.pl	3	7	15.1 KB	2	0	2	01:29:43
7	router2.cbmm.lodz.pl	3	1	1.5 KB	1	0	0	00:05:50
8	212.51.203.199	3	1	1.5 KB	1	0	0	00:02:34
9	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	0	00:00:00
TOTAL: 9		821	530	1.0 MB	380	2	2	44:22:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	312	726.7 KB	288	0	0	00:00:11
2	unresolved	231	99	195.9 KB	47	0	0	33:33:02
3	*.man.lodz.pl	679	96	95.7 KB	39	2	0	00:30:29
4	*.lze.lodz.pl	2	15	28.7 KB	3	0	0	00:08:25
5	*.105.135.22.tvsat364.lodz.pl	3	7	15.1 KB	2	0	2	01:29:43
6	*.cbmm.lodz.pl	3	1	1.5 KB	1	0	0	00:05:50
7	*.207.15.62.static.jazztel.es	1	0	0.0 KB	0	0	0	08:34:10
8	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	0	00:00:00
9	*.toya.net.pl	95	0	0.0 KB	0	0	0	00:00:09
TOTAL: 9		1304	530	1.0 MB	380	2	2	44:22:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.864	5.860	32:49:51
232.207.15.62.static.jazztel.es	3.940	5.132	08:34:10
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.168	01:29:43
212.191.196.1	0.012	0.292	00:40:36
pc-vicki.man.lodz.pl	0.008	0.020	00:30:00
lze200.lze.lodz.pl	0.000	0.468	00:08:25
router2.cbmm.lodz.pl	0.020	0.168	00:05:50
212.51.203.199	0.000	0.108	00:02:34
web.man.lodz.pl	1.948	16.468	00:00:21
vegas.cm.p.lodz.pl	1.312	13.764	00:00:11
staticline41435.toya.net.pl	0.524	4.128	00:00:09
f-oper-v100.man.lodz.pl	1.936	16.240	00:00:06
david.man.lodz.pl	0.348	2.760	00:00:01
gcq193.internetdsl.tpnet.pl	0.012	0.168	00:00:00
kujawiak.man.lodz.pl	0.008	0.092	00:00:00
TOTAL: 15	10.952	65.836	44:22:03

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	95
gcq193.internetdsl.tpnet.pl	2
232.207.15.62.static.jazztel.es	1
pc-vicki.man.lodz.pl	1
TOTAL: 6	483

NNRP client timeouts [Top 20]:

System	Conn	Peer
232.207.15.62.static.jazztel.es	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
pc-vicki.man.lodz.pl	1	0
TOTAL: 3	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	58.9%
2	pl	216	40.8%
3	alt	2	0.4%
TOTAL: 3		530	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	89
3	pl.rec.rowery	41
4	pl.misc.kolej	30
5	pl.comp.os.ms-windows.winnt	18
6	pl.soc.prawo	14
7	pl.regionalne.lodz	8
8	pl.comp.bazy-danych	6
9	pl.regionalne.warszawa	2
10	alt.pl.praca.oferowana	2
11	pl.internet.polip	2
12	pl.comp.os.linux	2
13	pl.misc.paranauki	1
14	pl.rec.kuchnia	1
15	pl.rec.dom	1
16	pl.sci.filozofia	1
TOTAL: 16		530

Daily Usenet report

Jun 5 04:15:04 -- Jun 6 04:15:05