Daily Usenet report: Jan 16 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Jan 16 08:32:42 newsfeed nnrpd[10641]: SERVER perl filter not defined
Jan 16 13:06:54 newsfeed nnrpd[19576]: SERVER perl filter not defined
Jan 16 13:16:13 newsfeed nnrpd[19902]: SERVER perl filter not defined
Jan 16 13:54:30 newsfeed nnrpd[21139]: SERVER perl filter not defined
Jan 16 13:57:05 newsfeed nnrpd[21209]: SERVER perl filter not defined
Jan 16 14:30:21 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 16 14:42:21 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 16 15:33:24 newsfeed nnrpd[24496]: SERVER perl filter not defined
Jan 16 16:08:20 newsfeed nnrpd[25586]: SERVER perl filter not defined
Jan 16 18:10:10 newsfeed nnrpd[29713]: SERVER perl filter not defined
Jan 16 18:36:00 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 16 20:36:00 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 16 22:36:00 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 16 22:38:48 newsfeed nnrpd[6060]: SERVER perl filter not defined
Jan 17 00:36:00 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 17 02:36:00 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2066559	98.4%	247.4 MB	98.0%
innd	16620	0.8%	2.8 MB	1.1%
nnrpd	11061	0.5%	1.1 MB	0.4%
innfeed	6932	0.3%	1.0 MB	0.4%
TOTAL: 4	2101172	100.0%	252.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2059993	95.7%
Negative hits	49980	2.3%
Positive hits	41603	1.9%
Cache misses	339	0.0%
TOTAL: 4	2151915	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:17.488	0.0%	410	0.000	42.654	706.000
article cleanup	00:00:27.807	0.0%	2066861	0.002	0.013	0.026
article logging	00:00:57.480	0.1%	2067107	0.005	0.028	0.553
article parse	00:58:50.759	4.1%	151702590	0.004	0.023	0.066
article write	00:15:19.361	1.1%	2058741	0.206	0.447	1.180
artlog/artcncl	00:00:00.010	0.0%	409	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:04:10.690	0.3%	164015540	0.000	0.002	0.005
hisgrep/artcncl	00:00:03.342	0.0%	352	0.000	9.494	292.000
hishave/artcncl	00:00:01.221	0.0%	410	0.000	2.978	268.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:16:47.524	13.7%	2152687	0.259	5.485	15.587
history sync	00:00:00.013	0.0%	292	0.000	0.045	1.000
history write	03:41:17.252	15.4%	2061033	1.378	6.442	22.480
hiswrite/artcncl	00:00:00.392	0.0%	58	0.000	6.759	283.000
idle	09:47:12.099	40.8%	101500926	0.133	0.347	1.114
nntp read	01:02:33.780	4.3%	150085734	0.005	0.025	0.045
overview write	00:00:12.862	0.0%	2058741	0.000	0.006	0.016
perl filter	04:07:26.795	17.2%	2061027	3.258	7.204	10.949
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:17.969	0.3%	4159235	0.004	0.062	0.093
TOTAL: 24:00:33.685	23:19:56.844	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.139	0.0%	44402	0.000	0.003	1.000
article prepare	00:00:00.008	0.0%	43166	0.000	0.000	0.042
article read	00:00:01.799	0.0%	10506	0.000	0.171	4.692
backlog stats	00:00:00.179	0.0%	249606	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.615	0.0%	152333	0.000	0.011	0.073
data write	00:00:04.232	0.0%	115556	0.000	0.037	14.000
idle	23:59:42.570	100.0%	249606	0.000	346.076	9581.000
status file	00:00:01.126	0.0%	247	0.000	4.559	217.500
TOTAL: 23:59:57.098	23:59:51.668	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.817	0.0%	2520	0.324	0.324	0.324
idle	85:07:43.841	100.0%	219325	0.000	1397.305	450185.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.996	0.0%	441017	0.000	0.016	8.444
readart	00:00:01.696	0.0%	2814	0.000	0.603	15.000
TOTAL: 85:08:40.359	85:07:53.350	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	540	2052145	2045850	808	5487	99%	304:04:20
2	news.task.gda.pl	15	30442	6561	22417	1464	21%	45:31:09
3	news.nask.pl	2	18585	5397	12041	1147	29%	24:14:49
4	localhost	10	9	9	0	0	100%	00:00:00
5	news.icm.edu.pl	19	120	0	119	1	0%	09:11:47
TOTAL: 5		586	2101301	2057817	35385	8099	97%	383:02:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	549.2 GB	228.6 MB	272.3 MB	549.7 GB	99%	281.0 KB
2	news.nask.pl	19.7 MB	3.1 MB	3.1 MB	25.9 MB	76%	4.0 KB
3	news.task.gda.pl	13.6 MB	49.2 KB	4.8 MB	18.4 MB	73%	2.4 KB
4	localhost	13.0 KB	0.0 KB	0.0 KB	13.0 KB	100%	1.4 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	11.2 KB	11.2 KB	0%	11.2 KB
TOTAL: 5		549.2 GB	231.7 MB	280.2 MB	549.7 GB	99%	279.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 16 04:15:02 - 04:59:59	71767	3.5%	26.60	20.1 GB	3.7%	7824.05
Jan 16 05:00:00 - 05:59:59	103439	5.0%	28.73	29.5 GB	5.4%	8578.49
Jan 16 06:00:00 - 06:59:59	105840	5.1%	29.40	30.6 GB	5.6%	8901.89
Jan 16 07:00:00 - 07:59:59	81108	3.9%	22.53	26.0 GB	4.7%	7575.42
Jan 16 08:00:00 - 08:59:59	76644	3.7%	21.29	29.0 GB	5.3%	8450.91
Jan 16 09:00:00 - 09:59:59	107949	5.2%	29.99	28.9 GB	5.3%	8413.79
Jan 16 10:00:00 - 10:59:59	102125	5.0%	28.37	27.5 GB	5.0%	8004.37
Jan 16 11:00:00 - 11:59:59	88303	4.3%	24.53	24.2 GB	4.4%	7059.08
Jan 16 12:00:00 - 12:59:59	101560	4.9%	28.21	27.1 GB	4.9%	7897.84
Jan 16 13:00:00 - 13:59:59	94494	4.6%	26.25	24.5 GB	4.5%	7146.86
Jan 16 14:00:00 - 14:59:59	74267	3.6%	20.63	18.1 GB	3.3%	5257.90
Jan 16 15:00:00 - 15:59:59	80207	3.9%	22.28	19.0 GB	3.5%	5539.64
Jan 16 16:00:00 - 16:59:59	73675	3.6%	20.47	16.5 GB	3.0%	4818.85
Jan 16 17:00:00 - 17:59:59	77775	3.8%	21.60	18.7 GB	3.4%	5433.04
Jan 16 18:00:00 - 18:59:59	77417	3.8%	21.50	17.7 GB	3.2%	5149.41
Jan 16 19:00:00 - 19:59:59	68706	3.3%	19.09	16.5 GB	3.0%	4797.41
Jan 16 20:00:00 - 20:59:59	68511	3.3%	19.03	17.4 GB	3.2%	5082.53
Jan 16 21:00:00 - 21:59:59	71696	3.5%	19.92	16.2 GB	3.0%	4730.14
Jan 16 22:00:00 - 22:59:59	72822	3.5%	20.23	16.4 GB	3.0%	4791.25
Jan 16 23:00:00 - 23:59:59	73090	3.6%	20.30	16.7 GB	3.0%	4861.96
Jan 17 00:00:00 - 00:59:59	85430	4.2%	23.73	23.8 GB	4.3%	6927.01
Jan 17 01:00:00 - 01:59:59	85160	4.1%	23.66	23.7 GB	4.3%	6911.13
Jan 17 02:00:00 - 02:59:59	97366	4.7%	27.05	28.9 GB	5.3%	8406.80
Jan 17 03:00:00 - 03:59:59	95312	4.6%	26.48	26.3 GB	4.8%	7671.00
Jan 17 04:00:00 - 04:15:02	23117	1.1%	25.63	6.2 GB	1.1%	7183.52
TOTAL: 24:00:00	2057780	100.0%	23.82	549.6 GB	100.0%	6669.60

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5704	190	4671	3	840
2	news.task.gda.pl	1499	1287	30	0	182
3	news.nask.pl	1166	5	1131	7	23
4	news.icm.edu.pl	1	0	0	0	1
TOTAL: 4		8370	1482	5832	10	1046

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	123
mailing.freebsd.svn-ports-head	115
fa.freebsd.cvs-all	86
mailing.freebsd.svn-src-all	85
uk.railway	79
mailing.freebsd.ports-bugs	77
alt.windows7.general	71
mailing.openbsd.misc	62
it.tlc.cellulari.android	54
mozilla.dev.tree-management	52
fr.usenet.abus.nocem	50
mailing.postfix.users	49
mailing.unix.samba	46
mailing.gcc.gcc-cvs	35
24hoursupport.helpdesk	35
gmane.linux.alsa.devel	33
mailing.database.pgsql-general	26
mailing.openbsd.ports-changes	25
alt.politics.scorched-earth	25
mailing.freebsd.svn-src-head	25
TOTAL: 77	1482

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

Groups	Count
pl.sci.matematyka	8
pl.sci.historia	2
TOTAL: 2	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	619
EMP (fsl)	139
Attached HTML file	20
HTML post	11
EMP (md5)	3
Scoring filter (8)	2
TOTAL: 6	794

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	23289	6592	10414	1443	16	25966	28%	24:06:46
2	panorama	44154	2318	39021	463	0	0	5%	24:01:30
3	poznan	14055	32	11824	8	0	0	0%	24:02:32
4	task	2205	18	1177	4	0	1	0%	22:12:50
5	nask	21065	8	13825	0	0	0	0%	24:00:27
6	okapi	0	0	0	0	0	2680	0%	24:00:01
TOTAL: 6		104768	8968	76261	1918	16	28647	8%	142:24:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	453.8 MB	30.2 MB	484.0 MB	5.7 KB/s	61.7 KB	24:06:46
2	panorama	7.6 MB	1.6 MB	9.2 MB	0.1 KB/s	3.4 KB	24:01:30
3	poznan	227.6 KB	25.7 KB	253.3 KB	0.0 KB/s	6.3 KB	24:02:32
4	nask	46.6 KB	0.0 KB	46.6 KB	0.0 KB/s	5.8 KB	24:00:27
5	task	40.1 KB	96.1 KB	136.2 KB	0.0 KB/s	6.2 KB	22:12:50
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		461.7 MB	31.9 MB	493.6 MB	1.0 KB/s	46.4 KB	142:24:06

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	384	551.8 KB	288	0	00:00:11
2	212.191.78.190	310	68	111.7 KB	27	1	47:28:17
3	web.man.lodz.pl	288	54	46.8 KB	25	0	00:00:27
4	router2.cbmm.lodz.pl	1	9	15.7 KB	2	0	00:03:49
5	ip-176.105.135.22.tvsat364.lodz.pl	2	3	3.8 KB	2	0	00:34:26
6	kujawiak.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 6		897	518	729.8 KB	344	9	85:08:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	384	551.8 KB	288	0	00:00:11
2	unresolved	310	68	111.7 KB	27	1	47:28:17
3	*.man.lodz.pl	680	54	46.8 KB	25	8	00:00:35
4	*.cbmm.lodz.pl	1	9	15.7 KB	2	0	00:03:49
5	*.105.135.22.tvsat364.lodz.pl	2	3	3.8 KB	2	0	00:34:26
6	*.pa.nsw.optusnet.com.au	92	0	0.0 KB	0	0	00:02:30
7	*.zsk.p.lodz.pl	1	0	0.0 KB	0	0	00:02:44
8	*.wroclaw.mm.pl	84	0	0.0 KB	0	0	12:36:11
9	*.191.14.62.static.jazztel.es	19	0	0.0 KB	0	0	24:19:24
10	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:11
11	*.toya.net.pl	62	0	0.0 KB	0	0	00:00:15
TOTAL: 11		1540	518	729.8 KB	344	9	85:08:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.728	3.712	47:28:17
202.191.14.62.static.jazztel.es	11.352	15.768	24:19:24
host-89-231-50-15.wroclaw.mm.pl	0.192	0.992	12:36:11
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.028	00:34:26
router2.cbmm.lodz.pl	0.000	0.020	00:03:49
pc26.zsk.p.lodz.pl	0.008	0.008	00:02:44
pa49-181-246-60.pa.nsw.optusnet.com.au	0.184	1.108	00:02:30
web.man.lodz.pl	0.688	3.456	00:00:27
staticline41435.toya.net.pl	0.152	0.824	00:00:15
ccz61.neoplus.adsl.tpnet.pl	0.000	0.016	00:00:11
vegas.cm.p.lodz.pl	0.576	3.628	00:00:11
mon.man.lodz.pl	0.628	3.692	00:00:04
kujawiak.man.lodz.pl	0.040	0.124	00:00:01
david.man.lodz.pl	0.180	1.028	00:00:01
TOTAL: 14	14.732	34.404	85:08:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
pa49-181-246-60.pa.nsw.optusnet.com.au	92
host-89-231-50-15.wroclaw.mm.pl	84
staticline41435.toya.net.pl	62
202.191.14.62.static.jazztel.es	19
pc26.zsk.p.lodz.pl	1
ccz61.neoplus.adsl.tpnet.pl	1
TOTAL: 8	643

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	1	0
202.191.14.62.static.jazztel.es	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	384	74.1%
2	pl	133	25.7%
3	alt	1	0.2%
TOTAL: 3		518	100.0%

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

Newsgroup		Count
1	lodman.info	384
2	pl.comp.pecet	40
3	pl.misc.kolej	25
4	pl.regionalne.lodz	17
5	pl.comp.os.ms-windows.winnt	14
6	pl.comp.os.linux	13
7	pl.soc.prawo	10
8	pl.rec.rowery	8
9	pl.regionalne.warszawa	2
10	pl.sci.chemia	2
11	pl.praca.dyskusje	1
12	pl.rec.gory	1
13	alt.pl.praca.oferowana	1
TOTAL: 13		518

Daily Usenet report

Jan 16 04:15:02 -- Jan 17 04:15:02