Daily Usenet report

May 1 04:15:04 -- May 2 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

May 1 05:22:45 newsfeed overchan[22014]: input line too long, skipping
May 1 05:23:43 newsfeed overchan[22014]: input line too long, skipping
May 1 11:32:25 newsfeed overchan[22014]: input line too long, skipping
May 1 16:53:02 newsfeed overchan[22014]: input line too long, skipping
May 1 16:54:30 newsfeed overchan[22014]: input line too long, skipping
May 1 16:59:04 newsfeed overchan[22014]: input line too long, skipping
May 1 21:12:30 newsfeed overchan[22014]: input line too long, skipping
May 1 22:00:03 newsfeed pgpverify[31671]: pgpverify: verification failed
May 2 00:40:15 newsfeed overchan[22014]: input line too long, skipping
May 2 00:43:52 newsfeed overchan[22014]: input line too long, skipping
May 2 00:51:42 newsfeed overchan[22014]: input line too long, skipping
May 2 00:53:15 newsfeed overchan[22014]: input line too long, skipping
May 2 00:54:28 newsfeed overchan[22014]: input line too long, skipping
May 2 01:06:38 newsfeed overchan[22014]: input line too long, skipping
May 2 01:07:31 newsfeed overchan[22014]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2649443 97.4%301.7 MB 97.0%
innfeed 45865 1.7%5.5 MB 1.8%
innd 19609 0.7%3.2 MB 1.0%
nnrpd 4814 0.2%512.4 KB 0.2%
overchan 15 0.0%2.7 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72719751 100.0%310.9 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2636313 96.6%
Negative hits 82587 3.0%
Positive hits 10767 0.4%
Cache misses 116 0.0%
TOTAL: 4 2729783 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.312 0.0%176 0.000 18.818 350.500
article cleanup00:00:38.120 0.0%2646062 0.010 0.014 0.018
article logging00:02:17.669 0.2%2646715 0.035 0.052 0.189
article parse00:55:17.582 3.8%81635267 0.022 0.041 0.062
article write00:22:33.745 1.6%2640255 0.278 0.513 0.812
artlog/artcncl00:00:00.005 0.0%175 0.000 0.029 0.333
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:27.726 0.2%96379274 0.001 0.002 0.003
hisgrep/artcncl00:00:01.048 0.0%169 0.000 6.201 72.250
hishave/artcncl00:00:00.511 0.0%176 0.000 2.903 52.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:46:23.435 3.2%2735969 0.043 1.017 5.265
history sync00:00:00.016 0.0%290 0.000 0.055 1.000
history write02:35:11.871 10.8%2642136 0.373 3.524 9.164
hiswrite/artcncl00:00:00.015 0.0%7 0.000 2.143 15.000
idle12:29:40.450 52.1%59173881 0.481 0.760 1.989
nntp read01:02:02.151 4.3%79626063 0.030 0.047 0.072
overview write00:00:15.465 0.0%2640255 0.003 0.006 0.008
perl filter05:09:43.858 21.5%2642133 4.323 7.034 8.803
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:05.330 0.4%5299231 0.049 0.069 0.105
TOTAL: 24:00:05.15923:32:42.309 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.184 0.0%61871 0.000 0.003 0.015
article prepare00:00:00.003 0.0%10720 0.000 0.000 0.014
article read00:01:09.311 0.1%2671 0.000 25.949 337.750
backlog stats00:00:00.144 0.0%162893 0.000 0.001 0.004
callbacks00:00:00.000 0.0%6 0.000 0.000 0.000
data read00:00:01.664 0.0%85665 0.005 0.019 0.047
data write00:00:04.054 0.0%67106 0.024 0.060 0.112
idle23:52:37.005 99.9%162893 339.660 527.690 925.664
status file00:00:15.359 0.0%15953 0.633 0.963 1.758
TOTAL: 23:54:27.11023:54:07.724100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:42:45.568 94.3%25126 0.000 102.108 9672.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.417 0.1%72929 0.000 0.033 0.789
readart00:02:16.988 5.0%22070 0.000 6.207 8.630
TOTAL: 00:45:21.71900:45:04.973 99.4%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1035 26420332633145 5073 3815 99%443:01:21
2news.nask.pl 2 12507 8956 1554 1997 71%24:06:07
3news.icm.edu.pl 45 202 21 179 2 10%14:37:56
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 1083 26547422642122 6806 5814 99%481:45:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl750.9 GB18.5 MB31.8 MB750.9 GB 99%298.6 KB
2news.nask.pl31.8 MB1.2 MB17.3 MB50.2 MB 63%4.7 KB
3news.icm.edu.pl541.5 KB0.0 KB18.2 KB559.8 KB 96%24.3 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4750.9 GB19.7 MB49.0 MB751.0 GB 99%297.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 1 04:15:04 - 04:59:59 63161 2.4% 23.4320.4 GB 2.7%7927.67
May 1 05:00:00 - 05:59:59 85137 3.2% 23.6528.4 GB 3.8%8282.00
May 1 06:00:00 - 06:59:59 82176 3.1% 22.8328.4 GB 3.8%8266.13
May 1 07:00:00 - 07:59:59 93489 3.5% 25.9733.8 GB 4.5%9848.41
May 1 08:00:00 - 08:59:59 104895 4.0% 29.1437.3 GB 5.0%10876.22
May 1 09:00:00 - 09:59:59 100859 3.8% 28.0235.9 GB 4.8%10448.94
May 1 10:00:00 - 10:59:59 96467 3.7% 26.8025.2 GB 3.4%7349.53
May 1 11:00:00 - 11:59:59 87325 3.3% 24.2629.0 GB 3.9%8434.34
May 1 12:00:00 - 12:59:59 85476 3.2% 23.7428.1 GB 3.7%8192.09
May 1 13:00:00 - 13:59:59 112734 4.3% 31.3230.5 GB 4.1%8895.01
May 1 14:00:00 - 14:59:59 95300 3.6% 26.4730.3 GB 4.0%8834.91
May 1 15:00:00 - 15:59:59 78303 3.0% 21.7527.7 GB 3.7%8079.75
May 1 16:00:00 - 16:59:59 119971 4.5% 33.3337.3 GB 5.0%10856.67
May 1 17:00:00 - 17:59:59 92717 3.5% 25.7531.0 GB 4.1%9025.28
May 1 18:00:00 - 18:59:59 184665 7.0% 51.3037.4 GB 5.0%10882.19
May 1 19:00:00 - 19:59:59 140387 5.3% 39.0036.3 GB 4.8%10577.32
May 1 20:00:00 - 20:59:59 178467 6.8% 49.5737.4 GB 5.0%10879.34
May 1 21:00:00 - 21:59:59 120829 4.6% 33.5630.8 GB 4.1%8964.16
May 1 22:00:00 - 22:59:59 127155 4.8% 35.3234.3 GB 4.6%9994.75
May 1 23:00:00 - 23:59:59 180997 6.8% 50.2836.4 GB 4.8%10597.13
May 2 00:00:00 - 00:59:59 70568 2.7% 19.6020.3 GB 2.7%5914.18
May 2 01:00:00 - 01:59:59 64380 2.4% 17.8820.3 GB 2.7%5904.26
May 2 02:00:00 - 02:59:59 153447 5.8% 42.6235.8 GB 4.8%10419.05
May 2 03:00:00 - 03:59:59 97002 3.7% 26.9531.4 GB 4.2%9154.01
May 2 04:00:00 - 04:15:02 26886 1.0% 29.817.3 GB 1.0%8472.06
TOTAL: 23:59:58 2642793 100.0% 30.59751.0 GB 100.0%9114.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4203 251 0 3467 0 0 0 0 485
2news.nask.pl 2270 35 0 480 1 0 0 0 1754
3news.icm.edu.pl 2 0 0 0 0 0 0 0 2
TOTAL: 3 6475 286 0 3947 1 0 0 0 2241

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 146
it.tlc.cellulari.android 51
comp.sys.raspberry-pi 33
it.comp.os.win.windows10 31
comp.mobile.android 10
uk.rec.cycling.moderated 7
de.comm.software.mailreader 3
it.comp.os.win.windows8 2
it.politica.m5s 2
it.comp.os.linux.ubuntu 1
TOTAL: 10 286

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1295
Binary in non-binary group 205
Scoring filter (13) 18
EMP (phl) 12
Scoring filter (18) 11
Scoring filter (8) 10
Scoring filter (9) 10
HTML post 6
Scoring filter (10) 5
Scoring filter (16) 5
Too many newsgroups 3
EMP (fsl) 2
Scoring filter (21) 1
Scoring filter (11) 1
Scoring filter (12) 1
TOTAL: 15 1585

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 2
TOTAL: 1 2
Including strange strings
newsfeed.pionier.net.pl 369
news.nask.pl 284
TOTAL: 2 653
TOTAL: 2 655

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 47813 1183 19739 305 19 40807 2%23:59:55
2poznan 14477 353 8557 25 1 0 2%23:59:54
3icm 22468 46 16431 13 0 0 0%23:58:40
4nask 3640 1 2599 3 0 0 0%24:06:53
5okapi 0 0 0 0 0 1056 0%23:57:47
TOTAL: 5 88398 1583 47326 346 20 41863 1%120:03:09
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama3.5 MB1000.9 KB4.4 MB0.1 KB/s3.1 KB23:59:55
2icm3.4 MB1.9 MB5.3 MB0.1 KB/s92.5 KB23:58:40
3poznan1.1 MB122.5 KB1.2 MB0.0 KB/s3.3 KB23:59:54
4nask1.9 KB4.3 KB6.2 KB0.0 KB/s1.6 KB24:06:53
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 58.0 MB3.0 MB11.0 MB0.0 KB/s5.8 KB120:03:09
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
10890788407.static.corbina.ru 6 1102717.5 MB 3 0 000:44:36
2web.man.lodz.pl 288 1614.4 KB 8 0 000:00:26
TOTAL: 2 294 1104317.5 MB 11 0 000:45:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.static.corbina.ru 6 1102717.5 MB 3 0 000:44:36
2*.man.lodz.pl 672 1614.4 KB 8 0 000:00:31
3*.shodan.io 1 00.0 KB 0 0 000:00:00
4*.dsl.bell.ca 1 00.0 KB 0 0 000:00:02
5unresolved 2 00.0 KB 0 0 000:00:09
TOTAL: 5 682 1104317.5 MB 11 0 000:45:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
0890788407.static.corbina.ru 2.184 5.288 0.00000:44:36
web.man.lodz.pl 1.864 5.008 0.00000:00:26
107.170.238.28 0.000 0.020 0.00000:00:09
mon.man.lodz.pl 1.568 4.964 0.00000:00:03
mtrlpq2314w-lp140-05-70-55-56-30.dsl.bell.ca 0.036 0.024 0.00000:00:02
david.man.lodz.pl 0.552 1.684 0.00000:00:01
census11.shodan.io 0.008 0.012 0.00000:00:00
TOTAL: 7 6.212 17.000 0.00000:45:20

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
census11.shodan.io 1
107.170.238.28 1
mtrlpq2314w-lp140-05-70-55-56-30.dsl.bell.ca 1
89.38.40.54 1
TOTAL: 6 388

Newsgroup request counts (by category):

CategoryCountPct
1lodman 11027 99.9%
2pl 16 0.1%
TOTAL: 2 11043100.0%

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

NewsgroupCount
1lodman.info 11027
2pl.comp.pecet 16
TOTAL: 2 11043