Daily Usenet report

Aug 28 04:15:02 -- Aug 29 04:15:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Aug 28 04:32:32 newsfeed overchan[15631]: input line too long, skipping
Aug 28 04:40:10 newsfeed overchan[15631]: input line too long, skipping
Aug 28 04:50:10 newsfeed overchan[15631]: input line too long, skipping
Aug 28 08:50:05 newsfeed nnrpd[26434]: SERVER perl filter not defined
Aug 28 16:01:43 newsfeed overchan[15631]: input line too long, skipping
Aug 28 16:04:26 newsfeed overchan[15631]: input line too long, skipping
Aug 28 16:25:00 newsfeed nnrpd[11124]: SERVER perl filter not defined
Aug 28 16:29:17 newsfeed overchan[15631]: input line too long, skipping
Aug 28 18:30:13 newsfeed overchan[15631]: input line too long, skipping
Aug 28 19:33:51 newsfeed overchan[15631]: input line too long, skipping
Aug 28 19:43:32 newsfeed overchan[15631]: input line too long, skipping
Aug 28 19:50:06 newsfeed overchan[15631]: input line too long, skipping
Aug 28 20:21:17 newsfeed overchan[15631]: input line too long, skipping
Aug 28 20:30:40 newsfeed overchan[15631]: input line too long, skipping
Aug 28 21:15:17 newsfeed overchan[15631]: input line too long, skipping
Aug 28 21:33:39 newsfeed overchan[15631]: input line too long, skipping
Aug 28 21:34:01 newsfeed overchan[15631]: input line too long, skipping
Aug 29 03:51:26 newsfeed overchan[15631]: input line too long, skipping
Aug 29 03:57:23 newsfeed overchan[15631]: input line too long, skipping
Aug 29 03:58:51 newsfeed overchan[15631]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2027505 96.7%234.2 MB 96.3%
innfeed 47568 2.3%5.7 MB 2.3%
innd 16874 0.8%2.7 MB 1.1%
nnrpd 4775 0.2%507.4 KB 0.2%
overchan 19 0.0%3.9 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62096742 100.0%243.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1996731 90.1%
Positive hits 137753 6.2%
Negative hits 79090 3.6%
Cache misses 1334 0.1%
TOTAL: 4 2214908 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.066 0.0%157 0.000 19.529 1358.000
article cleanup00:00:25.233 0.0%2028250 0.008 0.012 0.022
article logging00:01:27.232 0.1%2028711 0.027 0.043 0.158
article parse00:36:32.139 2.5%84724312 0.012 0.026 0.044
article write00:14:10.873 1.0%2020564 0.252 0.421 0.937
artlog/artcncl00:00:00.008 0.0%154 0.000 0.052 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:03:27.821 0.2%96921142 0.001 0.002 0.004
hisgrep/artcncl00:00:01.058 0.0%154 0.000 6.870 519.000
hishave/artcncl00:00:00.116 0.0%157 0.000 0.739 3.600
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:45:41.853 3.2%2243383 0.315 1.222 6.325
history sync00:00:00.014 0.0%290 0.000 0.048 0.500
history write01:34:03.116 6.5%2022464 0.490 2.790 13.135
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle15:38:38.358 65.2%68826076 0.469 0.818 2.505
nntp read01:05:27.754 4.5%83414937 0.033 0.047 0.073
overview write00:00:10.121 0.0%2020564 0.003 0.005 0.021
perl filter03:30:45.731 14.6%2022463 4.001 6.253 8.622
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:54.744 0.3%4250038 0.043 0.055 0.077
TOTAL: 24:00:02.37823:34:49.237 98.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.179 0.0%421810 0.000 0.003 0.007
article prepare00:00:00.082 0.0%290334 0.000 0.000 0.008
article read00:04:12.461 0.3%67692 0.288 3.730 50.887
backlog stats00:00:01.508 0.0%1727537 0.000 0.001 0.002
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:15.865 0.0%806094 0.008 0.020 0.095
data write00:04:12.384 0.3%927688 0.084 0.272 0.642
idle23:40:57.712 99.3%1727537 25.387 49.352 287.451
status file00:00:17.445 0.0%16958 0.667 1.029 4.041
TOTAL: 23:50:50.37823:49:58.636 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.846 54.2%3060 0.000 5.832 52.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.294 0.9%6833 0.000 0.043 0.667
readart00:00:00.005 0.0%76 0.000 0.066 0.500
TOTAL: 00:00:32.89900:00:18.145 55.2%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1105 20193742013765 2514 3095 99%392:12:55
2news.nask.pl 1 141194 5334 131276 4584 3%23:55:30
3news.icm.edu.pl 51 219 96 114 9 43%16:22:05
4localhost 3 2 2 0 0 100%00:00:00
TOTAL: 4 1160 21607892019197 133904 7688 93%432:30:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl589.6 GB114.1 MB224.4 MB590.0 GB 99%306.7 KB
2news.nask.pl183.6 MB718.5 MB728.7 MB1.6 GB 11%168.4 KB
3news.icm.edu.pl1.9 MB149.9 KB349.6 KB2.4 MB 79%23.0 KB
4localhost3.7 KB0.0 KB0.0 KB3.7 KB100%1.9 KB
TOTAL: 4589.8 GB832.7 MB953.4 MB591.6 GB 99%306.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 28 04:15:02 - 04:59:59 80046 4.0% 29.6723.9 GB 4.0%9280.51
Aug 28 05:00:00 - 05:59:59 101253 5.0% 28.1333.2 GB 5.6%9674.03
Aug 28 06:00:00 - 06:59:59 57087 2.8% 15.8619.9 GB 3.4%5783.40
Aug 28 07:00:00 - 07:59:59 17816 0.9% 4.958.0 GB 1.4%2334.71
Aug 28 08:00:00 - 08:59:59 19722 1.0% 5.487.7 GB 1.3%2251.46
Aug 28 09:00:00 - 09:59:59 53240 2.6% 14.7917.3 GB 2.9%5032.62
Aug 28 10:00:00 - 10:59:59 73040 3.6% 20.2921.7 GB 3.7%6321.96
Aug 28 11:00:00 - 11:59:59 125363 6.2% 34.8234.7 GB 5.9%10120.06
Aug 28 12:00:00 - 12:59:59 106150 5.3% 29.4935.0 GB 5.9%10198.16
Aug 28 13:00:00 - 13:59:59 116937 5.8% 32.4836.1 GB 6.1%10515.88
Aug 28 14:00:00 - 14:59:59 77013 3.8% 21.3923.6 GB 4.0%6874.03
Aug 28 15:00:00 - 15:59:59 56624 2.8% 15.7317.4 GB 3.0%5075.96
Aug 28 16:00:00 - 16:59:59 92502 4.6% 25.7029.4 GB 5.0%8568.63
Aug 28 17:00:00 - 17:59:59 82946 4.1% 23.0426.5 GB 4.5%7709.21
Aug 28 18:00:00 - 18:59:59 100319 5.0% 27.8729.3 GB 5.0%8520.55
Aug 28 19:00:00 - 19:59:59 81197 4.0% 22.5526.4 GB 4.5%7690.33
Aug 28 20:00:00 - 20:59:59 106281 5.3% 29.5232.6 GB 5.5%9482.15
Aug 28 21:00:00 - 21:59:59 85914 4.3% 23.8622.1 GB 3.7%6443.45
Aug 28 22:00:00 - 22:59:59 130868 6.5% 36.3529.5 GB 5.0%8585.12
Aug 28 23:00:00 - 23:59:59 148772 7.4% 41.3334.5 GB 5.8%10036.90
Aug 29 00:00:00 - 00:59:59 87841 4.4% 24.4022.8 GB 3.9%6633.43
Aug 29 01:00:00 - 01:59:59 61712 3.1% 17.1415.3 GB 2.6%4457.61
Aug 29 02:00:00 - 02:59:59 66081 3.3% 18.3617.4 GB 3.0%5073.65
Aug 29 03:00:00 - 03:59:59 70618 3.5% 19.6220.2 GB 3.4%5871.86
Aug 29 04:00:00 - 04:15:01 19901 1.0% 22.095.7 GB 1.0%6636.31
TOTAL: 23:59:59 2019243 100.0% 23.37590.1 GB 100.0%7161.39
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4658 4 0 3174 5 0 0 0 1475
2newsfeed.pionier.net.pl 3442 157 0 2587 5 0 0 0 693
3news.icm.edu.pl 8 0 0 3 0 0 0 0 5
TOTAL: 3 8108 161 0 5764 10 0 0 0 2173

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 73
it.comp.os.win.windows10 29
it.tlc.cellulari.android 29
uk.net.providers.gradwell 6
uk.rec.cycling.moderated 6
de.comp.lang.php 5
comp.mobile.ipad 4
nl.comp.os.linux.techniek 3
hr.ponuda.hardver.komponente 2
comp.sys.raspberry-pi 1
it.comp.os.linux.ubuntu 1
de.comm.software.mailreader 1
it.comp.os.win.windows7 1
TOTAL: 13 161

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

GroupsCount
pl.sci.matematyka 7
bit.listserv.catholic 2
pl.praca.oferowana 1
TOTAL: 3 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1224
Binary in non-binary group 407
EMP (phl) 34
Scoring filter (8) 14
Scoring filter (9) 10
Scoring filter (16) 10
Scoring filter (11) 3
Scoring filter (10) 3
Scoring filter (14) 1
Too many newsgroups 1
Scoring filter (18) 1
TOTAL: 11 1708

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 348
news.nask.pl 116
news.icm.edu.pl 1
TOTAL: 3 465
TOTAL: 1 465

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 290818 58973 136745 209 0 6957 20%23:59:57
2panorama 224331 1592 194336 352 13 221072 0%23:59:57
3poznan 7259 114 5079 16 0 0 1%23:58:34
4icm 260010 61 196633 11620 0 10 0%23:59:57
5okapi 0 0 0 0 0 1194 0%23:57:50
TOTAL: 5 782418 60740 532793 12197 13 229233 7%119:56:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask34.1 GB129.1 MB34.3 GB415.7 KB/s606.8 KB23:59:57
2panorama186.2 MB25.2 MB211.4 MB2.5 KB/s111.4 KB23:59:57
3icm4.4 MB6.2 GB6.2 GB75.3 KB/s557.1 KB23:59:57
4poznan394.2 KB54.8 KB449.0 KB0.0 KB/s3.5 KB23:58:34
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 534.3 GB6.4 GB40.7 GB98.8 KB/s584.6 KB119:56:15
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 7671.7 KB 36 0 000:00:27
2isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:00
TOTAL: 2 289 7671.7 KB 36 2 000:00:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 673 7671.7 KB 36 2 000:00:32
TOTAL: 1 673 7671.7 KB 36 2 000:00:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.388 4.432 0.00000:00:27
mon.man.lodz.pl 1.252 3.820 0.00000:00:03
david.man.lodz.pl 0.452 1.276 0.00000:00:01
isvcs01.man.lodz.pl 0.012 0.032 0.00000:00:00
TOTAL: 4 3.104 9.560 0.00000:00:32

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
TOTAL: 2 384

Newsgroup request counts (by category):

CategoryCountPct
1pl 76100.0%
TOTAL: 1 76100.0%

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

NewsgroupCount
1pl.comp.pecet 48
2pl.comp.os.ms-windows.winnt 28
TOTAL: 2 76