Daily Usenet report: Aug 26 04:15:03

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Aug 26 05:19:32 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 12:28:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 12:34:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 12:58:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 13:03:50 newsfeed nnrpd[30364]: SERVER perl filter not defined
Aug 26 13:10:40 newsfeed nnrpd[30635]: SERVER perl filter not defined
Aug 26 13:21:31 newsfeed nnrpd[30952]: SERVER perl filter not defined
Aug 26 14:10:30 newsfeed nnrpd[32530]: SERVER perl filter not defined
Aug 26 14:30:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 14:30:25 newsfeed nnrpd[708]: SERVER perl filter not defined
Aug 26 16:30:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 26 18:30:14 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1857977	98.4%	210.9 MB	97.9%
innd	16226	0.9%	2.7 MB	1.2%
nnrpd	7326	0.4%	772.9 KB	0.4%
innfeed	7039	0.4%	1.0 MB	0.5%
controlchan	2	0.0%	0.3 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1888571	100.0%	215.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1852503	93.6%
Negative hits	83038	4.2%
Positive hits	41718	2.1%
Cache misses	928	0.0%
TOTAL: 4	1978187	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:17.502	0.0%	835	0.000	20.960	596.000
article cleanup	00:00:24.963	0.0%	1856706	0.002	0.013	0.032
article logging	00:00:51.203	0.1%	1856917	0.008	0.028	0.642
article parse	01:05:44.996	4.6%	82200759	0.015	0.048	0.092
article write	00:20:48.649	1.4%	1850672	0.426	0.675	1.684
artlog/artcncl	00:00:00.012	0.0%	685	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:55.034	0.1%	93687985	0.000	0.001	0.005
hisgrep/artcncl	00:00:03.428	0.0%	431	0.000	7.954	95.000
hishave/artcncl	00:00:04.588	0.0%	835	0.000	5.495	124.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:07:46.234	13.0%	1978552	0.820	5.694	12.660
history sync	00:00:00.010	0.0%	291	0.000	0.034	1.000
history write	04:12:36.352	17.5%	1852932	0.238	8.180	46.348
hiswrite/artcncl	00:00:03.238	0.0%	404	0.000	8.015	94.000
idle	09:16:41.104	38.7%	54466480	0.232	0.613	5.911
nntp read	00:38:13.766	2.7%	80692105	0.014	0.028	0.048
overview write	00:00:10.863	0.0%	1850672	0.000	0.006	0.016
perl filter	04:47:28.761	20.0%	1852931	6.240	9.309	13.635
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:03.527	0.2%	3745032	0.014	0.049	0.124
TOTAL: 23:59:40.547	23:36:14.230	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.186	0.0%	67766	0.000	0.003	0.143
article prepare	00:00:00.008	0.0%	42938	0.000	0.000	0.083
article read	00:00:31.900	0.0%	10594	0.000	3.011	25.935
backlog stats	00:00:00.206	0.0%	273407	0.000	0.001	7.000
callbacks	00:00:00.000	0.0%	24	0.000	0.000	0.000
data read	00:00:01.587	0.0%	160185	0.000	0.010	0.081
data write	00:00:05.091	0.0%	132371	0.000	0.038	1.000
idle	23:58:11.657	99.9%	273407	0.000	315.616	8944.000
status file	00:00:04.595	0.0%	298	0.000	15.419	809.333
TOTAL: 23:59:01.309	23:58:55.230	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.308	0.0%	38	8.105	8.105	8.105
idle	16:28:07.768	99.9%	294358	0.000	201.414	600169.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.691	0.0%	590743	0.000	0.015	1.333
readart	00:00:01.621	0.0%	513	0.000	3.160	124.600
TOTAL: 16:28:53.318	16:28:18.388	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	190

Newsgroups created:

Group	Mode
free.de.piratenpartei	y
TOTAL	1

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
baerks@t-online.de	1	0	0	0	1	1
TOTAL	1	0	0	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	680	1845986	1839047	3617	3322	99%	326:17:03
2	news.nask.pl	4	17630	6151	10113	1366	34%	24:02:25
3	news.task.gda.pl	16	32517	5904	25349	1264	18%	46:28:50
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	17	322	1	230	91	0%	05:26:23
TOTAL: 5		723	1896460	1851108	39309	6043	97%	402:14:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	670.3 GB	55.3 MB	104.3 MB	670.5 GB	99%	381.6 KB
2	news.nask.pl	59.1 MB	4.1 MB	4.3 MB	67.4 MB	87%	9.2 KB
3	news.task.gda.pl	13.2 MB	55.9 KB	3.3 MB	16.6 MB	79%	2.4 KB
4	news.icm.edu.pl	11.0 KB	18.6 KB	2.9 MB	2.9 MB	0%	32.5 KB
5	localhost	7.4 KB	0.0 KB	0.0 KB	7.4 KB	100%	1.5 KB
TOTAL: 5		670.4 GB	59.4 MB	114.8 MB	670.5 GB	99%	378.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 26 04:15:03 - 04:59:59	70945	3.8%	26.31	26.2 GB	3.9%	10201.14
Aug 26 05:00:00 - 05:59:59	89125	4.8%	24.76	34.7 GB	5.2%	10104.09
Aug 26 06:00:00 - 06:59:59	77408	4.2%	21.50	29.7 GB	4.4%	8650.82
Aug 26 07:00:00 - 07:59:59	85573	4.6%	23.77	31.5 GB	4.7%	9175.94
Aug 26 08:00:00 - 08:59:59	91084	4.9%	25.30	32.6 GB	4.9%	9481.42
Aug 26 09:00:00 - 09:59:59	95984	5.2%	26.66	34.3 GB	5.1%	9992.26
Aug 26 10:00:00 - 10:59:59	89205	4.8%	24.78	32.0 GB	4.8%	9324.56
Aug 26 11:00:00 - 11:59:59	103640	5.6%	28.79	37.0 GB	5.5%	10765.43
Aug 26 12:00:00 - 12:59:59	100784	5.4%	28.00	36.5 GB	5.5%	10641.13
Aug 26 13:00:00 - 13:59:59	78460	4.2%	21.79	28.4 GB	4.2%	8279.08
Aug 26 14:00:00 - 14:59:59	90696	4.9%	25.19	33.2 GB	5.0%	9681.44
Aug 26 15:00:00 - 15:59:59	97284	5.3%	27.02	34.3 GB	5.1%	9996.00
Aug 26 16:00:00 - 16:59:59	100920	5.5%	28.03	34.4 GB	5.1%	10024.78
Aug 26 17:00:00 - 17:59:59	86210	4.7%	23.95	30.2 GB	4.5%	8807.90
Aug 26 18:00:00 - 18:59:59	51248	2.8%	14.24	18.9 GB	2.8%	5493.15
Aug 26 19:00:00 - 19:59:59	53069	2.9%	14.74	19.0 GB	2.8%	5520.20
Aug 26 20:00:00 - 20:59:59	43713	2.4%	12.14	15.0 GB	2.2%	4378.63
Aug 26 21:00:00 - 21:59:59	32553	1.8%	9.04	11.5 GB	1.7%	3355.22
Aug 26 22:00:00 - 22:59:59	31564	1.7%	8.77	11.6 GB	1.7%	3372.98
Aug 26 23:00:00 - 23:59:59	33379	1.8%	9.27	11.2 GB	1.7%	3270.48
Aug 27 00:00:00 - 00:59:59	51052	2.8%	14.18	18.0 GB	2.7%	5253.74
Aug 27 01:00:00 - 01:59:59	79382	4.3%	22.05	30.2 GB	4.5%	8798.40
Aug 27 02:00:00 - 02:59:59	103356	5.6%	28.71	37.6 GB	5.6%	10951.88
Aug 27 03:00:00 - 03:59:59	92016	5.0%	25.56	33.6 GB	5.0%	9792.67
Aug 27 04:00:00 - 04:15:03	22397	1.2%	24.80	8.5 GB	1.3%	9837.25
TOTAL: 24:00:00	1851047	100.0%	21.42	670.2 GB	100.0%	8134.25

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3503	295	2427	1	780
2	news.nask.pl	1405	40	1316	2	47
3	news.task.gda.pl	1246	966	30	0	250
4	news.icm.edu.pl	91	0	1	0	90
TOTAL: 4		6245	1301	3774	3	1167

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	88
mailing.freebsd.ports-bugs	87
mailing.www.horde-bugs	67
comp.mobile.ipad	56
mailing.freebsd.svn-ports-all	55
mailing.freebsd.svn-ports-head	55
it.comp.os.win.windows8	54
mailing.freebsd.svn-src-all	49
fa.freebsd.cvs-all	49
mailing.freebsd.svn-src-head	47
it.tlc.cellulari.android	38
mailing.unix.dovecot	37
mailing.openbsd.misc	34
mailing.unix.samba	33
mailing.openbsd.source-changes	33
24hoursupport.helpdesk	33
mailing.freebsd.current	32
alt.windows7.general	31
mailing.gcc.gcc-cvs	29
mozilla.dev.tree-management	29
TOTAL: 79	1301

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

Groups	Count
pl.sci.historia	2
pl.sci.matematyka	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	532
EMP (fsl)	209
EMP (md5)	108
Attached HTML file	86
HTML post	17
Scoring filter (11)	1
Scoring filter (12)	1
Scoring filter (8)	1
TOTAL: 8	955

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	45159	2949	39988	733	1	0	6%	24:03:02
2	icm	38990	994	28625	5849	56	29393	2%	24:05:22
3	poznan	13752	119	11466	44	0	2	0%	23:57:57
4	task	2419	17	1271	6	0	1	0%	21:41:39
5	nask	19579	10	13336	1	0	0	0%	24:03:15
6	okapi	0	0	0	0	0	2884	0%	24:02:28
TOTAL: 6		119899	4089	94686	6633	57	32280	3%	141:53:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	20.1 MB	1.6 GB	1.6 GB	19.0 KB/s	240.9 KB	24:05:22
2	panorama	8.8 MB	2.9 MB	11.6 MB	0.1 KB/s	3.2 KB	24:03:02
3	poznan	319.1 KB	100.6 KB	419.7 KB	0.0 KB/s	2.6 KB	23:57:57
4	nask	40.9 KB	1.4 MB	1.4 MB	0.0 KB/s	133.0 KB	24:03:15
5	task	38.2 KB	8.3 KB	46.4 KB	0.0 KB/s	2.0 KB	21:41:39
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:02:28
TOTAL: 6		29.3 MB	1.6 GB	1.6 GB	3.3 KB/s	155.0 KB	141:53:43

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	Elapsed
1	vegas.cm.p.lodz.pl	288	348	491.7 KB	288	0	00:00:06
2	web.man.lodz.pl	288	100	95.3 KB	44	0	00:00:27
3	lze200.lze.lodz.pl	6	60	128.6 KB	6	0	01:07:54
4	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	00:00:01
TOTAL: 4		587	508	715.6 KB	338	5	16:28:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	491.7 KB	288	0	00:00:06
2	*.man.lodz.pl	677	100	95.3 KB	44	5	00:00:33
3	*.lze.lodz.pl	6	60	128.6 KB	6	0	01:07:54
4	*.ips.lodz.pl	21	0	0.0 KB	0	0	00:00:00
5	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	0	13:16:55
6	*.cbmm.lodz.pl	6	0	0.0 KB	0	0	00:24:56
7	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:01:16
8	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
9	*.105.135.22.tvsat364.lodz.pl	3	0	0.0 KB	0	0	01:26:05
10	*.64-95.man.lodz.pl	1	0	0.0 KB	0	0	00:10:57
11	*.toya.net.pl	56	0	0.0 KB	0	0	00:00:05
TOTAL: 11		1066	508	715.6 KB	338	5	16:28:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
200.207.15.62.static.jazztel.es	14.529	22.313	13:16:55
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.044	01:26:05
lze200.lze.lodz.pl	0.016	0.128	01:07:54
router2.cbmm.lodz.pl	0.020	0.088	00:24:56
67.64-95.man.lodz.pl	0.004	0.016	00:10:57
ebz200.neoplus.adsl.tpnet.pl	0.012	0.024	00:01:16
web.man.lodz.pl	0.700	4.436	00:00:27
vegas.cm.p.lodz.pl	0.600	4.104	00:00:06
staticline41435.toya.net.pl	0.144	0.800	00:00:05
f-oper-v100.man.lodz.pl	0.516	4.328	00:00:03
david.man.lodz.pl	0.204	1.376	00:00:01
kujawiak.man.lodz.pl	0.020	0.096	00:00:01
pc1.ips.lodz.pl	0.064	0.276	00:00:00
gcq193.internetdsl.tpnet.pl	0.008	0.028	00:00:00
TOTAL: 14	16.845	38.057	16:28:52

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	56
pc1.ips.lodz.pl	21
router2.cbmm.lodz.pl	6
200.207.15.62.static.jazztel.es	4
ip-176.105.135.22.tvsat364.lodz.pl	3
ebz200.neoplus.adsl.tpnet.pl	2
gcq193.internetdsl.tpnet.pl	2
67.64-95.man.lodz.pl	1
TOTAL: 10	479

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	2	0
lze200.lze.lodz.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	68.5%
2	pl	160	31.5%
TOTAL: 2		508	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.pecet	96
3	pl.comp.os.ms-windows.winnt	51
4	pl.rec.dom	13
TOTAL: 4		508

Daily Usenet report

Aug 26 04:15:03 -- Aug 27 04:15:03