news.bbs.nz daily logs

May 5 23:58:01 -- May 6 23:58:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 6 04:10:03 news controlchan[9634]: Multiple control headers in article <vM7UZ-7xJ-9@gated-at.bofh.it>

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 135639 66.0%10.8 MB 44.3%
innd 25363 12.3%4.2 MB 17.1%
innfeed 23125 11.3%4.4 MB 18.2%
inn 19929 9.7%4.7 MB 19.3%
cnfsstat 1440 0.7%254.2 KB 1.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 205498 100.0%24.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 216065 52.2%
Positive hits 178544 43.1%
Do not exist 19142 4.6%
Cache misses 86 0.0%
TOTAL: 4 413837 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.083 0.0%163 0.000 0.509 4.333
article cleanup00:00:00.177 0.0%19327 0.000 0.009 0.034
article logging00:00:00.528 0.0%19750 0.000 0.027 0.216
article parse00:00:00.635 0.0%59562 0.000 0.011 0.042
article write00:00:01.365 0.0%16011 0.000 0.085 0.524
artlog/artcncl00:00:00.002 0.0%161 0.000 0.012 0.500
data move00:00:00.284 0.0%418198 0.000 0.001 0.003
hisgrep/artcncl00:00:00.001 0.0%144 0.000 0.007 0.500
hishave/artcncl00:00:00.000 0.0%163 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.410 0.0%415330 0.001 0.015 0.787
history sync00:00:00.012 0.0%314 0.000 0.038 0.500
history write00:00:03.500 0.0%19180 0.010 0.182 11.973
hiswrite/artcncl00:00:00.000 0.0%19 0.000 0.000 0.000
idle23:57:31.924 99.8%420345 81.083 205.193 684.107
nntp read00:00:03.528 0.0%421504 0.002 0.008 0.024
overview write00:00:39.636 0.0%16011 0.043 2.476 89.485
perl filter00:00:39.582 0.0%19158 1.029 2.066 15.759
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.000 0.0%32023 0.000 0.031 0.156
TOTAL: 24:00:25.37723:59:08.667 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.038 0.0%21935 0.000 0.002 0.024
article prepare00:00:00.000 0.0%5428 0.000 0.000 0.000
article read00:00:00.104 0.0%1166 0.000 0.089 2.000
backlog stats00:00:00.439 0.0%234127 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.010 0.0%194405 0.003 0.010 0.019
data write00:00:02.509 0.0%183227 0.006 0.014 0.021
idle23:54:46.341 99.9%234127 210.813 367.691 870.474
status file00:00:15.757 0.0%1875 0.308 8.404 399.938
TOTAL: 23:55:33.15723:55:07.198100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%15 0.000 0.000 0.000
idle30:42:46.120 98.4%187306 0.000 590.297 75326.250
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:51.492 0.0%422323 0.000 0.122 13.149
readart00:02:10.574 0.1%57470 0.000 2.272 18.500
TOTAL: 31:13:33.82830:45:48.186 98.5%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 169

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.mixmin.net 7 17294 3364 13324 606 19%24:42:47
2csiph.com 3 15517 2358 13102 57 15%24:00:29
3news.redatomik.org 1 16638 2201 14414 23 13%26:50:02
4newsfeed.datemas.de 2 16947 1916 14477 554 11%24:07:15
5feeder.eternal-september.org 17 17014 1823 14396 795 10%27:29:44
6news-out.aioe.org 2 15188 1718 13397 73 11%23:13:31
7newsfeed.neva.ru 147 4933 1413 2597 923 28%81:56:02
8news.albasani.net 1 16185 561 15489 135 3%23:35:00
9news.swapon.de 7 1282 111 1170 1 8%20:41:24
10feeder.awxg.com 2 13570 106 13453 11 0%23:59:46
11news.samoylyk.net 3 13073 88 12979 6 0%17:06:58
12agency-nz-out.news.mb-net.net 8 15089 56 14995 38 0%25:39:30
13news.freedyn.net 2 16458 34 16405 19 0%22:17:42
14localhost 10 9 9 0 0 100%00:00:00
TOTAL: 14 212 179197 15758 160198 3241 8%365:40:10
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.datemas.de17.6 MB45.1 KB1.8 MB19.5 MB 90%8.1 KB
2news-out.mixmin.net10.1 MB43.8 KB1.5 MB11.6 MB 87%3.0 KB
3news.redatomik.org7.0 MB21.0 KB81.2 KB7.1 MB 98%3.3 KB
4feeder.eternal-september.org7.0 MB128.2 KB3.8 MB10.9 MB 64%4.3 KB
5csiph.com6.9 MB37.6 KB121.4 KB7.1 MB 97%3.0 KB
6news.albasani.net5.2 MB55.5 KB317.0 KB5.6 MB 93%8.2 KB
7news-out.aioe.org4.5 MB35.0 KB314.6 KB4.9 MB 93%2.8 KB
8newsfeed.neva.ru3.6 MB66.3 KB3.0 MB6.7 MB 54%2.9 KB
9news.freedyn.net2.9 MB7.4 KB90.9 KB2.9 MB 96%57.0 KB
10news.samoylyk.net1.4 MB16.1 KB16.1 KB1.4 MB 97%15.3 KB
11news.swapon.de338.3 KB0.0 KB0.7 KB339.0 KB 99%3.0 KB
12feeder.awxg.com335.6 KB76.0 KB76.0 KB487.7 KB 68%4.2 KB
13agency-nz-out.news.mb-net.net178.0 KB39.8 KB261.2 KB479.0 KB 37%5.1 KB
14localhost25.5 KB0.0 KB0.0 KB25.5 KB100%2.8 KB
TOTAL: 1467.2 MB571.9 KB11.2 MB79.0 MB 85%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 5 23:58:01 - 23:59:59 13 0.1% 0.1127.3 KB 0.0% 0.23
May 6 00:00:00 - 00:59:59 761 4.7% 0.212.1 MB 2.4% 0.58
May 6 01:00:00 - 01:59:59 949 5.9% 0.263.4 MB 4.1% 0.97
May 6 02:00:00 - 02:59:59 713 4.4% 0.202.6 MB 3.1% 0.74
May 6 03:00:00 - 03:59:59 858 5.4% 0.243.1 MB 3.7% 0.89
May 6 04:00:00 - 04:59:59 940 5.9% 0.262.4 MB 2.9% 0.69
May 6 05:00:00 - 05:59:59 865 5.4% 0.242.1 MB 2.5% 0.60
May 6 06:00:00 - 06:59:59 801 5.0% 0.222.3 MB 2.8% 0.66
May 6 07:00:00 - 07:59:59 986 6.2% 0.274.5 MB 5.4% 1.29
May 6 08:00:00 - 08:59:59 790 4.9% 0.222.7 MB 3.3% 0.78
May 6 09:00:00 - 09:59:59 762 4.8% 0.213.7 MB 4.4% 1.05
May 6 10:00:00 - 10:59:59 695 4.3% 0.197.3 MB 8.7% 2.08
May 6 11:00:00 - 11:59:59 482 3.0% 0.131.4 MB 1.7% 0.41
May 6 12:00:00 - 12:59:59 426 2.7% 0.121.4 MB 1.7% 0.39
May 6 13:00:00 - 13:59:59 530 3.3% 0.151.7 MB 2.0% 0.47
May 6 14:00:00 - 14:59:59 337 2.1% 0.091.4 MB 1.7% 0.39
May 6 15:00:00 - 15:59:59 332 2.1% 0.091.9 MB 2.2% 0.53
May 6 16:00:00 - 16:59:59 298 1.9% 0.08888.9 KB 1.0% 0.25
May 6 17:00:00 - 17:59:59 353 2.2% 0.101.1 MB 1.3% 0.31
May 6 18:00:00 - 18:59:59 444 2.8% 0.121.4 MB 1.7% 0.41
May 6 19:00:00 - 19:59:59 711 4.4% 0.202.2 MB 2.6% 0.62
May 6 20:00:00 - 20:59:59 666 4.2% 0.182.0 MB 2.4% 0.57
May 6 21:00:00 - 21:59:59 692 4.3% 0.192.8 MB 3.4% 0.80
May 6 22:00:00 - 22:59:59 821 5.1% 0.2313.4 MB 16.0% 3.82
May 6 23:00:00 - 23:58:01 804 5.0% 0.2316.1 MB 19.1% 4.72
TOTAL: 24:00:00 16029 100.0% 0.1984.0 MB 100.0% 1.00
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB25.6 GB 51.1% 0 0.28 2203.79
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB29.5 GB 59.0% 0 0.28 2161.04
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB323.1 MB 0.6% 0 0.0415555.56
BUFF06BIN50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF07BIN50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF08CTCALL50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF09CTCALL50.0 GB1.6 MB 0.0% 1 0.00 0.00
BUFF10CTCALL50.0 GB20.8 GB 41.5% 0 0.95 640.17
TOTAL: 10-500.0 GB76.1 GB 15.2% 1 0.50-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 932 58 0 16 0 0 0 0 858
2feeder.eternal-september.org 844 0 0 20 0 0 0 0 824
3news-out.mixmin.net 704 0 0 18 0 0 0 0 686
4newsfeed.datemas.de 563 43 0 14 0 0 0 0 506
5news.redatomik.org 284 0 0 6 0 0 0 0 278
6news.albasani.net 166 8 0 18 0 0 0 0 140
7news-out.aioe.org 78 27 0 11 0 0 0 0 40
8csiph.com 56 0 0 9 0 0 0 0 47
9agency-nz-out.news.mb-net.net 44 6 0 16 0 0 0 0 22
10news.freedyn.net 27 1 0 3 0 0 0 0 23
11news.swapon.de 20 0 0 0 0 0 0 0 20
12feeder.awxg.com 10 0 0 8 0 0 0 0 2
13news.samoylyk.net 10 0 0 8 0 0 0 0 2
14news.ausics.org 1 0 0 0 0 0 0 0 1
TOTAL: 14 3739 143 0 147 0 0 0 0 3449

Unwanted newsgroups [Top 20]:

NewsgroupCount
mpc.lists.freebsd.ports.bugs 29
gmane.comp.gis.openstreetmap.tagging 20
gmane.comp.gis.openstreetmap.imports 15
mpc.lists.freebsd.gecko 10
mpc.lists.freebsd.gnome 9
mozilla.support.seamonkey 7
mpc.lists.nanog 7
gmane.linux.alsa.devel 6
gmane.linux.kernel 5
mozilla.test 4
south-wales.test 4
mozilla.dev.ui-alerts 3
fido7.ru.android 2
mpc.lists.freebsd.x11 2
mpc.lists.freebsd.wireless 2
alt.sex.cuseeme 2
mozilla.tools.marionette 2
al.christnet.bible 2
3do.bad-attitude 1
alt.penthouse.sex.femdom 1
TOTAL: 30 143

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2731
EMP (phn nph) 82
EMP (phn path) 81
Too many newsgroups 43
EMP (phl) 34
User-issued cancel 19
Binary: non-binary distribution 3
Binary Image: misplaced jpg 3
Binary: misplaced par2 3
User-issued spam cancel 3
Too many newsgroups (meow) 2
TOTAL: 11 3004

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
news.albasani.net 22
TOTAL: 1 22
Including strange strings
news.redatomik.org 262
news-out.mixmin.net 89
news.albasani.net 21
news.swapon.de 20
newsfeed.datemas.de 14
news-out.aioe.org 8
feeder.eternal-september.org 2
feeder.awxg.com 2
agency-nz-out.news.mb-net.net 2
newsfeed.neva.ru 2
TOTAL: 11 423
TOTAL: 2 445

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 14272 476 13777 18 1 33 3%23:59:28
2awxg 15283 52 15230 0 0 84 0%23:59:38
3news.freedyn.net 16086 34 15893 2 0 154 0%23:59:33
4csiph.com 14004 29 13612 5 0 95 0%23:59:27
5news.albasani.net 16299 23 14447 567 1 4732 0%23:59:38
6news.redatomik.org 13791 20 13723 0 0 125 0%23:59:38
7news.samoylyk.net 14252 15 14145 1 0 371 0%23:59:38
8aioe 12901 9 12839 0 0 18 0%23:59:37
9eternal-september 13752 9 13723 0 0 0 0%23:59:36
10news-in.mixmin.net 12133 9 12079 0 0 47 0%23:59:38
11mb-net.net 15870 5 15763 1 0 255 0%23:59:38
12neva.ru 14167 4 14107 4 0 69 0%23:59:38
13newsfeed.datemas.de 14129 4 14048 4 0 169 0%23:59:38
14news.snarked.org 0 0 0 0 0 15949 0%23:59:40
15news.swapon.de 1295 0 1295 0 0 0 0%23:37:44
TOTAL: 15 188234 689 184681 602 2 22101 0%359:32:09
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org4.5 MB1.9 MB6.4 MB0.1 KB/s13.2 KB23:59:28
2awxg151.3 KB0.0 KB151.3 KB0.0 KB/s2.9 KB23:59:38
3news.freedyn.net111.0 KB4.6 KB115.6 KB0.0 KB/s3.2 KB23:59:33
4csiph.com68.0 KB22.2 KB90.2 KB0.0 KB/s2.7 KB23:59:27
5news.albasani.net60.9 KB4.0 MB4.0 MB0.0 KB/s7.0 KB23:59:38
6news.redatomik.org54.2 KB0.0 KB54.2 KB0.0 KB/s2.7 KB23:59:38
7news.samoylyk.net41.3 KB2.0 KB43.3 KB0.0 KB/s2.7 KB23:59:38
8aioe25.5 KB0.0 KB25.5 KB0.0 KB/s2.8 KB23:59:37
9eternal-september25.5 KB0.0 KB25.5 KB0.0 KB/s2.8 KB23:59:36
10news-in.mixmin.net25.5 KB0.0 KB25.5 KB0.0 KB/s2.8 KB23:59:38
11neva.ru9.4 KB14.8 KB24.2 KB0.0 KB/s3.0 KB23:59:38
12mb-net.net5.4 KB7.5 KB12.9 KB0.0 KB/s2.2 KB23:59:38
13newsfeed.datemas.de4.0 KB16.2 KB20.2 KB0.0 KB/s2.5 KB23:59:38
14news.snarked.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:40
15news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:37:44
TOTAL: 155.1 MB5.9 MB11.0 MB0.0 KB/s8.7 KB359:32:09
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
10890788407.static.corbina.ru 9 1336349.6 MB 73 0 007:59:29
2agency.bbs.nz 385 504820.0 MB 2991 3 000:13:05
3184-155-113-241.cpe.cableone.net 8 265110.6 MB 508 2 1600:44:09
4vorlon.activateit.net.au 24 9181.2 MB 122 0 000:04:39
5192.168.1.9 6 3761.6 MB 18 2 000:00:22
660.234.68.122 9 2672.8 KB 1 2 000:30:53
TOTAL: 6 441 2238283.0 MB 3713 9 1631:13:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.static.corbina.ru 9 1336349.6 MB 73 0 007:59:29
2*.bbs.nz 385 504820.0 MB 2991 3 000:13:05
3*.cpe.cableone.net 11 265110.6 MB 508 2 1621:58:25
4*.activateit.net.au 24 9181.2 MB 122 0 000:04:39
5unresolved 26 4021.7 MB 19 4 000:57:11
6*.hsd1.or.comcast.net 24 00.0 KB 0 0 000:00:33
TOTAL: 6 479 2238283.0 MB 3713 9 1631:13:26

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 8
crash 7
paulh 6
malcolmlewis 3
TOTAL: 5 48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.260 0.128 0.03821:14:15
0890788407.static.corbina.ru 3.884 8.928 0.00007:59:29
184-155-113-241.cpe.cableone.net 1.304 0.708 0.01800:44:09
60.234.68.122 0.656 0.076 0.01900:30:53
118.149.149.131 0.356 0.016 0.00000:19:37
agency.bbs.nz 29.136 6.736 0.00000:13:05
118.149.153.27 0.144 0.008 0.00000:05:55
vorlon.activateit.net.au 1.568 0.388 0.17900:04:39
c-73-11-56-74.hsd1.or.comcast.net 1.304 0.196 0.00000:00:33
192.168.1.9 0.584 0.204 0.01600:00:22
78.133.49.32 0.132 0.240 0.00000:00:12
107.170.234.88 0.060 0.000 0.00000:00:09
? 1.360 0.116 0.00000:00:01
125.64.94.208 0.056 0.008 0.00000:00:00
TOTAL: 14 40.804 17.752 0.27031:13:26

Curious NNRP server explorers [Top 20]:

SystemConn
c-73-11-56-74.hsd1.or.comcast.net 24
118.149.149.131 5
67-60-219-146.cpe.cableone.net 3
78.133.49.32 2
118.149.153.27 2
107.170.234.88 1
125.64.94.208 1
TOTAL: 7 38

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 17
TOTAL: 1 17

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 13754 61.5%
2rec 2116 9.5%
3comp 1320 5.9%
4uk 1122 5.0%
5soc 1112 5.0%
6linux 1097 4.9%
7sci 811 3.6%
8misc 385 1.7%
9news 187 0.8%
10nz 169 0.8%
11talk 134 0.6%
12mozilla 80 0.4%
13microsoft 38 0.2%
14aioe 18 0.1%
15agency 11 0.0%
16humanities 8 0.0%
17aus 3 0.0%
18us 2 0.0%
TOTAL: 18 22367100.0%

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

NewsgroupCount
1alt.alien.vampire.flonk.flonk.flonk 8804
2alt.alan.c.sanderson.looks.like.a.dork 1035
3alt.agnosticism 961
4alt.airports.uk.edinburgh 930
5rec.arts.tv 732
6uk.d-i-y 547
7linux.debian.changes.devel 424
8rec.arts.drwho 358
9soc.retirement 335
10linux.debian.bugs.dist 315
11uk.legal 309
12comp.lang.python 234
13alt.aeffle.und.pferdle 226
14alt.comp.os.windows-10 219
15sci.physics 195
16comp.os.linux.advocacy 178
17alt.alien.visitors 177
18alt.alien.research 173
19comp.sys.raspberry-pi 172
20soc.culture.polish 155
21alt.activism.student 153
22nz.general 147
23alt.algebra.help 141
24sci.electronics.design 141
25misc.survivalism 134
26news.lists.filters 128
27comp.lang.c 124
28sci.med.cardiology 118
29rec.food.cooking 116
30soc.penpals 106
31sci.logic 94
32linux.debian.bugs.rc 91
33misc.health.alternative 90
34alt.activism.death-penalty 89
35alt.adoption.searching 89
36sci.physics.relativity 87
37soc.culture.israel 84
38rec.gambling.poker 80
39comp.ai.philosophy 80
40alt.alien 75
41uk.radio.amateur 74
42alt.ads 74
43uk.tech.digital-tv 70
44soc.culture.china 67
45rec.sport.pro-wrestling 67
46rec.boats 66
47rec.sport.tennis 64
48rec.arts.sf.written 64
49comp.lang.forth 62
50comp.lang.ada 62
51talk.politics.guns 59
52linux.debian.user 57
53comp.sys.mac.system 54
54rec.outdoors.rv-travel 53
55misc.phone.mobile.iphone 53
56alt.airports.us.atlanta 51
57alt.ads.forsale 49
58comp.os.vms 48
59alt.airports 46
60alt.conspiracy 46
61linux.debian.kernel 46
62talk.origins 46
63rec.drugs.cannabis 45
64alt.adoption 45
65misc.education 44
66soc.culture.indian 43
67mozilla.general 42
68uk.comp.sys.mac 38
69soc.history.medieval 38
70misc.invest.stocks 37
71alt.politics.trump 36
72alt.conspiracy.jfk 35
73mozilla.support.thunderbird 34
74alt.test 33
75uk.rec.cycling 30
76sci.astro.amateur 28
77rec.aviation.soaring 27
78rec.sport.football.college 27
79soc.culture.british 26
80rec.woodworking 25
81rec.arts.sf.tv 25
82sci.lang 25
83rec.arts.movies.current-films 24
84alt.agriculture 24
85comp.lang.tcl 24
86soc.culture.greek 24
87rec.music.dylan 24
88comp.sys.mac.apps 23
89soc.culture.usa 23
90rec.arts.sf.fandom 23
91alt.adult.stories 23
92uk.tech.broadcast 22
93microsoft.public.windowsxp.general 21
94linux.debian.maint.perl 20
95news.answers 20
96rec.music.classical.recordings 20
97linux.debian.devel 19
98soc.women 18
99sci.anthropology.paleo 18
100talk.politics.misc 18
TOTAL: 378 22367