Daily Usenet report: Dec 4 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Dec 4 10:56:24 newsfeed nnrpd[7954]: SERVER perl filter not defined
Dec 4 11:50:06 newsfeed pgpverify[9668]: pgpverify: verification failed
Dec 4 12:16:05 newsfeed nnrpd[10578]: SERVER perl filter not defined
Dec 4 14:21:18 newsfeed nnrpd[14611]: SERVER perl filter not defined
Dec 4 23:47:46 newsfeed nnrpd[758]: SERVER perl filter not defined
Dec 4 23:55:15 newsfeed overchan[2210]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1771227	98.1%	201.6 MB	97.7%
innd	16183	0.9%	2.7 MB	1.3%
nnrpd	10800	0.6%	1.1 MB	0.5%
innfeed	7185	0.4%	1.1 MB	0.5%
controlchan	3	0.0%	0.5 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1805400	100.0%	206.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1764015	94.0%
Negative hits	68729	3.7%
Positive hits	43973	2.3%
Cache misses	281	0.0%
TOTAL: 4	1876998	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.722	0.0%	353	0.000	33.207	618.000
article cleanup	00:00:30.821	0.0%	1769402	0.006	0.017	0.038
article logging	00:00:57.808	0.1%	1769565	0.011	0.033	0.592
article parse	01:10:07.829	4.9%	172995340	0.007	0.024	0.080
article write	00:16:35.619	1.2%	1762758	0.303	0.565	1.336
artlog/artcncl	00:00:00.006	0.0%	336	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:04:12.998	0.3%	184824747	0.000	0.001	0.032
hisgrep/artcncl	00:00:01.972	0.0%	300	0.000	6.573	295.000
hishave/artcncl	00:00:01.043	0.0%	353	0.000	2.955	178.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:18:25.424	9.6%	1878258	0.009	4.422	63.798
history sync	00:00:00.015	0.0%	293	0.000	0.051	1.000
history write	04:26:38.228	18.5%	1765014	0.061	9.064	43.948
hiswrite/artcncl	00:00:01.021	0.0%	53	0.000	19.264	256.000
idle	09:30:09.690	39.6%	114132647	0.169	0.300	1.585
nntp read	01:01:31.186	4.3%	171674025	0.008	0.022	0.040
overview write	00:00:11.046	0.0%	1762758	0.000	0.006	0.040
perl filter	04:28:15.304	18.6%	1765009	5.521	9.119	11.930
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:49.380	0.3%	3568398	0.020	0.064	0.097
TOTAL: 23:59:01.664	23:21:41.112	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.143	0.0%	42904	0.000	0.003	0.143
article prepare	00:00:00.008	0.0%	42550	0.000	0.000	0.083
article read	00:00:03.268	0.0%	10390	0.000	0.315	89.714
backlog stats	00:00:00.228	0.0%	331011	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.282	0.0%	194104	0.000	0.012	0.088
data write	00:00:07.013	0.0%	167325	0.000	0.042	1.000
idle	23:59:34.245	100.0%	331011	0.000	260.941	11624.000
status file	00:00:02.576	0.0%	244	0.000	10.557	276.500
TOTAL: 23:59:57.573	23:59:49.763	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	54:50:02.017	100.0%	6967	0.000	28333.862	600220.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.238	0.0%	16607	0.000	0.075	6.676
readart	00:00:01.031	0.0%	557	0.000	1.851	23.500
TOTAL: 54:50:56.309	54:50:04.286	100.0%	-	-	-	-

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
admin@openwatcom.news-admin.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	775	1757789	1751533	1746	4510	99%	331:00:19
2	news.task.gda.pl	16	33464	6375	25722	1367	19%	47:48:34
3	news.nask.pl	2	18985	5580	12607	798	29%	23:42:03
4	localhost	5	4	4	0	0	100%	00:00:00
5	news.icm.edu.pl	46	221	3	208	10	1%	16:45:01
TOTAL: 5		844	1810463	1763495	40283	6685	97%	419:15:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	632.0 GB	19.8 MB	91.2 MB	632.1 GB	99%	377.5 KB
2	news.nask.pl	27.9 MB	2.8 MB	3.0 MB	33.7 MB	82%	5.4 KB
3	news.task.gda.pl	13.8 MB	57.8 KB	4.1 MB	17.9 MB	77%	2.4 KB
4	news.icm.edu.pl	286.2 KB	0.0 KB	455.8 KB	742.0 KB	38%	57.1 KB
5	localhost	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	1.2 KB
TOTAL: 5		632.1 GB	22.7 MB	98.7 MB	632.2 GB	99%	374.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 4 04:15:03 - 04:59:59	67343	3.8%	24.97	24.4 GB	3.9%	9494.06
Dec 4 05:00:00 - 05:59:59	86119	4.9%	23.92	31.5 GB	5.0%	9181.93
Dec 4 06:00:00 - 06:59:59	88405	5.0%	24.56	31.8 GB	5.0%	9260.42
Dec 4 07:00:00 - 07:59:59	91006	5.2%	25.28	34.1 GB	5.4%	9942.32
Dec 4 08:00:00 - 08:59:59	95849	5.4%	26.62	34.3 GB	5.4%	9992.92
Dec 4 09:00:00 - 09:59:59	78750	4.5%	21.88	31.4 GB	5.0%	9159.18
Dec 4 10:00:00 - 10:59:59	84965	4.8%	23.60	31.6 GB	5.0%	9196.24
Dec 4 11:00:00 - 11:59:59	82871	4.7%	23.02	30.7 GB	4.9%	8935.87
Dec 4 12:00:00 - 12:59:59	73328	4.2%	20.37	25.3 GB	4.0%	7375.63
Dec 4 13:00:00 - 13:59:59	73799	4.2%	20.50	26.6 GB	4.2%	7758.55
Dec 4 14:00:00 - 14:59:59	52094	3.0%	14.47	18.1 GB	2.9%	5276.90
Dec 4 15:00:00 - 15:59:59	48796	2.8%	13.55	15.9 GB	2.5%	4622.91
Dec 4 16:00:00 - 16:59:59	48433	2.7%	13.45	15.6 GB	2.5%	4550.95
Dec 4 17:00:00 - 17:59:59	48062	2.7%	13.35	15.5 GB	2.4%	4507.64
Dec 4 18:00:00 - 18:59:59	47652	2.7%	13.24	16.1 GB	2.5%	4684.09
Dec 4 19:00:00 - 19:59:59	45645	2.6%	12.68	15.6 GB	2.5%	4545.78
Dec 4 20:00:00 - 20:59:59	48734	2.8%	13.54	17.0 GB	2.7%	4958.98
Dec 4 21:00:00 - 21:59:59	79621	4.5%	22.12	25.7 GB	4.1%	7494.23
Dec 4 22:00:00 - 22:59:59	92613	5.2%	25.73	30.6 GB	4.8%	8927.38
Dec 4 23:00:00 - 23:59:59	91143	5.2%	25.32	31.1 GB	4.9%	9045.20
Dec 5 00:00:00 - 00:59:59	78304	4.4%	21.75	28.1 GB	4.4%	8183.12
Dec 5 01:00:00 - 01:59:59	93222	5.3%	25.89	35.3 GB	5.6%	10288.04
Dec 5 02:00:00 - 02:59:59	67357	3.8%	18.71	26.1 GB	4.1%	7608.94
Dec 5 03:00:00 - 03:59:59	80921	4.6%	22.48	32.1 GB	5.1%	9336.30
Dec 5 04:00:00 - 04:15:03	19044	1.1%	21.09	7.5 GB	1.2%	8720.54
TOTAL: 24:00:00	1764076	100.0%	20.42	632.2 GB	100.0%	7672.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4648	202	3600	1	845
2	news.task.gda.pl	1336	1109	29	0	198
3	news.nask.pl	821	24	766	0	31
4	news.icm.edu.pl	10	0	0	0	10
TOTAL: 4		6815	1335	4395	1	1084

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	58
mailing.freebsd.svn-src-all	56
alt.politics.scorched-earth	54
fa.freebsd.cvs-all	51
mailing.openbsd.misc	51
mailing.freebsd.svn-ports-all	50
mailing.unix.samba	50
mailing.freebsd.svn-ports-head	50
mozilla.dev.tree-management	48
mailing.freebsd.ports-bugs	44
gmane.linux.alsa.devel	42
mailing.freebsd.svn-src-head	42
comp.sys.raspberry-pi	41
mailing.unix.samba-technical	40
alt.windows7.general	40
mailing.gcc.gcc-cvs	33
comp.mobile.android	31
it.comp.os.win.windows7	27
mailing.unix.dovecot	27
mailing.openbsd.source-changes	27
TOTAL: 83	1335

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

Groups	Count
pl.sci.historia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	716
EMP (fsl)	156
EMP (md5)	19
HTML post	17
Attached HTML file	3
Scoring filter (9)	2
Scoring filter (8)	2
Scoring filter (10)	1
TOTAL: 8	916

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44638	3626	38498	794	0	0	8%	24:03:38
2	icm	67673	1639	36559	4093	7	11	2%	23:53:30
3	task	2381	118	1207	15	0	0	4%	22:20:21
4	poznan	14506	32	11762	18	0	0	0%	23:56:28
5	nask	23135	29	15583	272	0	0	0%	23:59:24
6	okapi	0	0	0	0	0	2932	0%	24:00:00
TOTAL: 6		152333	5444	103609	5192	7	2943	3%	142:13:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	30.6 MB	803.8 MB	834.4 MB	9.9 KB/s	149.1 KB	23:53:30
2	panorama	16.6 MB	3.2 MB	19.8 MB	0.2 KB/s	4.6 KB	24:03:38
3	task	269.7 KB	27.9 KB	297.6 KB	0.0 KB/s	2.2 KB	22:20:21
4	nask	141.5 KB	320.4 MB	320.6 MB	3.8 KB/s	1.1 MB	23:59:24
5	poznan	102.5 KB	32.2 KB	134.7 KB	0.0 KB/s	2.7 KB	23:56:28
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		47.7 MB	1.1 GB	1.1 GB	2.4 KB/s	113.1 KB	142:13:21

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	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	324	458.1 KB	288	0	0	00:00:14
2	lze200.lze.lodz.pl	8	79	144.5 KB	12	0	0	01:22:58
3	web.man.lodz.pl	288	64	53.4 KB	29	0	0	00:00:25
4	212.191.78.190	315	44	84.8 KB	23	0	0	47:12:32
5	195.69.80.74	24	22	20.1 KB	4	0	11	00:47:46
6	staticline-31-182-67-226.toya.net.pl	17	12	9.9 KB	7	0	6	00:45:55
7	ip-176.105.135.22.tvsat364.lodz.pl	3	5	7.1 KB	1	0	0	01:24:00
8	432.lab.ics.p.lodz.pl	3	3	2.3 KB	1	1	0	00:34:07
9	cra164.neoplus.adsl.tpnet.pl	4	2	2.0 KB	1	0	1	00:06:16
10	h59ec3930.dkkosun.dyn.perspektivbredband.net	2	1	0.8 KB	1	0	0	00:00:01
11	staticline-31-182-157-244.toya.net.pl	1	0	0.0 KB	0	0	1	00:00:12
12	kujawiak.man.lodz.pl	3	0	0.0 KB	0	3	0	00:00:01
TOTAL: 12		956	556	783.1 KB	367	4	19	54:50:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	324	458.1 KB	288	0	0	00:00:14
2	*.lze.lodz.pl	8	79	144.5 KB	12	0	0	01:22:58
3	unresolved	341	66	104.9 KB	27	0	11	48:00:34
4	*.man.lodz.pl	676	64	53.4 KB	29	3	0	00:00:35
5	*.toya.net.pl	102	12	9.9 KB	7	0	7	00:46:13
6	*.105.135.22.tvsat364.lodz.pl	3	5	7.1 KB	1	0	0	01:24:00
7	*.lab.ics.p.lodz.pl	3	3	2.3 KB	1	1	0	00:34:07
8	*.neoplus.adsl.tpnet.pl	4	2	2.0 KB	1	0	1	00:06:16
9	*.dkkosun.dyn.perspektivbredband.net	2	1	0.8 KB	1	0	0	00:00:01
10	*.4web.pl	1	0	0.0 KB	0	0	0	00:30:00
11	*.p.lodz.pl	1	0	0.0 KB	0	0	0	00:00:49
12	*.lodz.mm.pl	14	0	0.0 KB	0	0	0	02:05:01
TOTAL: 12		1443	556	783.1 KB	367	4	19	54:50:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.788	3.640	47:12:32
host-89-228-165-199.lodz.mm.pl	0.032	0.140	02:05:01
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.028	01:24:00
lze200.lze.lodz.pl	0.036	0.096	01:22:58
195.69.80.74	0.368	0.356	00:47:46
staticline-31-182-67-226.toya.net.pl	0.088	0.204	00:45:55
432.lab.ics.p.lodz.pl	0.016	0.040	00:34:07
rej.4web.pl	0.000	0.008	00:30:00
cra164.neoplus.adsl.tpnet.pl	0.036	0.056	00:06:16
pc-212-51-221-162.p.lodz.pl	0.004	0.008	00:00:49
web.man.lodz.pl	0.548	3.152	00:00:25
212.51.203.199	0.000	0.032	00:00:14
vegas.cm.p.lodz.pl	0.548	3.188	00:00:14
staticline-31-182-157-244.toya.net.pl	0.004	0.008	00:00:12
mon.man.lodz.pl	0.612	3.128	00:00:06
staticline41435.toya.net.pl	0.156	0.840	00:00:05
david.man.lodz.pl	0.220	0.972	00:00:02
h59ec3930.dkkosun.dyn.perspektivbredband.net	0.012	0.016	00:00:01
kujawiak.man.lodz.pl	0.004	0.060	00:00:01
?	0.004	0.036	00:00:00
TOTAL: 20	3.492	16.008	54:50:54

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	84
host-89-228-165-199.lodz.mm.pl	14
212.51.203.199	2
rej.4web.pl	1
pc-212-51-221-162.p.lodz.pl	1
TOTAL: 7	487

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
rej.4web.pl	1	0
staticline-31-182-67-226.toya.net.pl	1	1
432.lab.ics.p.lodz.pl	1	0
TOTAL: 5	7	4

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	324	58.3%
2	pl	192	34.5%
3	lodz	40	7.2%
TOTAL: 3		556	100.0%

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

Newsgroup		Count
1	lodman.info	324
2	pl.comp.os.ms-windows.winnt	59
3	lodz.test	40
4	pl.comp.pecet	29
5	pl.rec.kuchnia	22
6	pl.comp.os.linux	16
7	pl.soc.prawo	14
8	pl.misc.telefonia.gsm	14
9	pl.rec.rowery	9
10	pl.internet.polip	8
11	pl.rec.gory	7
12	pl.regionalne.warszawa	6
13	pl.misc.kolej	3
14	pl.rec.dom	2
15	pl.comp.os.linux.sieci	2
16	pl.praca.dyskusje	1
TOTAL: 16		556

Daily Usenet report

Dec 4 04:15:03 -- Dec 5 04:15:03