Daily Usenet report: Sep 30 04:15:05

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 30 11:49:27 newsfeed nnrpd[26862]: SERVER perl filter not defined
Sep 30 18:30:03 newsfeed controlchan[2377]: Unknown "tags" control by robomod@news.nic.it

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2079882	98.5%	234.0 MB	98.0%
innd	18139	0.9%	3.0 MB	1.3%
nnrpd	7423	0.4%	789.9 KB	0.3%
innfeed	6922	0.3%	1.0 MB	0.4%
controlchan	5	0.0%	0.8 KB	0.0%
TOTAL: 5	2112371	100.0%	238.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2074337	94.4%
Negative hits	83198	3.8%
Positive hits	40409	1.8%
Cache misses	290	0.0%
TOTAL: 4	2198234	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.896	0.0%	354	0.000	27.955	322.000
article cleanup	00:00:28.527	0.0%	2079730	0.004	0.014	0.027
article logging	00:01:11.354	0.1%	2079890	0.014	0.034	0.778
article parse	01:02:46.900	4.4%	65620677	0.010	0.057	0.099
article write	00:22:04.580	1.5%	2072743	0.334	0.639	1.338
artlog/artcncl	00:00:00.006	0.0%	331	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:39.147	0.1%	77764237	0.000	0.001	0.003
hisgrep/artcncl	00:00:02.644	0.0%	320	0.000	8.262	277.000
hishave/artcncl	00:00:02.171	0.0%	354	0.000	6.133	260.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:43:08.958	19.7%	2199111	2.741	7.725	45.239
history sync	00:00:00.012	0.0%	291	0.000	0.041	2.000
history write	05:45:01.760	24.0%	2075286	6.799	9.975	79.050
hiswrite/artcncl	00:00:00.342	0.0%	34	0.000	10.059	191.000
idle	05:42:52.838	23.8%	30633001	0.196	0.672	4.874
nntp read	00:36:34.782	2.5%	63911490	0.010	0.034	0.054
overview write	00:00:13.192	0.0%	2072743	0.001	0.006	0.016
perl filter	05:18:20.401	22.1%	2075285	4.368	9.204	11.935
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:08.392	0.3%	4187859	0.019	0.059	0.085
TOTAL: 24:00:04.691	23:38:45.902	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.149	0.0%	42380	0.000	0.004	1.000
article prepare	00:00:00.011	0.0%	43270	0.000	0.000	0.062
article read	00:00:01.200	0.0%	10503	0.000	0.114	1.333
backlog stats	00:00:00.211	0.0%	302712	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.324	0.0%	181381	0.000	0.013	1.000
data write	00:00:05.654	0.0%	155268	0.000	0.036	1.000
idle	23:59:03.976	100.0%	302712	0.000	285.235	25271.000
status file	00:00:23.393	0.0%	247	0.000	94.709	7568.333
TOTAL: 23:59:43.807	23:59:36.918	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	05:46:22.496	99.8%	34980	0.000	594.125	600055.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.022	0.0%	71965	0.000	0.042	40.200
readart	00:00:03.676	0.0%	434	0.000	8.470	287.000
TOTAL: 05:47:10.139	05:46:29.194	99.8%	-	-	-	-

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
usenet@film.rlss.okayama-u.ac.jp	0	0	1	0	1	1
TOTAL	0	0	1	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	890	2065369	2058880	1992	4497	99%	469:26:17
2	news.task.gda.pl	16	32103	6173	24436	1494	19%	46:49:10
3	news.nask.pl	2	16117	5853	9274	990	36%	24:00:30
4	news.icm.edu.pl	43	282	12	236	34	4%	18:07:08
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		953	2113872	2070919	35938	7015	97%	558:23:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	749.8 GB	13.7 MB	96.1 MB	749.9 GB	99%	381.1 KB
2	news.nask.pl	20.8 MB	2.5 MB	2.6 MB	25.9 MB	80%	3.9 KB
3	news.task.gda.pl	12.5 MB	65.5 KB	4.4 MB	17.0 MB	73%	2.3 KB
4	news.icm.edu.pl	477.4 KB	119.1 KB	642.9 KB	1.2 MB	38%	26.9 KB
5	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 5		749.8 GB	16.3 MB	103.8 MB	750.0 GB	99%	378.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 04:15:05 - 04:59:59	66198	3.2%	24.56	22.9 GB	3.0%	8893.27
Sep 30 05:00:00 - 05:59:59	95884	4.6%	26.63	32.7 GB	4.4%	9526.98
Sep 30 06:00:00 - 06:59:59	99986	4.8%	27.77	33.8 GB	4.5%	9847.86
Sep 30 07:00:00 - 07:59:59	88749	4.3%	24.65	29.8 GB	4.0%	8674.30
Sep 30 08:00:00 - 08:59:59	105100	5.1%	29.19	36.3 GB	4.8%	10581.90
Sep 30 09:00:00 - 09:59:59	91212	4.4%	25.34	33.9 GB	4.5%	9876.97
Sep 30 10:00:00 - 10:59:59	83902	4.0%	23.31	31.8 GB	4.2%	9266.25
Sep 30 11:00:00 - 11:59:59	84029	4.1%	23.34	32.5 GB	4.3%	9469.23
Sep 30 12:00:00 - 12:59:59	83748	4.0%	23.26	31.9 GB	4.3%	9296.36
Sep 30 13:00:00 - 13:59:59	83324	4.0%	23.15	32.4 GB	4.3%	9430.43
Sep 30 14:00:00 - 14:59:59	87412	4.2%	24.28	33.6 GB	4.5%	9788.52
Sep 30 15:00:00 - 15:59:59	81838	3.9%	22.73	31.6 GB	4.2%	9204.25
Sep 30 16:00:00 - 16:59:59	86003	4.1%	23.89	32.3 GB	4.3%	9413.90
Sep 30 17:00:00 - 17:59:59	109380	5.3%	30.38	32.8 GB	4.4%	9541.68
Sep 30 18:00:00 - 18:59:59	93718	4.5%	26.03	31.9 GB	4.2%	9282.12
Sep 30 19:00:00 - 19:59:59	79157	3.8%	21.99	28.8 GB	3.8%	8391.59
Sep 30 20:00:00 - 20:59:59	84413	4.1%	23.45	31.2 GB	4.2%	9087.95
Sep 30 21:00:00 - 21:59:59	79904	3.9%	22.20	29.4 GB	3.9%	8553.06
Sep 30 22:00:00 - 22:59:59	80054	3.9%	22.24	29.1 GB	3.9%	8489.29
Sep 30 23:00:00 - 23:59:59	84681	4.1%	23.52	30.3 GB	4.0%	8821.55
Oct 1 00:00:00 - 00:59:59	81735	3.9%	22.70	29.2 GB	3.9%	8493.06
Oct 1 01:00:00 - 01:59:59	79906	3.9%	22.20	29.4 GB	3.9%	8553.31
Oct 1 02:00:00 - 02:59:59	80073	3.9%	22.24	30.5 GB	4.1%	8895.12
Oct 1 03:00:00 - 03:59:59	68473	3.3%	19.02	26.9 GB	3.6%	7838.96
Oct 1 04:00:00 - 04:15:02	13526	0.7%	15.00	5.2 GB	0.7%	6022.75
TOTAL: 23:59:57	2072405	100.0%	23.99	750.1 GB	100.0%	9104.12

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4625	321	3479	0	825
2	news.task.gda.pl	1499	1247	33	4	215
3	news.nask.pl	991	24	927	0	40
4	news.icm.edu.pl	31	0	5	0	26
TOTAL: 4		7146	1592	4444	4	1106

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	142
mailing.freebsd.svn-ports-head	142
gmane.linux.alsa.devel	134
uk.railway	73
uk.rec.cycling.moderated	71
alt.politics.scorched-earth	64
mailing.freebsd.kde	51
it.tlc.cellulari.android	50
mailing.freebsd.ports-bugs	48
fr.rec.loisirs-creatifs	47
comp.mobile.ipad	42
alt.windows7.general	37
mailing.openbsd.misc	36
mailing.openbsd.ports-changes	35
fr.usenet.abus.nocem	28
mailing.gcc.gcc-cvs	27
comp.mobile.android	25
mailing.openbsd.source-changes	22
mailing.www.horde-bugs	22
gmane.comp.gis.openstreetmap	21
TOTAL: 97	1592

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

Groups	Count
fr.comp.lang.php	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	658
EMP (fsl)	166
EMP (md5)	37
Attached HTML file	37
HTML post	26
EMP (phl)	13
Scoring filter (8)	5
Scoring filter (11)	1
Scoring filter (12)	1
Scoring filter (9)	1
TOTAL: 10	945

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43494	3143	38354	915	0	0	7%	23:58:16
2	icm	57977	2728	32532	3905	3	0	4%	24:09:16
3	poznan	14138	60	11851	34	0	1	0%	24:00:20
4	task	2038	30	1064	8	0	0	1%	22:32:46
5	nask	16941	4	13265	0	0	0	0%	23:59:58
6	okapi	0	0	0	0	0	2679	0%	24:00:00
TOTAL: 6		134588	5965	97066	4862	3	2680	4%	142:40:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	25.0 MB	1.2 GB	1.2 GB	14.3 KB/s	187.4 KB	24:09:16
2	panorama	9.0 MB	3.2 MB	12.3 MB	0.1 KB/s	3.1 KB	23:58:16
3	poznan	171.0 KB	64.6 KB	235.6 KB	0.0 KB/s	2.5 KB	24:00:20
4	task	57.1 KB	15.8 KB	73.0 KB	0.0 KB/s	1.9 KB	22:32:46
5	nask	7.4 KB	0.0 KB	7.4 KB	0.0 KB/s	1.9 KB	23:59:58
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		34.2 MB	1.2 GB	1.2 GB	2.4 KB/s	116.0 KB	142:40:36

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	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	300	469.9 KB	288	0	0	00:00:13
2	212.160.68.149	40	77	111.0 KB	16	0	4	01:21:51
3	web.man.lodz.pl	288	40	43.7 KB	19	0	0	00:00:24
4	212.191.196.1	2	14	22.9 KB	2	0	0	00:34:11
5	ip-176.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	0	01:00:04
6	ebr199.neoplus.adsl.tpnet.pl	2	1	1.6 KB	1	0	0	00:58:05
7	router2.cbmm.lodz.pl	5	1	1.8 KB	1	0	0	00:50:00
8	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	0	00:00:00
TOTAL: 8		628	434	652.5 KB	328	1	4	05:47:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	300	469.9 KB	288	0	0	00:00:13
2	unresolved	44	91	133.9 KB	18	0	4	01:56:12
3	*.man.lodz.pl	674	40	43.7 KB	19	1	0	00:00:39
4	*.cbmm.lodz.pl	5	1	1.8 KB	1	0	0	00:50:00
5	*.neoplus.adsl.tpnet.pl	2	1	1.6 KB	1	0	0	00:58:05
6	*.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	0	01:00:04
7	*.181.14.62.static.jazztel.es	4	0	0.0 KB	0	0	0	01:01:40
8	*.toya.net.pl	69	0	0.0 KB	0	0	0	00:00:13
TOTAL: 8		1088	434	652.5 KB	328	1	4	05:47:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.160.68.149	0.364	0.736	01:21:51
87.181.14.62.static.jazztel.es	1.928	2.732	01:01:40
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.036	01:00:04
ebr199.neoplus.adsl.tpnet.pl	0.008	0.036	00:58:05
router2.cbmm.lodz.pl	0.008	0.096	00:50:00
212.191.196.1	0.004	0.024	00:34:11
web.man.lodz.pl	0.760	4.924	00:00:24
vegas.cm.p.lodz.pl	0.736	4.980	00:00:13
staticline41435.toya.net.pl	0.196	1.204	00:00:13
f-oper-v100.man.lodz.pl	0.784	5.452	00:00:12
212.51.203.199	0.004	0.032	00:00:09
david.man.lodz.pl	0.248	1.648	00:00:01
kujawiak.man.lodz.pl	0.008	0.020	00:00:00
TOTAL: 13	5.052	21.920	05:47:09

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	69
87.181.14.62.static.jazztel.es	4
212.51.203.199	2
TOTAL: 5	460

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	357	82.3%
2	pl	56	12.9%
3	lodz	21	4.8%
TOTAL: 3		434	100.0%

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

Newsgroup		Count
1	lodman.info	346
2	pl.comp.pecet	34
3	lodz.test	19
4	lodman.test	11
5	pl.misc.paranauki	9
6	pl.comp.os.ms-windows.winnt	6
7	pl.regionalne.lodz	6
8	lodz.ogloszenia	2
9	pl.misc.dieta	1
TOTAL: 9		434

Daily Usenet report

Sep 30 04:15:05 -- Oct 1 04:15:02