Daily Usenet report: May 12 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

May 12 09:05:27 newsfeed nnrpd[29607]: SERVER perl filter not defined
May 12 14:28:35 newsfeed nnrpd[8025]: SERVER perl filter not defined
May 12 14:46:18 newsfeed nnrpd[8650]: SERVER perl filter not defined
May 12 14:57:42 newsfeed nnrpd[9013]: SERVER perl filter not defined
May 12 14:58:41 newsfeed nnrpd[9017]: SERVER perl filter not defined
May 12 22:04:45 newsfeed nnrpd[23389]: SERVER perl filter not defined
May 12 23:39:43 newsfeed nnrpd[26557]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	994210	97.4%	112.9 MB	96.8%
innd	11473	1.1%	1.9 MB	1.6%
nnrpd	7824	0.8%	825.2 KB	0.7%
innfeed	7144	0.7%	1.1 MB	0.9%
TOTAL: 4	1020651	100.0%	116.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	988184	91.2%
Negative hits	51617	4.8%
Positive hits	43183	4.0%
Cache misses	925	0.1%
TOTAL: 4	1083909	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:48.483	0.1%	1852	0.000	26.179	316.000
article cleanup	00:00:16.851	0.0%	992635	0.002	0.017	0.041
article logging	00:00:30.050	0.0%	992728	0.006	0.030	0.318
article parse	00:43:08.610	3.0%	117106826	0.009	0.022	0.084
article write	00:09:35.047	0.7%	986197	0.285	0.583	2.287
artlog/artcncl	00:00:00.032	0.0%	1761	0.000	0.018	0.500
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:03:05.192	0.2%	123797171	0.000	0.001	0.070
hisgrep/artcncl	00:00:14.915	0.0%	984	0.000	15.158	211.000
hishave/artcncl	00:00:06.597	0.0%	1852	0.000	3.562	255.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:37:34.306	6.8%	1082899	0.009	5.406	17.081
history sync	00:00:00.014	0.0%	291	0.000	0.048	1.000
history write	02:11:58.503	9.2%	988062	0.294	8.014	37.500
hiswrite/artcncl	00:00:06.476	0.0%	868	0.000	7.461	265.000
idle	15:25:04.178	64.2%	91710525	0.296	0.605	1.188
nntp read	00:43:46.433	3.0%	116390936	0.011	0.023	0.064
overview write	00:00:06.515	0.0%	986197	0.000	0.007	0.039
perl filter	02:28:07.558	10.3%	988057	4.220	8.995	15.502
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:12.863	0.2%	2013101	0.040	0.066	0.106
TOTAL: 24:00:21.719	23:26:42.623	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.175	0.0%	42045	0.000	0.004	0.167
article prepare	00:00:00.012	0.0%	58782	0.000	0.000	0.050
article read	00:00:04.526	0.0%	13979	0.000	0.324	77.353
backlog stats	00:00:00.243	0.0%	301725	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.949	0.0%	182840	0.000	0.011	0.068
data write	00:00:05.465	0.0%	151661	0.000	0.036	1.048
idle	24:00:02.233	100.0%	301725	0.000	286.361	16249.000
status file	00:00:00.952	0.0%	233	0.000	4.086	133.000
TOTAL: 24:00:22.494	24:00:15.555	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.423	0.0%	86	0.000	4.919	6.048
idle	29:53:24.877	99.9%	606470	0.000	177.428	694329.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:37.912	0.0%	1215265	0.000	0.031	955.762
readart	00:00:02.753	0.0%	564	0.000	4.881	483.000
TOTAL: 29:55:02.603	29:54:05.965	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	622	980248	974615	1737	3896	99%	242:54:30
2	news.task.gda.pl	17	32866	7172	24245	1449	21%	46:34:49
3	news.nask.pl	4	19256	5325	12795	1136	27%	24:12:02
4	localhost	8	7	7	0	0	100%	00:00:01
5	news.icm.edu.pl	59	171	1	164	6	0%	14:30:16
TOTAL: 5		710	1032548	987120	38941	6487	95%	328:11:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	342.6 GB	92.3 MB	103.6 MB	342.8 GB	99%	367.3 KB
2	news.nask.pl	25.8 MB	3.3 MB	3.3 MB	32.3 MB	79%	5.1 KB
3	news.task.gda.pl	15.1 MB	52.9 KB	4.3 MB	19.5 MB	77%	2.3 KB
4	localhost	10.3 KB	0.0 KB	0.0 KB	10.3 KB	100%	1.5 KB
5	news.icm.edu.pl	9.1 KB	0.0 KB	67.6 KB	76.7 KB	11%	11.0 KB
TOTAL: 5		342.6 GB	95.6 MB	111.3 MB	342.8 GB	99%	361.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 12 04:15:02 - 04:59:59	22105	2.2%	8.19	9.0 GB	2.6%	3478.58
May 12 05:00:00 - 05:59:59	23218	2.4%	6.45	9.5 GB	2.8%	2765.10
May 12 06:00:00 - 06:59:59	23912	2.4%	6.64	10.1 GB	3.0%	2940.86
May 12 07:00:00 - 07:59:59	25309	2.6%	7.03	9.8 GB	2.9%	2861.92
May 12 08:00:00 - 08:59:59	30114	3.1%	8.37	12.2 GB	3.6%	3547.86
May 12 09:00:00 - 09:59:59	44489	4.5%	12.36	16.4 GB	4.8%	4770.83
May 12 10:00:00 - 10:59:59	38301	3.9%	10.64	14.1 GB	4.1%	4116.92
May 12 11:00:00 - 11:59:59	37238	3.8%	10.34	13.5 GB	4.0%	3938.25
May 12 12:00:00 - 12:59:59	35948	3.6%	9.99	12.8 GB	3.7%	3731.70
May 12 13:00:00 - 13:59:59	33348	3.4%	9.26	12.4 GB	3.6%	3618.86
May 12 14:00:00 - 14:59:59	33737	3.4%	9.37	12.6 GB	3.7%	3662.59
May 12 15:00:00 - 15:59:59	30991	3.1%	8.61	11.7 GB	3.4%	3410.30
May 12 16:00:00 - 16:59:59	33436	3.4%	9.29	12.0 GB	3.5%	3507.54
May 12 17:00:00 - 17:59:59	33719	3.4%	9.37	12.3 GB	3.6%	3577.54
May 12 18:00:00 - 18:59:59	32492	3.3%	9.03	10.8 GB	3.2%	3141.62
May 12 19:00:00 - 19:59:59	34602	3.5%	9.61	10.7 GB	3.1%	3107.46
May 12 20:00:00 - 20:59:59	37870	3.8%	10.52	11.5 GB	3.4%	3341.86
May 12 21:00:00 - 21:59:59	36355	3.7%	10.10	11.3 GB	3.3%	3305.20
May 12 22:00:00 - 22:59:59	34833	3.5%	9.68	11.1 GB	3.3%	3238.52
May 12 23:00:00 - 23:59:59	38646	3.9%	10.73	12.4 GB	3.6%	3616.28
May 13 00:00:00 - 00:59:59	74372	7.5%	20.66	24.0 GB	7.0%	6987.30
May 13 01:00:00 - 01:59:59	90088	9.1%	25.02	29.0 GB	8.5%	8432.92
May 13 02:00:00 - 02:59:59	85596	8.7%	23.78	27.9 GB	8.2%	8128.39
May 13 03:00:00 - 03:59:59	62163	6.3%	17.27	20.7 GB	6.0%	6023.49
May 13 04:00:00 - 04:15:03	13032	1.3%	14.43	4.3 GB	1.2%	4952.85
TOTAL: 24:00:01	985914	100.0%	11.41	342.0 GB	100.0%	4150.90

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3977	186	3424	3	364
2	news.task.gda.pl	1409	1150	31	0	228
3	news.nask.pl	1143	2	1122	3	16
4	news.icm.edu.pl	6	0	0	0	6
TOTAL: 4		6535	1338	4577	6	614

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	146
mailing.freebsd.svn-ports-head	144
mailing.freebsd.ports-bugs	101
uk.railway	91
mailing.openbsd.ports-changes	47
alt.politics.scorched-earth	40
mailing.freebsd.svn-src-all	39
fa.freebsd.cvs-all	37
gmane.linux.alsa.devel	33
mailing.gcc.gcc-cvs	30
mailing.freebsd.ports	30
mailing.openbsd.source-changes	30
mailing.unix.samba-technical	29
mailing.unix.samba	27
it.tlc.cellulari.android	27
mozilla.dev.tree-management	27
mozilla.dev.platform	26
alt.windows7.general	25
gnu.hurd.bug	24
mailing.postfix.users	23
TOTAL: 79	1338

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

Groups	Count
pl.sci.matematyka	4
christnet.poetry	1
bit.listserv.catholic	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	262
EMP (fsl)	176
EMP (phl)	27
Attached HTML file	23
EMP (md5)	17
HTML post	6
Scoring filter (8)	4
Scoring filter (12)	1
TOTAL: 8	516

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	57860	8112	28196	4415	25	1337	14%	24:06:01
2	panorama	41858	1774	38655	291	0	0	4%	23:59:35
3	poznan	13476	32	11687	14	0	0	0%	23:54:08
4	task	1823	14	931	16	0	1	0%	21:55:51
5	nask	24417	9	16530	87	0	0	0%	23:55:26
6	okapi	0	0	0	0	0	2716	0%	24:00:04
TOTAL: 6		139434	9941	95999	4823	25	4054	7%	141:51:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	154.2 MB	280.1 MB	434.3 MB	5.1 KB/s	35.5 KB	24:06:01
2	panorama	29.2 MB	1.5 MB	30.6 MB	0.4 KB/s	15.2 KB	23:59:35
3	poznan	302.3 KB	39.1 KB	341.4 KB	0.0 KB/s	7.4 KB	23:54:08
4	task	30.5 KB	29.8 KB	60.2 KB	0.0 KB/s	2.0 KB	21:55:51
5	nask	18.9 KB	27.9 MB	27.9 MB	0.3 KB/s	297.4 KB	23:55:26
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 6		183.7 MB	309.6 MB	493.2 MB	1.0 KB/s	34.2 KB	141:51:05

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	pc-skrzep2.cm.p.lodz.pl	288	362	513.3 KB	288	0	00:00:10
2	lze200.lze.lodz.pl	8	96	168.1 KB	6	0	00:41:58
3	web.man.lodz.pl	288	78	74.2 KB	34	0	00:00:24
4	ip-176.105.135.22.tvsat364.lodz.pl	6	23	37.0 KB	3	0	00:29:50
5	abgr126.neoplus.adsl.tpnet.pl	15	2	2.8 KB	2	0	00:56:04
6	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 6		612	561	795.4 KB	333	7	29:55:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	362	513.3 KB	288	0	00:00:10
2	*.lze.lodz.pl	8	96	168.1 KB	6	0	00:41:58
3	*.man.lodz.pl	679	78	74.2 KB	34	7	00:00:30
4	*.105.135.22.tvsat364.lodz.pl	6	23	37.0 KB	3	0	00:29:50
5	*.neoplus.adsl.tpnet.pl	15	2	2.8 KB	2	0	00:56:04
6	*.pa.nsw.optusnet.com.au	65	0	0.0 KB	0	0	00:02:13
7	*.cbmm.lodz.pl	3	0	0.0 KB	0	0	00:30:06
8	unresolved	16	0	0.0 KB	0	0	02:59:41
9	*.191.14.62.static.jazztel.es	4	0	0.0 KB	0	0	23:59:44
10	*.dynamic.chello.pl	12	0	0.0 KB	0	0	00:13:56
11	*.toya.net.pl	83	0	0.0 KB	0	0	00:00:43
TOTAL: 11		1179	561	795.4 KB	333	7	29:55:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	29.260	38.804	23:59:44
37.190.189.79	0.044	0.148	02:59:26
abgr126.neoplus.adsl.tpnet.pl	0.252	0.260	00:56:04
lze200.lze.lodz.pl	0.020	0.104	00:41:58
router2.cbmm.lodz.pl	0.008	0.040	00:30:06
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.060	00:29:50
89-68-250-95.dynamic.chello.pl	0.056	0.228	00:13:56
pa49-181-246-92.pa.nsw.optusnet.com.au	0.124	0.620	00:02:13
staticline43485.toya.net.pl	0.032	0.084	00:00:37
web.man.lodz.pl	0.696	3.676	00:00:24
212.51.203.199	0.004	0.020	00:00:15
pc-skrzep2.cm.p.lodz.pl	0.532	3.152	00:00:10
staticline41435.toya.net.pl	0.124	0.780	00:00:06
mon.man.lodz.pl	0.508	2.912	00:00:03
david.man.lodz.pl	0.176	0.924	00:00:01
isvcs01.man.lodz.pl	0.040	0.072	00:00:00
?	0.000	0.008	00:00:00
TOTAL: 17	31.884	51.892	29:55:00

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	74
pa49-181-246-92.pa.nsw.optusnet.com.au	65
37.190.189.79	14
89-68-250-95.dynamic.chello.pl	12
staticline43485.toya.net.pl	9
242.191.14.62.static.jazztel.es	4
router2.cbmm.lodz.pl	3
212.51.203.199	2
TOTAL: 10	567

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
abgr126.neoplus.adsl.tpnet.pl	3	3
37.190.189.79	2	0
router2.cbmm.lodz.pl	1	0
TOTAL: 3	6	3

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	362	64.5%
2	pl	197	35.1%
3	lodz	1	0.2%
4	lodzman	1	0.2%
TOTAL: 4		561	100.0%

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

Newsgroup		Count
1	lodman.info	362
2	pl.comp.pecet	101
3	pl.misc.telefonia.gsm	45
4	pl.comp.os.ms-windows.winnt	20
5	pl.soc.prawo	16
6	pl.soc.dzieci	5
7	pl.rec.kuchnia	4
8	pl.internet.polip	4
9	pl.regionalne.lodz	2
10	lodz.ogloszenia	1
11	lodzman.test	1
TOTAL: 11		561

Daily Usenet report

May 12 04:15:02 -- May 13 04:15:03