Daily Usenet report: Nov 22 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Nov 22 15:40:07 newsfeed nnrpd[11939]: message repeated 3 times: [ staticline-31-182-92-99.toya.net.pl group lodz.test 0]
Nov 22 18:03:25 newsfeed nnrpd[17107]: message repeated 2 times: [ 77-255-223-114.adsl.inetia.pl group lodzman.test 0]
Nov 22 18:11:10 newsfeed nnrpd[17522]: message repeated 2 times: [ 77-255-223-114.adsl.inetia.pl group lodz.test 0]
Nov 22 21:15:12 newsfeed nnrpd[24012]: message repeated 2 times: [ staticline-31-182-85-238.toya.net.pl group lodz.test 0]
Nov 22 23:04:24 newsfeed nnrpd[27915]: message repeated 2 times: [ staticline-31-182-75-216.toya.net.pl group lodz.test 0]
Nov 23 04:15:02 newsfeed nnrpd[6368]: web.man.lodz.pl rejected Flushing log and syslog files
Nov 23 04:15:02 newsfeed nnrpd[6370]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2614729	97.3%	300.2 MB	96.8%
innfeed	45875	1.7%	5.5 MB	1.8%
innd	21067	0.8%	3.7 MB	1.2%
nnrpd	5949	0.2%	660.2 KB	0.2%
TOTAL: 4	2687620	100.0%	310.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2600219	92.9%
Negative hits	165734	5.9%
Positive hits	33974	1.2%
Cache misses	141	0.0%
TOTAL: 4	2800068	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.811	0.0%	170	0.000	16.535	720.000
article cleanup	00:00:44.232	0.1%	2614285	0.005	0.017	0.030
article logging	00:01:10.375	0.1%	2614782	0.012	0.027	0.156
article parse	01:19:08.637	5.5%	260614601	0.006	0.018	0.085
article write	00:46:53.693	3.3%	2601325	0.325	1.082	6.424
artlog/artcncl	00:00:00.001	0.0%	169	0.000	0.006	0.056
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:05:45.693	0.4%	277486419	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.205	0.0%	160	0.000	1.281	14.000
hishave/artcncl	00:00:00.095	0.0%	170	0.000	0.559	10.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:00.498	1.9%	2801855	0.049	0.578	16.276
history sync	00:00:00.012	0.0%	294	0.000	0.041	1.000
history write	00:48:32.693	3.4%	2601901	0.215	1.119	36.489
hiswrite/artcncl	00:00:00.010	0.0%	10	0.000	1.000	5.000
idle	12:30:49.788	52.1%	180129213	0.087	0.250	1.589
nntp read	01:34:34.280	6.6%	258575292	0.008	0.022	0.045
overview write	00:00:14.650	0.0%	2601325	0.001	0.006	0.016
perl filter	05:27:17.726	22.7%	2601901	4.216	7.547	9.580
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:53.592	0.3%	5376534	0.032	0.055	0.104
TOTAL: 24:00:16.002	23:07:08.991	96.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.730	0.0%	280524	0.000	0.003	0.038
article prepare	00:00:00.012	0.0%	30092	0.000	0.000	0.062
article read	00:01:12.979	0.1%	7515	0.000	9.711	931.000
backlog stats	00:00:00.308	0.0%	409979	0.000	0.001	0.013
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:03.000	0.0%	281722	0.000	0.011	0.088
data write	00:00:06.148	0.0%	110676	0.000	0.056	1.357
idle	23:58:23.457	99.9%	409979	48.016	210.507	884.810
status file	00:00:12.068	0.0%	16048	0.167	0.752	2.750
TOTAL: 24:00:13.156	23:59:58.702	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	11:37:11.473	99.9%	8318	0.000	5029.030	900081.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.657	0.0%	18650	0.000	0.089	7.265
readart	00:00:03.319	0.0%	280	0.000	11.854	105.429
TOTAL: 11:37:40.480	11:37:16.449	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	1099	2621130	2597476	11300	12354	99%	326:25:27
2	news.nask.pl	2	14033	3310	10174	549	23%	24:14:56
3	news.icm.edu.pl	45	246	31	156	59	12%	16:37:48
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1147	2635409	2600817	21630	12962	98%	367:18:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	769.8 GB	2.1 GB	2.1 GB	774.0 GB	99%	311.0 KB
2	news.nask.pl	11.0 MB	1.4 MB	1.4 MB	13.8 MB	79%	3.7 KB
3	news.icm.edu.pl	577.4 KB	42.8 KB	993.5 KB	1.6 MB	35%	17.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		769.8 GB	2.1 GB	2.1 GB	774.1 GB	99%	310.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 22 04:15:02 - 04:59:59	66408	2.6%	24.61	18.9 GB	2.5%	7347.05
Nov 22 05:00:00 - 05:59:59	97924	3.8%	27.20	28.5 GB	3.7%	8293.37
Nov 22 06:00:00 - 06:59:59	105577	4.1%	29.33	30.3 GB	3.9%	8813.49
Nov 22 07:00:00 - 07:59:59	109555	4.2%	30.43	33.1 GB	4.3%	9631.53
Nov 22 08:00:00 - 08:59:59	108678	4.2%	30.19	34.0 GB	4.4%	9906.75
Nov 22 09:00:00 - 09:59:59	112587	4.3%	31.27	34.9 GB	4.5%	10163.20
Nov 22 10:00:00 - 10:59:59	117881	4.5%	32.74	35.1 GB	4.6%	10220.18
Nov 22 11:00:00 - 11:59:59	111025	4.3%	30.84	31.3 GB	4.1%	9126.94
Nov 22 12:00:00 - 12:59:59	97359	3.7%	27.04	29.8 GB	3.9%	8678.36
Nov 22 13:00:00 - 13:59:59	93631	3.6%	26.01	30.1 GB	3.9%	8769.02
Nov 22 14:00:00 - 14:59:59	112927	4.3%	31.37	32.8 GB	4.3%	9540.11
Nov 22 15:00:00 - 15:59:59	103441	4.0%	28.73	30.6 GB	4.0%	8907.49
Nov 22 16:00:00 - 16:59:59	100463	3.9%	27.91	30.2 GB	3.9%	8800.62
Nov 22 17:00:00 - 17:59:59	107063	4.1%	29.74	32.1 GB	4.2%	9357.06
Nov 22 18:00:00 - 18:59:59	121919	4.7%	33.87	34.6 GB	4.5%	10087.47
Nov 22 19:00:00 - 19:59:59	121004	4.7%	33.61	35.2 GB	4.6%	10262.26
Nov 22 20:00:00 - 20:59:59	107674	4.1%	29.91	31.8 GB	4.1%	9257.47
Nov 22 21:00:00 - 21:59:59	124332	4.8%	34.54	34.3 GB	4.5%	9996.33
Nov 22 22:00:00 - 22:59:59	119134	4.6%	33.09	32.7 GB	4.2%	9518.20
Nov 22 23:00:00 - 23:59:59	115922	4.5%	32.20	34.1 GB	4.4%	9931.05
Nov 23 00:00:00 - 00:59:59	93830	3.6%	26.06	28.2 GB	3.7%	8210.30
Nov 23 01:00:00 - 01:59:59	118335	4.5%	32.87	35.6 GB	4.6%	10383.15
Nov 23 02:00:00 - 02:59:59	112105	4.3%	31.14	34.6 GB	4.5%	10072.29
Nov 23 03:00:00 - 03:59:59	97246	3.7%	27.01	28.9 GB	3.8%	8426.71
Nov 23 04:00:00 - 04:15:02	25084	1.0%	27.81	7.4 GB	1.0%	8549.42
TOTAL: 24:00:00	2601104	100.0%	30.11	769.0 GB	100.0%	9333.40

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	12772	225	11852	2	693
2	news.nask.pl	624	15	527	2	80
3	news.icm.edu.pl	60	0	4	1	55
TOTAL: 3		13456	240	12383	5	828

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	98
uk.railway	72
comp.mobile.ipad	16
comp.sys.raspberry-pi	16
it.tlc.cellulari.android	15
uk.rec.cycling.moderated	11
comp.mobile.android	5
hr.alt.cellular.gsm.android	3
nl.comp.sys.android	2
nl.comp.os.linux.techniek	1
rec.arts.tv.x-factor	1
TOTAL: 11	240

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

Groups	Count
pl.sci.historia	3
pl.sci.matematyka	2
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	214
Binary in non-binary group	65
EMP (md5)	40
Too many newsgroups	11
HTML post	1
TOTAL: 5	331

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	123668	2127	93891	1012	14	183486	1%	24:00:15
2	icm	39441	440	22761	3120	0	13	1%	23:58:46
3	poznan	4731	62	3153	16	0	0	1%	23:54:32
4	nask	0	0	0	0	0	13776	0%	24:00:00
5	okapi	0	0	0	0	0	1777	0%	24:00:00
TOTAL: 5		167840	2629	119805	4148	14	199052	1%	119:53:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	22.3 MB	757.5 MB	779.7 MB	9.2 KB/s	224.3 KB	23:58:46
2	panorama	11.7 MB	7.4 MB	19.1 MB	0.2 KB/s	6.2 KB	24:00:15
3	poznan	134.2 KB	39.8 KB	173.9 KB	0.0 KB/s	2.2 KB	23:54:32
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		34.1 MB	764.9 MB	799.0 MB	1.9 KB/s	120.7 KB	119:53:33

Backlog files shrunk by innfeed:

Server	Size
panorama	7.2 MB
TOTAL: 1	7.2 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	apn-37-7-104-191.dynamic.gprs.plus.pl	8	72	62.4 KB	2	1	00:25:20
2	web.man.lodz.pl	289	60	59.2 KB	26	0	00:00:31
3	staticline-31-182-85-238.toya.net.pl	35	49	46.0 KB	5	9	01:16:37
4	user-94-254-129-78.play-internet.pl	8	19	15.9 KB	3	3	00:46:14
5	staticline-31-183-143-187.toya.net.pl	20	19	15.2 KB	6	5	00:27:06
6	staticline-31-182-75-216.toya.net.pl	12	16	15.7 KB	3	2	00:28:57
7	staticline-31-183-134-212.toya.net.pl	18	10	8.4 KB	3	11	00:54:31
8	178-37-243-175.adsl.inetia.pl	13	10	11.9 KB	3	4	01:55:15
9	77-255-223-114.adsl.inetia.pl	6	6	5.7 KB	3	1	00:13:15
10	31.41.177.181	5	6	5.3 KB	1	1	00:11:57
11	159-205-131-198.adsl.inetia.pl	4	5	4.2 KB	1	1	00:40:58
12	staticline-31-182-92-99.toya.net.pl	3	5	4.2 KB	1	1	00:16:46
13	91.189.0.198	9	3	2.3 KB	2	7	00:07:46
14	159-205-231-234.adsl.inetia.pl	7	0	0.0 KB	0	5	00:42:27
15	dynamic-78-8-91-129.ssp.dialog.net.pl	1	0	0.0 KB	0	1	00:00:13
16	staticline-31-182-66-83.toya.net.pl	3	0	0.0 KB	0	2	00:33:24
17	staticline-31-183-65-154.toya.net.pl	5	0	0.0 KB	0	4	00:09:05
TOTAL: 17		446	280	256.4 KB	59	58	11:37:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	*.toya.net.pl	118	99	89.6 KB	18	34	04:06:30
2	*.dynamic.gprs.plus.pl	11	72	62.4 KB	2	1	00:57:19
3	*.man.lodz.pl	673	60	59.2 KB	26	0	00:00:35
4	*.adsl.inetia.pl	30	21	21.7 KB	7	11	03:31:57
5	*.play-internet.pl	9	19	15.9 KB	3	3	00:47:36
6	unresolved	21	9	7.7 KB	3	8	02:13:07
7	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:20
8	*.ssp.dialog.net.pl	1	0	0.0 KB	0	1	00:00:13
TOTAL: 8		867	280	256.4 KB	59	58	11:37:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
178-37-243-175.adsl.inetia.pl	0.124	0.173	01:55:15
89.38.41.173	0.298	0.390	01:45:17
staticline-31-182-85-238.toya.net.pl	0.140	0.368	01:16:37
staticline-31-183-134-212.toya.net.pl	0.160	0.219	00:54:31
user-94-254-129-78.play-internet.pl	0.726	0.161	00:46:14
159-205-231-234.adsl.inetia.pl	0.042	0.070	00:42:27
159-205-131-198.adsl.inetia.pl	0.029	0.038	00:40:58
staticline-31-182-66-83.toya.net.pl	0.039	0.036	00:33:24
apn-77-112-77-80.dynamic.gprs.plus.pl	0.038	0.030	00:31:20
staticline-31-182-75-216.toya.net.pl	0.151	0.149	00:28:57
staticline-31-183-143-187.toya.net.pl	0.101	0.223	00:27:06
apn-37-7-104-191.dynamic.gprs.plus.pl	0.044	0.116	00:25:20
staticline-31-182-92-99.toya.net.pl	0.012	0.050	00:16:46
77-255-223-114.adsl.inetia.pl	0.116	0.081	00:13:15
31.41.177.181	0.025	0.049	00:11:57
staticline-31-183-65-154.toya.net.pl	0.039	0.073	00:09:05
91.189.0.198	0.054	0.091	00:07:46
78.10.55.207	0.058	0.008	00:05:24
193.106.100.10	0.000	0.011	00:02:40
user-46-112-51-101.play-internet.pl	0.062	0.012	00:01:22
TOTAL: 28	4.626	10.409	11:37:40

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	22
89.38.41.173	5
host227.elsnet.pl	4
apn-77-112-77-80.dynamic.gprs.plus.pl	2
193.106.100.10	1
user-46-112-51-101.play-internet.pl	1
78.10.55.207	1
apn-46-169-92-104.dynamic.gprs.plus.pl	1
TOTAL: 10	421

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	3
TOTAL: 1	3

NNRP unrecognized commands (by host):

System	Conn
staticline-31-182-85-238.toya.net.pl	6
TOTAL: 1	6

NNRP unrecognized commands (by command):

Command	Count
#001	2
#026#003#001	2
À#011À#023À#024	2
TOTAL: 3	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.41.173	2	0
159-205-231-234.adsl.inetia.pl	1	0
staticline-31-182-85-238.toya.net.pl	1	2
user-94-254-129-78.play-internet.pl	1	0
178-37-243-175.adsl.inetia.pl	1	0
apn-77-112-77-80.dynamic.gprs.plus.pl	1	0
77-255-223-114.adsl.inetia.pl	1	1
staticline-31-182-66-83.toya.net.pl	1	0
staticline-31-183-134-212.toya.net.pl	1	0
159-205-131-198.adsl.inetia.pl	1	0
TOTAL: 10	11	3

Newsgroup request counts (by category):

Category		Count	Pct
1	lodz	207	73.9%
2	pl	60	21.4%
3	lodzman	10	3.6%
4	lodman	3	1.1%
TOTAL: 4		280	100.0%

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

Newsgroup		Count
1	lodz.test	207
2	pl.comp.os.ms-windows.winnt	30
3	pl.comp.pecet	28
4	lodzman.test	10
5	lodman.test	2
6	pl.internet.polip	2
7	lodman.info	1
TOTAL: 7		280

Daily Usenet report

Nov 22 04:15:02 -- Nov 23 04:15:02