Daily Usenet report: Jun 24 04:15:06

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jun 24 10:56:30 newsfeed nnrpd[27847]: SERVER perl filter not defined
Jun 24 10:56:52 newsfeed nnrpd[27849]: SERVER perl filter not defined
Jun 24 10:57:13 newsfeed nnrpd[27850]: SERVER perl filter not defined
Jun 24 10:58:56 newsfeed nnrpd[27873]: SERVER perl filter not defined
Jun 24 10:59:25 newsfeed nnrpd[27876]: SERVER perl filter not defined
Jun 24 11:00:09 newsfeed nnrpd[27964]: SERVER perl filter not defined
Jun 24 11:01:47 newsfeed nnrpd[28117]: SERVER perl filter not defined
Jun 24 11:03:20 newsfeed nnrpd[28124]: SERVER perl filter not defined
Jun 24 13:03:22 newsfeed nnrpd[32144]: SERVER perl filter not defined
Jun 24 13:10:55 newsfeed nnrpd[32325]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1102106	97.2%	125.2 MB	96.6%
innd	17086	1.5%	2.6 MB	2.0%
nnrpd	7739	0.7%	824.7 KB	0.6%
innfeed	7183	0.6%	1.0 MB	0.8%
controlchan	2	0.0%	0.3 KB	0.0%
TOTAL: 5	1134116	100.0%	129.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1097625	86.6%
Negative hits	68639	5.4%
Cache misses	60006	4.7%
Positive hits	41013	3.2%
TOTAL: 4	1267283	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.866	0.0%	439	0.000	20.196	804.000
article cleanup	00:00:20.128	0.0%	1101824	0.002	0.018	0.044
article logging	00:00:42.422	0.0%	1101980	0.008	0.038	0.763
article parse	00:46:11.463	3.2%	137877492	0.011	0.020	0.077
article write	00:11:54.332	0.8%	1089913	0.277	0.655	11.394
artlog/artcncl	00:00:00.010	0.0%	366	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:11.193	0.2%	145698228	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.062	0.0%	327	0.000	3.248	198.000
hishave/artcncl	00:00:00.740	0.0%	439	0.000	1.686	142.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:27:54.767	6.1%	1267846	0.021	4.160	12.976
history sync	00:00:00.026	0.0%	292	0.000	0.089	2.000
history write	02:05:44.964	8.7%	1098280	3.211	6.870	121.680
hiswrite/artcncl	00:00:01.028	0.0%	112	0.000	9.179	279.000
idle	14:46:59.032	61.6%	120518209	0.212	0.442	4.744
nntp read	00:54:58.704	3.8%	137277949	0.014	0.024	0.047
overview write	00:00:07.997	0.0%	1089913	0.000	0.007	0.024
perl filter	02:52:04.412	11.9%	1098279	4.721	9.401	12.040
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:50.146	0.2%	2234398	0.029	0.076	0.156
TOTAL: 24:00:39.478	23:12:11.292	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.264	0.0%	57130	0.000	0.005	1.000
article prepare	00:00:00.007	0.0%	30860	0.000	0.000	0.083
article read	00:00:00.761	0.0%	7335	0.000	0.104	1.000
backlog stats	00:00:00.324	0.0%	413878	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:03.323	0.0%	251672	0.000	0.013	1.000
data write	00:00:07.853	0.0%	211343	0.000	0.037	0.667
idle	23:59:27.788	100.0%	413878	0.000	208.679	13309.000
status file	00:00:00.420	0.0%	201	0.000	2.090	19.125
TOTAL: 23:59:50.473	23:59:40.740	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:39:59.470	99.9%	8890	0.000	2699.603	600037.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.511	0.0%	19800	0.000	0.026	2.000
readart	00:00:01.869	0.0%	474	0.000	3.943	232.000
TOTAL: 06:40:29.514	06:40:01.850	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ba-mod@panix.com	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	368	1072254	1063858	1142	7254	99%	154:38:58
2	news.task.gda.pl	32	103751	21049	79196	3506	20%	34:24:55
3	news.nask.pl	2	20850	4294	15481	1075	20%	23:54:24
4	localhost	11	10	10	0	0	100%	00:00:01
5	news.icm.edu.pl	42	326	1	281	44	0%	19:28:49
TOTAL: 5		455	1197191	1089212	96100	11879	90%	232:27:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	375.4 GB	15.3 MB	526.6 MB	375.9 GB	99%	368.0 KB
2	news.task.gda.pl	76.6 MB	43.3 KB	23.9 MB	100.5 MB	76%	4.2 KB
3	news.nask.pl	23.1 MB	2.7 MB	2.9 MB	28.7 MB	80%	5.5 KB
4	news.icm.edu.pl	102.0 KB	0.0 KB	611.7 KB	713.7 KB	14%	15.9 KB
5	localhost	18.3 KB	0.0 KB	0.0 KB	18.3 KB	100%	1.8 KB
TOTAL: 5		375.5 GB	18.0 MB	554.1 MB	376.0 GB	99%	358.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 24 04:15:06 - 04:59:59	40408	3.7%	15.00	15.8 GB	4.2%	6147.96
Jun 24 05:00:00 - 05:59:59	31142	2.9%	8.65	12.8 GB	3.4%	3738.62
Jun 24 06:00:00 - 06:59:59	38569	3.5%	10.71	16.2 GB	4.3%	4707.42
Jun 24 07:00:00 - 07:59:59	43071	4.0%	11.96	17.2 GB	4.6%	5012.86
Jun 24 08:00:00 - 08:59:59	41116	3.8%	11.42	15.7 GB	4.2%	4578.17
Jun 24 09:00:00 - 09:59:59	45634	4.2%	12.68	17.0 GB	4.5%	4958.04
Jun 24 10:00:00 - 10:59:59	41260	3.8%	11.46	16.6 GB	4.4%	4843.12
Jun 24 11:00:00 - 11:59:59	41678	3.8%	11.58	14.6 GB	3.9%	4250.44
Jun 24 12:00:00 - 12:59:59	41093	3.8%	11.41	13.8 GB	3.7%	4008.09
Jun 24 13:00:00 - 13:59:59	48218	4.4%	13.39	15.7 GB	4.2%	4580.92
Jun 24 14:00:00 - 14:59:59	43060	4.0%	11.96	15.6 GB	4.1%	4536.26
Jun 24 15:00:00 - 15:59:59	47312	4.3%	13.14	16.1 GB	4.3%	4682.70
Jun 24 16:00:00 - 16:59:59	45842	4.2%	12.73	16.0 GB	4.3%	4671.86
Jun 24 17:00:00 - 17:59:59	45195	4.1%	12.55	16.0 GB	4.3%	4660.57
Jun 24 18:00:00 - 18:59:59	24467	2.2%	6.80	7.1 GB	1.9%	2057.41
Jun 24 19:00:00 - 19:59:59	29539	2.7%	8.21	9.3 GB	2.5%	2709.09
Jun 24 20:00:00 - 20:59:59	28501	2.6%	7.92	8.9 GB	2.4%	2605.50
Jun 24 21:00:00 - 21:59:59	26704	2.5%	7.42	6.1 GB	1.6%	1766.42
Jun 24 22:00:00 - 22:59:59	25659	2.4%	7.13	5.2 GB	1.4%	1522.83
Jun 24 23:00:00 - 23:59:59	24275	2.2%	6.74	6.5 GB	1.7%	1896.35
Jun 25 00:00:00 - 00:59:59	59372	5.4%	16.49	19.4 GB	5.2%	5646.80
Jun 25 01:00:00 - 01:59:59	77793	7.1%	21.61	27.2 GB	7.2%	7916.96
Jun 25 02:00:00 - 02:59:59	87429	8.0%	24.29	28.8 GB	7.7%	8393.31
Jun 25 03:00:00 - 03:59:59	88823	8.2%	24.67	30.2 GB	8.0%	8786.38
Jun 25 04:00:00 - 04:15:02	23512	2.2%	26.07	8.4 GB	2.2%	9734.17
TOTAL: 23:59:56	1089672	100.0%	12.61	376.2 GB	100.0%	4565.62

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	7386	303	2665	0	4418
2	news.task.gda.pl	3554	1451	18	2	2083
3	news.nask.pl	1084	6	860	2	216
4	news.icm.edu.pl	44	0	0	0	44
TOTAL: 4		12068	1760	3543	4	6761

Unwanted newsgroups [Top 20]:

Newsgroup	Count
perl.cpan.uploads	223
alt.politics.scorched-earth	145
mailing.freebsd.svn-ports-all	132
alt.windows7.general	131
mailing.freebsd.svn-ports-head	131
uk.railway	126
it.tlc.cellulari.android	96
fr.usenet.abus.nocem	96
mailing.unix.samba	47
mailing.freebsd.ports-bugs	47
mozilla.dev.tree-management	38
mailing.gcc.gcc-cvs	34
mailing.freebsd.svn-src-all	33
24hoursupport.helpdesk	32
fa.freebsd.cvs-all	32
it.comp.os.win.windows7	28
mailing.postfix.users	24
mailing.openbsd.misc	22
gnu.hurd.bug	20
mailing.freebsd.svn-src-head	20
TOTAL: 79	1760

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

Groups	Count
fr.comp.securite	2
pl.sci.matematyka	2
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	4275
EMP (md5)	1625
EMP (fsl)	651
EMP (phl)	30
Attached HTML file	19
HTML post	3
TOTAL: 6	6603

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	78780	1948	48233	3083	1	2	2%	23:57:15
2	panorama	61649	1874	52360	325	0	1	3%	24:00:42
3	nask	32954	150	20717	97	0	1	0%	24:03:19
4	task	4221	145	3318	13	1	223	3%	23:38:18
5	poznan	27365	34	25296	38	0	0	0%	24:06:05
6	okapi	0	0	0	0	0	2880	0%	24:00:02
TOTAL: 6		204969	4151	149924	3556	2	3107	2%	143:45:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	100.0 MB	379.3 MB	479.3 MB	5.7 KB/s	97.6 KB	23:57:15
2	panorama	11.3 MB	1.1 MB	12.3 MB	0.1 KB/s	5.7 KB	24:00:42
3	nask	11.1 MB	20.1 MB	31.2 MB	0.4 KB/s	129.3 KB	24:03:19
4	poznan	513.0 KB	162.9 KB	675.9 KB	0.0 KB/s	9.4 KB	24:06:05
5	task	342.1 KB	56.5 KB	398.5 KB	0.0 KB/s	2.5 KB	23:38:18
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		123.2 MB	400.7 MB	523.9 MB	1.0 KB/s	69.6 KB	143:45:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	348	610.5 KB	288	0	00:00:09
2	web.man.lodz.pl	288	62	53.4 KB	28	0	00:00:25
3	lze200.lze.lodz.pl	9	61	110.9 KB	7	0	01:21:40
4	router2.cbmm.lodz.pl	2	3	57.0 KB	2	0	00:01:23
5	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:00
TOTAL: 5		597	474	831.8 KB	325	10	06:40:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	610.5 KB	288	0	00:00:09
2	*.man.lodz.pl	683	62	53.4 KB	28	10	00:30:32
3	*.lze.lodz.pl	9	61	110.9 KB	7	0	01:21:40
4	*.cbmm.lodz.pl	2	3	57.0 KB	2	0	00:01:23
5	*.pa.nsw.optusnet.com.au	73	0	0.0 KB	0	0	00:02:03
6	*.wroclaw.mm.pl	26	0	0.0 KB	0	0	04:06:38
7	*.181.14.62.static.jazztel.es	4	0	0.0 KB	0	0	00:07:38
8	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	00:30:11
9	*.toya.net.pl	82	0	0.0 KB	0	0	00:00:10
TOTAL: 9		1169	474	831.8 KB	325	10	06:40:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.044	0.424	04:06:38
lze200.lze.lodz.pl	0.040	0.156	01:21:40
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.024	00:30:11
pc-vicki.man.lodz.pl	0.000	0.020	00:30:00
200.181.14.62.static.jazztel.es	0.228	0.428	00:07:38
pa49-181-247-66.pa.nsw.optusnet.com.au	0.220	1.028	00:02:03
router2.cbmm.lodz.pl	0.012	0.032	00:01:23
web.man.lodz.pl	1.016	5.988	00:00:25
staticline41435.toya.net.pl	0.220	1.284	00:00:10
pc-skrzep2.cm.p.lodz.pl	0.836	4.632	00:00:09
mon.man.lodz.pl	0.872	4.944	00:00:04
david.man.lodz.pl	0.216	1.556	00:00:01
isvcs01.man.lodz.pl	0.076	0.176	00:00:00
?	0.000	0.016	00:00:00
TOTAL: 14	3.788	20.708	06:40:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	82
pa49-181-247-66.pa.nsw.optusnet.com.au	73
host-89-231-50-15.wroclaw.mm.pl	26
200.181.14.62.static.jazztel.es	4
ip-176.105.135.22.tvsat364.lodz.pl	2
pc-vicki.man.lodz.pl	1
TOTAL: 8	572

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
200.181.14.62.static.jazztel.es	2	2
pc-vicki.man.lodz.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 3	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	73.4%
2	pl	126	26.6%
TOTAL: 2		474	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.pecet	64
3	pl.misc.telefonia.gsm	46
4	pl.comp.os.ms-windows.winnt	10
5	pl.rec.kuchnia	3
6	pl.sci.chemia	2
7	pl.regionalne.lodz	1
TOTAL: 7		474

Daily Usenet report

Jun 24 04:15:06 -- Jun 25 04:15:02