Daily Usenet report: Jun 3 04:15:17

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Jun 3 04:15:17 newsfeed nnrpd[1402]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 3 04:15:17 newsfeed nnrpd[1406]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 3 04:15:17 newsfeed nnrpd[1407]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 3 04:15:17 newsfeed nnrpd[1408]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 3 04:15:17 newsfeed nnrpd[1409]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 3 11:30:03 newsfeed nnrpd[15503]: SERVER perl filter not defined
Jun 3 12:55:37 newsfeed nnrpd[3849]: SERVER perl filter not defined
Jun 3 12:59:13 newsfeed nnrpd[4118]: SERVER perl filter not defined
Jun 3 13:04:16 newsfeed nnrpd[4232]: SERVER perl filter not defined
Jun 3 19:20:46 newsfeed nnrpd[16196]: SERVER perl filter not defined
Jun 3 19:22:14 newsfeed nnrpd[16272]: SERVER perl filter not defined
Jun 3 19:33:50 newsfeed nnrpd[16592]: SERVER perl filter not defined
Jun 4 00:25:58 newsfeed nnrpd[25889]: SERVER perl filter not defined
Jun 4 04:15:03 newsfeed nnrpd[839]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[841]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[842]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[843]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[844]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[845]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 4 04:15:03 newsfeed nnrpd[846]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2319763	98.4%	264.2 MB	98.0%
innd	21318	0.9%	3.4 MB	1.3%
nnrpd	10112	0.4%	1.0 MB	0.4%
innfeed	6924	0.3%	1.0 MB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	2358118	100.0%	269.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2307909	94.7%
Negative hits	78614	3.2%
Positive hits	47609	2.0%
Cache misses	4085	0.2%
TOTAL: 4	2438217	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:01:00.209	0.1%	1303	0.000	46.208	758.000
article cleanup	00:00:32.248	0.0%	2316929	0.000	0.014	0.818
article logging	00:01:14.249	0.1%	2317116	0.000	0.032	0.777
article parse	01:10:27.923	5.1%	76493068	0.000	0.055	0.192
article write	00:24:32.280	1.8%	2302431	0.266	0.639	105.000
artlog/artcncl	00:00:00.018	0.0%	1277	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:03:08.563	0.2%	89319287	0.000	0.002	0.059
hisgrep/artcncl	00:00:16.553	0.0%	974	0.000	16.995	294.000
hishave/artcncl	00:00:10.029	0.0%	1303	0.000	7.697	236.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:30:41.284	15.3%	2437476	0.016	5.186	120.906
history sync	00:00:00.010	0.0%	281	0.000	0.036	1.000
history write	05:02:39.711	22.0%	2307784	0.996	7.869	50.849
hiswrite/artcncl	00:00:02.183	0.0%	329	0.000	6.635	156.000
idle	05:48:32.842	25.3%	40814733	0.091	0.512	15007.250
nntp read	00:40:17.896	2.9%	74614345	0.000	0.032	0.186
overview write	00:00:15.625	0.0%	2302431	0.000	0.007	0.177
perl filter	05:50:48.521	25.5%	2307764	3.128	9.121	38.012
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:28.809	0.5%	4651541	0.000	0.084	1.935
TOTAL: 22:58:22.924	22:41:08.953	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.172	0.0%	47056	0.000	0.004	0.111
article prepare	00:00:00.018	0.0%	68018	0.000	0.000	0.025
article read	00:00:01.992	0.0%	15626	0.000	0.127	30.286
backlog stats	00:00:00.414	0.0%	327294	0.000	0.001	0.284
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.544	0.0%	202390	0.000	0.022	3.370
data write	00:00:07.703	0.0%	163923	0.000	0.047	33.000
idle	22:58:26.794	100.0%	327294	0.000	252.699	25100.000
status file	00:00:07.028	0.0%	267	0.000	26.322	299.000
TOTAL: 22:59:02.805	22:58:48.665	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.347	0.0%	9067	0.259	0.259	0.259
idle	58:28:37.576	100.0%	43436	0.000	4846.615	600113.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:07.923	0.0%	89385	0.000	0.089	13.333
readart	00:00:13.765	0.0%	9635	0.000	1.429	71.900
TOTAL: 58:29:50.860	58:29:01.611	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	23
flushlogs	2
go	2
logmode	2
mode	142
name	3
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	183

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	701	2302955	2286877	3978	12100	99%	442:40:27
2	news.task.gda.pl	19	34375	7349	25651	1375	21%	38:55:48
3	news.nask.pl	4	19284	7318	11000	966	37%	22:46:28
4	news.icm.edu.pl	35	361	21	305	35	5%	17:48:01
5	localhost	9	8	8	0	0	100%	00:00:01
TOTAL: 5		768	2356983	2301573	40934	14476	97%	522:10:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	728.7 GB	1.0 GB	1.5 GB	731.1 GB	99%	333.5 KB
2	news.nask.pl	72.2 MB	4.7 MB	4.8 MB	81.6 MB	88%	10.1 KB
3	news.task.gda.pl	15.1 MB	69.5 KB	4.0 MB	19.1 MB	78%	2.2 KB
4	news.icm.edu.pl	6.5 MB	4.3 MB	4.9 MB	15.7 MB	41%	286.4 KB
5	localhost	12.1 KB	0.0 KB	0.0 KB	12.1 KB	100%	1.5 KB
TOTAL: 5		728.8 GB	1.0 GB	1.5 GB	731.2 GB	99%	331.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 3 04:15:17 - 04:59:59	39443	1.7%	14.70	12.7 GB	1.7%	4970.88
Jun 3 05:00:00 - 05:59:59	80299	3.5%	22.31	26.9 GB	3.7%	7837.04
Jun 3 06:00:00 - 06:59:59	89236	3.9%	24.79	29.4 GB	4.0%	8565.97
Jun 3 07:00:00 - 07:59:59	89718	3.9%	24.92	30.5 GB	4.2%	8877.58
Jun 3 08:00:00 - 08:59:59	89008	3.9%	24.72	30.8 GB	4.2%	8967.86
Jun 3 09:00:00 - 09:59:59	82949	3.6%	23.04	26.6 GB	3.6%	7750.79
Jun 3 10:00:00 - 10:59:59	113600	4.9%	31.56	37.2 GB	5.1%	10847.36
Jun 3 11:00:00 - 11:59:59	106100	4.6%	29.47	34.4 GB	4.7%	10029.15
Jun 3 12:00:00 - 12:59:59	3875	0.2%	1.08	575.5 MB	0.1%	163.71
Jun 3 13:00:00 - 13:59:59	97666	4.2%	27.13	28.4 GB	3.9%	8261.74
Jun 3 14:00:00 - 14:59:59	107817	4.7%	29.95	34.9 GB	4.8%	10167.04
Jun 3 15:00:00 - 15:59:59	118354	5.1%	32.88	37.8 GB	5.2%	11023.65
Jun 3 16:00:00 - 16:59:59	114233	5.0%	31.73	33.5 GB	4.6%	9751.32
Jun 3 17:00:00 - 17:59:59	104270	4.5%	28.96	34.5 GB	4.7%	10052.20
Jun 3 18:00:00 - 18:59:59	106957	4.6%	29.71	34.4 GB	4.7%	10029.78
Jun 3 19:00:00 - 19:59:59	103487	4.5%	28.75	32.4 GB	4.4%	9434.15
Jun 3 20:00:00 - 20:59:59	103964	4.5%	28.88	32.4 GB	4.4%	9446.25
Jun 3 21:00:00 - 21:59:59	70075	3.0%	19.47	20.1 GB	2.8%	5863.38
Jun 3 22:00:00 - 22:59:59	104557	4.5%	29.04	32.2 GB	4.4%	9374.03
Jun 3 23:00:00 - 23:59:59	96808	4.2%	26.89	30.0 GB	4.1%	8745.95
Jun 4 00:00:00 - 00:59:59	103888	4.5%	28.86	31.7 GB	4.3%	9237.54
Jun 4 01:00:00 - 01:59:59	102440	4.4%	28.46	31.5 GB	4.3%	9161.36
Jun 4 02:00:00 - 02:59:59	130374	5.7%	36.22	40.2 GB	5.5%	11710.89
Jun 4 03:00:00 - 03:59:59	114523	5.0%	31.81	36.6 GB	5.0%	10647.23
Jun 4 04:00:00 - 04:15:03	30149	1.3%	33.39	9.6 GB	1.3%	11127.94
TOTAL: 23:59:46	2303790	100.0%	26.67	729.4 GB	100.0%	8853.18

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	12239	337	8170	2	3730
2	news.task.gda.pl	1401	1193	39	0	169
3	news.nask.pl	1020	9	935	1	75
4	news.icm.edu.pl	36	0	11	0	25
TOTAL: 4		14696	1539	9155	3	3999

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	140
mailing.openbsd.source-changes	99
mailing.freebsd.ports-bugs	86
mailing.freebsd.svn-src-all	85
fa.freebsd.cvs-all	83
mailing.freebsd.svn-ports-all	78
mailing.freebsd.svn-ports-head	78
uk.rec.cycling.moderated	66
mailing.freebsd.svn-src-head	58
alt.politics.scorched-earth	47
mailing.www.horde-bugs	42
mailing.openbsd.misc	40
it.comp.os.win.windows8	39
it.comp.os.win.windows7	36
mailing.gcc.gcc-cvs	35
gmane.linux.alsa.devel	35
mozilla.dev.tree-management	31
mailing.unix.samba-technical	29
mailing.openbsd.ports-changes	27
mailing.unix.samba	25
TOTAL: 88	1539

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

Groups	Count
pl.sci.matematyka	1
pl.sci.historia	1
bit.listserv.catholic	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3538
EMP (fsl)	139
EMP (md5)	48
Attached HTML file	44
HTML post	20
Scoring filter (12)	1
Scoring filter (10)	1
Scoring filter (8)	1
TOTAL: 8	3792

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56914	8431	29498	4184	3	362	14%	22:53:13
2	panorama	51089	3080	42468	897	0	0	6%	22:50:03
3	poznan	15866	74	13856	50	0	0	0%	22:51:51
4	task	1735	73	890	7	0	0	4%	21:16:52
5	nask	19574	22	13593	43	0	0	0%	22:44:11
6	okapi	0	0	0	0	0	2848	0%	22:40:01
TOTAL: 6		145178	11680	100305	5181	3	3210	8%	135:16:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	237.2 MB	376.6 MB	613.9 MB	7.6 KB/s	49.8 KB	22:53:13
2	panorama	15.9 MB	15.1 MB	31.0 MB	0.4 KB/s	8.0 KB	22:50:03
3	poznan	358.8 KB	79.0 KB	437.9 KB	0.0 KB/s	3.5 KB	22:51:51
4	task	155.0 KB	24.4 KB	179.4 KB	0.0 KB/s	2.2 KB	21:16:52
5	nask	47.4 KB	44.7 MB	44.8 MB	0.6 KB/s	705.1 KB	22:44:11
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:40:01
TOTAL: 6		253.7 MB	436.6 MB	690.3 MB	1.5 KB/s	41.9 KB	135:16:11

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	276	348	637.8 KB	276	0	00:00:09
2	212.191.78.190	283	157	275.3 KB	32	1	44:49:14
3	web.man.lodz.pl	286	48	44.6 KB	21	0	00:00:24
4	ip-176.105.135.22.tvsat364.lodz.pl	6	19	34.9 KB	6	0	02:13:56
5	router2.cbmm.lodz.pl	8	19	35.7 KB	4	1	00:26:41
6	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 6		865	591	1.0 MB	339	8	58:29:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	276	348	637.8 KB	276	0	00:00:09
2	unresolved	283	157	275.3 KB	32	1	44:49:14
3	*.man.lodz.pl	660	48	44.6 KB	21	6	00:00:36
4	*.cbmm.lodz.pl	8	19	35.7 KB	4	1	00:26:41
5	*.105.135.22.tvsat364.lodz.pl	6	19	34.9 KB	6	0	02:13:56
6	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	0	10:58:36
7	*.internetdsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:00
8	*.toya.net.pl	128	0	0.0 KB	0	0	00:00:33
TOTAL: 8		1366	591	1.0 MB	339	8	58:29:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.940	6.340	44:49:14
85.207.15.62.static.jazztel.es	6.320	14.789	10:58:36
ip-176.105.135.22.tvsat364.lodz.pl	0.100	0.552	02:13:56
router2.cbmm.lodz.pl	0.064	1.108	00:26:41
staticline41435.toya.net.pl	0.308	2.712	00:00:33
web.man.lodz.pl	1.032	8.644	00:00:24
vegas.cm.p.lodz.pl	0.992	8.952	00:00:09
f-oper-v100.man.lodz.pl	0.852	6.724	00:00:06
david.man.lodz.pl	0.360	4.276	00:00:02
kujawiak.man.lodz.pl	0.024	0.068	00:00:01
gcq193.internetdsl.tpnet.pl	0.044	0.264	00:00:00
TOTAL: 11	11.036	54.429	58:29:49

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	277
staticline41435.toya.net.pl	128
david.man.lodz.pl	91
85.207.15.62.static.jazztel.es	4
gcq193.internetdsl.tpnet.pl	1
TOTAL: 5	501

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	58.9%
2	pl	243	41.1%
TOTAL: 2		591	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.misc.kolej	62
3	pl.comp.pecet	46
4	pl.regionalne.lodz	37
5	pl.rec.rowery	30
6	pl.regionalne.warszawa	28
7	pl.soc.prawo	20
8	pl.comp.os.linux	13
9	pl.rec.gory	3
10	pl.comp.os.ms-windows.winnt	2
11	pl.soc.dzieci	1
12	pl.misc.dieta	1
TOTAL: 12		591

Daily Usenet report

Jun 3 04:15:17 -- Jun 4 04:15:03