Daily Usenet report: Jan 9 04:15:02

Unknown entries from news log file:

First 50 / 4690 lines (1.1%)

Jan 9 04:18:12 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Jan 9 04:19:00 newsfeed nnrpd[1802050]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:00 newsfeed nnrpd[1802071]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802072]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802073]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802074]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802075]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802076]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802077]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:20:01 newsfeed nnrpd[1802078]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:21:00 newsfeed nnrpd[1802169]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:22:00 newsfeed nnrpd[1802178]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:23:00 newsfeed nnrpd[1802190]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:24:00 newsfeed nnrpd[1802200]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:00 newsfeed nnrpd[1802222]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802264]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802277]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802280]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802287]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802293]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802294]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:25:02 newsfeed nnrpd[1802297]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:26:00 newsfeed nnrpd[1802371]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:27:00 newsfeed nnrpd[1802380]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:27:25 newsfeed nnrpd[1802383]: 212.51.192.185 rejected Expiring process 1801836
Jan 9 04:28:00 newsfeed nnrpd[1802391]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:29:00 newsfeed nnrpd[1802545]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:00 newsfeed nnrpd[1802566]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802567]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802568]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802569]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802570]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802571]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802572]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:30:01 newsfeed nnrpd[1802573]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:31:00 newsfeed nnrpd[1802669]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:32:00 newsfeed nnrpd[1802678]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:33:00 newsfeed nnrpd[1802690]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:34:00 newsfeed nnrpd[1802699]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:00 newsfeed nnrpd[1802724]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802725]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802726]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802727]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802728]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802729]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802730]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:35:01 newsfeed nnrpd[1802731]: web.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:36:00 newsfeed nnrpd[1802822]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:37:00 newsfeed nnrpd[1802831]: nms1.man.lodz.pl rejected Expiring process 1801836
Jan 9 04:38:00 newsfeed nnrpd[1802842]: nms1.man.lodz.pl rejected Expiring process 1801836

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	24230	55.8%	2.2 MB	51.4%
inn	17304	39.9%	1.9 MB	44.4%
innfeed	1017	2.3%	96.4 KB	2.2%
innd	836	1.9%	88.9 KB	2.0%
overchan	4	0.0%	0.5 KB	0.0%
controlchan	4	0.0%	0.3 KB	0.0%
TOTAL: 6	43395	100.0%	4.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	14899	97.3%
Negative hits	330	2.2%
Positive hits	89	0.6%
Cache misses	0	0.0%
TOTAL: 4	15318	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.002	0.0%	8	0.000	0.250	0.500
article cleanup	00:00:00.069	0.0%	16587	0.000	0.004	0.008
article logging	00:00:00.300	0.0%	16587	0.000	0.018	0.024
article parse	00:00:00.364	0.0%	42286	0.000	0.009	0.011
article write	00:00:03.267	0.0%	16478	0.000	0.198	18.000
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:00.236	0.0%	997407	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:58.820	0.1%	16939	0.000	3.472	5.500
history sync	00:00:00.004	0.0%	281	0.000	0.014	0.333
history write	00:00:59.596	0.1%	16502	0.000	3.611	8.000
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:07:57.527	99.8%	1006701	17.694	82.723	25024.750
nntp read	00:00:06.160	0.0%	983199	0.000	0.006	0.022
overview write	00:00:00.054	0.0%	16478	0.000	0.003	0.006
perl filter	00:00:12.259	0.0%	16502	0.619	0.743	32.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.424	0.0%	35350	0.000	0.012	0.016
TOTAL: 23:10:55.718	23:10:19.082	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.002	0.0%	2339	0.000	0.001	0.020
article prepare	00:00:00.000	0.0%	0	0.000	0.000	0.000
article read	00:00:00.000	0.0%	0	0.000	0.000	0.000
backlog stats	00:00:00.015	0.0%	9314	0.000	0.002	0.069
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.027	0.0%	3546	0.000	0.008	0.026
data write	00:00:00.039	0.0%	1415	0.000	0.028	0.059
idle	23:04:35.162	100.0%	9314	243.649	8919.386	22230.407
status file	00:00:00.170	0.0%	354	0.000	0.480	1.857
TOTAL: 23:04:36.052	23:04:35.415	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	08:01:59.441	99.9%	13879	0.000	2083.683	300411.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.411	0.0%	30024	0.000	0.014	1.000
readart	00:00:00.000	0.0%	2	0.000	0.000	0.000
TOTAL: 08:02:39.461	08:01:59.852	99.9%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	1
logmode	2
lowmark	1
mode	148
name	6
pause	2
paused	2
reload	2
reserve	1
shutdown	3
throttle	10
TOTAL: 13	207

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	10	15485	15468	0	17	99%	15:25:05
2	news.icm.edu.pl	4	1832	1718	26	88	93%	08:13:46
3	localhost	4	3	3	0	0	100%	00:00:01
TOTAL: 3		18	17320	17189	26	105	99%	23:38:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	100.3 MB	178.2 KB	181.1 KB	100.7 MB	99%	6.7 KB
2	news.icm.edu.pl	20.0 MB	951.0 KB	1.2 MB	22.1 MB	90%	12.5 KB
3	localhost	4.0 KB	0.0 KB	0.0 KB	4.0 KB	100%	1.3 KB
TOTAL: 3		120.3 MB	1.1 MB	1.4 MB	122.8 MB	97%	7.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:02 - 04:59:59	560	3.3%	0.21	3.0 MB	0.0%	1.14
Jan 9 05:00:00 - 05:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 06:00:00 - 06:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 07:00:00 - 07:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 08:00:00 - 08:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 09:00:00 - 09:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 10:00:00 - 10:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 11:00:00 - 11:59:59	8	0.0%	0.00	76.3 KB	0.0%	0.02
Jan 9 12:00:00 - 12:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 18:00:00 - 18:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 19:00:00 - 19:59:59	2	0.0%	0.00	2.6 KB	0.0%	0.00
Jan 9 20:00:00 - 20:59:59	1	0.0%	0.00	1.4 KB	0.0%	0.00
Jan 9 21:00:00 - 21:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 22:00:00 - 22:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 9 23:00:00 - 23:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 10 00:00:00 - 00:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 10 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 10 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Jan 10 03:00:00 - 03:59:59	13948	81.2%	3.87	102.1 MB	0.0%	29.04
Jan 10 04:00:00 - 04:15:01	2660	15.5%	2.95	17.6 MB	0.0%	20.02
TOTAL: 23:59:59	17179	100.0%	0.20	0.0 KB	100.0%	0.00

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.icm.edu.pl	100	1	80	19
2	newsfeed.pionier.net.pl	17	2	15	0
TOTAL: 2		117	3	95	19

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.mobile.android	3
TOTAL: 1	3

INND Perl filter [Top 20]:

Reason	Count
Scoring filter (9)	12
Scoring filter (10)	3
Scoring filter (8)	2
Binary in non-binary group	2
TOTAL: 4	19

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Refused	Spool	%Took	Elapsed
1	panorama	0	0	2493	0%	23:24:49
2	icm	1160	797	1	0%	01:45:42
3	nask	0	0	2405	0%	23:24:49
4	okapi	0	0	10	0%	23:17:04
5	poznan	313	306	0	0%	01:32:54
TOTAL: 5		1473	1103	4909	0%	73:25:18

Outgoing Feeds (innfeed) by Volume:

Server		Elapsed
1	panorama	23:24:49
2	icm	01:45:42
3	nask	23:24:49
4	okapi	23:17:04
5	poznan	01:32:54
TOTAL: 5		73:25:18

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	784	2	1.2 KB	1	0	00:00:08
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	3	00:00:00
TOTAL: 2		790	2	1.2 KB	1	3	08:02:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	2190	2	1.2 KB	1	3	00:00:21
2	*.internet-census.org	1	0	0.0 KB	0	0	00:00:10
3	unresolved	128	0	0.0 KB	0	0	00:00:04
4	*.icm.edu.pl	1762	0	0.0 KB	0	0	00:00:51
5	*.pionier.net.pl	1902	0	0.0 KB	0	0	08:01:13
TOTAL: 5		5983	2	1.2 KB	1	3	08:02:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
newsfeed.pionier.net.pl	6.316	7.238	08:01:13
news.icm.edu.pl	5.781	6.740	00:00:51
sh-chi-us-gp1-wk101d.internet-census.org	0.003	0.011	00:00:10
nms2.man.lodz.pl	2.892	3.444	00:00:08
web.man.lodz.pl	2.009	3.527	00:00:08
nms1.man.lodz.pl	1.293	2.515	00:00:02
newsfeed.man.lodz.pl	0.003	0.020	00:00:01
80.111.142.213	0.020	0.018	00:00:01
45.227.254.19	0.114	0.092	00:00:01
212.51.192.185	0.270	0.382	00:00:00
198.199.93.66	0.004	0.004	00:00:00
isvcs01.man.lodz.pl	0.037	0.030	00:00:00
194.165.16.76	0.004	0.000	00:00:00
pc-tom.man.lodz.pl	0.000	0.013	00:00:00
TOTAL: 14	18.746	24.034	08:02:40

Curious NNRP server explorers [Top 20]:

System	Conn
newsfeed.pionier.net.pl	1902
news.icm.edu.pl	1762
nms2.man.lodz.pl	969
nms1.man.lodz.pl	426
212.51.192.185	94
45.227.254.19	28
80.111.142.213	4
newsfeed.man.lodz.pl	3
pc-tom.man.lodz.pl	2
194.165.16.76	1
198.199.93.66	1
sh-chi-us-gp1-wk101d.internet-census.org	1
TOTAL: 12	5193

NNRP unrecognized commands (by host):

System	Conn
45.227.254.19	28
194.165.16.76	1
198.199.93.66	1
TOTAL: 3	30

NNRP unrecognized commands (by command):

Command	Count
#003	29
MGLNDD_212.191.2.21_119	1
TOTAL: 2	30

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.19	28	28
194.165.16.76	1	1
TOTAL: 2	29	29

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	2	100.0%
TOTAL: 1		2	100.0%

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

Newsgroup		Count
1	lodman.test	2
TOTAL: 1		2

Daily Usenet report

Jan 9 04:15:02 -- Jan 10 04:15:01