Daily Usenet report: Jun 19 04:15:01

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Jun 19 06:46:24 newsfeed overchan[172511]: input line too long, skipping
Jun 19 07:41:55 newsfeed nnrpd[182110]: SERVER perl filter not defined
Jun 19 08:20:37 newsfeed nnrpd[183863]: SERVER perl filter not defined
Jun 19 09:22:51 newsfeed nnrpd[186687]: SERVER perl filter not defined
Jun 19 09:28:01 newsfeed nnrpd[186830]: SERVER perl filter not defined
Jun 19 13:32:17 newsfeed overchan[172511]: input line too long, skipping
Jun 19 14:24:07 newsfeed nnrpd[199855]: SERVER perl filter not defined
Jun 19 14:24:56 newsfeed nnrpd[199876]: SERVER perl filter not defined
Jun 19 14:39:05 newsfeed nnrpd[200434]: SERVER perl filter not defined
Jun 19 14:39:13 newsfeed nnrpd[200435]: SERVER perl filter not defined
Jun 19 15:23:51 newsfeed nnrpd[202508]: SERVER perl filter not defined
Jun 19 15:48:07 newsfeed overchan[172511]: input line too long, skipping
Jun 19 15:49:30 newsfeed overchan[172511]: input line too long, skipping
Jun 19 16:24:26 newsfeed nnrpd[205147]: SERVER perl filter not defined
Jun 19 18:26:07 newsfeed overchan[172511]: input line too long, skipping
Jun 19 19:52:02 newsfeed nnrpd[215375]: SERVER perl filter not defined
Jun 19 19:59:53 newsfeed nnrpd[215704]: SERVER perl filter not defined
Jun 19 20:03:03 newsfeed nnrpd[215992]: SERVER perl filter not defined
Jun 19 20:53:48 newsfeed overchan[172511]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	78546	70.4%	8.9 MB	69.9%
innfeed	23676	21.2%	2.8 MB	21.7%
nnrpd	7726	6.9%	863.5 KB	6.6%
innd	1544	1.4%	234.2 KB	1.8%
overchan	7	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	111500	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	78010	98.9%
Negative hits	649	0.8%
Positive hits	206	0.3%
Cache misses	12	0.0%
TOTAL: 4	78877	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.466	0.0%	29	0.000	16.069	58.000
article cleanup	00:00:00.308	0.0%	78198	0.000	0.004	0.015
article logging	00:00:01.418	0.0%	78525	0.000	0.018	0.052
article parse	00:00:02.094	0.0%	665181	0.000	0.003	0.009
article write	00:00:12.000	0.0%	78109	0.043	0.154	2.571
artlog/artcncl	00:00:00.000	0.0%	29	0.000	0.000	0.000
data move	00:00:00.387	0.0%	867052	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.144	0.0%	29	0.000	4.966	25.000
hishave/artcncl	00:00:00.124	0.0%	29	0.000	4.276	12.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:12:20.864	0.9%	79024	3.267	9.375	41.122
history sync	00:00:00.014	0.0%	296	0.000	0.047	0.500
history write	00:11:28.148	0.8%	78186	3.962	8.801	29.235
idle	23:34:47.165	98.2%	737304	22.138	115.132	636.201
nntp read	00:00:05.852	0.0%	662943	0.004	0.009	0.017
overview write	00:00:00.163	0.0%	78109	0.000	0.002	0.014
perl filter	00:00:58.433	0.1%	78186	0.496	0.747	1.709
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.585	0.0%	166558	0.002	0.010	0.022
TOTAL: 24:00:12.273	23:59:59.165	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	31770	0.000	0.001	0.008
article prepare	00:00:00.000	0.0%	2716	0.000	0.000	0.000
article read	00:00:12.248	0.0%	681	0.000	17.985	51.667
backlog stats	00:00:00.039	0.0%	72213	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:00.223	0.0%	36283	0.000	0.006	0.035
data write	00:00:00.511	0.0%	26070	0.000	0.020	0.062
idle	23:56:02.022	100.0%	72213	681.967	1193.165	3366.559
status file	00:00:04.580	0.0%	7681	0.149	0.596	12.374
TOTAL: 23:56:21.215	23:56:19.643	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:21.539	53.7%	4273	0.000	5.041	9738.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.120	0.3%	10279	0.000	0.012	0.500
readart	00:00:00.029	0.1%	24	0.000	1.208	14.000
TOTAL: 00:00:40.083	00:00:21.688	54.1%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
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	71	78228	78145	1	82	99%	37:25:43
2	localhost	14	13	13	0	0	100%	00:00:00
3	news.icm.edu.pl	48	190	9	175	6	4%	14:01:53
TOTAL: 3		133	78431	78167	176	88	99%	51:27:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	1.5 GB	256.5 KB	428.9 KB	1.5 GB	99%	19.7 KB
2	news.icm.edu.pl	345.4 KB	30.2 KB	126.3 KB	501.9 KB	68%	33.5 KB
3	localhost	18.5 KB	0.0 KB	0.0 KB	18.5 KB	100%	1.4 KB
TOTAL: 3		1.5 GB	286.7 KB	555.2 KB	1.5 GB	99%	19.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 19 04:15:01 - 04:59:59	3071	3.9%	1.14	85.1 MB	5.6%	32.27
Jun 19 05:00:00 - 05:59:59	2327	3.0%	0.65	59.5 MB	4.0%	16.92
Jun 19 06:00:00 - 06:59:59	4517	5.8%	1.25	69.3 MB	4.6%	19.71
Jun 19 07:00:00 - 07:59:59	4056	5.2%	1.13	82.8 MB	5.5%	23.56
Jun 19 08:00:00 - 08:59:59	3536	4.5%	0.98	91.8 MB	6.1%	26.10
Jun 19 09:00:00 - 09:59:59	4869	6.2%	1.35	118.5 MB	7.9%	33.72
Jun 19 10:00:00 - 10:59:59	6300	8.1%	1.75	166.9 MB	11.1%	47.47
Jun 19 11:00:00 - 11:59:59	5464	7.0%	1.52	142.7 MB	9.5%	40.59
Jun 19 12:00:00 - 12:59:59	5183	6.6%	1.44	120.4 MB	8.0%	34.23
Jun 19 13:00:00 - 13:59:59	4516	5.8%	1.25	138.3 MB	9.2%	39.33
Jun 19 14:00:00 - 14:59:59	3391	4.3%	0.94	37.8 MB	2.5%	10.74
Jun 19 15:00:00 - 15:59:59	3250	4.2%	0.90	38.8 MB	2.6%	11.03
Jun 19 16:00:00 - 16:59:59	2411	3.1%	0.67	32.2 MB	2.1%	9.16
Jun 19 17:00:00 - 17:59:59	2189	2.8%	0.61	33.0 MB	2.2%	9.38
Jun 19 18:00:00 - 18:59:59	2163	2.8%	0.60	29.0 MB	1.9%	8.25
Jun 19 19:00:00 - 19:59:59	2922	3.7%	0.81	37.9 MB	2.5%	10.78
Jun 19 20:00:00 - 20:59:59	3203	4.1%	0.89	51.0 MB	3.4%	14.52
Jun 19 21:00:00 - 21:59:59	1929	2.5%	0.54	18.7 MB	1.2%	5.31
Jun 19 22:00:00 - 22:59:59	2107	2.7%	0.59	23.0 MB	1.5%	6.54
Jun 19 23:00:00 - 23:59:59	2764	3.5%	0.77	29.9 MB	2.0%	8.50
Jun 20 00:00:00 - 00:59:59	2709	3.5%	0.75	30.1 MB	2.0%	8.57
Jun 20 01:00:00 - 01:59:59	1621	2.1%	0.45	22.2 MB	1.5%	6.32
Jun 20 02:00:00 - 02:59:59	2161	2.8%	0.60	25.3 MB	1.7%	7.19
Jun 20 03:00:00 - 03:59:59	1063	1.4%	0.30	15.9 MB	1.1%	4.53
Jun 20 04:00:00 - 04:15:01	379	0.5%	0.42	5.6 MB	0.4%	6.37
TOTAL: 24:00:00	78101	100.0%	0.90	1.5 GB	100.0%	17.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	409	73	8	1	327
2	news.icm.edu.pl	7	0	4	0	3
TOTAL: 2		416	73	12	1	330

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	24
comp.sys.raspberry-pi	17
it.tlc.cellulari.android	11
alt.comp.software.firefox	8
alt.comp.software.seamonkey	6
de.comm.software.mailreader	5
de.comm.provider.telefonie	1
de.comp.lang.vbclassic	1
TOTAL: 8	73

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

Groups	Count
pl.sci.matematyka	1
TOTAL: 1	1

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	21643	130	8736	219	4	22754	0%	23:59:58
2	icm	13373	13	10199	11	0	0	0%	23:59:52
3	poznan	13	0	9	0	0	0	0%	01:40:21
4	okapi	0	0	0	0	0	848	0%	23:57:48
5	nask	0	0	0	0	0	6861	0%	23:57:48
TOTAL: 5		35029	143	18944	230	4	30463	0%	97:35:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	panorama	411.5 KB	3.1 MB	3.5 MB	10.4 KB	23:59:58
2	icm	36.6 KB	26.6 KB	63.2 KB	2.6 KB	23:59:52
3	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:40:21
4	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
5	nask	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
TOTAL: 5		448.1 KB	3.2 MB	3.6 MB	9.9 KB	97:35:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	287	22	24.1 KB	10	0	00:00:08
2	d75-159-118-192.abhsia.telus.net	1	2	1.3 KB	2	0	00:00:04
3	isvcs01.man.lodz.pl	13	0	0.0 KB	0	13	00:00:00
TOTAL: 3		301	24	25.4 KB	12	13	00:00:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	1738	22	24.1 KB	10	13	00:00:21
2	*.abhsia.telus.net	1	2	1.3 KB	2	0	00:00:04
3	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	00:00:01
4	unresolved	103	0	0.0 KB	0	0	00:00:13
TOTAL: 4		1844	24	25.4 KB	12	13	00:00:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
nms1.man.lodz.pl	2.407	3.086	00:00:09
107.170.255.16	0.005	0.000	00:00:09
web.man.lodz.pl	0.694	0.845	00:00:08
d75-159-118-192.abhsia.telus.net	0.011	0.005	00:00:04
nms2.man.lodz.pl	0.779	0.881	00:00:02
80.111.142.213	0.012	0.012	00:00:01
212.51.192.185	0.198	0.247	00:00:00
scanner-29.ch1.censys-scanner.com	0.007	0.000	00:00:00
isvcs01.man.lodz.pl	0.054	0.031	00:00:00
scanner-06.ch1.censys-scanner.com	0.002	0.005	00:00:00
192.241.232.8	0.000	0.005	00:00:00
176.117.195.64	0.000	0.005	00:00:00
45.227.254.55	0.000	0.005	00:00:00
TOTAL: 13	4.169	5.127	00:00:40

Curious NNRP server explorers [Top 20]:

System	Conn
nms1.man.lodz.pl	1123
nms2.man.lodz.pl	315
212.51.192.185	95
80.111.142.213	4
176.117.195.64	1
45.227.254.55	1
scanner-06.ch1.censys-scanner.com	1
scanner-29.ch1.censys-scanner.com	1
107.170.255.16	1
192.241.232.8	1
TOTAL: 10	1543

NNRP unrecognized commands (by host):

System	Conn
scanner-06.ch1.censys-scanner.com	1
192.241.232.8	1
45.227.254.55	1
scanner-29.ch1.censys-scanner.com	1
TOTAL: 4	4

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	2
MGLNDD_212.191.2.21_119	1
#003	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.55	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	22	91.7%
2	lodman	2	8.3%
TOTAL: 2		24	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	12
2	pl.comp.os.ms-windows.winnt	10
3	lodman.test	1
4	lodman.info	1
TOTAL: 4		24

Daily Usenet report

Jun 19 04:15:01 -- Jun 20 04:15:01