news.bbs.nz daily logs

Sep 6 23:58:01 -- Sep 7 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 124146 60.0%9.6 MB 37.3%
innd 34008 16.4%5.4 MB 20.9%
innfeed 24681 11.9%5.0 MB 19.2%
inn 22707 11.0%5.6 MB 21.7%
cnfsstat 1440 0.7%247.8 KB 0.9%
TOTAL: 5 206982 100.0%25.8 MB100.0%

History cache:

ReasonCount%Count
Negative hits 291944 48.9%
Positive hits 283161 47.4%
Do not exist 22118 3.7%
Cache misses 255 0.0%
TOTAL: 4 597478 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.258 0.0%82 0.000 3.146 31.000
article cleanup00:00:00.214 0.0%22309 0.000 0.010 0.040
article logging00:00:00.566 0.0%22632 0.000 0.025 0.119
article parse00:00:00.708 0.0%72579 0.000 0.010 0.028
article write00:00:01.413 0.0%18119 0.011 0.078 1.420
artlog/artcncl00:00:00.000 0.0%76 0.000 0.000 0.000
data move00:00:00.325 0.0%579824 0.000 0.001 0.003
hisgrep/artcncl00:00:00.000 0.0%82 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%82 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.980 0.0%600470 0.002 0.015 0.763
history sync00:00:00.023 0.0%373 0.000 0.062 0.500
history write00:00:06.081 0.0%22216 0.000 0.274 18.700
idle23:57:41.192 99.8%588315 71.319 146.624 437.567
nntp read00:00:04.625 0.0%590864 0.002 0.008 0.023
overview write00:00:38.406 0.0%18119 0.029 2.120 56.008
perl filter00:00:43.176 0.0%22216 0.704 1.943 15.396
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.179 0.0%36238 0.000 0.033 0.190
TOTAL: 24:00:53.82323:59:27.146 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.048 0.0%24194 0.000 0.002 0.038
article prepare00:00:00.003 0.0%9824 0.000 0.000 0.025
article read00:00:00.167 0.0%2219 0.000 0.075 2.625
backlog stats00:00:00.588 0.0%311169 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.611 0.0%261338 0.003 0.010 0.019
data write00:00:03.023 0.0%247708 0.002 0.012 0.021
idle23:55:18.454100.0%311169 177.006 276.758 696.908
status file00:00:10.307 0.0%1909 0.400 5.399 199.636
TOTAL: 23:55:52.31323:55:35.201100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle23:02:02.670 99.7%130907 0.000 633.447 84783.652
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:05.275 0.0%276112 0.000 0.019 39.570
readart00:00:01.800 0.0%7416 0.000 0.243 4.143
TOTAL: 23:05:36.92823:02:09.745 99.8%----

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
1bbs-nz-out.news.weretis.net 3 37481 5193 31988 300 13%46:43:11
2news.snarked.org 35 22047 3003 17582 1462 13%37:55:03
3bbsnz-out.feed.uzoreto.com 1108 27917 2194 24568 1155 7%95:17:08
4news-out.aioe.org 3 19352 2174 17139 39 11%26:10:41
5feeder.eternal-september.org 4 21051 2156 18395 500 10%24:34:21
6news-out.mixmin.net 2 20633 1432 18692 509 6%25:16:57
7newsfeed.neva.ru 142 13096 848 12084 164 6%72:31:18
8news.redatomik.org 3 17956 599 17305 52 3%24:08:15
9news.albasani.net 1 20767 175 20518 74 0%25:18:14
10csiph.com 1 14171 153 14011 7 1%18:12:28
11news.swapon.de 4 1256 99 1157 0 7%18:20:41
12agency-nz-out.news.mb-net.net 4 19135 60 19058 17 0%22:36:41
13news.samoylyk.net 2 13731 48 13666 17 0%16:13:18
14feeder.awxg.com 1 12456 21 12432 3 0%16:57:26
15localhost 13 12 12 0 0 100%00:00:00
16news.freedyn.net 7 7191 5 7184 2 0%08:10:22
TOTAL: 16 1333 268252 18172 245779 4301 6%478:26:04
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net22.3 MB42.2 KB1.0 MB23.4 MB 95%4.4 KB
2news.snarked.org9.3 MB14.7 KB21.1 MB30.4 MB 30%7.0 KB
3bbsnz-out.feed.uzoreto.com7.4 MB98.0 KB6.3 MB13.8 MB 53%4.2 KB
4news-out.aioe.org5.6 MB10.7 KB257.3 KB5.9 MB 95%2.7 KB
5feeder.eternal-september.org5.6 MB897.6 KB2.4 MB8.9 MB 63%3.4 KB
6news-out.mixmin.net5.1 MB58.1 KB1.2 MB6.3 MB 79%3.3 KB
7news.redatomik.org2.4 MB856.5 KB955.0 KB4.1 MB 57%6.5 KB
8newsfeed.neva.ru2.0 MB4.5 KB1.3 MB3.4 MB 60%3.4 KB
9news.albasani.net1.5 MB877.0 KB1.0 MB3.3 MB 44%13.8 KB
10csiph.com428.0 KB42.0 KB42.0 KB511.9 KB 83%3.2 KB
11news.swapon.de278.2 KB0.0 KB0.0 KB278.2 KB100%2.8 KB
12news.samoylyk.net177.0 KB851.6 KB1.3 MB2.3 MB 7%36.4 KB
13agency-nz-out.news.mb-net.net144.2 KB13.0 KB74.0 KB231.3 KB 62%3.0 KB
14feeder.awxg.com50.6 KB11.0 KB11.0 KB72.6 KB 69%3.0 KB
15localhost37.2 KB0.0 KB0.0 KB37.2 KB100%3.1 KB
16news.freedyn.net13.0 KB1.5 KB2.7 KB17.2 KB 75%2.5 KB
TOTAL: 1662.3 MB3.7 MB37.0 MB103.0 MB 60%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 6 23:58:01 - 23:59:59 10 0.1% 0.0826.5 KB 0.0% 0.22
Sep 7 00:00:00 - 00:59:59 918 5.1% 0.262.7 MB 4.4% 0.77
Sep 7 01:00:00 - 01:59:59 933 5.2% 0.263.5 MB 5.6% 0.99
Sep 7 02:00:00 - 02:59:59 939 5.2% 0.263.0 MB 4.8% 0.84
Sep 7 03:00:00 - 03:59:59 1121 6.2% 0.314.5 MB 7.3% 1.29
Sep 7 04:00:00 - 04:59:59 976 5.4% 0.273.5 MB 5.6% 0.98
Sep 7 05:00:00 - 05:59:59 1081 6.0% 0.303.0 MB 4.8% 0.84
Sep 7 06:00:00 - 06:59:59 1037 5.7% 0.294.0 MB 6.4% 1.13
Sep 7 07:00:00 - 07:59:59 973 5.4% 0.272.9 MB 4.6% 0.81
Sep 7 08:00:00 - 08:59:59 813 4.5% 0.233.0 MB 4.8% 0.85
Sep 7 09:00:00 - 09:59:59 879 4.9% 0.242.7 MB 4.3% 0.76
Sep 7 10:00:00 - 10:59:59 771 4.3% 0.213.0 MB 4.8% 0.85
Sep 7 11:00:00 - 11:59:59 506 2.8% 0.142.1 MB 3.4% 0.61
Sep 7 12:00:00 - 12:59:59 438 2.4% 0.121.5 MB 2.5% 0.44
Sep 7 13:00:00 - 13:59:59 418 2.3% 0.122.3 MB 3.7% 0.65
Sep 7 14:00:00 - 14:59:59 380 2.1% 0.111.4 MB 2.3% 0.41
Sep 7 15:00:00 - 15:59:59 349 1.9% 0.101.2 MB 1.9% 0.34
Sep 7 16:00:00 - 16:59:59 359 2.0% 0.101.5 MB 2.4% 0.43
Sep 7 17:00:00 - 17:59:59 491 2.7% 0.141.4 MB 2.2% 0.39
Sep 7 18:00:00 - 18:59:59 632 3.5% 0.182.1 MB 3.3% 0.59
Sep 7 19:00:00 - 19:59:59 801 4.4% 0.222.2 MB 3.5% 0.62
Sep 7 20:00:00 - 20:59:59 770 4.3% 0.212.1 MB 3.4% 0.60
Sep 7 21:00:00 - 21:59:59 836 4.6% 0.233.2 MB 5.2% 0.92
Sep 7 22:00:00 - 22:59:59 909 5.0% 0.252.5 MB 4.1% 0.72
Sep 7 23:00:00 - 23:58:01 776 4.3% 0.222.8 MB 4.5% 0.83
TOTAL: 24:00:00 18116 100.0% 0.2162.0 MB 100.0% 0.74
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB28.3 GB 56.5% 0 0.28 2179.31
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB32.6 GB 65.2% 0 0.35 1757.41
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB360.1 MB 0.7% 0 0.0231604.94
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 GB26.6 GB 53.2% 0 0.59 1030.34
TOTAL: 10-500.0 GB87.8 GB 17.6% 1 0.39-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 1371 2 0 3 0 0 0 0 1366
2bbsnz-out.feed.uzoreto.com 1164 22 0 12 0 0 0 0 1130
3news-out.mixmin.net 561 0 0 7 0 0 0 0 554
4feeder.eternal-september.org 497 0 0 17 0 0 0 0 480
5bbs-nz-out.news.weretis.net 472 49 0 8 0 0 0 0 415
6newsfeed.neva.ru 164 16 0 3 0 0 0 0 145
7news.albasani.net 95 2 0 7 0 0 0 0 86
8news.redatomik.org 67 0 0 7 0 0 0 0 60
9news-out.aioe.org 36 22 0 3 0 0 0 0 11
10news.swapon.de 32 0 0 0 0 0 0 0 32
11news.samoylyk.net 19 0 0 7 0 0 0 0 12
12agency-nz-out.news.mb-net.net 19 5 0 4 0 0 0 0 10
13news.freedyn.net 8 0 0 5 0 0 0 0 3
14csiph.com 7 0 0 7 0 0 0 0 0
15feeder.awxg.com 3 0 0 3 0 0 0 0 0
TOTAL: 15 4515 118 0 93 0 0 0 0 4304

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.linux.alsa.devel 33
mozilla.dev.ui-alerts 10
gmane.comp.gis.openstreetmap.tagging 9
mozilla.support.seamonkey 7
mozilla.support.bugzilla 5
gmane.linux.alsa.user 4
fido7.ru.android 4
mozilla.dev.telemetry-alerts 3
mozilla.dev.tech.js-engine.internals 3
gmane.linux.kernel 3
mozilla.dev.apps.seamonkey 2
albasani.test 2
mozilla.dev.builds 2
mozilla.apis 2
su.market 2
mozilla.dev.l10n 2
mozilla.dev.security.policy 2
upenn.forsale 1
demos.local.statistics 1
mozilla.dev.planning 1
TOTAL: 40 118

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2631
EMP (phn path) 566
User-issued cancel 508
EMP (phl) 104
Binary: non-binary distribution 101
Too many newsgroups 35
Binary: misplaced par2 12
User-issued spam cancel 9
Too many newsgroups (low_xpost) 2
HTML post 2
Binary: misplaced rar 1
Binary: misplaced 004 1
Binary: misplaced 257 1
Binary: misplaced zip 1
Binary: misplaced 216 1
Binary: misplaced 255 1
Too many newsgroups (meow) 1
Binary: misplaced 008 1
Binary: misplaced 025 1
Binary: misplaced 013 1
TOTAL: 21 3981

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
bbsnz-out.feed.uzoreto.com 3
TOTAL: 1 3
Including strange strings
bbs-nz-out.news.weretis.net 170
news-out.mixmin.net 86
news.swapon.de 26
news.redatomik.org 13
bbsnz-out.feed.uzoreto.com 8
news-out.aioe.org 7
news.snarked.org 6
news.albasani.net 4
agency-nz-out.news.mb-net.net 2
newsfeed.neva.ru 1
TOTAL: 10 323
TOTAL: 2 326

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 16469 1099 15343 27 0 0 6%23:59:50
2awxg 17700 64 17629 0 0 0 0%23:59:51
3news.freedyn.net 18321 22 18061 17 0 2 0%23:59:51
4mb-net.net 18064 16 18015 3 0 0 0%23:59:50
5aioe 15233 15 15119 0 0 0 0%23:59:50
6csiph.com 18032 13 17924 0 0 0 0%23:59:47
7news.samoylyk.net 16487 12 16395 1 0 131 0%23:59:51
8eternal-september 15614 12 15582 0 0 0 0%23:59:51
9news.snarked.org 15186 12 15099 0 0 54 0%23:59:49
10news.redatomik.org 17493 12 17453 0 0 9 0%23:59:49
11news-in.mixmin.net 16424 12 16403 0 0 0 0%23:59:51
12weretis.net 12602 12 12586 0 0 0 0%23:59:46
13news.albasani.net 19358 9 16500 973 0 5977 0%23:59:51
14neva.ru 16864 8 16842 4 0 0 0%23:59:51
15news.uzoreto.com 15890 2 15876 10 0 0 0%23:59:45
16news.swapon.de 1624 0 1624 0 0 0 0%23:53:21
TOTAL: 16 251361 1320 246451 1035 0 6173 0%383:50:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org10.8 MB522.0 KB11.3 MB0.1 KB/s10.3 KB23:59:50
2awxg177.3 KB0.0 KB177.3 KB0.0 KB/s2.8 KB23:59:51
3news.freedyn.net64.0 KB67.1 KB131.1 KB0.0 KB/s3.4 KB23:59:51
4mb-net.net44.4 KB15.5 KB59.9 KB0.0 KB/s3.2 KB23:59:50
5aioe43.4 KB0.0 KB43.4 KB0.0 KB/s2.9 KB23:59:50
6csiph.com40.5 KB0.0 KB40.5 KB0.0 KB/s3.1 KB23:59:47
7news.samoylyk.net37.2 KB98.8 KB136.0 KB0.0 KB/s10.5 KB23:59:51
8eternal-september37.2 KB0.0 KB37.2 KB0.0 KB/s3.1 KB23:59:51
9news.snarked.org37.2 KB0.0 KB37.2 KB0.0 KB/s3.1 KB23:59:49
10news.redatomik.org37.2 KB0.0 KB37.2 KB0.0 KB/s3.1 KB23:59:49
11news-in.mixmin.net37.2 KB0.0 KB37.2 KB0.0 KB/s3.1 KB23:59:51
12weretis.net37.2 KB0.0 KB37.2 KB0.0 KB/s3.1 KB23:59:46
13news.albasani.net28.5 KB4.2 MB4.3 MB0.1 KB/s4.4 KB23:59:51
14neva.ru27.5 KB15.4 KB42.8 KB0.0 KB/s3.6 KB23:59:51
15news.uzoreto.com7.7 KB29.6 KB37.2 KB0.0 KB/s3.1 KB23:59:45
16news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:53:21
TOTAL: 1611.4 MB5.0 MB16.4 MB0.0 KB/s7.1 KB383:50:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 386 579120.5 MB 3276 12 000:15:34
2vorlon.activateit.net.au 24 10391.5 MB 132 0 000:04:55
3184-155-113-241.cpe.cableone.net 1 1980.5 KB 1 0 000:32:34
4160-3-245-118.cpe.cableone.net 2 13.9 KB 1 0 022:10:08
TOTAL: 4 413 685022.1 MB 3410 12 023:05:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 386 579120.5 MB 3276 12 000:15:34
2*.activateit.net.au 24 10391.5 MB 132 0 000:04:55
3*.cpe.cableone.net 3 2084.4 KB 2 0 022:42:42
4unresolved 3 00.0 KB 0 0 000:02:12
5*.us-west-1.compute.amazonaws.com 1 00.0 KB 0 0 000:00:03
6*.us-west-2.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
TOTAL: 6 418 685022.1 MB 3410 12 023:05:30

NNRP auth users [Top 20]:

UserConn
amybbs 24
malcolmlewis 2
TOTAL: 2 26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-245-118.cpe.cableone.net 0.228 0.104 0.00422:10:08
184-155-113-241.cpe.cableone.net 0.188 0.056 0.00000:32:34
agency.bbs.nz 29.724 6.624 0.00000:15:34
vorlon.activateit.net.au 1.604 0.436 0.08100:04:55
118.149.222.173 0.128 0.004 0.00000:02:03
107.170.226.21 0.068 0.000 0.00000:00:09
ec2-54-183-228-169.us-west-1.compute.amazonaws.com 0.064 0.004 0.00000:00:03
? 1.088 0.096 0.00000:00:01
ec2-54-189-210-70.us-west-2.compute.amazonaws.com 0.068 0.000 0.00000:00:00
TOTAL: 9 33.160 7.324 0.08523:05:30

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.222.173 2
ec2-54-183-228-169.us-west-1.compute.amazonaws.com 1
ec2-54-189-210-70.us-west-2.compute.amazonaws.com 1
107.170.226.21 1
TOTAL: 4 5

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
184-155-113-241.cpe.cableone.net 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1rec 2595 37.9%
2comp 857 12.5%
3sci 755 11.0%
4soc 709 10.4%
5uk 590 8.6%
6linux 523 7.6%
7alt 364 5.3%
8talk 159 2.3%
9misc 128 1.9%
10news 54 0.8%
11mozilla 36 0.5%
12nz 35 0.5%
13microsoft 32 0.5%
14humanities 9 0.1%
15aus 4 0.1%
TOTAL: 15 6850100.0%

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

NewsgroupCount
1rec.arts.tv 774
2uk.d-i-y 310
3rec.food.cooking 268
4rec.arts.drwho 249
5linux.debian.bugs.dist 217
6comp.lang.python 202
7sci.physics.relativity 197
8rec.sport.tennis 190
9sci.physics 181
10uk.legal 172
11comp.lang.c 159
12comp.os.linux.advocacy 131
13alt.comp.os.windows-10 130
14linux.debian.changes.devel 129
15rec.arts.sf.written 125
16rec.sport.football.college 119
17rec.gambling.poker 99
18rec.drugs.cannabis 99
19talk.politics.guns 85
20soc.culture.israel 83
21soc.retirement 80
22soc.culture.china 78
23sci.math 77
24linux.debian.bugs.rc 70
25alt.politics.trump 67
26sci.logic 66
27soc.penpals 65
28sci.electronics.design 62
29rec.boats 57
30soc.culture.indian 54
31alt.conspiracy.jfk 53
32rec.crafts.metalworking 50
33soc.culture.polish 50
34talk.origins 45
35news.lists.filters 45
36rec.sport.pro-wrestling 44
37rec.bicycles.tech 42
38rec.aviation.soaring 42
39misc.survivalism 41
40misc.invest.stocks 41
41rec.sport.golf 38
42sci.med.cardiology 35
43rec.music.classical.recordings 34
44nz.general 33
45alt.test 32
46uk.radio.amateur 31
47uk.legal.moderated 30
48soc.genealogy.britain 29
49rec.outdoors.rv-travel 29
50alt.conspiracy 28
51comp.ai.philosophy 27
52sci.bio.paleontology 27
53microsoft.public.windowsxp.general 26
54alt.os.linux.ubuntu 26
55soc.culture.usa 25
56comp.sys.apple2 24
57comp.protocols.dns.bind 24
58rec.arts.movies.current-films 24
59soc.culture.vietnamese 23
60linux.debian.user 23
61talk.politics.misc 22
62comp.misc 22
63soc.history.living 21
64sci.med.pharmacy 20
65comp.lang.forth 20
66soc.culture.greek 19
67rec.woodworking 19
68comp.os.vms 19
69sci.military.naval 18
70mozilla.general 18
71linux.debian.l10n.french 17
72comp.mobile.android 17
73rec.photo.digital 16
74comp.sys.raspberry-pi 16
75comp.lang.c++ 16
76soc.culture.brazil 16
77soc.history 15
78comp.lang.clipper.visual-objects 15
79alt.games.warcraft 14
80rec.arts.tv.uk.coronation-st 14
81sci.lang 14
82rec.games.pinball 13
83uk.comp.sys.mac 13
84misc.invest.misc 13
85rec.radio.amateur.antenna 12
86rec.sport.rowing 12
87rec.music.dylan 12
88comp.sys.mac.system 12
89soc.women 12
90sci.anthropology.paleo 11
91soc.genealogy.medieval 11
92mozilla.support.firefox 11
93comp.sys.mac.advocacy 10
94rec.arts.poems 10
95comp.lang.tcl 10
96soc.culture.german 10
97rec.sport.billiard 10
98rec.aviation.student 10
99misc.phone.mobile.iphone 10
100misc.news.internet.discuss 9
TOTAL: 332 6850