Daily Usenet report: Sep 27 04:15:03

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Sep 27 04:20:27 newsfeed nnrpd[32000]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:27 newsfeed nnrpd[32001]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:27 newsfeed nnrpd[32002]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:27 newsfeed nnrpd[32003]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:27 newsfeed nnrpd[32004]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:27 newsfeed nnrpd[32005]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 04:20:28 newsfeed nnrpd[32006]: web.man.lodz.pl rejected Expiring process 31916
Sep 27 05:33:28 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 07:34:32 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 08:30:28 newsfeed nnrpd[7726]: SERVER perl filter not defined
Sep 27 09:35:34 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 11:36:37 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 13:37:40 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 15:38:43 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 17:39:46 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 19:40:49 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable
Sep 27 21:41:52 newsfeed innfeed[2378]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2165985	97.9%	243.8 MB	97.4%
innd	29834	1.3%	4.5 MB	1.8%
innfeed	10375	0.5%	1.4 MB	0.6%
nnrpd	7311	0.3%	758.3 KB	0.3%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2213506	100.0%	250.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2158002	93.4%
Negative hits	109404	4.7%
Positive hits	40667	1.8%
Cache misses	1296	0.1%
TOTAL: 4	2309369	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.742	0.0%	350	0.000	24.977	883.000
article cleanup	00:00:28.418	0.0%	2164905	0.004	0.013	0.028
article logging	00:01:14.207	0.1%	2165052	0.011	0.034	0.993
article parse	01:00:32.693	4.2%	71861173	0.012	0.051	0.089
article write	00:22:17.498	1.5%	2145078	0.304	0.624	1.642
artlog/artcncl	00:00:00.007	0.0%	348	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:30.323	0.1%	84524975	0.000	0.001	0.003
hisgrep/artcncl	00:00:02.554	0.0%	258	0.000	9.899	280.000
hishave/artcncl	00:00:01.410	0.0%	350	0.000	4.029	256.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:42:48.089	15.5%	2309535	1.976	5.788	44.383
history sync	00:00:00.009	0.0%	291	0.000	0.031	1.000
history write	04:59:51.066	20.8%	2158213	4.825	8.336	45.177
hiswrite/artcncl	00:00:00.769	0.0%	92	0.000	8.359	153.000
idle	07:38:36.508	31.9%	39131682	0.192	0.703	6.569
nntp read	00:36:46.613	2.6%	70126346	0.010	0.031	0.046
overview write	00:00:13.177	0.0%	2145078	0.000	0.006	0.022
perl filter	05:09:04.551	21.5%	2158211	3.877	8.593	12.137
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:45.384	0.3%	4333333	0.011	0.052	0.078
TOTAL: 23:59:35.137	23:37:22.018	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.179	0.0%	51246	0.000	0.003	1.000
article prepare	00:00:00.005	0.0%	17492	0.000	0.000	0.125
article read	00:00:56.443	0.1%	4306	0.000	13.108	4640.800
backlog stats	00:00:00.146	0.0%	215413	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.511	0.0%	133588	0.000	0.011	1.000
data write	00:00:02.886	0.0%	92253	0.000	0.031	0.500
idle	23:56:56.085	99.8%	215413	0.000	400.236	12906.000
status file	00:01:27.109	0.1%	1766	0.000	49.326	4107.333
TOTAL: 23:59:30.114	23:59:24.364	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:55:55.752	99.7%	7895	0.000	881.033	450235.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.302	0.0%	17749	0.000	0.017	1.000
readart	00:00:00.360	0.0%	450	0.000	0.800	36.000
TOTAL: 01:56:19.702	01:55:56.414	99.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
alois.unverdorben@gmail.com	1	0	0	0	0	1
TOTAL	1	0	0	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1010	2152675	2132365	3662	16648	99%	406:23:42
2	news.nask.pl	2	17883	8009	7880	1994	44%	24:04:20
3	news.task.gda.pl	11	30775	6012	23550	1213	19%	46:06:04
4	news.icm.edu.pl	2	432	6	413	13	1%	01:15:57
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		1027	2201766	2146393	35505	19868	97%	477:50:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	741.9 GB	493.5 MB	2.2 GB	744.5 GB	99%	363.3 KB
2	news.nask.pl	224.4 MB	7.5 MB	19.5 MB	251.3 MB	89%	25.7 KB
3	news.task.gda.pl	12.6 MB	51.7 KB	3.3 MB	15.9 MB	78%	2.3 KB
4	news.icm.edu.pl	129.5 KB	0.0 KB	183.4 KB	312.8 KB	41%	16.5 KB
5	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 5		742.1 GB	501.0 MB	2.2 GB	744.8 GB	99%	360.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 27 04:15:03 - 04:59:59	50556	2.4%	18.75	20.5 GB	2.8%	7960.73
Sep 27 05:00:00 - 05:59:59	66416	3.1%	18.45	26.2 GB	3.5%	7628.49
Sep 27 06:00:00 - 06:59:59	62807	2.9%	17.45	23.8 GB	3.2%	6945.10
Sep 27 07:00:00 - 07:59:59	65777	3.1%	18.27	24.0 GB	3.2%	6976.28
Sep 27 08:00:00 - 08:59:59	79161	3.7%	21.99	28.9 GB	3.9%	8410.96
Sep 27 09:00:00 - 09:59:59	115917	5.4%	32.20	34.5 GB	4.7%	10040.35
Sep 27 10:00:00 - 10:59:59	78853	3.7%	21.90	25.1 GB	3.4%	7314.77
Sep 27 11:00:00 - 11:59:59	91121	4.2%	25.31	33.1 GB	4.5%	9649.97
Sep 27 12:00:00 - 12:59:59	92382	4.3%	25.66	33.1 GB	4.5%	9627.06
Sep 27 13:00:00 - 13:59:59	97695	4.6%	27.14	35.1 GB	4.7%	10224.59
Sep 27 14:00:00 - 14:59:59	104115	4.9%	28.92	36.0 GB	4.9%	10495.28
Sep 27 15:00:00 - 15:59:59	113892	5.3%	31.64	36.0 GB	4.9%	10479.66
Sep 27 16:00:00 - 16:59:59	100554	4.7%	27.93	34.9 GB	4.7%	10154.13
Sep 27 17:00:00 - 17:59:59	88838	4.1%	24.68	30.4 GB	4.1%	8850.36
Sep 27 18:00:00 - 18:59:59	90759	4.2%	25.21	30.9 GB	4.2%	9005.97
Sep 27 19:00:00 - 19:59:59	92657	4.3%	25.74	29.7 GB	4.0%	8649.69
Sep 27 20:00:00 - 20:59:59	93844	4.4%	26.07	30.5 GB	4.1%	8881.28
Sep 27 21:00:00 - 21:59:59	87967	4.1%	24.44	29.0 GB	3.9%	8458.31
Sep 27 22:00:00 - 22:59:59	95946	4.5%	26.65	32.6 GB	4.4%	9492.85
Sep 27 23:00:00 - 23:59:59	93027	4.3%	25.84	30.4 GB	4.1%	8868.48
Sep 28 00:00:00 - 00:59:59	79928	3.7%	22.20	27.4 GB	3.7%	7982.91
Sep 28 01:00:00 - 01:59:59	97148	4.5%	26.99	34.9 GB	4.7%	10177.56
Sep 28 02:00:00 - 02:59:59	94336	4.4%	26.20	34.3 GB	4.6%	9994.89
Sep 28 03:00:00 - 03:59:59	90393	4.2%	25.11	31.9 GB	4.3%	9300.90
Sep 28 04:00:00 - 04:15:03	21567	1.0%	23.88	7.8 GB	1.1%	9081.99
TOTAL: 24:00:00	2145656	100.0%	24.83	741.1 GB	100.0%	8993.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	16747	304	4767	0	11676
2	news.nask.pl	2024	18	1901	1	104
3	news.task.gda.pl	1197	973	27	0	197
4	news.icm.edu.pl	13	0	0	0	13
TOTAL: 4		19981	1295	6695	1	11990

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	113
mailing.freebsd.svn-ports-head	112
uk.railway	83
mailing.openbsd.ports-changes	82
it.tlc.cellulari.android	55
mailing.freebsd.ports-bugs	51
uk.rec.cycling.moderated	49
mailing.database.pgsql-general	40
alt.windows7.general	40
mailing.gcc.gcc-cvs	36
mailing.unix.dovecot	36
mailing.freebsd.current	35
mozilla.dev.tree-management	33
alt.politics.scorched-earth	28
24hoursupport.helpdesk	25
mailing.unix.samba-technical	24
it.comp.os.win.windows8	22
mailing.unix.samba	21
mozilla.dev.identity	19
comp.mobile.ipad	18
TOTAL: 80	1295

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

Groups	Count
uk.religion.christian	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	11579
EMP (fsl)	144
Attached HTML file	72
EMP (md5)	25
EMP (phl)	10
HTML post	9
Angle-bracket bot	1
Scoring filter (12)	1
TOTAL: 8	11841

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	44588	2987	39596	714	0	6%	24:08:16
2	icm	9285	45	8100	484	38579	0%	24:01:37
3	poznan	14419	40	12128	20	0	0%	24:00:22
4	task	2459	30	1281	30	0	1%	21:32:28
5	nask	16532	9	11685	34	0	0%	23:59:58
6	okapi	0	0	0	0	2615	0%	24:00:04
TOTAL: 6		87283	3111	72790	1282	41194	3%	141:42:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.6 MB	4.8 MB	13.4 MB	0.2 KB/s	3.7 KB	24:08:16
2	icm	2.6 MB	119.0 MB	121.6 MB	1.4 KB/s	235.4 KB	24:01:37
3	nask	215.7 KB	21.5 MB	21.7 MB	0.3 KB/s	517.7 KB	23:59:58
4	poznan	81.0 KB	55.8 KB	136.8 KB	0.0 KB/s	2.3 KB	24:00:22
5	task	62.2 KB	142.5 KB	204.8 KB	0.0 KB/s	3.4 KB	21:32:28
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 6		11.5 MB	145.6 MB	157.1 MB	0.3 KB/s	36.6 KB	141:42:45

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	vegas.cm.p.lodz.pl	288	300	411.6 KB	288	0	00:00:07
2	web.man.lodz.pl	294	136	128.5 KB	56	0	00:00:22
3	212.191.196.1	2	10	16.7 KB	2	0	00:18:23
4	router2.cbmm.lodz.pl	5	3	4.9 KB	3	0	00:52:37
5	ip-176.105.135.22.tvsat364.lodz.pl	1	1	1.5 KB	1	0	00:30:01
6	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 6		591	450	563.2 KB	350	1	01:56:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	411.6 KB	288	0	00:00:07
2	*.man.lodz.pl	681	136	128.5 KB	56	1	00:00:30
3	unresolved	2	10	16.7 KB	2	0	00:18:23
4	*.cbmm.lodz.pl	5	3	4.9 KB	3	0	00:52:37
5	*.105.135.22.tvsat364.lodz.pl	1	1	1.5 KB	1	0	00:30:01
6	*.dynamic.chello.pl	2	0	0.0 KB	0	0	00:00:00
7	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:05:13
8	*.181.14.62.static.jazztel.es	3	0	0.0 KB	0	0	00:09:15
9	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
10	*.toya.net.pl	84	0	0.0 KB	0	0	00:00:09
TOTAL: 10		1070	450	563.2 KB	350	1	01:56:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
router2.cbmm.lodz.pl	0.008	0.100	00:52:37
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.016	00:30:01
212.191.196.1	0.012	0.036	00:18:23
87.181.14.62.static.jazztel.es	0.224	0.324	00:09:15
eci204.neoplus.adsl.tpnet.pl	0.000	0.040	00:05:13
web.man.lodz.pl	0.760	5.240	00:00:22
staticline41435.toya.net.pl	0.228	1.464	00:00:09
vegas.cm.p.lodz.pl	0.600	4.848	00:00:07
f-oper-v100.man.lodz.pl	0.724	4.536	00:00:04
david.man.lodz.pl	0.216	1.548	00:00:03
89-69-218-206.dynamic.chello.pl	0.004	0.024	00:00:00
gcq193.internetdsl.tpnet.pl	0.004	0.032	00:00:00
kujawiak.man.lodz.pl	0.008	0.016	00:00:00
TOTAL: 13	2.792	18.224	01:56:19

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	291
david.man.lodz.pl	95
staticline41435.toya.net.pl	84
87.181.14.62.static.jazztel.es	3
gcq193.internetdsl.tpnet.pl	2
89-69-218-206.dynamic.chello.pl	2
eci204.neoplus.adsl.tpnet.pl	2
TOTAL: 7	479

NNRP client timeouts [Top 20]:

System	Conn	Peer
router2.cbmm.lodz.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	300	66.7%
2	pl	150	33.3%
TOTAL: 2		450	100.0%

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

Newsgroup		Count
1	lodman.info	300
2	pl.comp.pecet	134
3	pl.misc.paranauki	9
4	pl.regionalne.lodz	4
5	pl.comp.os.ms-windows.winnt	2
6	pl.sci.chemia	1
TOTAL: 6		450

Daily Usenet report

Sep 27 04:15:03 -- Sep 28 04:15:03