Daily Usenet report: Sep 3 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 3 13:23:15 newsfeed nnrpd[31865]: SERVER perl filter not defined
Sep 3 13:38:00 newsfeed nnrpd[32447]: SERVER perl filter not defined
Sep 3 13:39:14 newsfeed nnrpd[32458]: SERVER perl filter not defined
Sep 3 13:52:04 newsfeed nnrpd[428]: SERVER perl filter not defined
Sep 3 13:52:47 newsfeed nnrpd[429]: SERVER perl filter not defined
Sep 3 13:56:10 newsfeed nnrpd[538]: SERVER perl filter not defined
Sep 3 15:32:41 newsfeed nnrpd[3947]: SERVER perl filter not defined
Sep 3 08:06:36 newsfeed overchan[28100]: message repeated 18 times: [ input line too long, skipping]
Sep 3 23:00:48 newsfeed nnrpd[19629]: SERVER perl filter not defined
Sep 4 00:52:22 newsfeed overchan[6800]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2598486	97.3%	300.6 MB	96.9%
innfeed	44837	1.7%	5.3 MB	1.7%
innd	20903	0.8%	3.6 MB	1.2%
nnrpd	5330	0.2%	578.7 KB	0.2%
overchan	3	0.0%	0.2 KB	0.0%
controlchan	2	0.0%	0.3 KB	0.0%
TOTAL: 6	2669561	100.0%	310.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2591153	98.2%
Negative hits	31175	1.2%
Positive hits	16890	0.6%
Cache misses	115	0.0%
TOTAL: 4	2639333	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.013	0.0%	204	0.000	14.770	337.000
article cleanup	00:00:37.598	0.0%	2597668	0.006	0.014	0.026
article logging	00:01:26.844	0.1%	2598134	0.020	0.033	0.332
article parse	01:04:45.063	4.5%	114145118	0.025	0.034	0.079
article write	00:31:55.072	2.2%	2593559	0.340	0.738	3.318
artlog/artcncl	00:00:00.006	0.0%	203	0.000	0.030	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:04.164	0.1%	130163751	0.001	0.001	0.007
hisgrep/artcncl	00:00:00.337	0.0%	185	0.000	1.822	27.000
hishave/artcncl	00:00:00.087	0.0%	204	0.000	0.426	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:20:27.594	1.4%	2641921	0.056	0.465	6.970
history sync	00:00:00.019	0.0%	292	0.000	0.065	2.000
history write	00:40:49.778	2.8%	2593867	0.181	0.944	23.494
hiswrite/artcncl	00:00:00.076	0.0%	19	0.000	4.000	30.000
idle	13:39:15.883	56.9%	83233269	0.155	0.591	1.680
nntp read	00:51:45.746	3.6%	112076408	0.021	0.028	0.036
overview write	00:00:17.375	0.0%	2593559	0.001	0.007	0.019
perl filter	06:06:20.060	25.4%	2593867	6.840	8.474	10.524
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:28.192	0.4%	5261605	0.039	0.062	0.133
TOTAL: 24:00:13.544	23:25:16.907	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.489	0.0%	173917	0.000	0.003	0.167
article prepare	00:00:00.031	0.0%	117028	0.000	0.000	0.003
article read	00:00:44.602	0.1%	29221	0.000	1.526	534.250
backlog stats	00:00:00.324	0.0%	410685	0.000	0.001	0.016
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:03.544	0.0%	229454	0.000	0.015	0.620
data write	00:00:21.751	0.0%	166397	0.000	0.131	0.576
idle	23:57:36.012	99.9%	410685	20.401	210.030	1654.381
status file	00:00:13.740	0.0%	16490	0.000	0.833	5.467
TOTAL: 23:59:17.454	23:59:00.493	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.499	0.0%	6930	0.072	0.072	0.072
idle	29:41:04.840	99.9%	753754	0.000	141.777	226521.375
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:30.948	0.0%	1508775	0.000	0.021	0.684
readart	00:00:06.733	0.0%	7101	0.000	0.948	26.953
TOTAL: 29:42:46.774	29:41:43.020	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	190

Newsgroups created:

Group	Mode
alt.binaries.3d.daz-studio	y
TOTAL	1

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ignore@domain.com	1	0	0	0	1	1
TOTAL	1	0	0	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1099	2593454	2589288	432	3734	99%	291:10:02
2	news.nask.pl	2	17293	4429	12532	332	25%	24:21:16
3	news.icm.edu.pl	43	316	73	193	50	23%	19:01:02
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1153	2611071	2593798	13157	4116	99%	334:32:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	829.1 GB	19.6 MB	24.3 MB	829.1 GB	99%	335.3 KB
2	news.nask.pl	25.3 MB	829.4 KB	905.3 KB	27.0 MB	93%	5.8 KB
3	news.icm.edu.pl	4.6 MB	16.8 KB	951.2 KB	5.5 MB	82%	46.2 KB
4	localhost	13.3 KB	0.0 KB	0.0 KB	13.3 KB	100%	1.7 KB
TOTAL: 4		829.1 GB	20.5 MB	26.1 MB	829.1 GB	99%	334.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 3 04:15:02 - 04:59:59	73301	2.8%	27.17	26.2 GB	3.2%	10186.95
Sep 3 05:00:00 - 05:59:59	91455	3.5%	25.40	32.7 GB	3.9%	9512.34
Sep 3 06:00:00 - 06:59:59	86620	3.3%	24.06	32.1 GB	3.9%	9346.39
Sep 3 07:00:00 - 07:59:59	97200	3.7%	27.00	35.0 GB	4.2%	10205.26
Sep 3 08:00:00 - 08:59:59	99106	3.8%	27.53	33.8 GB	4.1%	9834.48
Sep 3 09:00:00 - 09:59:59	138763	5.3%	38.55	37.2 GB	4.5%	10845.63
Sep 3 10:00:00 - 10:59:59	117713	4.5%	32.70	36.1 GB	4.4%	10516.44
Sep 3 11:00:00 - 11:59:59	100607	3.9%	27.95	34.6 GB	4.2%	10076.50
Sep 3 12:00:00 - 12:59:59	104910	4.0%	29.14	35.7 GB	4.3%	10400.46
Sep 3 13:00:00 - 13:59:59	102899	4.0%	28.58	33.5 GB	4.0%	9746.36
Sep 3 14:00:00 - 14:59:59	117695	4.5%	32.69	35.9 GB	4.3%	10454.24
Sep 3 15:00:00 - 15:59:59	112039	4.3%	31.12	35.0 GB	4.2%	10207.79
Sep 3 16:00:00 - 16:59:59	132352	5.1%	36.76	35.8 GB	4.3%	10431.95
Sep 3 17:00:00 - 17:59:59	113482	4.4%	31.52	32.4 GB	3.9%	9448.42
Sep 3 18:00:00 - 18:59:59	89494	3.5%	24.86	28.4 GB	3.4%	8284.81
Sep 3 19:00:00 - 19:59:59	108161	4.2%	30.04	35.8 GB	4.3%	10420.10
Sep 3 20:00:00 - 20:59:59	102247	3.9%	28.40	36.6 GB	4.4%	10665.02
Sep 3 21:00:00 - 21:59:59	116453	4.5%	32.35	37.9 GB	4.6%	11049.09
Sep 3 22:00:00 - 22:59:59	119826	4.6%	33.28	38.1 GB	4.6%	11098.06
Sep 3 23:00:00 - 23:59:59	126688	4.9%	35.19	37.5 GB	4.5%	10914.22
Sep 4 00:00:00 - 00:59:59	88513	3.4%	24.59	26.7 GB	3.2%	7770.44
Sep 4 01:00:00 - 01:59:59	96540	3.7%	26.82	30.0 GB	3.6%	8735.26
Sep 4 02:00:00 - 02:59:59	118930	4.6%	33.04	37.4 GB	4.5%	10905.30
Sep 4 03:00:00 - 03:59:59	109090	4.2%	30.30	34.1 GB	4.1%	9932.28
Sep 4 04:00:00 - 04:15:03	29625	1.1%	32.81	8.8 GB	1.1%	10237.92
TOTAL: 24:00:01	2593709	100.0%	30.02	827.4 GB	100.0%	10041.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4149	106	3495	0	548
2	news.nask.pl	375	10	304	14	47
3	news.icm.edu.pl	50	0	2	0	48
TOTAL: 3		4574	116	3801	14	643

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	48
it.comp.os.win.windows10	16
it.comp.os.win.windows8	9
it.tlc.cellulari.android	7
comp.mobile.android	7
de.comp.lang.vbclassic	6
it.comp.os.win.windows7	6
alt.sex.telephones	4
de.comm.provider.telefonie	3
uk.rec.cycling.moderated	3
hr.alt.cellular.gsm.iphone	2
de.rec.sf.startrek	1
de.comp.office-pakete.ms-office.outlook	1
it.discussioni.energia	1
hr.comp.os.linux.ubuntu	1
it.comp.os.linux.ubuntu	1
TOTAL: 16	116

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

Groups	Count
pl.praca.oferowana	14
TOTAL: 1	14

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	127
EMP (md5)	37
Too many newsgroups	10
HTML post	4
TOTAL: 4	178

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	67359	1366	25132	25753	1	29	2%	23:53:27
2	panorama	107208	999	78487	442	5	93981	0%	24:00:11
3	poznan	6771	140	4252	46	0	0	2%	23:51:22
4	okapi	0	0	0	0	0	2277	0%	23:54:16
5	nask	0	0	0	0	0	12964	0%	23:54:16
TOTAL: 5		181338	2505	107871	26241	6	109251	1%	119:33:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	13.5 MB	6.3 GB	6.3 GB	76.4 KB/s	242.3 KB	23:53:27
2	poznan	7.2 MB	881.7 KB	8.0 MB	0.1 KB/s	44.1 KB	23:51:22
3	panorama	3.8 MB	1.9 MB	5.7 MB	0.1 KB/s	4.1 KB	24:00:11
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:16
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:16
TOTAL: 5		24.4 MB	6.3 GB	6.3 GB	15.3 KB/s	229.1 KB	119:33:32

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-191-92-103.p.lodz.pl	10	199	646.0 KB	9	0	02:43:06
2	web.man.lodz.pl	288	56	53.6 KB	21	0	00:00:32
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 3		306	255	699.6 KB	30	8	29:42:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	12	199	646.0 KB	9	0	02:44:06
2	*.man.lodz.pl	680	56	53.6 KB	21	8	00:00:37
3	*.toya.net.pl	104	0	0.0 KB	0	0	00:00:07
4	unresolved	3	0	0.0 KB	0	0	26:57:40
5	*.ozorkow.mm.pl	1	0	0.0 KB	0	0	00:00:15
TOTAL: 5		800	255	699.6 KB	30	8	29:42:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.40.223	48.001	68.441	26:57:40
pc-212-191-92-103.p.lodz.pl	0.075	0.250	02:43:06
pc-212-51-218-225.p.lodz.pl	0.013	0.033	00:00:59
web.man.lodz.pl	1.101	5.516	00:00:32
host-89-230-0-116.ozorkow.mm.pl	0.007	0.018	00:00:15
staticline41435.toya.net.pl	0.345	1.984	00:00:07
mon.man.lodz.pl	0.951	5.725	00:00:02
david.man.lodz.pl	0.272	1.836	00:00:00
isvcs01.man.lodz.pl	0.042	0.191	00:00:00
TOTAL: 9	50.807	83.994	29:42:46

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	104
david.man.lodz.pl	96
89.38.40.223	3
pc-212-51-218-225.p.lodz.pl	2
host-89-230-0-116.ozorkow.mm.pl	1
TOTAL: 6	494

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.40.223	3	0
pc-212-191-92-103.p.lodz.pl	2	1
TOTAL: 2	5	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	100
2	pl.misc.kolej	99
3	pl.comp.pecet	38
4	pl.comp.os.ms-windows.winnt	18
TOTAL: 4		255

Daily Usenet report

Sep 3 04:15:02 -- Sep 4 04:15:03