Daily Usenet report: Jan 29 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 29 09:34:27 newsfeed nnrpd[26119]: message repeated 2 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:34:57 newsfeed nnrpd[26345]: message repeated 2 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:35:35 newsfeed nnrpd[26176]: message repeated 3 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:36:44 newsfeed nnrpd[26177]: message repeated 6 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:38:39 newsfeed nnrpd[26188]: message repeated 2 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:39:32 newsfeed nnrpd[26338]: message repeated 3 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:45:46 newsfeed nnrpd[26334]: message repeated 5 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:55:21 newsfeed nnrpd[26372]: message repeated 7 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]
Jan 29 09:55:31 newsfeed nnrpd[26336]: message repeated 3 times: [ qv153.internetdsl.tpnet.pl group lodz.ogolne 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2545821	97.3%	293.4 MB	96.8%
innfeed	46051	1.8%	5.5 MB	1.8%
innd	20473	0.8%	3.6 MB	1.2%
nnrpd	5396	0.2%	587.7 KB	0.2%
TOTAL: 4	2617741	100.0%	303.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2537214	96.9%
Negative hits	59421	2.3%
Positive hits	21998	0.8%
Cache misses	118	0.0%
TOTAL: 4	2618751	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.686	0.0%	217	0.000	12.378	854.000
article cleanup	00:00:31.071	0.0%	2545498	0.005	0.012	0.023
article logging	00:01:18.620	0.1%	2546127	0.017	0.031	0.658
article parse	01:03:54.248	4.4%	137621523	0.012	0.028	0.084
article write	00:30:15.278	2.1%	2538042	0.318	0.715	4.339
artlog/artcncl	00:00:00.004	0.0%	216	0.000	0.019	0.500
artlog/artparse	00:00:00.003	0.0%	159	0.000	0.019	0.200
data move	00:04:47.115	0.3%	153262455	0.000	0.002	0.009
hisgrep/artcncl	00:00:00.250	0.0%	180	0.000	1.389	18.000
hishave/artcncl	00:00:00.057	0.0%	217	0.000	0.263	2.444
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:19.970	2.4%	2620200	0.039	0.786	17.507
history sync	00:00:00.012	0.0%	293	0.000	0.041	1.000
history write	00:49:57.654	3.5%	2538558	0.182	1.181	36.953
hiswrite/artcncl	00:00:00.030	0.0%	37	0.000	0.811	2.400
idle	13:50:27.726	57.7%	106313111	0.098	0.469	1.436
nntp read	01:08:39.148	4.8%	135693807	0.014	0.030	0.049
overview write	00:00:15.361	0.0%	2538042	0.001	0.006	0.013
perl filter	05:17:53.196	22.1%	2538399	5.091	7.514	9.538
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:57.487	0.3%	5149892	0.036	0.058	0.108
TOTAL: 24:00:20.965	23:27:19.916	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.482	0.0%	151452	0.000	0.003	0.111
article prepare	00:00:00.015	0.0%	74584	0.000	0.000	0.250
article read	00:00:38.464	0.0%	18563	0.000	2.072	1304.000
backlog stats	00:00:00.279	0.0%	341905	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:02.603	0.0%	194938	0.000	0.013	0.081
data write	00:00:11.629	0.0%	128960	0.000	0.090	1.098
idle	23:58:23.957	99.9%	341905	20.818	252.421	1459.548
status file	00:00:12.564	0.0%	16122	0.000	0.779	3.188
TOTAL: 23:59:44.987	23:59:29.993	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:34:57.762	99.9%	7329	0.000	2251.025	440119.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.343	0.0%	15774	0.000	0.022	0.684
readart	00:00:01.647	0.0%	97	0.000	16.979	91.647
TOTAL: 04:35:18.937	04:34:59.752	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1092	2542178	2532182	2895	7101	99%	325:14:32
2	news.nask.pl	2	16970	4906	11562	502	28%	23:21:10
3	news.icm.edu.pl	41	202	48	146	8	23%	14:46:04
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1136	2559350	2537136	14603	7611	99%	363:21:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	803.3 GB	595.7 MB	655.0 MB	804.5 GB	99%	332.2 KB
2	news.nask.pl	16.9 MB	1.5 MB	1.6 MB	19.9 MB	84%	3.8 KB
3	news.icm.edu.pl	1.8 MB	17.0 KB	88.0 KB	1.9 MB	94%	35.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		803.3 GB	597.2 MB	656.6 MB	804.6 GB	99%	331.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:02 - 04:59:59	73470	2.9%	27.23	24.9 GB	3.1%	9671.77
Jan 29 05:00:00 - 05:59:59	99635	3.9%	27.68	32.2 GB	4.0%	9387.25
Jan 29 06:00:00 - 06:59:59	107869	4.3%	29.96	35.4 GB	4.4%	10319.49
Jan 29 07:00:00 - 07:59:59	113366	4.5%	31.49	37.2 GB	4.6%	10827.36
Jan 29 08:00:00 - 08:59:59	116951	4.6%	32.49	36.5 GB	4.5%	10629.34
Jan 29 09:00:00 - 09:59:59	120451	4.7%	33.46	36.7 GB	4.6%	10678.47
Jan 29 10:00:00 - 10:59:59	84709	3.3%	23.53	25.6 GB	3.2%	7465.70
Jan 29 11:00:00 - 11:59:59	99386	3.9%	27.61	33.3 GB	4.2%	9713.40
Jan 29 12:00:00 - 12:59:59	108915	4.3%	30.25	36.2 GB	4.5%	10538.45
Jan 29 13:00:00 - 13:59:59	95330	3.8%	26.48	32.8 GB	4.1%	9548.67
Jan 29 14:00:00 - 14:59:59	111335	4.4%	30.93	35.9 GB	4.5%	10458.72
Jan 29 15:00:00 - 15:59:59	117216	4.6%	32.56	35.9 GB	4.5%	10465.10
Jan 29 16:00:00 - 16:59:59	111714	4.4%	31.03	34.5 GB	4.3%	10042.96
Jan 29 17:00:00 - 17:59:59	101599	4.0%	28.22	32.8 GB	4.1%	9560.75
Jan 29 18:00:00 - 18:59:59	107989	4.3%	30.00	34.1 GB	4.2%	9934.01
Jan 29 19:00:00 - 19:59:59	114309	4.5%	31.75	35.3 GB	4.4%	10284.06
Jan 29 20:00:00 - 20:59:59	115108	4.5%	31.97	33.7 GB	4.2%	9802.96
Jan 29 21:00:00 - 21:59:59	100269	4.0%	27.85	30.9 GB	3.8%	8988.92
Jan 29 22:00:00 - 22:59:59	105980	4.2%	29.44	34.1 GB	4.2%	9926.34
Jan 29 23:00:00 - 23:59:59	93865	3.7%	26.07	29.1 GB	3.6%	8490.10
Jan 30 00:00:00 - 00:59:59	97092	3.8%	26.97	29.7 GB	3.7%	8644.27
Jan 30 01:00:00 - 01:59:59	123833	4.9%	34.40	37.1 GB	4.6%	10807.22
Jan 30 02:00:00 - 02:59:59	89154	3.5%	24.77	29.0 GB	3.6%	8460.49
Jan 30 03:00:00 - 03:59:59	102970	4.1%	28.60	31.8 GB	4.0%	9275.14
Jan 30 04:00:00 - 04:15:02	24845	1.0%	27.54	7.9 GB	1.0%	9162.10
TOTAL: 24:00:00	2537360	100.0%	29.37	802.7 GB	100.0%	9741.39

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	7652	232	6614	6	800
2	news.nask.pl	585	11	483	4	87
3	news.icm.edu.pl	8	0	2	0	6
TOTAL: 3		8245	243	7099	10	893

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.tlc.cellulari.android	80
uk.railway	51
it.comp.os.win.windows10	31
comp.mobile.android	22
hr.ponuda.hardver.komponente	13
comp.mobile.ipad	9
comp.sys.raspberry-pi	8
nl.comp.os.linux.techniek	8
de.comp.office-pakete.ms-office.word	5
de.comm.software.mailreader	5
de.comp.office-pakete.ms-office.excel	3
it.comp.os.linux.ubuntu	3
hr.alt.cellular.gsm.android	2
it.comp.os.win.windows8	2
comp.sys.mac.vintage	1
TOTAL: 15	243

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

Groups	Count
pl.sci.historia	8
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	87
EMP (md5)	5
EMP (fsl)	5
Too many newsgroups	4
HTML post	3
TOTAL: 5	104

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	61378	12313	29987	4632	0	8	20%	24:07:13
2	panorama	86124	658	58237	496	22	92036	0%	24:00:06
3	poznan	8228	102	4935	3	0	0	1%	24:06:19
4	okapi	0	0	0	0	0	2451	0%	24:00:01
5	nask	0	0	0	0	0	17067	0%	24:00:01
TOTAL: 5		155730	13073	93159	5131	22	111562	8%	120:13:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.0 GB	1007.2 MB	3.0 GB	36.1 KB/s	184.7 KB	24:07:13
2	panorama	2.7 MB	14.1 MB	16.9 MB	0.2 KB/s	15.0 KB	24:00:06
3	poznan	356.0 KB	10.7 KB	366.7 KB	0.0 KB/s	3.5 KB	24:06:19
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		2.0 GB	1021.4 MB	3.0 GB	7.3 KB/s	172.9 KB	120:13:40

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	web.man.lodz.pl	288	58	53.2 KB	25	0	00:00:29
2	lze200.lze.lodz.pl	11	39	65.0 KB	10	0	00:24:30
3	qv153.internetdsl.tpnet.pl	21	0	0.0 KB	0	3	03:58:04
TOTAL: 3		320	97	118.2 KB	35	3	04:35:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	*.man.lodz.pl	672	58	53.2 KB	25	0	00:00:33
2	*.lze.lodz.pl	11	39	65.0 KB	10	0	00:24:30
3	*.elsnet.pl	6	0	0.0 KB	0	0	00:02:27
4	*.toya.net.pl	100	0	0.0 KB	0	0	00:00:09
5	unresolved	2	0	0.0 KB	0	0	00:09:32
6	*.internetdsl.tpnet.pl	21	0	0.0 KB	0	3	03:58:04
TOTAL: 6		812	97	118.2 KB	35	3	04:35:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
qv153.internetdsl.tpnet.pl	0.348	0.277	03:58:04
lze200.lze.lodz.pl	0.042	0.134	00:24:30
185.48.195.165	0.240	0.352	00:09:32
host227.elsnet.pl	0.016	0.075	00:02:27
web.man.lodz.pl	1.006	3.701	00:00:29
staticline41435.toya.net.pl	0.348	1.186	00:00:09
mon.man.lodz.pl	0.935	3.359	00:00:03
david.man.lodz.pl	0.289	1.086	00:00:01
TOTAL: 8	3.224	10.170	04:35:18

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	100
david.man.lodz.pl	96
host227.elsnet.pl	6
185.48.195.165	2
TOTAL: 5	492

NNRP client timeouts [Top 20]:

System	Conn	Peer
qv153.internetdsl.tpnet.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	47
2	pl.comp.os.ms-windows.winnt	32
3	pl.comp.os.linux	13
4	pl.misc.telefonia.gsm	5
TOTAL: 4		97

Daily Usenet report

Jan 29 04:15:02 -- Jan 30 04:15:02