Daily Usenet report: Nov 12 04:15:04

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Nov 12 05:03:07 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 12 10:17:39 newsfeed nnrpd[28686]: SERVER perl filter not defined
Nov 12 10:47:42 newsfeed nnrpd[29635]: SERVER perl filter not defined
Nov 12 10:56:04 newsfeed nnrpd[29867]: SERVER perl filter not defined
Nov 12 17:05:51 newsfeed nnrpd[9592]: SERVER perl filter not defined
Nov 12 17:56:47 newsfeed nnrpd[11219]: SERVER perl filter not defined
Nov 12 19:58:24 newsfeed overchan[2136]: input line too long, skipping
Nov 12 21:52:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 12 21:58:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 12 22:22:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 12 22:58:03 newsfeed pgpverify[21126]: pgpverify: verification failed
Nov 12 22:58:03 newsfeed controlchan[2134]: skipping newgroup y control@usenet-fr.news.eu.org (pgpverify failed) in <newgroup-fr.comp.os.firefox-os-1384293371@usenet-fr.news.eu.org>
Nov 12 22:58:07 newsfeed pgpverify[21132]: pgpverify: verification failed
Nov 12 22:58:07 newsfeed controlchan[2134]: skipping newgroup y control@usenet-fr.news.eu.org (pgpverify failed) in <newgroup-fr.comp.os.windows-phone-1384293381@usenet-fr.news.eu.org>
Nov 12 23:12:52 newsfeed pgpverify[21717]: pgpverify: verification failed
Nov 12 23:54:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 13 01:54:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable
Nov 13 03:54:40 newsfeed innfeed[2135]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2339865	98.4%	266.4 MB	98.0%
innd	19620	0.8%	3.2 MB	1.2%
nnrpd	10387	0.4%	1.1 MB	0.4%
innfeed	6894	0.3%	1.0 MB	0.4%
controlchan	5	0.0%	0.6 KB	0.0%
pgpverify	3	0.0%	0.5 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	2376775	100.0%	271.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2333402	94.4%
Negative hits	97798	4.0%
Positive hits	41531	1.7%
Cache misses	389	0.0%
TOTAL: 4	2473120	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.602	0.0%	359	0.000	32.318	800.000
article cleanup	00:00:31.162	0.0%	2338583	0.005	0.013	0.024
article logging	00:01:09.781	0.1%	2338706	0.015	0.030	0.322
article parse	01:07:52.390	4.7%	61196981	0.023	0.067	0.092
article write	00:21:16.998	1.5%	2331728	0.316	0.548	1.299
artlog/artcncl	00:00:00.003	0.0%	337	0.000	0.009	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:44.967	0.1%	74428864	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.184	0.0%	261	0.000	8.368	240.000
hishave/artcncl	00:00:01.175	0.0%	359	0.000	3.273	90.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:38:59.895	11.0%	2473781	1.943	3.856	7.602
history sync	00:00:00.071	0.0%	292	0.000	0.243	37.000
history write	05:09:12.264	21.5%	2334119	5.376	7.948	53.964
hiswrite/artcncl	00:00:00.545	0.0%	98	0.000	5.561	237.000
idle	07:41:06.123	32.0%	25146650	0.199	1.100	3.089
nntp read	00:33:50.478	2.4%	59292692	0.015	0.034	0.041
overview write	00:00:14.977	0.0%	2331728	0.001	0.006	0.014
perl filter	05:58:47.983	24.9%	2334119	6.347	9.223	10.963
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:48.251	0.3%	4709168	0.029	0.061	0.085
TOTAL: 23:59:23.837	23:39:50.849	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.193	0.0%	50866	0.000	0.004	0.125
article prepare	00:00:00.011	0.0%	46638	0.000	0.000	0.042
article read	00:00:08.914	0.0%	11433	0.000	0.780	120.909
backlog stats	00:00:00.314	0.0%	327178	0.000	0.001	0.043
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.366	0.0%	181341	0.000	0.013	1.000
data write	00:00:10.521	0.0%	173144	0.000	0.061	2.000
idle	23:59:29.670	100.0%	327178	0.000	263.984	16095.000
status file	00:00:01.803	0.0%	236	0.000	7.640	235.000
TOTAL: 24:00:02.819	23:59:53.792	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	50:33:37.781	100.0%	6761	0.000	26921.725	450332.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:25.632	0.0%	197394	0.000	0.130	5.875
readart	00:00:06.739	0.0%	626	0.000	10.765	250.000
TOTAL: 50:34:54.147	50:34:10.152	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet-fr.news.eu.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	977	2324942	2317081	4096	3765	99%	445:51:22
2	news.nask.pl	2	17703	7164	9004	1535	40%	23:47:14
3	news.task.gda.pl	19	31728	6878	23384	1466	21%	45:00:01
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	35	305	1	242	62	0%	13:31:16
TOTAL: 5		1039	2374683	2331129	36726	6828	98%	528:09:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	804.7 GB	134.5 MB	156.8 MB	805.0 GB	99%	363.7 KB
2	news.nask.pl	28.1 MB	6.0 MB	16.3 MB	50.4 MB	55%	5.9 KB
3	news.task.gda.pl	14.3 MB	45.2 KB	4.2 MB	18.5 MB	77%	2.3 KB
4	news.icm.edu.pl	12.2 KB	8.3 KB	4.2 MB	4.2 MB	0%	68.7 KB
5	localhost	6.7 KB	0.0 KB	0.0 KB	6.7 KB	100%	1.3 KB
TOTAL: 5		804.7 GB	140.5 MB	181.5 MB	805.0 GB	99%	361.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 12 04:15:04 - 04:59:59	71002	3.0%	26.34	24.9 GB	3.1%	9670.20
Nov 12 05:00:00 - 05:59:59	104447	4.5%	29.01	36.8 GB	4.6%	10708.13
Nov 12 06:00:00 - 06:59:59	95428	4.1%	26.51	31.9 GB	4.0%	9284.17
Nov 12 07:00:00 - 07:59:59	77629	3.3%	21.56	25.4 GB	3.2%	7409.24
Nov 12 08:00:00 - 08:59:59	89593	3.8%	24.89	29.5 GB	3.7%	8596.77
Nov 12 09:00:00 - 09:59:59	100657	4.3%	27.96	36.0 GB	4.5%	10476.97
Nov 12 10:00:00 - 10:59:59	96432	4.1%	26.79	31.6 GB	3.9%	9194.84
Nov 12 11:00:00 - 11:59:59	89104	3.8%	24.75	30.7 GB	3.8%	8956.34
Nov 12 12:00:00 - 12:59:59	87161	3.7%	24.21	32.2 GB	4.0%	9386.80
Nov 12 13:00:00 - 13:59:59	88679	3.8%	24.63	32.5 GB	4.0%	9455.02
Nov 12 14:00:00 - 14:59:59	119327	5.1%	33.15	37.0 GB	4.6%	10787.10
Nov 12 15:00:00 - 15:59:59	115778	5.0%	32.16	36.7 GB	4.6%	10686.24
Nov 12 16:00:00 - 16:59:59	124026	5.3%	34.45	38.4 GB	4.8%	11174.48
Nov 12 17:00:00 - 17:59:59	105617	4.5%	29.34	36.4 GB	4.5%	10600.40
Nov 12 18:00:00 - 18:59:59	105324	4.5%	29.26	36.0 GB	4.5%	10498.79
Nov 12 19:00:00 - 19:59:59	106508	4.6%	29.59	37.1 GB	4.6%	10804.44
Nov 12 20:00:00 - 20:59:59	105447	4.5%	29.29	36.5 GB	4.5%	10623.79
Nov 12 21:00:00 - 21:59:59	103389	4.4%	28.72	36.8 GB	4.6%	10724.56
Nov 12 22:00:00 - 22:59:59	99072	4.2%	27.52	34.6 GB	4.3%	10089.65
Nov 12 23:00:00 - 23:59:59	97217	4.2%	27.00	34.4 GB	4.3%	10017.22
Nov 13 00:00:00 - 00:59:59	78015	3.3%	21.67	27.9 GB	3.5%	8139.91
Nov 13 01:00:00 - 01:59:59	87356	3.7%	24.27	31.1 GB	3.9%	9059.81
Nov 13 02:00:00 - 02:59:59	84737	3.6%	23.54	31.5 GB	3.9%	9184.53
Nov 13 03:00:00 - 03:59:59	81173	3.5%	22.55	31.4 GB	3.9%	9148.32
Nov 13 04:00:00 - 04:15:03	19434	0.8%	21.52	7.1 GB	0.9%	8206.08
TOTAL: 23:59:59	2332552	100.0%	27.00	804.4 GB	100.0%	9762.94

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3867	240	2997	5	625
2	news.nask.pl	1555	27	1439	6	83
3	news.task.gda.pl	1492	1313	25	1	153
4	news.icm.edu.pl	62	0	1	0	61
TOTAL: 4		6976	1580	4462	12	922

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	114
mailing.freebsd.svn-ports-head	114
uk.railway	107
mailing.freebsd.ports-bugs	93
alt.windows7.general	91
mailing.openbsd.source-changes	63
mailing.openbsd.ports-changes	50
fr.comp.os.android	50
mailing.postfix.users	47
alt.politics.scorched-earth	46
mailing.freebsd.svn-src-all	42
fa.freebsd.cvs-all	42
mailing.gcc.gcc-cvs	38
mozilla.dev.tree-management	38
mailing.freebsd.current	37
mailing.freebsd.svn-src-head	35
24hoursupport.helpdesk	33
it.tlc.cellulari.android	32
mailing.unix.samba	29
mailing.database.pgsql-general	25
TOTAL: 86	1580

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

Groups	Count
pl.sci.historia	11
fr.comp.securite	1
TOTAL: 2	12

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	574
EMP (fsl)	127
EMP (md5)	70
HTML post	25
Attached HTML file	1
Scoring filter (12)	1
Scoring filter (10)	1
TOTAL: 7	799

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46702	3422	41370	839	0	0	7%	23:53:52
2	icm	51947	1767	31004	5486	1	11358	3%	24:01:33
3	poznan	15994	45	13616	13	0	0	0%	23:51:48
4	task	2692	31	1423	3	0	0	1%	22:07:09
5	nask	18345	10	13095	9	0	0	0%	24:06:45
6	okapi	0	0	0	0	0	2991	0%	24:00:00
TOTAL: 6		135680	5275	100508	6350	1	14349	3%	142:01:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	33.3 MB	2.9 GB	2.9 GB	35.4 KB/s	421.7 KB	24:01:33
2	panorama	11.4 MB	2.9 MB	14.4 MB	0.2 KB/s	3.5 KB	23:53:52
3	poznan	111.4 KB	26.4 KB	137.8 KB	0.0 KB/s	2.4 KB	23:51:48
4	task	59.8 KB	5.4 KB	65.3 KB	0.0 KB/s	1.9 KB	22:07:09
5	nask	14.1 KB	10.9 MB	10.9 MB	0.1 KB/s	587.2 KB	24:06:45
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		44.9 MB	2.9 GB	2.9 GB	6.0 KB/s	265.3 KB	142:01:07

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	288	408.4 KB	288	0	00:00:21
2	212.191.78.190	299	168	325.0 KB	61	2	47:12:15
3	web.man.lodz.pl	288	146	147.6 KB	57	0	00:00:26
4	pc24.zsk.p.lodz.pl	6	9	10.0 KB	2	1	00:11:28
5	router2.cbmm.lodz.pl	5	6	9.2 KB	3	1	00:58:43
6	ip-176.105.135.22.tvsat364.lodz.pl	5	5	6.5 KB	2	0	01:13:20
7	212.51.203.199	2	3	7.3 KB	1	0	00:01:23
8	pc26.zsk.p.lodz.pl	6	1	0.7 KB	1	1	00:50:45
TOTAL: 8		899	626	914.7 KB	415	5	50:34:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	288	408.4 KB	288	0	00:00:21
2	unresolved	303	171	332.3 KB	62	2	47:17:27
3	*.man.lodz.pl	673	146	147.6 KB	57	0	00:00:30
4	*.zsk.p.lodz.pl	12	10	10.7 KB	3	2	01:02:14
5	*.cbmm.lodz.pl	5	6	9.2 KB	3	1	00:58:43
6	*.105.135.22.tvsat364.lodz.pl	5	5	6.5 KB	2	0	01:13:20
7	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:01:02
8	*.centertel.pl	9	0	0.0 KB	0	0	00:00:04
9	*.toya.net.pl	61	0	0.0 KB	0	0	00:01:08
TOTAL: 9		1357	626	914.7 KB	415	5	50:34:53

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.772	5.580	47:12:15
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.092	01:13:20
router2.cbmm.lodz.pl	0.004	0.112	00:58:43
pc26.zsk.p.lodz.pl	0.308	0.204	00:50:45
pc24.zsk.p.lodz.pl	0.616	0.368	00:11:28
87.204.176.53	0.068	0.040	00:03:48
212.51.203.199	0.000	0.044	00:01:23
staticline-31-182-71-141.toya.net.pl	0.028	0.028	00:01:04
ebs49.neoplus.adsl.tpnet.pl	0.000	0.020	00:01:02
web.man.lodz.pl	0.680	5.072	00:00:26
vegas.cm.p.lodz.pl	0.704	5.308	00:00:21
staticline41435.toya.net.pl	0.108	0.972	00:00:04
public-gprs156890.centertel.pl	0.056	0.160	00:00:04
f-oper-v100.man.lodz.pl	0.620	5.004	00:00:03
david.man.lodz.pl	0.228	1.704	00:00:01
TOTAL: 15	4.200	24.708	50:34:53

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	60
public-gprs156890.centertel.pl	9
87.204.176.53	2
staticline-31-182-71-141.toya.net.pl	1
ebs49.neoplus.adsl.tpnet.pl	1
TOTAL: 7	458

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	338	54.0%
2	lodman	288	46.0%
TOTAL: 2		626	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.pecet	118
3	pl.misc.kolej	52
4	pl.rec.rowery	50
5	pl.soc.prawo	31
6	pl.comp.os.ms-windows.winnt	28
7	pl.regionalne.lodz	15
8	pl.regionalne.warszawa	14
9	pl.comp.os.linux	13
10	pl.test	10
11	pl.rec.radio.amatorskie	3
12	pl.praca.dyskusje	2
13	pl.sci.chemia	2
TOTAL: 13		626

Daily Usenet report

Nov 12 04:15:04 -- Nov 13 04:15:03