Daily Usenet report: Oct 14 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Oct 14 07:41:44 newsfeed overchan[2219]: input line too long, skipping
Oct 14 07:53:26 newsfeed overchan[2219]: input line too long, skipping
Oct 14 08:02:05 newsfeed overchan[2219]: input line too long, skipping
Oct 14 08:16:45 newsfeed overchan[2219]: input line too long, skipping
Oct 14 17:13:19 newsfeed nnrpd[3636]: SERVER perl filter not defined
Oct 14 18:46:44 newsfeed overchan[2219]: input line too long, skipping
Oct 14 22:24:32 newsfeed overchan[2219]: input line too long, skipping
Oct 15 01:19:21 newsfeed overchan[2219]: input line too long, skipping
Oct 15 02:57:43 newsfeed overchan[2219]: input line too long, skipping
Oct 15 03:37:54 newsfeed overchan[2219]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1473939	98.1%	168.0 MB	97.6%
innd	13979	0.9%	2.3 MB	1.3%
nnrpd	7839	0.5%	829.4 KB	0.5%
innfeed	7050	0.5%	1.0 MB	0.6%
overchan	9	0.0%	1.3 KB	0.0%
TOTAL: 5	1502816	100.0%	172.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1467900	93.9%
Negative hits	52408	3.4%
Positive hits	42105	2.7%
Cache misses	353	0.0%
TOTAL: 4	1562766	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.882	0.0%	383	0.000	23.191	581.000
article cleanup	00:00:20.122	0.0%	1473701	0.002	0.014	0.031
article logging	00:00:54.909	0.1%	1473830	0.014	0.037	0.757
article parse	00:41:48.367	2.9%	49603470	0.018	0.051	0.098
article write	00:16:10.030	1.1%	1467713	0.388	0.661	1.642
artlog/artcncl	00:00:00.010	0.0%	379	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:06.990	0.1%	58412223	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.956	0.0%	295	0.000	6.631	135.500
hishave/artcncl	00:00:02.346	0.0%	383	0.000	6.125	288.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:34:20.756	10.7%	1564549	0.153	5.919	59.125
history sync	00:00:00.018	0.0%	291	0.000	0.062	1.000
history write	03:30:38.927	14.6%	1469776	1.274	8.599	43.480
hiswrite/artcncl	00:00:00.618	0.0%	88	0.000	7.023	80.500
idle	12:22:09.601	51.5%	32464816	0.168	1.372	3.744
nntp read	00:25:56.630	1.8%	48485004	0.016	0.032	0.052
overview write	00:00:09.562	0.0%	1467713	0.000	0.007	0.019
perl filter	03:44:35.074	15.6%	1469774	5.911	9.168	12.344
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:53.734	0.2%	2976529	0.025	0.058	0.093
TOTAL: 24:00:08.459	23:41:18.532	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.175	0.0%	41107	0.000	0.004	0.500
article prepare	00:00:00.013	0.0%	51678	0.000	0.000	0.062
article read	00:00:00.915	0.0%	12368	0.000	0.074	1.500
backlog stats	00:00:00.184	0.0%	315056	0.000	0.001	0.014
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.170	0.0%	187876	0.000	0.012	1.000
data write	00:00:05.448	0.0%	158000	0.000	0.034	1.000
idle	23:59:35.060	100.0%	315056	0.000	274.158	11857.000
status file	00:00:02.404	0.0%	222	0.000	10.829	231.500
TOTAL: 23:59:52.992	23:59:46.369	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:45:58.967	99.9%	212049	0.000	284.646	527020.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.962	0.0%	426207	0.000	0.016	9.559
readart	00:00:01.090	0.0%	457	0.000	2.385	295.000
TOTAL: 16:46:41.153	16:46:07.019	99.9%	-	-	-	-

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	645	1461271	1457019	666	3586	99%	268:57:14
2	news.task.gda.pl	12	30798	6521	23069	1208	21%	44:09:52
3	news.nask.pl	2	18755	3973	13657	1125	21%	24:11:00
4	news.icm.edu.pl	45	325	14	290	21	4%	18:44:59
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		706	1511150	1467528	37682	5940	97%	356:03:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	506.7 GB	11.3 MB	35.7 MB	506.7 GB	99%	363.8 KB
2	news.nask.pl	14.7 MB	3.0 MB	3.1 MB	20.7 MB	70%	4.2 KB
3	news.task.gda.pl	13.7 MB	74.2 KB	3.6 MB	17.4 MB	78%	2.3 KB
4	news.icm.edu.pl	813.5 KB	18.5 KB	352.7 KB	1.2 MB	68%	33.8 KB
5	localhost	1.1 KB	0.0 KB	0.0 KB	1.1 KB	100%	1.1 KB
TOTAL: 5		506.7 GB	14.4 MB	42.7 MB	506.7 GB	99%	360.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 14 04:15:02 - 04:59:59	65760	4.5%	24.37	23.9 GB	4.7%	9298.98
Oct 14 05:00:00 - 05:59:59	74166	5.1%	20.60	26.4 GB	5.2%	7696.96
Oct 14 06:00:00 - 06:59:59	74827	5.1%	20.79	27.4 GB	5.4%	7969.89
Oct 14 07:00:00 - 07:59:59	89265	6.1%	24.80	32.9 GB	6.5%	9572.41
Oct 14 08:00:00 - 08:59:59	74767	5.1%	20.77	27.5 GB	5.4%	8004.11
Oct 14 09:00:00 - 09:59:59	71340	4.9%	19.82	25.6 GB	5.1%	7468.90
Oct 14 10:00:00 - 10:59:59	35732	2.4%	9.93	13.7 GB	2.7%	3976.25
Oct 14 11:00:00 - 11:59:59	36096	2.5%	10.03	13.3 GB	2.6%	3877.82
Oct 14 12:00:00 - 12:59:59	40413	2.8%	11.23	14.0 GB	2.8%	4091.16
Oct 14 13:00:00 - 13:59:59	42352	2.9%	11.76	14.5 GB	2.9%	4235.66
Oct 14 14:00:00 - 14:59:59	45251	3.1%	12.57	13.7 GB	2.7%	3975.90
Oct 14 15:00:00 - 15:59:59	44048	3.0%	12.24	13.7 GB	2.7%	3988.70
Oct 14 16:00:00 - 16:59:59	45968	3.1%	12.77	15.5 GB	3.1%	4526.08
Oct 14 17:00:00 - 17:59:59	42925	2.9%	11.92	13.7 GB	2.7%	3993.42
Oct 14 18:00:00 - 18:59:59	40022	2.7%	11.12	13.5 GB	2.7%	3931.66
Oct 14 19:00:00 - 19:59:59	46384	3.2%	12.88	15.3 GB	3.0%	4466.97
Oct 14 20:00:00 - 20:59:59	46490	3.2%	12.91	15.3 GB	3.0%	4450.69
Oct 14 21:00:00 - 21:59:59	45500	3.1%	12.64	15.2 GB	3.0%	4416.74
Oct 14 22:00:00 - 22:59:59	38752	2.6%	10.76	12.8 GB	2.5%	3728.62
Oct 14 23:00:00 - 23:59:59	50407	3.4%	14.00	17.2 GB	3.4%	4997.74
Oct 15 00:00:00 - 00:59:59	95216	6.5%	26.45	31.4 GB	6.2%	9132.87
Oct 15 01:00:00 - 01:59:59	91270	6.2%	25.35	31.4 GB	6.2%	9159.19
Oct 15 02:00:00 - 02:59:59	92834	6.3%	25.79	31.0 GB	6.1%	9042.88
Oct 15 03:00:00 - 03:59:59	110949	7.6%	30.82	38.0 GB	7.5%	11082.18
Oct 15 04:00:00 - 04:15:02	26707	1.8%	29.61	9.7 GB	1.9%	11276.73
TOTAL: 24:00:00	1467441	100.0%	16.98	506.7 GB	100.0%	6149.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3709	282	2764	6	657
2	news.task.gda.pl	1246	1034	45	1	166
3	news.nask.pl	1143	8	1117	1	17
4	news.icm.edu.pl	21	0	1	0	20
TOTAL: 4		6119	1324	3927	8	860

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	130
mailing.freebsd.svn-ports-all	84
mailing.freebsd.svn-ports-head	84
mailing.gcc.gcc-cvs	68
alt.politics.scorched-earth	67
mailing.unix.samba	54
mailing.www.horde-bugs	49
mailing.freebsd.ports-bugs	45
mozilla.dev.tree-management	41
mailing.openbsd.ports-changes	38
alt.windows7.general	34
it.comp.os.win.windows8	32
it.tlc.cellulari.android	31
it.comp.os.linux.ubuntu	30
mailing.openbsd.source-changes	28
fr.usenet.abus.nocem	25
mailing.unix.samba-technical	24
mailing.freebsd.svn-src-all	22
fa.freebsd.cvs-all	22
mailing.freebsd.current	21
TOTAL: 76	1324

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

Groups	Count
pl.sci.historia	6
fr.comp.securite	1
bit.listserv.catholic	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	529
EMP (fsl)	134
EMP (md5)	33
Attached HTML file	27
HTML post	3
Scoring filter (12)	1
Scoring filter (10)	1
Scoring filter (8)	1
TOTAL: 8	729

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	58536	6585	28968	3502	9	0	11%	24:06:20
2	panorama	43196	2084	38470	621	0	0	4%	24:06:37
3	task	2659	59	1401	9	0	9	2%	20:56:49
4	poznan	11617	49	10211	15	0	0	0%	24:02:54
5	nask	23997	26	15602	0	0	0	0%	23:57:13
6	okapi	0	0	0	0	0	3181	0%	24:00:00
TOTAL: 6		140005	8803	94652	4147	9	3190	6%	141:09:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	32.0 MB	783.2 MB	815.2 MB	9.6 KB/s	82.8 KB	24:06:20
2	panorama	5.9 MB	2.6 MB	8.5 MB	0.1 KB/s	3.2 KB	24:06:37
3	poznan	152.7 KB	36.5 KB	189.1 KB	0.0 KB/s	3.0 KB	24:02:54
4	task	126.6 KB	102.4 KB	229.0 KB	0.0 KB/s	3.4 KB	20:56:49
5	nask	80.8 KB	0.0 KB	80.8 KB	0.0 KB/s	3.1 KB	23:57:13
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		38.3 MB	786.0 MB	824.2 MB	1.7 KB/s	65.2 KB	141:09:53

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	421.9 KB	288	0	00:00:09
2	web.man.lodz.pl	288	130	121.7 KB	56	0	00:00:20
3	212.191.78.190	41	25	55.5 KB	13	1	05:20:04
4	router2.cbmm.lodz.pl	4	8	16.9 KB	1	0	00:13:31
5	ip-176.105.135.22.tvsat364.lodz.pl	18	3	5.8 KB	2	0	03:30:34
6	212.51.203.199	2	3	4.2 KB	1	0	00:03:45
TOTAL: 6		641	457	626.0 KB	361	1	16:46:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	288	421.9 KB	288	0	00:00:09
2	*.man.lodz.pl	672	130	121.7 KB	56	0	00:00:27
3	unresolved	43	28	59.7 KB	14	1	05:23:50
4	*.cbmm.lodz.pl	4	8	16.9 KB	1	0	00:13:31
5	*.105.135.22.tvsat364.lodz.pl	18	3	5.8 KB	2	0	03:30:34
6	*.tvkdiana.pl	1	0	0.0 KB	0	0	00:08:16
7	*.191.14.62.static.jazztel.es	5	0	0.0 KB	0	0	07:29:44
8	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
9	*.toya.net.pl	103	0	0.0 KB	0	0	00:00:05
TOTAL: 9		1136	457	626.0 KB	361	1	16:46:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
16.191.14.62.static.jazztel.es	13.033	13.929	07:29:44
212.191.78.190	0.128	0.604	05:20:04
ip-176.105.135.22.tvsat364.lodz.pl	0.064	0.288	03:30:34
router2.cbmm.lodz.pl	0.016	0.076	00:13:31
host31-42-21-191.tvkdiana.pl	0.024	0.024	00:08:16
212.51.203.199	0.016	0.020	00:03:45
web.man.lodz.pl	0.836	4.708	00:00:20
vegas.cm.p.lodz.pl	0.532	4.464	00:00:09
f-oper-v100.man.lodz.pl	0.696	5.136	00:00:06
staticline41435.toya.net.pl	0.292	1.608	00:00:05
david.man.lodz.pl	0.248	1.560	00:00:01
gcq193.internetdsl.tpnet.pl	0.012	0.024	00:00:00
TOTAL: 12	15.897	32.441	16:46:40

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
staticline41435.toya.net.pl	103
david.man.lodz.pl	96
16.191.14.62.static.jazztel.es	5
gcq193.internetdsl.tpnet.pl	2
host31-42-21-191.tvkdiana.pl	1
TOTAL: 6	495

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	63.0%
2	pl	169	37.0%
TOTAL: 2		457	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.pecet	102
3	pl.comp.os.ms-windows.winnt	28
4	pl.rec.rowery	18
5	pl.regionalne.lodz	13
6	pl.praca.dyskusje	3
7	pl.rec.radio.amatorskie	3
8	pl.misc.kolej	2
TOTAL: 8		457

Daily Usenet report

Oct 14 04:15:02 -- Oct 15 04:15:02