Daily Usenet report: Mar 5 04:15:03

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Mar 5 07:30:25 newsfeed overchan[25343]: input line too long, skipping
Mar 5 20:24:08 newsfeed overchan[25343]: input line too long, skipping
Mar 5 20:25:50 newsfeed overchan[25343]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	756915	92.4%	82.7 MB	91.5%
innfeed	48458	5.9%	5.7 MB	6.3%
innd	9825	1.2%	1.5 MB	1.7%
nnrpd	4165	0.5%	421.7 KB	0.5%
overchan	4	0.0%	0.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	819368	100.0%	90.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	753161	99.0%
Negative hits	7070	0.9%
Positive hits	163	0.0%
Cache misses	25	0.0%
TOTAL: 4	760419	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.172	0.0%	58	0.000	20.207	82.000
article cleanup	00:00:11.286	0.0%	757884	0.009	0.015	0.022
article logging	00:00:42.152	0.0%	758266	0.043	0.056	0.340
article parse	00:21:22.192	1.5%	61915127	0.011	0.021	0.032
article write	00:08:56.312	0.6%	757739	0.440	0.708	3.956
artlog/artcncl	00:00:00.000	0.0%	57	0.000	0.000	0.000
data move	00:01:02.974	0.1%	66569552	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.223	0.0%	57	0.000	3.912	45.500
hishave/artcncl	00:00:00.140	0.0%	58	0.000	2.414	17.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:57:11.171	4.0%	765982	0.365	4.479	46.669
history sync	00:00:00.030	0.0%	291	0.000	0.103	1.000
history write	01:27:44.360	6.1%	757860	1.087	6.946	80.521
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	18:41:17.744	77.9%	53128140	0.632	1.266	4.187
nntp read	00:40:33.120	2.8%	61477555	0.025	0.040	0.056
overview write	00:00:04.852	0.0%	757739	0.003	0.006	0.009
perl filter	01:18:12.736	5.4%	757860	4.231	6.192	9.680
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:44.202	0.1%	1528093	0.022	0.029	0.036
TOTAL: 24:00:04.196	23:38:04.666	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.153	0.0%	48915	0.000	0.003	0.017
article prepare	00:00:00.000	0.0%	1958	0.000	0.000	0.000
article read	00:00:06.448	0.0%	489	0.000	13.186	1706.000
backlog stats	00:00:00.100	0.0%	96546	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.982	0.0%	48734	0.000	0.020	0.047
data write	00:00:02.159	0.0%	36277	0.016	0.060	0.096
idle	23:47:37.987	99.5%	96546	645.081	887.225	1251.256
status file	00:07:34.634	0.5%	17055	0.822	26.657	2001.707
TOTAL: 23:55:31.254	23:55:22.463	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.817	46.9%	3018	0.000	5.904	285.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.289	0.8%	6651	0.000	0.043	0.632
readart	00:00:00.001	0.0%	16	0.000	0.062	0.500
TOTAL: 00:00:38.013	00:00:18.107	47.6%	-	-	-	-

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	229	756815	756673	0	142	99%	125:46:06
2	news.icm.edu.pl	46	132	22	107	3	16%	12:02:31
3	localhost	1441	0	0	0	0	0%	00:00:05
TOTAL: 3		1716	756947	756695	107	145	99%	137:48:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	282.0 GB	2.5 MB	2.8 MB	282.0 GB	99%	390.8 KB
2	news.icm.edu.pl	1.8 MB	8.2 KB	89.1 KB	1.9 MB	95%	78.7 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		282.0 GB	2.5 MB	2.9 MB	282.0 GB	99%	390.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 5 04:15:03 - 04:59:59	29976	4.0%	11.11	10.1 GB	3.6%	3908.62
Mar 5 05:00:00 - 05:59:59	34776	4.6%	9.66	14.7 GB	5.2%	4293.82
Mar 5 06:00:00 - 06:59:59	34081	4.5%	9.47	11.7 GB	4.2%	3419.51
Mar 5 07:00:00 - 07:59:59	22759	3.0%	6.32	9.3 GB	3.3%	2720.55
Mar 5 08:00:00 - 08:59:59	26547	3.5%	7.37	8.3 GB	3.0%	2423.81
Mar 5 09:00:00 - 09:59:59	22032	2.9%	6.12	5.4 GB	1.9%	1578.70
Mar 5 10:00:00 - 10:59:59	23460	3.1%	6.52	5.9 GB	2.1%	1713.38
Mar 5 11:00:00 - 11:59:59	22967	3.0%	6.38	6.0 GB	2.1%	1734.17
Mar 5 12:00:00 - 12:59:59	26198	3.5%	7.28	8.5 GB	3.0%	2474.21
Mar 5 13:00:00 - 13:59:59	24354	3.2%	6.76	7.6 GB	2.7%	2213.68
Mar 5 14:00:00 - 14:59:59	36945	4.9%	10.26	13.9 GB	4.9%	4041.81
Mar 5 15:00:00 - 15:59:59	36386	4.8%	10.11	14.7 GB	5.2%	4285.36
Mar 5 16:00:00 - 16:59:59	30925	4.1%	8.59	11.7 GB	4.2%	3417.12
Mar 5 17:00:00 - 17:59:59	32236	4.3%	8.95	12.2 GB	4.3%	3540.00
Mar 5 18:00:00 - 18:59:59	38707	5.1%	10.75	12.2 GB	4.3%	3546.97
Mar 5 19:00:00 - 19:59:59	38828	5.1%	10.79	16.3 GB	5.8%	4756.70
Mar 5 20:00:00 - 20:59:59	36842	4.9%	10.23	15.3 GB	5.4%	4445.76
Mar 5 21:00:00 - 21:59:59	37587	5.0%	10.44	15.7 GB	5.6%	4560.31
Mar 5 22:00:00 - 22:59:59	39100	5.2%	10.86	14.2 GB	5.0%	4135.16
Mar 5 23:00:00 - 23:59:59	37800	5.0%	10.50	14.7 GB	5.2%	4268.09
Mar 6 00:00:00 - 00:59:59	33134	4.4%	9.20	13.7 GB	4.8%	3981.47
Mar 6 01:00:00 - 01:59:59	31500	4.2%	8.75	14.8 GB	5.2%	4304.46
Mar 6 02:00:00 - 02:59:59	29515	3.9%	8.20	13.6 GB	4.8%	3950.25
Mar 6 03:00:00 - 03:59:59	24856	3.3%	6.90	10.0 GB	3.5%	2911.11
Mar 6 04:00:00 - 04:15:02	4821	0.6%	5.34	1.6 GB	0.6%	1823.02
TOTAL: 23:59:59	756332	100.0%	8.75	281.9 GB	100.0%	3420.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	523	113	23	6	381
2	news.icm.edu.pl	3	0	1	0	2
TOTAL: 2		526	113	24	6	383

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	45
it.tlc.cellulari.android	43
comp.mobile.android	13
it.comp.os.win.windows7	3
it.comp.os.win.windows10	3
it.comp.os.linux.ubuntu	2
de.comp.lang.vbclassic	2
de.comm.provider.telefonie	2
TOTAL: 8	113

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

Groups	Count
pl.praca.oferowana	4
pl.sci.matematyka	2
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2
TOTAL: 1	2

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	14290	197	12079	17	0	0	1%	24:01:16
2	panorama	38867	53	10545	34	1	38479	0%	23:59:54
3	nask	0	0	0	0	0	8314	0%	24:05:20
4	poznan	27	0	20	0	0	0	0%	02:12:12
5	okapi	0	0	0	0	0	845	0%	23:57:45
TOTAL: 5		53184	250	22644	51	1	47638	0%	98:16:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	635.2 KB	48.1 KB	683.2 KB	3.2 KB	24:01:16
2	panorama	133.1 KB	136.9 KB	270.0 KB	3.1 KB	23:59:54
3	nask	0.0 KB	0.0 KB	0.0 KB	0	24:05:20
4	poznan	0.0 KB	0.0 KB	0.0 KB	0	02:12:12
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:45
TOTAL: 5		768.2 KB	185.0 KB	953.2 KB	3.2 KB	98:16:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	16	13.9 KB	8	0	0	00:00:22
TOTAL: 1		288	16	13.9 KB	8	0	0	00:00:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	16	13.9 KB	8	00:00:26
2	unresolved	100	0	0.0 KB	0	00:00:10
3	*.ch1.censys-scanner.com	1	0	0.0 KB	0	00:00:00
TOTAL: 3		677	16	13.9 KB	8	00:00:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	2.007	5.571	00:00:22
80.111.142.213	0.015	0.076	00:00:09
mon.man.lodz.pl	1.587	3.770	00:00:04
212.51.192.185	0.549	1.228	00:00:01
scanner-21.ch1.censys-scanner.com	0.014	0.011	00:00:00
TOTAL: 5	4.172	10.656	00:00:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
scanner-21.ch1.censys-scanner.com	1
TOTAL: 4	389

NNRP client timeouts [Top 20]:

System	Conn	Peer
scanner-21.ch1.censys-scanner.com	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Mar 5 04:15:03 -- Mar 6 04:15:02