Daily Usenet report: Oct 30 04:15:06

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Oct 30 08:10:09 newsfeed nnrpd[4301]: SERVER perl filter not defined
Oct 30 09:07:25 newsfeed overchan[27417]: input line too long, skipping
Oct 30 11:36:31 newsfeed nnrpd[12490]: SERVER perl filter not defined
Oct 30 11:39:45 newsfeed nnrpd[12544]: SERVER perl filter not defined
Oct 30 12:33:11 newsfeed nnrpd[14714]: SERVER perl filter not defined
Oct 30 13:29:35 newsfeed nnrpd[16662]: SERVER perl filter not defined
Oct 30 14:18:29 newsfeed overchan[27417]: input line too long, skipping
Oct 30 15:11:44 newsfeed overchan[27417]: input line too long, skipping
Oct 30 15:27:08 newsfeed nnrpd[21002]: SERVER perl filter not defined
Oct 30 15:32:02 newsfeed nnrpd[21256]: SERVER perl filter not defined
Oct 30 16:11:00 newsfeed overchan[27417]: input line too long, skipping
Oct 30 16:43:52 newsfeed overchan[27417]: input line too long, skipping
Oct 30 17:58:50 newsfeed overchan[27417]: input line too long, skipping
Oct 30 19:06:51 newsfeed overchan[27417]: input line too long, skipping
Oct 30 21:27:43 newsfeed nnrpd[1799]: SERVER perl filter not defined
Oct 30 21:29:20 newsfeed nnrpd[1858]: SERVER perl filter not defined
Oct 30 21:30:58 newsfeed nnrpd[2048]: SERVER perl filter not defined
Oct 30 23:25:59 newsfeed overchan[27417]: input line too long, skipping
Oct 31 00:15:37 newsfeed overchan[27417]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3398264	96.9%	389.5 MB	96.5%
innd	62480	1.8%	8.6 MB	2.1%
innfeed	41511	1.2%	4.9 MB	1.2%
nnrpd	4857	0.1%	504.3 KB	0.1%
overchan	9	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3507122	100.0%	403.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3351289	92.9%
Negative hits	218194	6.1%
Positive hits	36633	1.0%
Cache misses	317	0.0%
TOTAL: 4	3606433	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.343	0.0%	209	0.000	6.426	83.000
article cleanup	00:00:45.990	0.1%	3402588	0.009	0.014	0.019
article logging	00:02:15.605	0.2%	3403217	0.025	0.040	0.137
article parse	00:59:35.303	4.1%	117759339	0.018	0.030	0.043
article write	00:21:16.999	1.5%	3338995	0.175	0.382	0.703
artlog/artcncl	00:00:00.003	0.0%	137	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:31.605	0.2%	135271540	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.053	0.0%	176	0.000	0.301	15.000
hishave/artcncl	00:00:00.143	0.0%	209	0.000	0.684	2.053
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:42:40.778	3.0%	3644147	0.065	0.703	4.915
history sync	00:00:00.015	0.0%	290	0.000	0.052	1.000
history write	02:16:12.013	9.5%	3380297	0.230	2.418	10.802
hiswrite/artcncl	00:00:00.159	0.0%	33	0.000	4.818	9.500
idle	11:52:18.781	49.5%	87203565	0.236	0.490	1.335
nntp read	01:04:47.385	4.5%	115132047	0.019	0.034	0.046
overview write	00:00:17.402	0.0%	3338995	0.003	0.005	0.007
perl filter	05:51:00.620	24.4%	3380295	4.763	6.230	7.672
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:03.924	0.4%	6699095	0.031	0.045	0.074
TOTAL: 24:00:02.987	23:19:48.121	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.158	0.0%	54394	0.000	0.003	0.014
article prepare	00:00:00.006	0.0%	13438	0.000	0.000	0.018
article read	00:01:36.612	0.1%	3350	0.333	28.839	318.333
backlog stats	00:00:00.168	0.0%	169435	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	30	0.000	0.000	0.000
data read	00:00:01.811	0.0%	90592	0.000	0.020	0.044
data write	00:00:04.193	0.0%	71162	0.011	0.059	0.233
idle	23:51:59.801	99.8%	169435	307.320	507.096	1293.252
status file	00:00:17.008	0.0%	15320	0.554	1.110	7.165
TOTAL: 23:54:14.298	23:53:59.757	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:03:03.595	92.3%	3122	0.000	58.807	19273.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.202	0.1%	6885	0.000	0.029	1.000
readart	00:00:00.004	0.0%	64	0.000	0.062	0.500
TOTAL: 00:03:18.938	00:03:03.801	92.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	897	3367754	3322884	8602	36268	98%	329:06:49
2	news.nask.pl	5	44595	11603	6723	26269	26%	23:39:35
3	localhost	11	10	10	0	0	100%	00:00:00
4	news.icm.edu.pl	56	224	5	217	2	2%	07:41:44
TOTAL: 4		969	3412583	3334502	15542	62539	97%	360:28:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	783.4 GB	3.1 GB	5.8 GB	792.3 GB	98%	247.3 KB
2	news.nask.pl	86.2 MB	20.7 MB	3.9 GB	4.0 GB	2%	109.6 KB
3	news.icm.edu.pl	74.1 KB	117.3 KB	117.3 KB	308.7 KB	23%	44.1 KB
4	localhost	16.9 KB	0.0 KB	0.0 KB	16.9 KB	100%	1.7 KB
TOTAL: 4		783.4 GB	3.2 GB	9.6 GB	796.2 GB	98%	245.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 30 04:15:06 - 04:59:59	73380	2.2%	27.24	21.1 GB	2.7%	8214.15
Oct 30 05:00:00 - 05:59:59	112469	3.4%	31.24	30.4 GB	3.9%	8857.40
Oct 30 06:00:00 - 06:59:59	69128	2.1%	19.20	15.9 GB	2.0%	4626.76
Oct 30 07:00:00 - 07:59:59	66817	2.0%	18.56	15.9 GB	2.0%	4637.31
Oct 30 08:00:00 - 08:59:59	59609	1.8%	16.56	15.9 GB	2.0%	4621.31
Oct 30 09:00:00 - 09:59:59	67527	2.0%	18.76	17.0 GB	2.2%	4962.12
Oct 30 10:00:00 - 10:59:59	166350	5.0%	46.21	27.0 GB	3.4%	7860.32
Oct 30 11:00:00 - 11:59:59	126644	3.8%	35.18	34.0 GB	4.3%	9909.49
Oct 30 12:00:00 - 12:59:59	128735	3.9%	35.76	34.5 GB	4.4%	10063.03
Oct 30 13:00:00 - 13:59:59	154939	4.6%	43.04	38.4 GB	4.9%	11184.87
Oct 30 14:00:00 - 14:59:59	130797	3.9%	36.33	35.9 GB	4.6%	10460.18
Oct 30 15:00:00 - 15:59:59	137190	4.1%	38.11	39.7 GB	5.1%	11569.68
Oct 30 16:00:00 - 16:59:59	133508	4.0%	37.09	39.4 GB	5.0%	11462.43
Oct 30 17:00:00 - 17:59:59	128458	3.9%	35.68	38.1 GB	4.9%	11085.57
Oct 30 18:00:00 - 18:59:59	121065	3.6%	33.63	35.4 GB	4.5%	10310.30
Oct 30 19:00:00 - 19:59:59	155228	4.7%	43.12	36.8 GB	4.7%	10720.21
Oct 30 20:00:00 - 20:59:59	153760	4.6%	42.71	37.9 GB	4.8%	11029.25
Oct 30 21:00:00 - 21:59:59	150376	4.5%	41.77	39.5 GB	5.0%	11518.07
Oct 30 22:00:00 - 22:59:59	187953	5.6%	52.21	43.3 GB	5.5%	12604.72
Oct 30 23:00:00 - 23:59:59	315249	9.5%	87.57	39.5 GB	5.0%	11501.70
Oct 31 00:00:00 - 00:59:59	242240	7.3%	67.29	31.5 GB	4.0%	9184.39
Oct 31 01:00:00 - 01:59:59	146447	4.4%	40.68	38.0 GB	4.9%	11073.72
Oct 31 02:00:00 - 02:59:59	158448	4.8%	44.01	38.0 GB	4.9%	11072.63
Oct 31 03:00:00 - 03:59:59	120482	3.6%	33.47	32.2 GB	4.1%	9373.46
Oct 31 04:00:00 - 04:15:02	28221	0.8%	31.29	8.1 GB	1.0%	9402.88
TOTAL: 23:59:56	3335020	100.0%	38.60	783.4 GB	100.0%	9508.43

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	36630	223	21461	0	14946
2	news.nask.pl	26475	34	460	4	25977
3	news.icm.edu.pl	2	0	2	0	0
TOTAL: 3		63107	257	21923	4	40923

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	183
comp.mobile.android	24
it.tlc.cellulari.android	15
it.comp.os.win.windows10	14
de.rec.sf.startrek	9
comp.mobile.ipad	3
de.comm.provider.telefonie	3
nl.comp.os.linux.techniek	2
alt.sex.telephones	2
it.comp.os.linux.ubuntu	1
it.discussioni.energia	1
TOTAL: 11	257

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

Groups	Count
pl.praca.oferowana	2
pl.sci.matematyka	1
bit.listserv.catholic	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	37489
EMP (md5)	2566
Poison newsgroup	87
Scoring filter (8)	70
Scoring filter (9)	35
EMP (phl)	17
Scoring filter (11)	10
HTML post	9
Scoring filter (10)	7
Scoring filter (12)	3
Excessively crossposted test article	1
TOTAL: 11	40294

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	355
	news.nask.pl	272
	TOTAL: 2	627
No colon-space in header
	newsfeed.pionier.net.pl	1
	TOTAL: 1	1
TOTAL: 2		628

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40545	1104	13768	311	77	38842	2%	23:59:46
2	poznan	17767	172	11297	21	0	0	0%	23:50:00
3	icm	25138	39	19203	806	0	0	0%	23:50:24
4	nask	2861	4	1988	0	0	0	0%	18:47:07
5	okapi	0	0	0	0	0	1489	0%	23:50:01
TOTAL: 5		86311	1319	46256	1138	77	40331	1%	114:17:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	5.2 MB	270.7 MB	276.0 MB	3.3 KB/s	334.4 KB	23:50:24
2	panorama	3.3 MB	1.4 MB	4.7 MB	0.1 KB/s	3.4 KB	23:59:46
3	poznan	1.0 MB	338.6 KB	1.3 MB	0.0 KB/s	7.1 KB	23:50:00
4	nask	95.9 KB	0.0 KB	95.9 KB	0.0 KB/s	24.0 KB	18:47:07
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		9.6 MB	272.5 MB	282.1 MB	0.7 KB/s	117.6 KB	114:17:18

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	287	64	58.1 KB	28	0	00:00:25
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:03
TOTAL: 2		297	64	58.1 KB	28	10	00:03:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	681	64	58.1 KB	28	10	00:00:33
2	*.dhcp.ftwo.tx.charter.com	5	0	0.0 KB	0	0	00:02:44
TOTAL: 2		686	64	58.1 KB	28	10	00:03:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
47-37-112-59.dhcp.ftwo.tx.charter.com	0.012	0.196	00:02:44
web.man.lodz.pl	1.376	11.992	00:00:25
mon.man.lodz.pl	1.104	12.088	00:00:04
isvcs01.man.lodz.pl	0.060	0.396	00:00:03
david.man.lodz.pl	0.288	3.768	00:00:01
TOTAL: 5	2.840	28.440	00:03:18

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
47-37-112-59.dhcp.ftwo.tx.charter.com	5
TOTAL: 3	389

NNRP client timeouts [Top 20]:

System	Conn	Peer
47-37-112-59.dhcp.ftwo.tx.charter.com	5	5
TOTAL: 1	5	5

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	64	100.0%
TOTAL: 1		64	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	38
2	pl.comp.os.ms-windows.winnt	24
3	pl.internet.polip	2
TOTAL: 3		64

Daily Usenet report

Oct 30 04:15:06 -- Oct 31 04:15:02