Daily Usenet report: Sep 2 04:15:04

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 2 16:16:55 newsfeed overchan[27194]: input line too long, skipping
Sep 2 17:17:08 newsfeed overchan[27194]: input line too long, skipping
Sep 2 17:44:19 newsfeed overchan[27194]: input line too long, skipping
Sep 2 17:49:12 newsfeed nnrpd[25291]: SERVER perl filter not defined
Sep 2 18:12:19 newsfeed overchan[27194]: input line too long, skipping
Sep 2 18:36:57 newsfeed overchan[27194]: input line too long, skipping
Sep 2 19:03:55 newsfeed overchan[27194]: input line too long, skipping
Sep 2 19:26:53 newsfeed nnrpd[28770]: SERVER perl filter not defined
Sep 2 22:19:25 newsfeed overchan[27194]: input line too long, skipping
Sep 3 04:10:15 newsfeed overchan[27194]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2704855	97.4%	317.4 MB	97.1%
innfeed	44308	1.6%	5.2 MB	1.6%
innd	23464	0.8%	3.7 MB	1.1%
nnrpd	4768	0.2%	500.5 KB	0.1%
overchan	9	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2777405	100.0%	326.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2683748	96.6%
Negative hits	81841	2.9%
Positive hits	11290	0.4%
Cache misses	393	0.0%
TOTAL: 4	2777272	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.440	0.0%	448	0.000	16.607	105.000
article cleanup	00:00:42.263	0.0%	2703675	0.012	0.016	0.019
article logging	00:01:49.007	0.1%	2704229	0.030	0.040	0.153
article parse	01:17:19.613	5.4%	183624633	0.017	0.025	0.036
article write	00:23:35.633	1.6%	2696813	0.421	0.525	1.020
artlog/artcncl	00:00:00.017	0.0%	439	0.000	0.039	0.056
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:46.210	0.3%	201039660	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.416	0.0%	447	0.000	5.405	21.333
hishave/artcncl	00:00:01.183	0.0%	448	0.000	2.641	5.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:30.583	2.4%	2796307	0.030	0.740	6.291
history sync	00:00:00.022	0.0%	290	0.000	0.076	1.000
history write	01:33:04.280	6.5%	2700983	0.179	2.067	10.263
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	11:32:45.403	48.1%	126828775	0.195	0.328	0.473
nntp read	01:37:36.456	6.8%	181498268	0.022	0.032	0.044
overview write	00:00:15.944	0.0%	2696813	0.005	0.006	0.008
perl filter	06:05:29.363	25.4%	2700982	7.487	8.119	9.247
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:50.051	0.3%	5412579	0.040	0.054	0.070
TOTAL: 24:00:00.366	23:15:55.884	96.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.179	0.0%	59196	0.000	0.003	0.010
article prepare	00:00:00.007	0.0%	6424	0.000	0.001	0.029
article read	00:01:04.153	0.1%	1598	0.000	40.146	1425.000
backlog stats	00:00:00.171	0.0%	162399	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	13	0.000	0.000	0.000
data read	00:00:02.191	0.0%	85576	0.009	0.026	0.067
data write	00:00:04.627	0.0%	68948	0.022	0.067	0.231
idle	23:52:23.024	99.9%	162399	336.289	529.209	942.602
status file	00:00:16.164	0.0%	16261	0.633	0.994	8.492
TOTAL: 23:54:06.659	23:53:50.516	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.758	55.1%	3046	0.000	5.830	85.889
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.207	0.6%	6764	0.000	0.031	0.500
readart	00:00:01.311	4.1%	54	0.000	24.278	654.000
TOTAL: 00:00:32.220	00:00:19.276	59.8%	-	-	-	-

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	1295	2696812	2687376	6794	2642	99%	386:43:27
2	news.nask.pl	2	15400	9740	1409	4251	63%	24:07:21
3	news.icm.edu.pl	36	451	48	365	38	10%	20:14:45
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1336	2712665	2697166	8568	6931	99%	431:05:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	866.6 GB	29.9 MB	30.2 MB	866.6 GB	99%	337.8 KB
2	news.nask.pl	56.5 MB	416.4 KB	26.0 MB	82.9 MB	68%	6.1 KB
3	news.icm.edu.pl	980.7 KB	0.0 KB	2.6 MB	3.6 MB	26%	42.4 KB
4	localhost	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.7 KB
TOTAL: 4		866.6 GB	30.3 MB	58.8 MB	866.7 GB	99%	336.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 2 04:15:04 - 04:59:59	75572	2.8%	28.03	25.4 GB	2.9%	9879.98
Sep 2 05:00:00 - 05:59:59	94785	3.5%	26.33	32.8 GB	3.8%	9556.57
Sep 2 06:00:00 - 06:59:59	89015	3.3%	24.73	31.7 GB	3.7%	9230.32
Sep 2 07:00:00 - 07:59:59	92874	3.4%	25.80	33.3 GB	3.9%	9703.63
Sep 2 08:00:00 - 08:59:59	89215	3.3%	24.78	32.1 GB	3.7%	9361.87
Sep 2 09:00:00 - 09:59:59	106653	4.0%	29.63	32.8 GB	3.8%	9560.70
Sep 2 10:00:00 - 10:59:59	105914	3.9%	29.42	36.0 GB	4.2%	10493.59
Sep 2 11:00:00 - 11:59:59	135976	5.0%	37.77	40.7 GB	4.7%	11842.31
Sep 2 12:00:00 - 12:59:59	133080	4.9%	36.97	40.7 GB	4.7%	11856.29
Sep 2 13:00:00 - 13:59:59	112818	4.2%	31.34	36.0 GB	4.2%	10498.85
Sep 2 14:00:00 - 14:59:59	114884	4.3%	31.91	36.0 GB	4.2%	10488.18
Sep 2 15:00:00 - 15:59:59	129418	4.8%	35.95	39.8 GB	4.6%	11591.23
Sep 2 16:00:00 - 16:59:59	122678	4.5%	34.08	40.2 GB	4.6%	11703.17
Sep 2 17:00:00 - 17:59:59	126270	4.7%	35.08	39.4 GB	4.6%	11464.17
Sep 2 18:00:00 - 18:59:59	117415	4.4%	32.62	39.7 GB	4.6%	11557.41
Sep 2 19:00:00 - 19:59:59	120423	4.5%	33.45	38.7 GB	4.5%	11279.06
Sep 2 20:00:00 - 20:59:59	122459	4.5%	34.02	36.7 GB	4.2%	10695.06
Sep 2 21:00:00 - 21:59:59	119242	4.4%	33.12	38.7 GB	4.5%	11271.48
Sep 2 22:00:00 - 22:59:59	108042	4.0%	30.01	32.1 GB	3.7%	9339.08
Sep 2 23:00:00 - 23:59:59	117672	4.4%	32.69	35.4 GB	4.1%	10322.62
Sep 3 00:00:00 - 00:59:59	102644	3.8%	28.51	32.4 GB	3.7%	9440.74
Sep 3 01:00:00 - 01:59:59	105484	3.9%	29.30	33.7 GB	3.9%	9821.21
Sep 3 02:00:00 - 02:59:59	108804	4.0%	30.22	35.0 GB	4.0%	10194.73
Sep 3 03:00:00 - 03:59:59	114823	4.3%	31.90	35.8 GB	4.1%	10416.68
Sep 3 04:00:00 - 04:15:04	30840	1.1%	34.12	9.5 GB	1.1%	11041.47
TOTAL: 24:00:00	2697000	100.0%	31.22	864.7 GB	100.0%	10494.19

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4438	34	134	2	4268
2	newsfeed.pionier.net.pl	2941	56	2559	2	324
3	news.icm.edu.pl	37	0	0	0	37
TOTAL: 3		7416	90	2693	4	4629

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	34
comp.mobile.android	33
it.tlc.cellulari.android	8
de.comp.lang.php	3
it.comp.os.win.windows10	2
de.comp.lang.vbclassic	1
nl.gezondheid.alternatief-genezen	1
nl.actueel	1
nl.comp.os.linux.techniek	1
it.comp.os.win.windows7	1
alt.sex.telephones	1
nl.wetenschap.regulier	1
nl.comp.sys.android	1
nl.comp.3d-printen	1
comp.sys.raspberry-pi	1
TOTAL: 15	90

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3669
EMP (phl)	259
Binary in non-binary group	85
Scoring filter (8)	31
Scoring filter (9)	22
Scoring filter (10)	3
HTML post	2
Too many newsgroups	2
Scoring filter (11)	1
TOTAL: 9	4074

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	299
	news.nask.pl	252
	news.icm.edu.pl	3
	TOTAL: 3	554
TOTAL: 1		554

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46079	417	18391	151	30	40499	0%	23:59:57
2	poznan	15933	179	9534	37	0	2	1%	23:59:56
3	icm	22731	76	17510	298	0	0	0%	23:59:48
4	nask	3172	5	2348	0	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1195	0%	23:57:50
TOTAL: 5		87915	677	47783	486	30	41696	0%	119:57:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.1 MB	440.8 KB	5.5 MB	0.1 KB/s	9.9 KB	23:59:57
2	icm	1.7 MB	199.7 MB	201.5 MB	2.4 KB/s	551.6 KB	23:59:48
3	poznan	806.2 KB	220.1 KB	1.0 MB	0.0 KB/s	4.8 KB	23:59:56
4	nask	8.6 KB	0.0 KB	8.6 KB	0.0 KB/s	1.7 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		7.6 MB	200.4 MB	208.0 MB	0.5 KB/s	183.1 KB	119:57:28

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	web.man.lodz.pl	288	54	244.0 KB	24	0	00:00:25
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	54	244.0 KB	24	2	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	674	54	244.0 KB	24	2	0	00:00:31
TOTAL: 1		674	54	244.0 KB	24	2	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.388	5.280	00:00:25
mon.man.lodz.pl	1.268	5.888	00:00:04
david.man.lodz.pl	0.440	1.864	00:00:01
isvcs01.man.lodz.pl	0.012	0.056	00:00:00
TOTAL: 4	3.108	13.088	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	54
TOTAL: 1		54

Daily Usenet report

Sep 2 04:15:04 -- Sep 3 04:15:04