Daily Usenet report: Aug 7 04:15:02

Unknown entries from news log file:

First 50 / 311 lines (16.1%)

Aug 7 04:15:03 newsfeed overchan[1530]: tradindexed: index inode mismatch for alt.binaries.bloaf
Aug 7 04:15:03 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:04 newsfeed overchan[1530]: message repeated 4 times: [ tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E]
Aug 7 04:15:05 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:05 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:07 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:18 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:23 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:24 newsfeed overchan[1530]: message repeated 3 times: [ tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E]
Aug 7 04:15:29 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:29 newsfeed overchan[1530]: message repeated 2 times: [ tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E]
Aug 7 04:15:30 newsfeed overchan[1530]: tradindexed: index inode mismatch for alt.binaries.xylo
Aug 7 04:15:30 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:31 newsfeed overchan[1530]: message repeated 41 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:31 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:31 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:32 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:32 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:32 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:32 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:33 newsfeed overchan[1530]: message repeated 30 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:33 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:34 newsfeed overchan[1530]: message repeated 36 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:34 newsfeed overchan[1530]: message repeated 5 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:34 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:35 newsfeed overchan[1530]: message repeated 36 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:35 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:35 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:39 newsfeed overchan[1530]: message repeated 93 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:39 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:40 newsfeed overchan[1530]: message repeated 30 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:40 newsfeed overchan[1530]: tradindexed: index inode mismatch for 4054C11FA84E1FDBCD363ED8E380CE4E
Aug 7 04:15:40 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:40 newsfeed overchan[1530]: message repeated 7 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:40 newsfeed overchan[1530]: tradindexed: index inode mismatch for alt.binaries.ijsklontje
Aug 7 04:15:40 newsfeed overchan[1530]: tradindexed: index inode mismatch for 92CD7C98D3113E036DDB55A217827E6F
Aug 7 04:15:40 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:42 newsfeed overchan[1530]: message repeated 71 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:42 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:43 newsfeed overchan[1530]: message repeated 101 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:43 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:48 newsfeed overchan[1530]: message repeated 222 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:48 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D
Aug 7 04:15:48 newsfeed overchan[1530]: message repeated 2 times: [ tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D]
Aug 7 04:15:48 newsfeed overchan[1530]: tradindexed: index inode mismatch for D9636C0FD1AA99F5BC14B71705DFF22D

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1279771	95.7%	146.3 MB	95.4%
innfeed	43699	3.3%	5.1 MB	3.3%
innd	8369	0.6%	1.4 MB	0.9%
nnrpd	4953	0.4%	533.5 KB	0.3%
overchan	302	0.0%	38.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1337095	100.0%	153.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1270994	99.2%
Negative hits	10142	0.8%
Positive hits	277	0.0%
Cache misses	47	0.0%
TOTAL: 4	1281460	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.292	0.0%	119	0.000	10.857	136.000
article cleanup	00:00:16.624	0.0%	1286974	0.005	0.013	0.017
article logging	00:00:56.345	0.1%	1287455	0.031	0.044	0.057
article parse	00:30:09.622	2.1%	59321144	0.019	0.031	0.039
article write	00:11:33.392	0.8%	1286786	0.198	0.539	0.877
artlog/artcncl	00:00:00.004	0.0%	117	0.000	0.034	1.000
data move	00:01:18.047	0.1%	67726067	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.178	0.0%	117	0.000	1.521	22.000
hishave/artcncl	00:00:00.024	0.0%	119	0.000	0.202	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:30.013	1.3%	1297337	0.030	0.856	9.226
history sync	00:00:00.017	0.0%	294	0.000	0.058	0.500
history write	00:33:04.828	2.3%	1286960	0.131	1.542	11.508
hiswrite/artcncl	00:00:00.015	0.0%	2	6.000	7.500	9.000
idle	18:09:47.838	75.7%	48193814	0.491	1.357	121.483
nntp read	00:44:40.645	3.1%	58328377	0.035	0.046	0.056
overview write	00:00:06.698	0.0%	1286786	0.001	0.005	0.007
perl filter	03:16:15.534	13.6%	1286960	3.318	9.150	22.350
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:07.273	0.1%	2588291	0.018	0.026	0.031
TOTAL: 24:00:04.592	23:47:48.389	99.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.135	0.0%	56015	0.000	0.002	0.023
article prepare	00:00:00.001	0.0%	8854	0.000	0.000	0.010
article read	00:00:09.647	0.0%	2214	0.000	4.357	111.000
backlog stats	00:00:00.101	0.0%	140548	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.211	0.0%	67924	0.003	0.018	0.042
data write	00:00:02.904	0.0%	53181	0.016	0.055	0.178
idle	23:53:41.164	100.0%	140548	88.667	612.041	1402.277
status file	00:00:17.220	0.0%	16343	0.000	1.054	2.142
TOTAL: 23:54:18.729	23:54:12.383	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:02:40.992	94.7%	3101	0.000	51.916	17122.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.197	0.1%	6764	0.000	0.029	0.667
TOTAL: 00:02:49.957	00:02:41.189	94.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	266	1279163	1278982	0	181	99%	127:34:14
2	news.icm.edu.pl	48	211	13	191	7	6%	17:43:33
3	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 3		325	1279384	1279005	191	188	99%	145:17:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	510.3 GB	1.6 MB	1.9 MB	510.3 GB	99%	418.3 KB
2	news.icm.edu.pl	599.2 KB	0.0 KB	98.0 KB	697.3 KB	85%	34.9 KB
3	localhost	14.8 KB	0.0 KB	0.0 KB	14.8 KB	100%	1.5 KB
TOTAL: 3		510.3 GB	1.6 MB	2.0 MB	510.3 GB	99%	418.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 7 04:15:02 - 04:59:59	44875	3.5%	16.63	7.1 GB	1.4%	2751.78
Aug 7 05:00:00 - 05:59:59	59521	4.7%	16.53	31.4 GB	6.1%	9152.34
Aug 7 06:00:00 - 06:59:59	65016	5.1%	18.06	36.9 GB	7.2%	10750.29
Aug 7 07:00:00 - 07:59:59	49102	3.8%	13.64	24.8 GB	4.9%	7236.99
Aug 7 08:00:00 - 08:59:59	50956	4.0%	14.15	22.1 GB	4.3%	6433.85
Aug 7 09:00:00 - 09:59:59	59163	4.6%	16.43	25.2 GB	4.9%	7339.57
Aug 7 10:00:00 - 10:59:59	77028	6.0%	21.40	34.5 GB	6.8%	10049.00
Aug 7 11:00:00 - 11:59:59	75303	5.9%	20.92	32.7 GB	6.4%	9535.46
Aug 7 12:00:00 - 12:59:59	47263	3.7%	13.13	28.2 GB	5.5%	8204.66
Aug 7 13:00:00 - 13:59:59	43276	3.4%	12.02	19.7 GB	3.9%	5744.95
Aug 7 14:00:00 - 14:59:59	46511	3.6%	12.92	14.7 GB	2.9%	4280.35
Aug 7 15:00:00 - 15:59:59	40793	3.2%	11.33	17.4 GB	3.4%	5066.68
Aug 7 16:00:00 - 16:59:59	116458	9.1%	32.35	32.1 GB	6.3%	9347.28
Aug 7 17:00:00 - 17:59:59	56152	4.4%	15.60	17.7 GB	3.5%	5147.93
Aug 7 18:00:00 - 18:59:59	49601	3.9%	13.78	16.2 GB	3.2%	4717.88
Aug 7 19:00:00 - 19:59:59	55069	4.3%	15.30	14.1 GB	2.8%	4095.84
Aug 7 20:00:00 - 20:59:59	63055	4.9%	17.52	14.6 GB	2.8%	4239.25
Aug 7 21:00:00 - 21:59:59	54642	4.3%	15.18	22.5 GB	4.4%	6565.23
Aug 7 22:00:00 - 22:59:59	46427	3.6%	12.90	20.9 GB	4.1%	6093.14
Aug 7 23:00:00 - 23:59:59	11025	0.9%	3.06	4.4 GB	0.9%	1275.52
Aug 8 00:00:00 - 00:59:59	51155	4.0%	14.21	27.7 GB	5.4%	8074.78
Aug 8 01:00:00 - 01:59:59	36988	2.9%	10.27	10.9 GB	2.1%	3178.80
Aug 8 02:00:00 - 02:59:59	42934	3.4%	11.93	16.9 GB	3.3%	4909.49
Aug 8 03:00:00 - 03:59:59	29396	2.3%	8.17	14.4 GB	2.8%	4181.32
Aug 8 04:00:00 - 04:15:02	7281	0.6%	8.07	4.0 GB	0.8%	4654.57
TOTAL: 24:00:00	1278990	100.0%	14.80	511.0 GB	100.0%	6202.05

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	657	166	14	1	476
2	news.icm.edu.pl	7	0	0	0	7
TOTAL: 2		664	166	14	1	483

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	98
it.tlc.cellulari.android	45
comp.sys.raspberry-pi	9
comp.mobile.android	9
de.comp.lang.php	1
it.comp.virtualizzazione	1
it.discussioni.energia	1
it.politica.m5s	1
uk.rec.cycling.moderated	1
TOTAL: 9	166

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	4
Scoring filter (8)	2
Too many newsgroups	1
TOTAL: 3	7

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	476
	TOTAL: 1	476
TOTAL: 1		476

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	45935	769	17213	744	7	37315	1%	23:59:58
2	icm	26816	282	14329	18	0	2	1%	23:59:57
3	okapi	0	0	0	0	0	1168	0%	23:57:51
4	nask	0	0	0	0	0	10262	0%	23:57:50
5	poznan	9	0	7	0	0	0	0%	01:20:10
TOTAL: 5		72760	1051	31549	762	7	48747	1%	97:15:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.2 MB	2.5 MB	4.7 MB	0.1 KB/s	3.2 KB	23:59:58
2	icm	740.1 KB	61.1 KB	801.2 KB	0.0 KB/s	2.7 KB	23:59:57
3	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:20:10
TOTAL: 5		2.9 MB	2.6 MB	5.5 MB	0.0 KB/s	3.1 KB	97:15:46

NNRP readership statistics:

System		Conn	Post	Elapsed
1	isvcs01.man.lodz.pl	10	10	00:00:00
2	93.115.241.194	4	0	00:02:14
3	ec2-54-153-53-216.us-west-1.compute.amazonaws.com	1	0	00:00:00
4	scan-141.security.ipip.net	1	0	00:00:00
5	mon.man.lodz.pl	288	0	00:00:02
6	117.50.7.159	1	0	00:00:00
7	212.51.192.185	96	0	00:00:00
8	80.111.142.213	4	0	00:00:03
9	web.man.lodz.pl	288	0	00:00:16
10	staticline-31-183-131-140.toya.net.pl	4	0	00:00:10
TOTAL: 10		697	10	00:02:49

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.security.ipip.net	1	0	00:00:00
2	*.us-west-1.compute.amazonaws.com	1	0	00:00:00
3	*.man.lodz.pl	586	10	00:00:19
4	unresolved	105	0	00:02:19
5	*.toya.net.pl	4	0	00:00:10
TOTAL: 5		697	10	00:02:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
93.115.241.194	0.052	0.052	00:02:14
web.man.lodz.pl	1.340	2.928	00:00:16
staticline-31-183-131-140.toya.net.pl	0.012	0.056	00:00:10
80.111.142.213	0.004	0.040	00:00:03
mon.man.lodz.pl	1.124	2.880	00:00:02
212.51.192.185	0.464	0.876	00:00:00
isvcs01.man.lodz.pl	0.072	0.116	00:00:00
117.50.7.159	0.008	0.012	00:00:00
scan-141.security.ipip.net	0.012	0.012	00:00:00
ec2-54-153-53-216.us-west-1.compute.amazonaws.com	0.000	0.008	00:00:00
TOTAL: 10	3.088	6.980	00:02:49

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Host: 212.191.2.21:119	1
Connection: close	1
GET / HTTP/1.1	1
TOTAL: 4	4

Daily Usenet report

Aug 7 04:15:02 -- Aug 8 04:15:02