Daily Usenet report: Feb 15 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 15 10:43:11 newsfeed nnrpd[23896]: SERVER perl filter not defined
Feb 15 11:11:07 newsfeed controlchan[2237]: control_checkgroups, ^.*$ !ka.lists #20140215 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000009366E8C0000006B@, , , utf-8, news.task.gda.pl, mail, 1
Feb 15 11:11:07 newsfeed controlchan[2237]: control_checkgroups, ^.*$ !ka.lists #20140215 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000009366E8C0000006B@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Feb 15 17:00:10 newsfeed pgpverify[3723]: pgpverify: verification failed
Feb 15 20:23:21 newsfeed nnrpd[10356]: SERVER perl filter not defined
Feb 15 20:25:31 newsfeed nnrpd[10424]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1802868	98.3%	210.5 MB	97.9%
innd	15916	0.9%	2.7 MB	1.2%
nnrpd	7774	0.4%	806.2 KB	0.4%
innfeed	7031	0.4%	1.1 MB	0.5%
controlchan	5	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1833595	100.0%	215.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1797014	96.0%
Negative hits	38778	2.1%
Positive hits	36562	2.0%
Cache misses	269	0.0%
TOTAL: 4	1872623	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.723	0.0%	347	0.000	39.548	645.000
article cleanup	00:00:28.199	0.0%	1803525	0.000	0.016	0.031
article logging	00:00:55.132	0.1%	1803620	0.010	0.031	0.490
article parse	01:03:33.912	4.4%	130315665	0.010	0.029	0.086
article write	00:15:04.375	1.0%	1796664	0.278	0.503	1.484
artlog/artcncl	00:00:00.009	0.0%	341	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:04:46.728	0.3%	141295953	0.000	0.002	0.088
hisgrep/artcncl	00:00:01.257	0.0%	276	0.000	4.554	47.333
hishave/artcncl	00:00:01.295	0.0%	347	0.000	3.732	289.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:36:59.002	10.9%	1874059	0.016	5.026	13.540
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	03:35:33.062	15.0%	1798607	0.607	7.191	18.376
hiswrite/artcncl	00:00:00.199	0.0%	71	0.000	2.803	21.000
idle	10:25:59.828	43.5%	90770820	0.169	0.414	1.515
nntp read	00:54:26.456	3.8%	128919120	0.013	0.025	0.057
overview write	00:00:12.180	0.0%	1796664	0.000	0.007	0.019
perl filter	04:15:06.464	17.7%	1798601	5.168	8.510	13.095
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:47.837	0.3%	3628345	0.033	0.063	0.240
TOTAL: 24:00:31.405	23:17:09.674	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	35031	0.000	0.005	1.000
article prepare	00:00:00.009	0.0%	28118	0.000	0.000	0.125
article read	00:00:03.475	0.0%	6890	0.000	0.504	309.500
backlog stats	00:00:00.221	0.0%	273970	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.785	0.0%	160415	0.000	0.011	1.000
data write	00:00:05.054	0.0%	135398	0.000	0.037	8.000
idle	24:00:08.724	100.0%	273970	0.000	315.395	22100.000
status file	00:00:01.289	0.0%	208	0.000	6.197	190.500
TOTAL: 24:00:27.136	24:00:20.722	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	10:57:09.611	99.9%	4722	0.000	8350.193	105024.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.252	0.0%	11173	0.000	0.023	1.000
readart	00:00:00.463	0.0%	388	0.000	1.193	222.000
TOTAL: 10:57:43.976	10:57:10.326	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	694	1790214	1785494	66	4654	99%	308:13:29
2	news.task.gda.pl	14	26049	6217	18707	1125	23%	44:34:18
3	news.nask.pl	2	16748	3623	12080	1045	21%	24:25:30
4	news.icm.edu.pl	42	311	8	263	40	2%	19:30:59
5	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 5		756	1833325	1795345	31116	6864	97%	396:44:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	545.3 GB	348.0 MB	444.2 MB	546.1 GB	99%	319.9 KB
2	news.nask.pl	15.8 MB	2.7 MB	2.7 MB	21.2 MB	74%	4.6 KB
3	news.task.gda.pl	11.8 MB	80.6 KB	2.9 MB	14.8 MB	79%	2.1 KB
4	news.icm.edu.pl	103.7 KB	0.0 KB	1.6 MB	1.7 MB	5%	36.3 KB
5	localhost	5.9 KB	0.0 KB	0.0 KB	5.9 KB	100%	2.0 KB
TOTAL: 5		545.4 GB	350.7 MB	451.4 MB	546.1 GB	99%	317.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 15 04:15:02 - 04:59:59	75064	4.2%	27.82	22.6 GB	4.1%	8778.90
Feb 15 05:00:00 - 05:59:59	107501	6.0%	29.86	32.4 GB	5.9%	9435.17
Feb 15 06:00:00 - 06:59:59	103921	5.8%	28.87	31.5 GB	5.8%	9171.76
Feb 15 07:00:00 - 07:59:59	104848	5.8%	29.12	32.5 GB	6.0%	9454.68
Feb 15 08:00:00 - 08:59:59	107737	6.0%	29.93	33.1 GB	6.1%	9648.55
Feb 15 09:00:00 - 09:59:59	98678	5.5%	27.41	31.0 GB	5.7%	9024.72
Feb 15 10:00:00 - 10:59:59	90122	5.0%	25.03	27.7 GB	5.1%	8074.86
Feb 15 11:00:00 - 11:59:59	106151	5.9%	29.49	33.6 GB	6.2%	9774.68
Feb 15 12:00:00 - 12:59:59	101564	5.7%	28.21	32.3 GB	5.9%	9421.36
Feb 15 13:00:00 - 13:59:59	59480	3.3%	16.52	19.3 GB	3.5%	5622.54
Feb 15 14:00:00 - 14:59:59	42589	2.4%	11.83	11.5 GB	2.1%	3349.37
Feb 15 15:00:00 - 15:59:59	37723	2.1%	10.48	9.7 GB	1.8%	2830.92
Feb 15 16:00:00 - 16:59:59	35021	2.0%	9.73	8.7 GB	1.6%	2536.08
Feb 15 17:00:00 - 17:59:59	42420	2.4%	11.78	11.8 GB	2.2%	3440.65
Feb 15 18:00:00 - 18:59:59	42676	2.4%	11.85	11.9 GB	2.2%	3472.42
Feb 15 19:00:00 - 19:59:59	82686	4.6%	22.97	18.9 GB	3.5%	5495.26
Feb 15 20:00:00 - 20:59:59	56725	3.2%	15.76	16.6 GB	3.0%	4826.73
Feb 15 21:00:00 - 21:59:59	53389	3.0%	14.83	16.2 GB	3.0%	4708.82
Feb 15 22:00:00 - 22:59:59	51452	2.9%	14.29	15.8 GB	2.9%	4594.66
Feb 15 23:00:00 - 23:59:59	51164	2.8%	14.21	15.1 GB	2.8%	4408.36
Feb 16 00:00:00 - 00:59:59	79621	4.4%	22.12	25.4 GB	4.7%	7409.23
Feb 16 01:00:00 - 01:59:59	65385	3.6%	18.16	21.1 GB	3.9%	6158.43
Feb 16 02:00:00 - 02:59:59	88108	4.9%	24.47	29.2 GB	5.4%	8515.79
Feb 16 03:00:00 - 03:59:59	88780	4.9%	24.66	29.8 GB	5.5%	8674.82
Feb 16 04:00:00 - 04:15:02	22761	1.3%	25.23	7.5 GB	1.4%	8751.31
TOTAL: 24:00:00	1795566	100.0%	20.78	545.3 GB	100.0%	6617.58

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4734	216	3855	2	661
2	news.task.gda.pl	1145	915	52	0	178
3	news.nask.pl	1042	3	1016	2	21
4	news.icm.edu.pl	40	0	0	0	40
TOTAL: 4		6961	1134	4923	4	900

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	174
mailing.freebsd.svn-ports-head	173
uk.railway	129
mailing.freebsd.ports-bugs	43
mailing.postfix.users	39
alt.windows7.general	38
mailing.openbsd.source-changes	36
mailing.freebsd.svn-src-all	34
fa.freebsd.cvs-all	34
mailing.database.pgsql-general	33
24hoursupport.helpdesk	32
mailing.freebsd.svn-src-head	31
fr.usenet.abus.nocem	23
mailing.unix.samba	20
mailing.freebsd.ports	19
gmane.linux.alsa.devel	15
mailing.freebsd.multimedia	13
mozilla.dev.tree-management	13
comp.mobile.ipad	12
alt.politics.scorched-earth	12
TOTAL: 71	1134

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

Groups	Count
pl.sci.historia	3
pl.sci.matematyka	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	434
EMP (fsl)	168
Attached HTML file	148
EMP (md5)	48
HTML post	1
TOTAL: 5	799

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56196	4283	29490	849	1	17	7%	24:03:40
2	panorama	36418	1532	33129	249	0	0	4%	23:54:23
3	poznan	10951	26	9666	20	0	0	0%	24:06:18
4	nask	21179	25	13251	18	0	0	0%	24:00:01
5	task	1960	18	1027	6	0	0	0%	22:50:40
6	okapi	0	0	0	0	0	2031	0%	24:00:03
TOTAL: 6		126704	5884	86563	1142	1	2048	4%	142:55:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	541.1 MB	14.6 MB	555.6 MB	6.6 KB/s	110.9 KB	24:03:40
2	panorama	4.8 MB	923.3 KB	5.7 MB	0.1 KB/s	3.3 KB	23:54:23
3	nask	71.6 KB	16.5 MB	16.6 MB	0.2 KB/s	394.7 KB	24:00:01
4	poznan	59.6 KB	47.7 KB	107.3 KB	0.0 KB/s	2.3 KB	24:06:18
5	task	37.0 KB	15.9 KB	52.9 KB	0.0 KB/s	2.2 KB	22:50:40
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		546.0 MB	32.0 MB	578.0 MB	1.2 KB/s	84.2 KB	142:55:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	312	460.2 KB	288	0	00:00:14
2	web.man.lodz.pl	289	75	124.7 KB	36	1	00:00:29
3	ip-176.105.135.22.tvsat364.lodz.pl	1	1	1.0 KB	1	0	00:04:23
4	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		580	388	585.8 KB	325	3	10:57:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	460.2 KB	288	0	00:00:14
2	*.man.lodz.pl	675	75	124.7 KB	36	3	00:00:35
3	*.105.135.22.tvsat364.lodz.pl	1	1	1.0 KB	1	0	00:04:23
4	*.pa.nsw.optusnet.com.au	56	0	0.0 KB	0	0	00:02:25
5	unresolved	64	0	0.0 KB	0	0	10:49:55
6	*.toya.net.pl	19	0	0.0 KB	0	0	00:00:07
TOTAL: 6		1103	388	585.8 KB	325	3	10:57:42

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.136	0.832	10:49:55
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.016	00:04:23
pa49-181-246-247.pa.nsw.optusnet.com.au	0.112	0.568	00:02:25
web.man.lodz.pl	0.684	3.364	00:00:29
vegas.cm.p.lodz.pl	0.620	3.348	00:00:14
staticline41435.toya.net.pl	0.056	0.196	00:00:07
mon.man.lodz.pl	0.672	3.128	00:00:04
david.man.lodz.pl	0.216	1.040	00:00:01
kujawiak.man.lodz.pl	0.012	0.028	00:00:00
TOTAL: 9	2.512	12.520	10:57:42

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
212.191.78.190	64
pa49-181-246-247.pa.nsw.optusnet.com.au	56
staticline41435.toya.net.pl	19
TOTAL: 5	523

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-246-247.pa.nsw.optusnet.com.au	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	80.4%
2	pl	76	19.6%
TOTAL: 2		388	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	44
3	pl.comp.os.ms-windows.winnt	30
4	pl.soc.prawo	1
5	pl.rec.mineraly	1
TOTAL: 5		388

Daily Usenet report

Feb 15 04:15:02 -- Feb 16 04:15:02