Daily Usenet report: Aug 28 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Aug 28 05:20:53 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 28 07:20:53 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 28 16:14:00 newsfeed nnrpd[32191]: SERVER perl filter not defined
Aug 28 16:16:35 newsfeed nnrpd[32242]: SERVER perl filter not defined
Aug 28 22:20:02 newsfeed controlchan[10869]: Unknown "tags" control by robomod@news.nic.it
Aug 29 00:10:16 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 29 00:16:17 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 29 00:40:17 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 29 02:12:17 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Aug 29 04:12:17 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2424513	98.6%	273.2 MB	98.1%
innd	19992	0.8%	3.3 MB	1.2%
innfeed	7264	0.3%	1.1 MB	0.4%
nnrpd	7127	0.3%	762.9 KB	0.3%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2458897	100.0%	278.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2416739	94.5%
Negative hits	97485	3.8%
Positive hits	42866	1.7%
Cache misses	522	0.0%
TOTAL: 4	2557612	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.911	0.0%	416	0.000	28.632	615.000
article cleanup	00:00:31.793	0.0%	2423222	0.005	0.013	0.023
article logging	00:01:10.860	0.1%	2423423	0.011	0.029	0.975
article parse	01:20:21.256	5.6%	93288149	0.022	0.052	0.088
article write	00:25:41.099	1.8%	2416012	0.082	0.638	5.573
artlog/artcncl	00:00:00.005	0.0%	395	0.000	0.013	1.000
artlog/artparse	00:00:00.001	0.0%	4	0.000	0.250	1.000
data move	00:02:08.282	0.1%	107642882	0.001	0.001	0.005
hisgrep/artcncl	00:00:03.037	0.0%	298	0.000	10.191	285.000
hishave/artcncl	00:00:00.771	0.0%	416	0.000	1.853	195.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:36:29.290	10.9%	2558866	0.141	3.669	12.938
history sync	00:00:00.013	0.0%	292	0.000	0.045	1.000
history write	03:58:51.499	16.6%	2418040	0.685	5.927	20.268
hiswrite/artcncl	00:00:00.986	0.0%	118	0.000	8.356	284.000
idle	08:15:09.537	34.4%	56485527	0.101	0.526	4.667
nntp read	00:45:33.103	3.2%	91345549	0.017	0.030	0.042
overview write	00:00:14.404	0.0%	2416012	0.001	0.006	0.015
perl filter	05:58:48.059	24.9%	2418036	1.023	8.903	10.867
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:01.167	0.3%	4879323	0.012	0.049	0.079
TOTAL: 23:59:41.301	23:29:17.073	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.242	0.0%	80853	0.000	0.003	0.083
article prepare	00:00:00.013	0.0%	59288	0.000	0.000	0.028
article read	00:01:46.168	0.1%	14504	0.000	7.320	32.308
backlog stats	00:00:00.218	0.0%	345530	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	27	0.000	0.000	0.000
data read	00:00:02.197	0.0%	205397	0.000	0.011	0.070
data write	00:00:05.889	0.0%	166925	0.000	0.035	0.333
idle	23:57:44.532	99.9%	345530	0.000	249.659	10402.000
status file	00:00:01.219	0.0%	239	0.000	5.100	237.000
TOTAL: 23:59:47.658	23:59:40.478	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:01:02.790	0.1%	480513	0.131	0.131	0.131
idle	28:17:54.516	99.7%	625231	0.000	162.939	600056.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:21.059	0.0%	1252336	0.000	0.017	0.500
readart	00:02:23.672	0.1%	480927	0.000	0.299	33.769
TOTAL: 28:22:42.909	28:21:42.037	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	930	2413097	2402764	5357	4976	99%	399:11:28
2	news.nask.pl	3	17454	8093	8268	1093	46%	24:17:08
3	news.task.gda.pl	16	31114	6079	23924	1111	19%	45:38:48
4	localhost	3	2	2	0	0	100%	00:00:00
5	news.icm.edu.pl	32	433	1	365	67	0%	14:18:52
TOTAL: 5		984	2462100	2416939	37914	7247	98%	483:26:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	827.6 GB	711.0 MB	745.2 MB	829.1 GB	99%	361.1 KB
2	news.nask.pl	29.2 MB	2.6 MB	2.8 MB	34.6 MB	84%	3.9 KB
3	news.task.gda.pl	13.0 MB	44.0 KB	3.1 MB	16.1 MB	80%	2.3 KB
4	news.icm.edu.pl	9.4 KB	0.0 KB	1.1 MB	1.2 MB	0%	17.4 KB
5	localhost	2.8 KB	0.0 KB	0.0 KB	2.8 KB	100%	1.4 KB
TOTAL: 5		827.7 GB	713.7 MB	752.2 MB	829.1 GB	99%	358.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 28 04:15:02 - 04:59:59	68107	2.8%	25.24	25.7 GB	3.1%	9994.41
Aug 28 05:00:00 - 05:59:59	86920	3.6%	24.14	34.5 GB	4.2%	10053.73
Aug 28 06:00:00 - 06:59:59	87443	3.6%	24.29	33.1 GB	4.0%	9629.77
Aug 28 07:00:00 - 07:59:59	97174	4.0%	26.99	35.8 GB	4.3%	10425.74
Aug 28 08:00:00 - 08:59:59	93781	3.9%	26.05	33.8 GB	4.1%	9832.86
Aug 28 09:00:00 - 09:59:59	98564	4.1%	27.38	35.5 GB	4.3%	10353.29
Aug 28 10:00:00 - 10:59:59	95021	3.9%	26.39	33.6 GB	4.1%	9797.01
Aug 28 11:00:00 - 11:59:59	99231	4.1%	27.56	35.2 GB	4.3%	10258.39
Aug 28 12:00:00 - 12:59:59	101474	4.2%	28.19	36.0 GB	4.4%	10483.34
Aug 28 13:00:00 - 13:59:59	100079	4.1%	27.80	35.3 GB	4.3%	10281.54
Aug 28 14:00:00 - 14:59:59	103054	4.3%	28.63	35.6 GB	4.3%	10361.77
Aug 28 15:00:00 - 15:59:59	107798	4.5%	29.94	38.0 GB	4.6%	11072.45
Aug 28 16:00:00 - 16:59:59	109110	4.5%	30.31	37.9 GB	4.6%	11029.95
Aug 28 17:00:00 - 17:59:59	141038	5.8%	39.18	31.3 GB	3.8%	9113.81
Aug 28 18:00:00 - 18:59:59	121894	5.0%	33.86	36.6 GB	4.4%	10649.58
Aug 28 19:00:00 - 19:59:59	123627	5.1%	34.34	35.5 GB	4.3%	10346.10
Aug 28 20:00:00 - 20:59:59	110258	4.6%	30.63	36.8 GB	4.4%	10716.26
Aug 28 21:00:00 - 21:59:59	102978	4.3%	28.61	35.0 GB	4.2%	10181.48
Aug 28 22:00:00 - 22:59:59	78810	3.3%	21.89	27.2 GB	3.3%	7916.84
Aug 28 23:00:00 - 23:59:59	88581	3.7%	24.61	31.0 GB	3.7%	9025.81
Aug 29 00:00:00 - 00:59:59	89119	3.7%	24.76	30.3 GB	3.7%	8827.23
Aug 29 01:00:00 - 01:59:59	89971	3.7%	24.99	32.5 GB	3.9%	9455.76
Aug 29 02:00:00 - 02:59:59	90874	3.8%	25.24	33.2 GB	4.0%	9658.35
Aug 29 03:00:00 - 03:59:59	104587	4.3%	29.05	37.7 GB	4.6%	10993.39
Aug 29 04:00:00 - 04:15:02	27214	1.1%	30.17	10.0 GB	1.2%	11595.75
TOTAL: 24:00:00	2416707	100.0%	27.97	826.9 GB	100.0%	10035.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5119	270	4144	1	704
2	news.nask.pl	1127	29	1014	0	84
3	news.task.gda.pl	1099	867	25	0	207
4	news.icm.edu.pl	67	0	0	0	67
TOTAL: 4		7412	1166	5183	1	1062

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	90
mailing.freebsd.ports-bugs	74
mailing.freebsd.svn-ports-all	56
mailing.freebsd.svn-ports-head	56
mailing.freebsd.svn-src-all	43
fa.freebsd.cvs-all	43
mailing.freebsd.svn-src-head	40
mailing.unix.samba	38
it.tlc.cellulari.android	38
mozilla.dev.gaia	34
alt.politics.scorched-earth	32
comp.mobile.ipad	29
mailing.gcc.gcc-cvs	27
mailing.postfix.users	26
it.comp.os.win.windows7	25
mozilla.dev.tree-management	25
gnu.hurd.bug	22
it.comp.os.win.windows8	22
mailing.database.pgsql-general	22
mailing.www.horde-bugs	21
TOTAL: 82	1166

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

Groups	Count
pl.sci.matematyka	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	456
EMP (fsl)	188
HTML post	111
EMP (md5)	90
Scoring filter (9)	7
Scoring filter (12)	2
Attached HTML file	1
Scoring filter (10)	1
Scoring filter (8)	1
TOTAL: 9	857

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	48811	4004	42207	1114	0	0	8%	24:05:30
2	icm	80985	2081	53816	7392	62	11548	2%	24:01:22
3	task	1901	87	965	1	0	0	4%	21:35:15
4	poznan	16784	75	13732	38	0	0	0%	23:57:06
5	nask	16724	10	11324	0	0	0	0%	24:01:38
6	okapi	0	0	0	0	0	2599	0%	24:00:02
TOTAL: 6		165205	6257	122044	8545	62	14147	3%	141:40:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	50.6 MB	1.2 GB	1.2 GB	14.8 KB/s	135.1 KB	24:01:22
2	panorama	10.9 MB	4.3 MB	15.1 MB	0.2 KB/s	3.0 KB	24:05:30
3	poznan	242.1 KB	65.6 KB	307.7 KB	0.0 KB/s	2.7 KB	23:57:06
4	task	169.3 KB	2.9 KB	172.2 KB	0.0 KB/s	2.0 KB	21:35:15
5	nask	19.4 KB	0.0 KB	19.4 KB	0.0 KB/s	1.9 KB	24:01:38
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		61.9 MB	1.2 GB	1.2 GB	2.5 KB/s	87.5 KB	141:40:53

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	312	476.2 KB	288	0	00:00:07
2	web.man.lodz.pl	288	102	103.5 KB	46	0	00:00:23
3	212.191.196.1	2	36	72.6 KB	2	0	00:52:39
4	router2.cbmm.lodz.pl	5	2	2.6 KB	2	0	00:25:15
5	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 5		585	452	654.9 KB	338	2	28:22:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	476.2 KB	288	0	00:00:07
2	*.man.lodz.pl	674	102	103.5 KB	46	2	00:00:31
3	unresolved	2	36	72.6 KB	2	0	00:52:39
4	*.cbmm.lodz.pl	5	2	2.6 KB	2	0	00:25:15
5	*.207.15.62.static.jazztel.es	1	0	0.0 KB	0	0	25:07:25
6	*.205.48.29.nat.umts.dynamic.t-mobile.pl	4	0	0.0 KB	0	0	00:31:28
7	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:30:32
8	*.internetdsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:00
9	*.105.135.22.tvsat364.lodz.pl	4	0	0.0 KB	0	0	00:54:32
10	*.toya.net.pl	46	0	0.0 KB	0	0	00:00:08
TOTAL: 10		1029	452	654.9 KB	338	2	28:22:42

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
116.207.15.62.static.jazztel.es	44.958	197.517	25:07:25
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.056	00:54:32
212.191.196.1	0.016	0.044	00:52:39
46.205.48.29.nat.umts.dynamic.t-mobile.pl	0.008	0.056	00:31:28
eby4.neoplus.adsl.tpnet.pl	0.008	0.024	00:30:32
router2.cbmm.lodz.pl	0.008	0.084	00:25:15
web.man.lodz.pl	0.800	4.656	00:00:23
staticline41435.toya.net.pl	0.100	0.756	00:00:08
vegas.cm.p.lodz.pl	0.528	4.292	00:00:07
f-oper-v100.man.lodz.pl	0.596	4.368	00:00:05
david.man.lodz.pl	0.248	1.488	00:00:02
gcq193.internetdsl.tpnet.pl	0.008	0.048	00:00:00
kujawiak.man.lodz.pl	0.004	0.040	00:00:00
TOTAL: 13	47.294	213.429	28:22:42

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	46
ip-176.105.135.22.tvsat364.lodz.pl	4
46.205.48.29.nat.umts.dynamic.t-mobile.pl	4
gcq193.internetdsl.tpnet.pl	3
eby4.neoplus.adsl.tpnet.pl	2
116.207.15.62.static.jazztel.es	1
TOTAL: 8	444

NNRP client timeouts [Top 20]:

System	Conn	Peer
eby4.neoplus.adsl.tpnet.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	312	69.0%
2	pl	140	31.0%
TOTAL: 2		452	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	70
3	pl.comp.os.ms-windows.winnt	32
4	pl.misc.paranauki	20
5	pl.listserv.chomor-l	16
6	pl.regionalne.lodz	2
TOTAL: 6		452

Daily Usenet report

Aug 28 04:15:02 -- Aug 29 04:15:02