Daily Usenet report: Mar 25 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Mar 25 09:47:50 newsfeed overchan[7775]: input line too long, skipping
Mar 25 11:37:03 newsfeed nnrpd[4922]: SERVER perl filter not defined
Mar 25 12:23:32 newsfeed innfeed[2370]: nask:0 connect: Network is unreachable
Mar 25 12:23:32 newsfeed innfeed[2370]: icm:0 connect: Network is unreachable
Mar 25 13:48:15 newsfeed nnrpd[6628]: SERVER perl filter not defined
Mar 25 15:36:54 newsfeed nnrpd[10284]: SERVER perl filter not defined
Mar 25 16:22:34 newsfeed overchan[2371]: input line too long, skipping
Mar 25 19:39:47 newsfeed nnrpd[18283]: SERVER perl filter not defined
Mar 25 21:00:03 newsfeed pgpverify[20990]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1841463	98.2%	210.3 MB	97.8%
innd	19196	1.0%	3.0 MB	1.4%
nnrpd	7022	0.4%	728.2 KB	0.3%
innfeed	6845	0.4%	1017.7 KB	0.5%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1874531	100.0%	214.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1834766	96.7%
Negative hits	46226	2.4%
Positive hits	16739	0.9%
Cache misses	169	0.0%
TOTAL: 4	1897900	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.760	0.0%	314	0.000	24.713	816.000
article cleanup	00:00:29.123	0.0%	1839456	0.003	0.016	0.029
article logging	00:00:49.489	0.1%	1839565	0.008	0.027	0.390
article parse	01:17:36.470	5.4%	149285746	0.010	0.031	0.070
article write	00:17:44.551	1.2%	1832038	0.274	0.581	3.822
artlog/artcncl	00:00:00.005	0.0%	283	0.000	0.018	0.500
data move	00:03:20.962	0.2%	160762208	0.000	0.001	0.005
hisgrep/artcncl	00:00:02.144	0.0%	283	0.000	7.576	88.000
hishave/artcncl	00:00:00.412	0.0%	314	0.000	1.312	19.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:42:02.557	7.1%	1898858	0.014	3.224	11.350
history sync	00:00:00.014	0.0%	293	0.000	0.048	1.000
history write	03:18:01.633	13.8%	1835835	0.070	6.472	25.649
hiswrite/artcncl	00:00:00.054	0.0%	31	0.000	1.742	14.000
idle	11:22:17.168	47.4%	91483585	0.311	0.447	14.702
nntp read	01:01:37.473	4.3%	147709730	0.011	0.025	0.038
overview write	00:00:12.363	0.0%	1832038	0.000	0.007	0.016
perl filter	04:23:02.213	18.3%	1835835	5.613	8.597	10.772
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:25.236	0.2%	3701390	0.011	0.055	0.079
TOTAL: 23:58:28.663	23:30:49.627	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.135	0.0%	37390	0.000	0.004	0.111
article prepare	00:00:00.004	0.0%	17040	0.000	0.000	0.125
article read	00:00:00.324	0.0%	4199	0.000	0.077	1.000
backlog stats	00:00:00.104	0.0%	214890	0.000	0.000	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.352	0.0%	141567	0.000	0.010	1.000
data write	00:00:04.989	0.0%	105038	0.000	0.047	13.000
idle	23:58:31.754	100.0%	214890	0.000	401.656	23100.000
status file	00:00:01.888	0.0%	226	0.000	8.354	270.500
TOTAL: 23:58:45.458	23:58:40.550	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.165	0.0%	41	4.024	4.024	4.024
idle	05:37:18.971	99.9%	12634	0.000	1601.945	450237.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.586	0.0%	27168	0.000	0.022	1.333
readart	00:00:03.500	0.0%	499	0.000	7.014	47.667
TOTAL: 05:37:49.195	05:37:23.222	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	778	1835930	1828217	1029	6684	99%	335:20:52
2	news.nask.pl	3	16955	4500	11835	620	26%	23:58:56
3	news.icm.edu.pl	44	174	19	127	28	10%	13:49:51
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		830	1853063	1832740	12991	7332	98%	373:09:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	596.0 GB	19.9 MB	952.2 MB	597.0 GB	99%	341.2 KB
2	news.nask.pl	23.2 MB	1.8 MB	1.8 MB	26.8 MB	86%	5.4 KB
3	news.icm.edu.pl	666.9 KB	19.5 KB	2.2 MB	2.9 MB	22%	63.1 KB
4	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.8 KB
TOTAL: 4		596.1 GB	21.7 MB	956.2 MB	597.0 GB	99%	340.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 25 04:15:02 - 04:59:59	52745	2.9%	19.55	18.1 GB	3.0%	7032.06
Mar 25 05:00:00 - 05:59:59	72315	3.9%	20.09	24.9 GB	4.2%	7255.72
Mar 25 06:00:00 - 06:59:59	82848	4.5%	23.01	28.5 GB	4.8%	8310.51
Mar 25 07:00:00 - 07:59:59	69073	3.8%	19.19	23.4 GB	3.9%	6813.73
Mar 25 08:00:00 - 08:59:59	77345	4.2%	21.48	26.8 GB	4.5%	7810.31
Mar 25 09:00:00 - 09:59:59	54551	3.0%	15.15	15.2 GB	2.5%	4420.57
Mar 25 10:00:00 - 10:59:59	60224	3.3%	16.73	18.8 GB	3.1%	5467.51
Mar 25 11:00:00 - 11:59:59	73012	4.0%	20.28	23.6 GB	4.0%	6885.95
Mar 25 12:00:00 - 12:59:59	74132	4.0%	20.59	25.8 GB	4.3%	7515.81
Mar 25 13:00:00 - 13:59:59	79223	4.3%	22.01	27.5 GB	4.6%	8000.12
Mar 25 14:00:00 - 14:59:59	76485	4.2%	21.25	26.6 GB	4.5%	7753.75
Mar 25 15:00:00 - 15:59:59	68238	3.7%	18.95	24.7 GB	4.1%	7191.94
Mar 25 16:00:00 - 16:59:59	78075	4.3%	21.69	23.7 GB	4.0%	6906.01
Mar 25 17:00:00 - 17:59:59	72166	3.9%	20.05	24.4 GB	4.1%	7097.04
Mar 25 18:00:00 - 18:59:59	83068	4.5%	23.07	27.2 GB	4.6%	7930.50
Mar 25 19:00:00 - 19:59:59	82396	4.5%	22.89	28.2 GB	4.7%	8200.49
Mar 25 20:00:00 - 20:59:59	89446	4.9%	24.85	28.4 GB	4.8%	8269.42
Mar 25 21:00:00 - 21:59:59	95044	5.2%	26.40	29.5 GB	4.9%	8597.64
Mar 25 22:00:00 - 22:59:59	88785	4.8%	24.66	28.6 GB	4.8%	8326.27
Mar 25 23:00:00 - 23:59:59	103343	5.6%	28.71	29.4 GB	4.9%	8552.13
Mar 26 00:00:00 - 00:59:59	64674	3.5%	17.96	17.0 GB	2.9%	4963.09
Mar 26 01:00:00 - 01:59:59	61074	3.3%	16.96	17.0 GB	2.9%	4962.78
Mar 26 02:00:00 - 02:59:59	71871	3.9%	19.96	24.1 GB	4.0%	7006.78
Mar 26 03:00:00 - 03:59:59	82900	4.5%	23.03	28.1 GB	4.7%	8173.46
Mar 26 04:00:00 - 04:15:04	20618	1.1%	22.81	7.1 GB	1.2%	8186.67
TOTAL: 24:00:02	1833651	100.0%	21.22	596.5 GB	100.0%	7238.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6768	173	3022	16	3557
2	news.nask.pl	641	6	603	11	21
3	news.icm.edu.pl	120	0	1	0	119
TOTAL: 3		7529	179	3626	27	3697

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	62
it.tlc.cellulari.android	60
comp.mobile.android	16
hr.ponuda.hardver.komponente	10
comp.mobile.ipad	9
it.comp.os.win.windows7	8
hr.alt.cellular.gsm.android	4
de.comm.software.mailreader	4
de.comp.lang.vbclassic	2
de.comp.office-pakete.ms-office.outlook	1
alt.sex.telephones	1
it.comp.dotnet	1
it.comp.os.win.windows8	1
TOTAL: 13	179

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

Groups	Count
pl.sci.matematyka	10
pl.praca.oferowana	9
pl.sci.historia	7
bit.listserv.catholic	1
TOTAL: 4	27

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3439
EMP (md5)	148
Attached HTML file	1
TOTAL: 3	3588

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	36928	2213	33764	222	0	5%	23:50:01
2	icm	37139	614	28003	1164	57	1%	23:50:20
3	nask	20529	34	14009	5	21	0%	23:50:31
4	poznan	6058	3	4285	3	0	0%	23:50:00
5	okapi	0	0	0	0	2354	0%	23:50:01
TOTAL: 5		100654	2864	80061	1394	2432	2%	119:10:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	53.7 MB	196.0 MB	249.7 MB	3.0 KB/s	143.8 KB	23:50:20
2	panorama	10.0 MB	626.8 KB	10.6 MB	0.1 KB/s	4.5 KB	23:50:01
3	nask	295.7 KB	22.6 KB	318.3 KB	0.0 KB/s	8.2 KB	23:50:31
4	poznan	18.4 KB	27.0 KB	45.4 KB	0.0 KB/s	7.6 KB	23:50:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		64.0 MB	196.6 MB	260.6 MB	0.6 KB/s	62.7 KB	119:10:53

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	pc-skrzep2.cm.p.lodz.pl	288	336	680.2 KB	288	0	00:00:10
2	pc-212-51-218-225.p.lodz.pl	13	109	200.7 KB	8	0	03:05:13
3	lze200.lze.lodz.pl	2	20	33.9 KB	4	0	00:37:08
4	auriga.rz.izeto.pl	2	20	33.0 KB	2	0	00:50:15
5	web.man.lodz.pl	288	14	11.0 KB	6	0	00:00:23
6	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 6		597	499	958.8 KB	308	4	05:37:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	680.2 KB	288	0	00:00:10
2	*.p.lodz.pl	13	109	200.7 KB	8	0	03:05:13
3	*.rz.izeto.pl	2	20	33.0 KB	2	0	00:50:15
4	*.lze.lodz.pl	2	20	33.9 KB	4	0	00:37:08
5	*.man.lodz.pl	676	14	11.0 KB	6	4	00:00:32
6	unresolved	3	0	0.0 KB	0	0	01:04:12
7	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:14
8	*.toya.net.pl	10	0	0.0 KB	0	0	00:00:01
TOTAL: 8		998	499	958.8 KB	308	4	05:37:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.063	0.180	03:05:13
auriga.rz.izeto.pl	0.011	0.034	00:50:15
212.191.87.58	0.000	0.024	00:45:25
lze200.lze.lodz.pl	0.004	0.043	00:37:08
89.38.41.86	0.281	0.582	00:18:47
web.man.lodz.pl	0.973	2.896	00:00:23
host227.elsnet.pl	0.014	0.041	00:00:14
pc-skrzep2.cm.p.lodz.pl	1.079	3.161	00:00:10
mon.man.lodz.pl	0.917	3.016	00:00:05
david.man.lodz.pl	0.344	0.936	00:00:01
staticline41435.toya.net.pl	0.052	0.086	00:00:01
isvcs01.man.lodz.pl	0.025	0.047	00:00:01
?	0.006	0.006	00:00:00
TOTAL: 13	3.769	11.052	05:37:48

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	10
host227.elsnet.pl	4
89.38.41.86	2
212.191.87.58	1
TOTAL: 6	401

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	4	0
lze200.lze.lodz.pl	1	0
TOTAL: 2	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	356	71.3%
2	pl	143	28.7%
TOTAL: 2		499	100.0%

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

Newsgroup		Count
1	lodman.info	356
2	pl.misc.elektronika	55
3	pl.misc.kolej	54
4	pl.misc.telefonia.gsm	13
5	pl.comp.os.ms-windows.winnt	11
6	pl.comp.pecet	6
7	pl.rec.kuchnia	4
TOTAL: 7		499

Daily Usenet report

Mar 25 04:15:02 -- Mar 26 04:15:04