Daily Usenet report: May 26 04:15:03

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

May 26 11:31:18 newsfeed innfeed[2323]: task:0 connect: Network is unreachable
May 26 11:31:18 newsfeed innfeed[2323]: nask:0 connect: Network is unreachable
May 26 11:31:18 newsfeed innfeed[2323]: icm:0 connect: Network is unreachable
May 26 14:26:47 newsfeed nnrpd[8388]: SERVER perl filter not defined
May 26 14:27:13 newsfeed nnrpd[8395]: SERVER perl filter not defined
May 26 14:36:33 newsfeed nnrpd[8712]: SERVER perl filter not defined
May 26 18:34:52 newsfeed nnrpd[25144]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2315541	98.6%	271.8 MB	98.2%
innd	19421	0.8%	3.2 MB	1.1%
nnrpd	7456	0.3%	789.4 KB	0.3%
innfeed	7006	0.3%	1.1 MB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	2349425	100.0%	276.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2310351	94.9%
Negative hits	84667	3.5%
Positive hits	37090	1.5%
Cache misses	2343	0.1%
TOTAL: 4	2434451	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.744	0.0%	312	0.000	18.410	343.000
article cleanup	00:00:33.516	0.0%	2314197	0.000	0.014	0.028
article logging	00:01:07.727	0.1%	2314300	0.013	0.029	0.565
article parse	01:14:08.724	5.2%	234095710	0.005	0.019	0.071
article write	00:25:41.819	1.8%	2308613	0.296	0.668	6.158
artlog/artcncl	00:00:00.003	0.0%	304	0.000	0.010	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:58.568	0.3%	249114173	0.000	0.001	0.006
hisgrep/artcncl	00:00:01.085	0.0%	237	0.000	4.578	296.000
hishave/artcncl	00:00:00.535	0.0%	312	0.000	1.715	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:29:15.064	10.4%	2434756	0.996	3.678	14.168
history sync	00:00:00.020	0.0%	292	0.000	0.068	1.000
history write	02:57:24.406	12.3%	2310654	2.098	4.607	36.741
hiswrite/artcncl	00:00:00.114	0.0%	75	0.000	1.520	18.000
idle	10:01:29.425	41.8%	156606138	0.116	0.230	12.483
nntp read	01:06:22.999	4.6%	232363591	0.006	0.017	0.051
overview write	00:00:13.852	0.0%	2308613	0.000	0.006	0.014
perl filter	04:57:04.789	20.7%	2310651	4.263	7.714	12.201
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:45.103	0.3%	4689950	0.010	0.061	0.107
TOTAL: 23:58:31.843	23:22:13.493	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.260	0.0%	72889	0.000	0.004	0.097
article prepare	00:00:00.040	0.0%	134256	0.000	0.000	0.021
article read	00:00:11.503	0.0%	32481	0.000	0.354	48.500
backlog stats	00:00:00.414	0.0%	524124	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:03.059	0.0%	277638	0.000	0.011	1.000
data write	00:00:21.555	0.0%	283172	0.000	0.076	85.000
idle	23:58:16.961	99.9%	524124	0.000	164.650	13397.000
status file	00:00:01.881	0.0%	275	0.000	6.840	262.000
TOTAL: 23:59:06.738	23:58:55.673	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:50:03.406	96.7%	4470	0.000	1477.272	600034.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.325	0.0%	71248	0.000	0.033	1.474
readart	00:00:00.956	0.0%	389	0.000	2.458	64.000
TOTAL: 01:53:50.430	01:50:06.687	96.7%	-	-	-	-

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	965	2302049	2295526	2793	3730	99%	349:03:42
2	news.nask.pl	3	17058	6287	10398	373	36%	23:13:54
3	news.task.gda.pl	23	29146	5883	21908	1355	20%	44:28:52
4	localhost	5	4	4	0	0	100%	00:00:00
5	news.icm.edu.pl	39	330	2	214	114	0%	18:38:21
TOTAL: 5		1035	2348587	2307702	35313	5572	98%	435:24:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	699.8 GB	66.6 MB	125.4 MB	699.9 GB	99%	319.2 KB
2	news.nask.pl	25.7 MB	1.2 MB	1.3 MB	28.2 MB	91%	4.3 KB
3	news.task.gda.pl	12.4 MB	21.3 KB	4.3 MB	16.7 MB	74%	2.4 KB
4	news.icm.edu.pl	22.4 KB	52.3 KB	1.4 MB	1.4 MB	1%	12.7 KB
5	localhost	5.1 KB	0.0 KB	0.0 KB	5.1 KB	100%	1.3 KB
TOTAL: 5		699.8 GB	67.8 MB	132.4 MB	700.0 GB	99%	317.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 26 04:15:03 - 04:59:59	60898	2.6%	22.58	22.0 GB	3.1%	8538.77
May 26 05:00:00 - 05:59:59	72069	3.1%	20.02	28.2 GB	4.0%	8219.73
May 26 06:00:00 - 06:59:59	72312	3.1%	20.09	27.9 GB	4.0%	8114.59
May 26 07:00:00 - 07:59:59	58685	2.5%	16.30	23.7 GB	3.4%	6911.17
May 26 08:00:00 - 08:59:59	74875	3.2%	20.80	29.1 GB	4.2%	8469.18
May 26 09:00:00 - 09:59:59	85724	3.7%	23.81	27.9 GB	4.0%	8112.27
May 26 10:00:00 - 10:59:59	95701	4.1%	26.58	30.6 GB	4.4%	8910.53
May 26 11:00:00 - 11:59:59	79710	3.5%	22.14	23.1 GB	3.3%	6730.66
May 26 12:00:00 - 12:59:59	115873	5.0%	32.19	30.9 GB	4.4%	9004.87
May 26 13:00:00 - 13:59:59	115870	5.0%	32.19	32.1 GB	4.6%	9348.44
May 26 14:00:00 - 14:59:59	118253	5.1%	32.85	30.7 GB	4.4%	8946.05
May 26 15:00:00 - 15:59:59	128939	5.6%	35.82	35.6 GB	5.1%	10359.20
May 26 16:00:00 - 16:59:59	124523	5.4%	34.59	34.3 GB	4.9%	9982.76
May 26 17:00:00 - 17:59:59	120995	5.2%	33.61	31.4 GB	4.5%	9158.71
May 26 18:00:00 - 18:59:59	117452	5.1%	32.63	30.6 GB	4.4%	8910.51
May 26 19:00:00 - 19:59:59	118038	5.1%	32.79	32.1 GB	4.6%	9347.42
May 26 20:00:00 - 20:59:59	97288	4.2%	27.02	31.9 GB	4.6%	9282.16
May 26 21:00:00 - 21:59:59	88492	3.8%	24.58	29.6 GB	4.2%	8634.82
May 26 22:00:00 - 22:59:59	91487	4.0%	25.41	31.4 GB	4.5%	9132.40
May 26 23:00:00 - 23:59:59	86231	3.7%	23.95	29.5 GB	4.2%	8603.02
May 27 00:00:00 - 00:59:59	55488	2.4%	15.41	18.9 GB	2.7%	5501.25
May 27 01:00:00 - 01:59:59	77366	3.3%	21.49	22.0 GB	3.1%	6396.52
May 27 02:00:00 - 02:59:59	109933	4.8%	30.54	28.9 GB	4.1%	8403.66
May 27 03:00:00 - 03:59:59	113815	4.9%	31.62	30.5 GB	4.4%	8897.07
May 27 04:00:00 - 04:15:04	29527	1.3%	32.66	7.5 GB	1.1%	8674.75
TOTAL: 24:00:01	2309544	100.0%	26.73	700.2 GB	100.0%	8497.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3823	138	3188	0	497
2	news.task.gda.pl	1366	1155	13	0	198
3	news.nask.pl	390	17	345	2	26
4	news.icm.edu.pl	114	0	1	0	113
TOTAL: 4		5693	1310	3547	2	834

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	186
mailing.freebsd.svn-ports-head	183
mailing.freebsd.ports-bugs	117
uk.railway	77
gmane.linux.alsa.devel	58
mailing.openbsd.misc	43
mailing.unix.samba-technical	42
mailing.freebsd.ports	39
fa.freebsd.cvs-all	38
mailing.freebsd.svn-src-all	37
mailing.gcc.gcc-cvs	34
alt.windows7.general	31
fr.usenet.abus.nocem	28
mailing.openbsd.source-changes	26
comp.mobile.android	24
mailing.unix.dovecot	20
mailing.openssl.dev	17
mailing.unix.samba	15
mailing.freebsd.svn-src-head	15
mailing.postfix.users	14
TOTAL: 79	1310

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

Groups	Count
pl.sci.matematyka	1
bit.listserv.catholic	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	387
EMP (fsl)	166
EMP (md5)	136
Attached HTML file	26
Scoring filter (8)	9
Scoring filter (9)	2
Scoring filter (11)	1
HTML post	1
TOTAL: 8	728

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	68906	7666	27548	21774	13	127	11%	24:00:30
2	panorama	77698	3131	69279	548	0	0	4%	23:59:34
3	task	1899	188	793	208	0	4	9%	21:53:27
4	poznan	15357	123	12040	70	1	0	0%	23:54:07
5	nask	19756	51	12647	46	0	10	0%	24:00:31
6	okapi	0	0	0	0	0	2584	0%	23:50:01
TOTAL: 6		183616	11159	122307	22646	14	2725	6%	141:38:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	287.2 MB	6.0 GB	6.3 GB	76.5 KB/s	224.5 KB	24:00:30
2	panorama	12.8 MB	8.2 MB	21.1 MB	0.2 KB/s	5.9 KB	23:59:34
3	poznan	548.8 KB	115.0 KB	663.8 KB	0.0 KB/s	3.4 KB	23:54:07
4	task	370.4 KB	406.3 KB	776.7 KB	0.0 KB/s	2.0 KB	21:53:27
5	nask	111.1 KB	15.4 MB	15.5 MB	0.2 KB/s	164.0 KB	24:00:31
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 6		301.0 MB	6.0 GB	6.3 GB	13.0 KB/s	196.7 KB	141:38:10

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	324	443.5 KB	288	0	00:00:17
2	web.man.lodz.pl	288	56	54.5 KB	25	0	00:00:32
3	212.191.78.153	9	3	2.7 KB	3	1	01:12:35
4	router2.cbmm.lodz.pl	2	3	3.4 KB	1	0	00:30:27
5	194.33.77.50	2	3	4.4 KB	1	0	00:01:50
6	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 6		592	389	508.6 KB	318	4	01:53:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	324	443.5 KB	288	0	00:00:17
2	*.man.lodz.pl	675	56	54.5 KB	25	3	00:00:41
3	unresolved	13	6	7.1 KB	4	1	01:14:36
4	*.cbmm.lodz.pl	2	3	3.4 KB	1	0	00:30:27
5	*.pa.nsw.optusnet.com.au	34	0	0.0 KB	0	0	00:01:00
6	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	00:06:31
7	*.toya.net.pl	98	0	0.0 KB	0	0	00:00:12
8	*.lze.lodz.pl	2	0	0.0 KB	0	0	00:00:02
TOTAL: 8		1114	389	508.6 KB	318	4	01:53:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.153	0.356	0.176	01:12:35
router2.cbmm.lodz.pl	0.008	0.016	00:30:27
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.024	00:06:31
194.33.77.50	0.004	0.020	00:01:50
pa49-181-246-76.pa.nsw.optusnet.com.au	0.068	0.348	00:01:00
web.man.lodz.pl	0.648	3.944	00:00:32
pc-skrzep2.cm.p.lodz.pl	0.668	3.392	00:00:17
staticline41435.toya.net.pl	0.200	0.944	00:00:12
212.51.203.199	0.004	0.012	00:00:10
mon.man.lodz.pl	0.580	3.260	00:00:06
lze200.lze.lodz.pl	0.000	0.028	00:00:02
david.man.lodz.pl	0.164	1.076	00:00:01
isvcs01.man.lodz.pl	0.016	0.024	00:00:00
TOTAL: 13	2.720	13.264	01:53:49

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	98
david.man.lodz.pl	96
pa49-181-246-76.pa.nsw.optusnet.com.au	34
lze200.lze.lodz.pl	2
ip-176.105.135.22.tvsat364.lodz.pl	2
212.51.203.199	2
TOTAL: 7	522

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.153	1	0
router2.cbmm.lodz.pl	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	327	84.1%
2	pl	59	15.2%
3	alt	3	0.8%
TOTAL: 3		389	100.0%

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

Newsgroup		Count
1	lodman.info	327
2	pl.comp.pecet	34
3	pl.comp.os.ms-windows.winnt	22
4	alt.test.pl	3
5	pl.sci.chemia	3
TOTAL: 5		389

Daily Usenet report

May 26 04:15:03 -- May 27 04:15:04