Daily Usenet report: Feb 4 04:15:03

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Feb 4 11:50:03 newsfeed pgpverify[25146]: pgpverify: verification failed
Feb 4 12:18:00 newsfeed nnrpd[26094]: SERVER perl filter not defined
Feb 4 12:23:19 newsfeed nnrpd[26328]: SERVER perl filter not defined
Feb 4 13:54:47 newsfeed nnrpd[29251]: SERVER perl filter not defined
Feb 4 14:00:50 newsfeed nnrpd[29449]: SERVER perl filter not defined
Feb 4 14:02:09 newsfeed nnrpd[29592]: SERVER perl filter not defined
Feb 4 14:03:22 newsfeed nnrpd[29593]: SERVER perl filter not defined
Feb 4 14:27:05 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Feb 4 14:41:55 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Feb 4 16:01:46 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Feb 4 16:03:53 newsfeed nnrpd[1060]: SERVER perl filter not defined
Feb 4 17:44:33 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Feb 4 18:09:32 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Feb 5 01:34:44 newsfeed nnrpd[19963]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1393981	97.7%	159.5 MB	97.2%
innd	14193	1.0%	2.3 MB	1.4%
nnrpd	11524	0.8%	1.2 MB	0.7%
innfeed	7067	0.5%	1.1 MB	0.6%
controlchan	3	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1426769	100.0%	164.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1387188	92.0%
Negative hits	75420	5.0%
Positive hits	44227	2.9%
Cache misses	283	0.0%
TOTAL: 4	1507118	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.346	0.0%	332	0.000	37.187	587.000
article cleanup	00:00:21.336	0.0%	1393078	0.002	0.015	0.037
article logging	00:00:44.251	0.1%	1393279	0.008	0.032	0.458
article parse	00:50:33.359	3.5%	131938457	0.004	0.023	0.088
article write	00:13:17.982	0.9%	1384718	0.257	0.576	2.538
artlog/artcncl	00:00:00.007	0.0%	319	0.000	0.022	1.000
artlog/artparse	00:00:00.001	0.0%	11	0.000	0.091	1.000
data move	00:02:13.276	0.2%	140720368	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.821	0.0%	273	0.000	6.670	258.000
hishave/artcncl	00:00:01.890	0.0%	332	0.000	5.693	203.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:00:55.814	16.7%	1506972	0.648	9.593	15.116
history sync	00:00:00.014	0.0%	291	0.000	0.048	2.000
history write	04:32:25.712	18.9%	1387180	0.997	11.783	27.002
hiswrite/artcncl	00:00:00.679	0.0%	59	0.000	11.508	154.000
idle	09:36:52.639	40.1%	96300576	0.134	0.359	13.231
nntp read	00:48:21.954	3.4%	130966161	0.005	0.022	0.042
overview write	00:00:08.473	0.0%	1384718	0.000	0.006	0.017
perl filter	03:09:34.636	13.2%	1387169	3.520	8.200	10.317
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:47.792	0.2%	2818945	0.010	0.060	0.103
TOTAL: 23:59:45.840	23:18:33.982	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.181	0.0%	51618	0.000	0.004	0.167
article prepare	00:00:00.016	0.0%	73910	0.000	0.000	0.083
article read	00:00:05.717	0.0%	18151	0.000	0.315	37.875
backlog stats	00:00:00.235	0.0%	370046	0.000	0.001	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.521	0.0%	215261	0.000	0.012	1.000
data write	00:00:12.069	0.0%	192385	0.000	0.063	31.000
idle	23:59:06.738	100.0%	370046	0.000	233.341	13824.000
status file	00:00:01.312	0.0%	233	0.000	5.631	236.000
TOTAL: 23:59:38.637	23:59:28.789	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	61:59:57.817	100.0%	92164	0.000	2421.746	600419.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.882	0.0%	186740	0.000	0.021	23.444
readart	00:00:02.829	0.0%	520	0.000	5.440	341.000
TOTAL: 62:00:59.629	62:00:04.528	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	716	1378775	1371371	2810	4594	99%	282:00:07
2	news.nask.pl	2	19862	6914	10903	2045	34%	23:46:38
3	news.task.gda.pl	13	32623	6737	24177	1709	20%	45:41:08
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	51	260	4	243	13	1%	17:55:18
TOTAL: 5		791	1431528	1385034	38133	8361	96%	369:23:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	436.6 GB	53.9 MB	64.1 MB	436.7 GB	99%	332.8 KB
2	news.nask.pl	31.4 MB	6.3 MB	6.4 MB	44.1 MB	71%	5.0 KB
3	news.task.gda.pl	15.4 MB	148.6 KB	4.9 MB	20.4 MB	75%	2.5 KB
4	news.icm.edu.pl	194.2 KB	21.6 KB	134.1 KB	349.9 KB	55%	20.6 KB
5	localhost	14.0 KB	0.0 KB	0.0 KB	14.0 KB	100%	1.8 KB
TOTAL: 5		436.6 GB	60.4 MB	75.5 MB	436.7 GB	99%	328.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 4 04:15:03 - 04:59:59	65855	4.8%	24.42	20.0 GB	4.6%	7773.92
Feb 4 05:00:00 - 05:59:59	82829	6.0%	23.01	26.4 GB	6.0%	7680.74
Feb 4 06:00:00 - 06:59:59	78585	5.7%	21.83	25.5 GB	5.8%	7424.30
Feb 4 07:00:00 - 07:59:59	40462	2.9%	11.24	14.1 GB	3.2%	4094.91
Feb 4 08:00:00 - 08:59:59	43977	3.2%	12.22	15.5 GB	3.5%	4506.86
Feb 4 09:00:00 - 09:59:59	41876	3.0%	11.63	14.9 GB	3.4%	4340.12
Feb 4 10:00:00 - 10:59:59	44098	3.2%	12.25	16.0 GB	3.7%	4647.56
Feb 4 11:00:00 - 11:59:59	40950	3.0%	11.38	13.0 GB	3.0%	3798.13
Feb 4 12:00:00 - 12:59:59	50907	3.7%	14.14	14.0 GB	3.2%	4089.10
Feb 4 13:00:00 - 13:59:59	51008	3.7%	14.17	14.3 GB	3.3%	4179.63
Feb 4 14:00:00 - 14:59:59	49434	3.6%	13.73	13.1 GB	3.0%	3815.68
Feb 4 15:00:00 - 15:59:59	51688	3.7%	14.36	13.9 GB	3.2%	4056.15
Feb 4 16:00:00 - 16:59:59	59487	4.3%	16.52	15.5 GB	3.6%	4528.32
Feb 4 17:00:00 - 17:59:59	64946	4.7%	18.04	17.3 GB	4.0%	5024.54
Feb 4 18:00:00 - 18:59:59	59304	4.3%	16.47	15.6 GB	3.6%	4540.06
Feb 4 19:00:00 - 19:59:59	52769	3.8%	14.66	16.4 GB	3.8%	4783.54
Feb 4 20:00:00 - 20:59:59	46712	3.4%	12.98	15.3 GB	3.5%	4449.12
Feb 4 21:00:00 - 21:59:59	41283	3.0%	11.47	13.5 GB	3.1%	3931.57
Feb 4 22:00:00 - 22:59:59	42372	3.1%	11.77	13.9 GB	3.2%	4063.15
Feb 4 23:00:00 - 23:59:59	43876	3.2%	12.19	14.1 GB	3.2%	4118.22
Feb 5 00:00:00 - 00:59:59	79193	5.7%	22.00	25.5 GB	5.8%	7414.97
Feb 5 01:00:00 - 01:59:59	85898	6.2%	23.86	27.7 GB	6.3%	8068.73
Feb 5 02:00:00 - 02:59:59	69482	5.0%	19.30	24.3 GB	5.6%	7070.88
Feb 5 03:00:00 - 03:59:59	77663	5.6%	21.57	28.7 GB	6.6%	8367.10
Feb 5 04:00:00 - 04:15:03	20436	1.5%	22.63	7.8 GB	1.8%	9099.38
TOTAL: 24:00:00	1385090	100.0%	16.03	436.4 GB	100.0%	5295.82

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4759	256	3831	5	667
2	news.nask.pl	2081	5	2018	8	50
3	news.task.gda.pl	1719	1206	59	0	454
4	news.icm.edu.pl	13	0	1	0	12
TOTAL: 4		8572	1467	5909	13	1183

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	157
mailing.freebsd.svn-ports-all	147
mailing.freebsd.svn-ports-head	143
mailing.freebsd.svn-src-all	61
fa.freebsd.cvs-all	60
mailing.freebsd.ports-bugs	51
mailing.gcc.gcc-cvs	47
mailing.unix.samba	38
mailing.openbsd.misc	37
mailing.openbsd.source-changes	34
mailing.freebsd.svn-src-head	34
mailing.unix.dovecot	31
mailing.database.pgsql-general	30
alt.politics.scorched-earth	29
24hoursupport.helpdesk	29
mozilla.dev.tree-management	27
mailing.openbsd.ports-changes	26
mailing.freebsd.doc	26
mailing.freebsd.ports	26
mailing.unix.samba-technical	25
TOTAL: 74	1467

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	517
EMP (phl)	212
EMP (fsl)	171
EMP (md5)	36
Scoring filter (8)	21
HTML post	11
Attached HTML file	2
Scoring filter (9)	1
TOTAL: 8	971

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	69966	11804	34184	3466	3	2103	16%	24:07:02
2	panorama	50877	2526	46634	371	0	1	4%	24:01:30
3	task	1756	318	729	35	0	13	18%	22:06:20
4	poznan	15701	29	13425	18	0	0	0%	24:02:32
5	nask	21047	16	14711	0	0	0	0%	24:00:28
6	okapi	0	0	0	0	0	2980	0%	24:00:00
TOTAL: 6		159347	14693	109683	3890	3	5097	9%	142:17:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.5 GB	603.2 MB	2.1 GB	25.4 KB/s	144.6 KB	24:07:02
2	panorama	14.8 MB	1.2 MB	16.1 MB	0.2 KB/s	5.7 KB	24:01:30
3	task	714.6 KB	83.5 KB	798.1 KB	0.0 KB/s	2.3 KB	22:06:20
4	poznan	141.8 KB	46.5 KB	188.3 KB	0.0 KB/s	4.0 KB	24:02:32
5	nask	108.5 KB	0.0 KB	108.5 KB	0.0 KB/s	6.8 KB	24:00:28
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		1.5 GB	604.6 MB	2.1 GB	4.3 KB/s	119.8 KB	142:17:52

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	352	557.8 KB	288	0	00:00:18
2	212.191.78.190	329	98	196.6 KB	33	1	47:03:46
3	web.man.lodz.pl	288	70	68.8 KB	34	0	00:00:42
4	kujawiak.man.lodz.pl	7	0	0.0 KB	0	7	00:00:01
TOTAL: 4		912	520	823.2 KB	355	8	62:00:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	352	557.8 KB	288	0	00:00:18
2	unresolved	331	98	196.6 KB	33	1	47:04:00
3	*.man.lodz.pl	679	70	68.8 KB	34	7	00:00:50
4	*.pa.nsw.optusnet.com.au	214	0	0.0 KB	0	0	00:06:39
5	*.4web.pl	38	0	0.0 KB	0	0	06:55:47
6	*.dynamic.chello.pl	2	0	0.0 KB	0	0	00:00:00
7	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:28:06
8	*.105.135.22.tvsat364.lodz.pl	1	0	0.0 KB	0	0	00:03:27
9	*.206.15.62.static.jazztel.es	4	0	0.0 KB	0	0	07:21:21
10	*.toya.net.pl	92	0	0.0 KB	0	0	00:00:25
TOTAL: 10		1650	520	823.2 KB	355	8	62:00:58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.820	5.004	47:03:46
241.206.15.62.static.jazztel.es	4.952	5.860	07:21:21
net133-43.4web.pl	0.088	0.520	06:55:47
ecj49.neoplus.adsl.tpnet.pl	0.004	0.016	00:28:06
pa49-181-246-1.pa.nsw.optusnet.com.au	0.580	2.900	00:06:39
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.012	00:03:27
web.man.lodz.pl	0.576	4.380	00:00:42
staticline41435.toya.net.pl	0.208	1.328	00:00:25
vegas.cm.p.lodz.pl	0.620	4.780	00:00:18
212.51.203.199	0.000	0.032	00:00:13
mon.man.lodz.pl	0.468	4.140	00:00:05
david.man.lodz.pl	0.248	1.476	00:00:01
kujawiak.man.lodz.pl	0.032	0.136	00:00:01
89-76-214-38.dynamic.chello.pl	0.008	0.028	00:00:00
TOTAL: 14	8.608	30.612	62:00:58

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-1.pa.nsw.optusnet.com.au	214
david.man.lodz.pl	96
staticline41435.toya.net.pl	92
net133-43.4web.pl	38
241.206.15.62.static.jazztel.es	4
89-76-214-38.dynamic.chello.pl	2
212.51.203.199	2
ip-176.105.135.22.tvsat364.lodz.pl	1
ecj49.neoplus.adsl.tpnet.pl	1
TOTAL: 10	738

NNRP client timeouts [Top 20]:

System	Conn	Peer
net133-43.4web.pl	2	0
TOTAL: 1	2	6

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	352	67.7%
2	pl	167	32.1%
3	alt	1	0.2%
TOTAL: 3		520	100.0%

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

Newsgroup		Count
1	lodman.info	352
2	pl.comp.pecet	50
3	pl.rec.rowery	39
4	pl.misc.kolej	33
5	pl.comp.os.ms-windows.winnt	20
6	pl.soc.prawo	13
7	pl.regionalne.warszawa	6
8	pl.rec.gory	5
9	alt.pl.praca.oferowana	1
10	pl.comp.os.linux	1
TOTAL: 10		520

Daily Usenet report

Feb 4 04:15:03 -- Feb 5 04:15:03