Daily Usenet report: Aug 4 04:15:01

Unknown entries from news log file:

First 50 / 111 lines (45.0%)

Aug 4 04:18:00 newsfeed nnrpd[501986]: nms1.man.lodz.pl rejected Expiring process 501949
Aug 4 04:25:40 newsfeed overchan[501709]: input line too long, skipping
Aug 4 06:49:05 newsfeed overchan[501709]: input line too long, skipping
Aug 4 06:56:54 newsfeed overchan[501709]: input line too long, skipping
Aug 4 07:29:03 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:03:32 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:03:44 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:03:56 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:10:14 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:10:25 newsfeed overchan[501709]: input line too long, skipping
Aug 4 08:10:41 newsfeed overchan[501709]: input line too long, skipping
Aug 4 09:13:00 newsfeed overchan[501709]: input line too long, skipping
Aug 4 13:34:05 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:38:33 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:38:43 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:38:49 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:38:57 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:40:18 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:40:44 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:41:52 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:42:38 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:42:44 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:42:51 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:43:39 newsfeed innd: message repeated 7 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:43:44 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:43:52 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:44:12 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:44:30 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:44:41 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:45:47 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:46:12 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:46:25 newsfeed innd: message repeated 6 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:46:28 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:46:40 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:46:56 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:49:08 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:49:20 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:49:32 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:49:51 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:50:58 newsfeed innd: message repeated 9 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:51:11 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:51:26 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4498432 duplicate size 0 rejected size 0]
Aug 4 13:51:32 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:51:48 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:52:20 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:53:35 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:53:49 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:53:59 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:54:02 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]
Aug 4 13:54:07 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:237 checkpoint seconds 1 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 4505600 duplicate size 0 rejected size 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1000901	98.5%	106.8 MB	98.3%
nnrpd	7677	0.8%	759.5 KB	0.7%
innd	6307	0.6%	975.2 KB	0.9%
innfeed	1378	0.1%	157.8 KB	0.1%
overchan	26	0.0%	2.9 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	1016290	100.0%	108.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1000299	99.8%
Negative hits	1460	0.1%
Positive hits	347	0.0%
Cache misses	14	0.0%
TOTAL: 4	1002120	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.303	0.0%	25	0.000	12.120	32.200
article cleanup	00:00:03.479	0.0%	1000559	0.000	0.003	0.016
article logging	00:00:11.528	0.0%	1000790	0.008	0.012	0.074
article parse	00:00:26.344	0.0%	8647923	0.002	0.003	0.014
article write	00:00:54.548	0.1%	1000398	0.032	0.055	10.075
artlog/artcncl	00:00:00.001	0.0%	23	0.000	0.043	0.200
data move	00:00:03.859	0.0%	11207806	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.095	0.0%	24	0.000	3.958	12.800
hishave/artcncl	00:00:00.037	0.0%	25	0.000	1.480	4.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:06:16.317	0.4%	1002336	0.017	0.375	54.868
history sync	00:00:00.014	0.0%	375	0.000	0.037	0.500
history write	00:17:22.294	1.2%	1000537	0.081	1.042	60.347
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:21:15.832	97.2%	8212287	0.394	10.238	411.228
nntp read	00:00:49.730	0.1%	7925870	0.004	0.006	0.032
overview write	00:00:01.563	0.0%	1000398	0.000	0.002	0.012
perl filter	00:11:18.267	0.8%	1000537	0.504	0.678	4.129
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:17.122	0.0%	2011180	0.007	0.009	0.027
TOTAL: 24:00:57.863	23:59:01.333	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	10378	0.000	0.003	0.028
article prepare	00:00:00.000	0.0%	208	0.000	0.000	0.000
article read	00:00:00.002	0.0%	52	0.000	0.038	1.000
backlog stats	00:00:00.047	0.0%	53418	0.000	0.001	0.010
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.304	0.0%	26748	0.000	0.011	0.040
data write	00:00:00.774	0.0%	16433	0.000	0.047	0.113
idle	23:59:19.871	100.0%	53418	830.950	1616.681	4287.944
status file	00:00:00.541	0.0%	368	0.000	1.470	9.143
TOTAL: 23:59:22.695	23:59:21.570	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:02:09.837	85.1%	4183	0.000	31.039	10852.636
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.293	0.2%	10142	0.000	0.029	3.318
TOTAL: 00:02:32.624	00:02:10.130	85.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
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	81	951559	951408	6	145	99%	44:27:48
2	news.icm.edu.pl	36	351	26	309	16	7%	19:15:05
3	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 3		118	951910	951434	315	161	99%	63:42:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	19.1 GB	356.1 KB	659.4 KB	19.1 GB	99%	21.0 KB
2	news.icm.edu.pl	872.7 KB	20.5 KB	243.0 KB	1.1 MB	76%	27.1 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		19.1 GB	376.6 KB	902.5 KB	19.1 GB	99%	21.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 4 04:15:01 - 04:59:59	2322	0.2%	0.86	36.6 MB	0.2%	13.90
Aug 4 05:00:00 - 05:59:59	5495	0.5%	1.53	66.4 MB	0.3%	18.88
Aug 4 06:00:00 - 06:59:59	3669	0.4%	1.02	65.7 MB	0.3%	18.69
Aug 4 07:00:00 - 07:59:59	5037	0.5%	1.40	153.1 MB	0.7%	43.56
Aug 4 08:00:00 - 08:59:59	3939	0.4%	1.09	50.8 MB	0.2%	14.45
Aug 4 09:00:00 - 09:59:59	3903	0.4%	1.08	54.6 MB	0.3%	15.52
Aug 4 10:00:00 - 10:59:59	5647	0.6%	1.57	76.6 MB	0.4%	21.78
Aug 4 11:00:00 - 11:59:59	5923	0.6%	1.65	68.3 MB	0.3%	19.43
Aug 4 12:00:00 - 12:59:59	42396	4.2%	11.78	950.6 MB	4.6%	270.39
Aug 4 13:00:00 - 13:59:59	681184	68.1%	189.22	14.2 GB	70.9%	4129.93
Aug 4 14:00:00 - 14:59:59	175138	17.5%	48.65	3.6 GB	18.0%	1048.49
Aug 4 15:00:00 - 15:59:59	4794	0.5%	1.33	66.3 MB	0.3%	18.85
Aug 4 16:00:00 - 16:59:59	4328	0.4%	1.20	56.1 MB	0.3%	15.96
Aug 4 17:00:00 - 17:59:59	5080	0.5%	1.41	55.6 MB	0.3%	15.82
Aug 4 18:00:00 - 18:59:59	5188	0.5%	1.44	62.2 MB	0.3%	17.70
Aug 4 19:00:00 - 19:59:59	4534	0.5%	1.26	57.4 MB	0.3%	16.34
Aug 4 20:00:00 - 20:59:59	4069	0.4%	1.13	48.7 MB	0.2%	13.85
Aug 4 21:00:00 - 21:59:59	3297	0.3%	0.92	40.9 MB	0.2%	11.64
Aug 4 22:00:00 - 22:59:59	3711	0.4%	1.03	59.4 MB	0.3%	16.91
Aug 4 23:00:00 - 23:59:59	10029	1.0%	2.79	78.2 MB	0.4%	22.23
Aug 5 00:00:00 - 00:59:59	4865	0.5%	1.35	50.4 MB	0.2%	14.34
Aug 5 01:00:00 - 01:59:59	3561	0.4%	0.99	39.5 MB	0.2%	11.24
Aug 5 02:00:00 - 02:59:59	5233	0.5%	1.45	56.0 MB	0.3%	15.93
Aug 5 03:00:00 - 03:59:59	5966	0.6%	1.66	60.6 MB	0.3%	17.23
Aug 5 04:00:00 - 04:15:01	1177	0.1%	1.31	15.1 MB	0.1%	17.13
TOTAL: 24:00:00	1000485	100.0%	11.58	20.0 GB	100.0%	242.66

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.pionier.net.pl	377	125	20	232
2	news.icm.edu.pl	16	0	2	14
TOTAL: 2		393	125	22	246

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	64
comp.mobile.android	16
alt.comp.software.firefox	14
alt.comp.software.seamonkey	7
de.comp.lang.vbclassic	7
it.tlc.cellulari.android	6
comp.sys.raspberry-pi	5
it.discussioni.energia	2
de.rec.sf.startrek	2
de.comm.software.mailreader	1
it.tlc.cellulari.iphone	1
TOTAL: 11	125

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	13
Scoring filter (8)	1
TOTAL: 2	14

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	16357	46	10111	6	0	0%	23:52:35
2	panorama	0	0	0	0	10384	0%	23:57:50
3	nask	0	0	0	0	6955	0%	23:57:50
4	okapi	0	0	0	0	731	0%	23:57:50
5	poznan	38	0	23	0	0	0%	03:40:23
TOTAL: 5		16395	46	10134	6	18070	0%	99:26:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	264.6 KB	21.6 KB	286.2 KB	5.5 KB	23:52:35
2	panorama	0.0 KB	0.0 KB	0.0 KB	0	23:57:50
3	nask	0.0 KB	0.0 KB	0.0 KB	0	23:57:50
4	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:50
5	poznan	0.0 KB	0.0 KB	0.0 KB	0	03:40:23
TOTAL: 5		264.6 KB	21.6 KB	286.2 KB	5.5 KB	99:26:28

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
TOTAL: 1	976.8 KB

NNRP readership statistics:

System		Conn	Elapsed
1	nms1.man.lodz.pl	1033	00:00:10
2	45.227.254.8	1	00:00:00
3	web.man.lodz.pl	292	00:00:09
4	80.111.142.213	4	00:00:02
5	212.51.192.185	96	00:00:01
6	115.231.181.140	5	00:00:04
7	nms2.man.lodz.pl	406	00:00:04
8	89.248.163.84	1	00:00:00
9	198.199.119.112	1	00:00:00
10	91-233-161-98.interkonekt.pl	1	00:02:00
TOTAL: 10		1840	00:02:32

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	108	00:00:08
2	*.interkonekt.pl	1	00:02:00
3	*.man.lodz.pl	1731	00:00:24
TOTAL: 3		1840	00:02:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91-233-161-98.interkonekt.pl	0.038	0.019	00:02:00
nms1.man.lodz.pl	2.249	11.949	00:00:10
web.man.lodz.pl	0.671	3.784	00:00:09
115.231.181.140	0.000	0.087	00:00:04
nms2.man.lodz.pl	0.723	6.134	00:00:04
80.111.142.213	0.061	0.058	00:00:02
212.51.192.185	0.172	1.313	00:00:01
198.199.119.112	0.004	0.015	00:00:00
89.248.163.84	0.000	0.016	00:00:00
45.227.254.8	0.005	0.010	00:00:00
TOTAL: 10	3.923	23.385	00:02:32

NNRP unrecognized commands (by host):

System	Conn
115.231.181.140	5
89.248.163.84	1
45.227.254.8	1
198.199.119.112	1
TOTAL: 4	8

NNRP unrecognized commands (by command):

Command	Count
#003	7
MGLNDD_212.191.2.21_119	1
TOTAL: 2	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
115.231.181.140	5	5
89.248.163.84	1	1
45.227.254.8	1	1
TOTAL: 3	7	7

Daily Usenet report

Aug 4 04:15:01 -- Aug 5 04:15:01