Daily Usenet report: Jan 13 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 13 04:20:01 newsfeed nnrpd[31745]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31746]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31747]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31748]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31751]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31752]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 04:20:02 newsfeed nnrpd[31755]: web.man.lodz.pl rejected Expiring process 31694
Jan 13 09:44:15 newsfeed nnrpd[10768]: SERVER perl filter not defined
Jan 13 19:13:35 newsfeed overchan[31436]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1823117	97.4%	211.5 MB	97.2%
innfeed	29156	1.6%	3.5 MB	1.6%
innd	13715	0.7%	2.2 MB	1.0%
nnrpd	4892	0.3%	520.1 KB	0.2%
overchan	2	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1870883	100.0%	217.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1818878	98.5%
Negative hits	18313	1.0%
Positive hits	8998	0.5%
Cache misses	243	0.0%
TOTAL: 4	1846432	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.071	0.0%	79	0.000	13.557	122.000
article cleanup	00:00:25.558	0.0%	1822441	0.000	0.014	0.050
article logging	00:01:21.418	0.1%	1823052	0.000	0.045	0.163
article parse	00:31:26.159	2.2%	77827650	0.000	0.024	0.058
article write	00:12:02.684	0.8%	1817465	0.205	0.398	3.474
artlog/artcncl	00:00:00.002	0.0%	76	0.000	0.026	0.500
data move	00:01:20.335	0.1%	87939020	0.000	0.001	0.055
hisgrep/artcncl	00:00:00.341	0.0%	74	0.000	4.608	30.000
hishave/artcncl	00:00:00.069	0.0%	79	0.000	0.873	7.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:37:18.559	2.6%	1846412	0.000	1.212	23.049
history sync	00:00:00.033	0.0%	308	0.000	0.107	1.000
history write	00:49:32.449	3.4%	1818914	0.088	1.634	18.184
hiswrite/artcncl	00:00:00.017	0.0%	5	0.000	3.400	8.500
idle	17:23:44.277	72.5%	62524841	0.526	1.002	6304.579
nntp read	00:55:28.838	3.9%	76292828	0.000	0.044	0.190
overview write	00:00:09.670	0.0%	1817465	0.000	0.005	0.034
perl filter	02:59:23.019	12.5%	1818914	1.519	5.917	18.273
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:05.558	0.3%	3649961	0.020	0.067	0.212
TOTAL: 24:00:09.619	23:36:20.057	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.193	0.0%	96180	0.000	0.002	0.018
article prepare	00:00:00.001	0.0%	9078	0.000	0.000	0.008
article read	00:00:33.629	0.0%	2262	0.000	14.867	250.500
backlog stats	00:00:00.353	0.0%	627060	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:06.042	0.0%	317494	0.003	0.019	0.157
data write	00:00:07.578	0.0%	296674	0.012	0.026	0.355
idle	23:53:26.259	99.9%	627060	17.465	137.158	987.377
status file	00:00:10.714	0.0%	10248	0.645	1.045	2.322
TOTAL: 23:54:41.800	23:54:24.769	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:31.830	69.1%	3025	0.000	10.522	1580.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.485	1.1%	6732	0.000	0.072	3.684
readart	00:00:00.018	0.0%	41	0.000	0.439	18.000
TOTAL: 00:00:46.037	00:00:32.333	70.2%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	596	1816847	1815084	500	1263	99%	211:51:12
2	news.nask.pl	2	10840	2206	4954	3680	20%	23:42:54
3	news.icm.edu.pl	46	235	30	174	31	12%	15:11:18
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		646	1827923	1817321	5628	4974	99%	250:45:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	463.0 GB	8.4 MB	8.7 MB	463.1 GB	99%	267.3 KB
2	news.nask.pl	11.6 MB	9.4 MB	19.4 MB	40.4 MB	28%	7.0 KB
3	news.icm.edu.pl	766.4 KB	66.3 KB	1.0 MB	1.8 MB	41%	30.5 KB
4	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
TOTAL: 4		463.0 GB	17.8 MB	29.1 MB	463.1 GB	99%	266.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 13 04:15:02 - 04:59:59	203	0.0%	0.08	728.4 KB	0.0%	0.27
Jan 13 05:00:00 - 05:59:59	294	0.0%	0.08	1.0 MB	0.0%	0.29
Jan 13 06:00:00 - 06:59:59	34402	1.9%	9.56	9.8 GB	2.1%	2859.39
Jan 13 07:00:00 - 07:59:59	65265	3.6%	18.13	21.1 GB	4.6%	6135.74
Jan 13 08:00:00 - 08:59:59	72431	4.0%	20.12	22.9 GB	4.9%	6656.44
Jan 13 09:00:00 - 09:59:59	87928	4.8%	24.42	23.4 GB	5.1%	6819.78
Jan 13 10:00:00 - 10:59:59	98817	5.4%	27.45	27.3 GB	5.9%	7943.07
Jan 13 11:00:00 - 11:59:59	68628	3.8%	19.06	19.9 GB	4.3%	5788.44
Jan 13 12:00:00 - 12:59:59	91898	5.1%	25.53	22.6 GB	4.9%	6584.19
Jan 13 13:00:00 - 13:59:59	84449	4.6%	23.46	22.9 GB	5.0%	6681.72
Jan 13 14:00:00 - 14:59:59	88246	4.9%	24.51	24.7 GB	5.3%	7194.00
Jan 13 15:00:00 - 15:59:59	102888	5.7%	28.58	26.9 GB	5.8%	7831.31
Jan 13 16:00:00 - 16:59:59	80701	4.4%	22.42	23.5 GB	5.1%	6830.94
Jan 13 17:00:00 - 17:59:59	89995	5.0%	25.00	21.0 GB	4.5%	6115.21
Jan 13 18:00:00 - 18:59:59	77243	4.3%	21.46	22.1 GB	4.8%	6428.40
Jan 13 19:00:00 - 19:59:59	104622	5.8%	29.06	25.5 GB	5.5%	7415.40
Jan 13 20:00:00 - 20:59:59	120960	6.7%	33.60	19.5 GB	4.2%	5692.58
Jan 13 21:00:00 - 21:59:59	91750	5.0%	25.49	19.9 GB	4.3%	5782.94
Jan 13 22:00:00 - 22:59:59	108135	5.9%	30.04	25.1 GB	5.4%	7314.22
Jan 13 23:00:00 - 23:59:59	99335	5.5%	27.59	24.5 GB	5.3%	7139.61
Jan 14 00:00:00 - 00:59:59	66865	3.7%	18.57	12.9 GB	2.8%	3748.54
Jan 14 01:00:00 - 01:59:59	70536	3.9%	19.59	18.8 GB	4.1%	5477.55
Jan 14 02:00:00 - 02:59:59	94451	5.2%	26.24	25.2 GB	5.5%	7352.96
Jan 14 03:00:00 - 03:59:59	17303	1.0%	4.81	3.4 GB	0.7%	987.53
Jan 14 04:00:00 - 04:15:01	107	0.0%	0.12	365.5 KB	0.0%	0.41
TOTAL: 23:59:59	1817452	100.0%	21.04	462.7 GB	100.0%	5615.92

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3756	4	2360	1	1391
2	newsfeed.pionier.net.pl	1799	97	1162	0	540
3	news.icm.edu.pl	34	0	5	0	29
TOTAL: 3		5589	101	3527	1	1960

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	28
it.tlc.cellulari.android	18
it.comp.os.win.windows10	17
comp.sys.raspberry-pi	11
nl.comp.os.linux.techniek	10
comp.mobile.android	9
it.discussioni.energia	5
it.comp.os.linux.ubuntu	3
TOTAL: 8	101

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1195
Binary in non-binary group	65
EMP (phl)	62
Scoring filter (8)	16
Scoring filter (9)	5
Too many newsgroups	2
HTML post	2
Scoring filter (18)	1
Scoring filter (11)	1
TOTAL: 9	1349

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	536
	news.nask.pl	72
	news.icm.edu.pl	3
	TOTAL: 3	611
TOTAL: 1		611

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	30545	642	12741	234	3	29514	2%	23:59:24
2	poznan	430140	436	1822	19	0	54333	0%	23:51:35
3	icm	21207	73	14566	349	0	0	0%	24:09:52
4	nask	8378	5	7198	2	0	0	0%	21:55:18
5	okapi	0	0	0	0	0	1270	0%	23:57:50
TOTAL: 5		490270	1156	36327	604	3	85117	0%	117:53:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.2 MB	789.7 KB	3.0 MB	0.0 KB/s	3.5 KB	23:59:24
2	poznan	1.5 MB	64.7 KB	1.6 MB	0.0 KB/s	3.6 KB	23:51:35
3	icm	731.7 KB	128.8 MB	129.6 MB	1.5 KB/s	314.4 KB	24:09:52
4	nask	315.9 KB	3.9 KB	319.9 KB	0.0 KB/s	45.7 KB	21:55:18
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		4.8 MB	129.7 MB	134.4 MB	0.3 KB/s	78.2 KB	117:53:59

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	294	40	38.1 KB	19	0	00:00:26
2	az1-ss13.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		297	41	38.8 KB	20	1	00:00:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	583	40	38.1 KB	19	1	00:00:30
2	*.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	unresolved	97	0	0.0 KB	0	0	00:00:01
TOTAL: 3		682	41	38.8 KB	20	1	00:00:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.712	4.692	00:00:26
az1-ss13.a2hosting.com	0.032	0.028	00:00:12
mon.man.lodz.pl	1.660	4.412	00:00:04
212.51.192.185	0.384	1.076	00:00:01
122.228.19.79	0.004	0.016	00:00:00
isvcs01.man.lodz.pl	0.016	0.012	00:00:00
TOTAL: 6	3.808	10.236	00:00:44

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
122.228.19.79	1
TOTAL: 3	385

NNRP unrecognized commands (by host):

System	Conn
122.228.19.79	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
GET / HTTP/1.1	1
Connection: keep-alive	1
Accept: /	1
Host: 212.191.2.21:119	1
TOTAL: 5	5

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	40	97.6%
2	lodzman	1	2.4%
TOTAL: 2		41	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	30
2	pl.comp.pecet	10
3	lodzman.test	1
TOTAL: 3		41

Daily Usenet report

Jan 13 04:15:02 -- Jan 14 04:15:01