news.bbs.nz daily logs

Nov 8 23:58:01 -- Nov 9 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 121803 60.0%9.5 MB 37.3%
innd 31800 15.7%5.1 MB 20.2%
innfeed 24523 12.1%4.8 MB 19.0%
inn 23527 11.6%5.7 MB 22.4%
cnfsstat 1450 0.7%258.0 KB 1.0%
controlchan 3 0.0%0.4 KB 0.0%
TOTAL: 6 203106 100.0%25.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 273509 48.6%
Positive hits 266368 47.3%
Do not exist 22881 4.1%
Cache misses 402 0.1%
TOTAL: 4 563160 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.179 0.0%57 0.000 3.140 8.500
article cleanup00:00:00.197 0.0%23270 0.000 0.008 0.057
article logging00:00:00.718 0.0%23522 0.000 0.031 0.216
article parse00:00:00.739 0.0%72390 0.000 0.010 0.024
article write00:00:01.656 0.0%18144 0.009 0.091 0.993
artlog/artcncl00:00:00.001 0.0%57 0.000 0.018 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.537 0.0%535917 0.000 0.001 0.013
hisgrep/artcncl00:00:00.002 0.0%57 0.000 0.035 1.000
hishave/artcncl00:00:00.000 0.0%57 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:48.776 0.1%567828 0.001 0.086 1.484
history sync00:00:00.011 0.0%311 0.000 0.035 0.500
history write00:00:36.976 0.0%23075 0.013 1.602 19.918
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:35:33.427 99.7%547337 93.749 155.176 419.442
nntp read00:00:04.231 0.0%550308 0.003 0.008 0.022
overview write00:01:03.016 0.1%18144 0.036 3.473 72.131
perl filter00:00:42.406 0.0%23069 0.986 1.838 13.301
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.896 0.0%36288 0.000 0.025 0.131
TOTAL: 23:40:26.45623:38:53.768 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.052 0.0%24692 0.000 0.002 0.029
article prepare00:00:00.000 0.0%17372 0.000 0.000 0.000
article read00:00:00.252 0.0%4080 0.000 0.062 1.750
backlog stats00:00:00.552 0.0%321119 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.325 0.0%265954 0.002 0.009 0.018
data write00:00:03.046 0.0%252794 0.004 0.012 0.028
idle23:33:14.136 99.9%321119 153.498 264.058 551.480
status file00:00:27.875 0.0%1856 0.250 15.019 530.136
TOTAL: 23:34:09.72423:33:48.238100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%17 0.000 0.059 1.000
idle02:53:40.126 96.2%127762 0.000 81.559 77432.853
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.662 0.0%269255 0.000 0.006 0.333
readart00:00:02.333 0.0%7549 0.000 0.309 32.000
TOTAL: 03:00:30.80702:53:44.122 96.2%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
shutdown 3
throttle 2
TOTAL: 13 172

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 31 22186 3647 16084 2455 16%32:59:24
2bbs-nz-out.news.weretis.net 9 36309 3499 32475 335 9%45:09:01
3feeder.eternal-september.org 8 21771 2374 19122 275 10%25:13:50
4bbsnz-out.feed.uzoreto.com 848 30307 2374 27257 676 7%69:05:33
5news-out.aioe.org 3 17426 2057 15337 32 11%22:54:04
6newsfeed.neva.ru 150 13832 1120 12212 500 8%70:21:33
7news.redatomik.org 5 19430 872 18499 59 4%25:45:09
8news-out.mixmin.net 2 17993 831 16749 413 4%21:56:16
9news.albasani.net 2 21061 820 20015 226 3%25:14:32
10news.swapon.de 4 1830 179 1647 4 9%28:12:02
11csiph.com 2 19095 103 18981 11 0%26:48:31
12agency-nz-out.news.mb-net.net 5 18794 90 18679 25 0%23:01:50
13news.freedyn.net 3 22531 53 22450 28 0%28:55:15
14feeder.awxg.com 3 18028 16 18002 10 0%28:03:34
15news.ausics.org 1 80451 8 80441 2 0%124:56:40
16localhost 2 1 1 0 0 100%00:00:00
TOTAL: 16 1078 361045 18044 337950 5051 4%598:37:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net13.7 MB141.7 KB1.1 MB15.0 MB 91%4.0 KB
2news.snarked.org12.9 MB60.1 KB22.3 MB35.2 MB 36%5.9 KB
3feeder.eternal-september.org6.6 MB54.5 KB894.5 KB7.5 MB 87%2.9 KB
4bbsnz-out.feed.uzoreto.com6.5 MB72.0 KB3.6 MB10.2 MB 64%3.4 KB
5news-out.aioe.org4.9 MB47.1 KB241.2 KB5.2 MB 94%2.6 KB
6news.redatomik.org3.0 MB166.1 KB263.5 KB3.4 MB 87%3.7 KB
7newsfeed.neva.ru2.6 MB73.6 KB8.2 MB10.9 MB 24%6.9 KB
8news.albasani.net2.5 MB55.5 KB471.3 KB3.0 MB 83%3.0 KB
9news-out.mixmin.net2.5 MB50.9 KB905.5 KB3.5 MB 72%2.8 KB
10news.swapon.de471.3 KB3.0 KB10.1 KB484.4 KB 97%2.6 KB
11csiph.com296.4 KB38.9 KB40.3 KB375.6 KB 78%3.3 KB
12agency-nz-out.news.mb-net.net264.6 KB113.7 KB138.2 KB516.5 KB 51%4.5 KB
13news.freedyn.net155.9 KB71.7 KB137.5 KB365.1 KB 42%4.5 KB
14feeder.awxg.com82.5 KB28.0 KB28.0 KB138.4 KB 59%5.3 KB
15news.ausics.org30.7 KB1.8 KB6.6 KB39.2 KB 78%3.9 KB
16localhost1.1 KB0.0 KB0.0 KB1.1 KB100%1.1 KB
TOTAL: 1656.6 MB978.5 KB38.3 MB95.8 MB 59%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 8 23:58:01 - 23:59:59 29 0.2% 0.2483.8 KB 0.1% 0.70
Nov 9 00:00:00 - 00:59:59 791 4.4% 0.222.3 MB 4.1% 0.66
Nov 9 01:00:00 - 01:59:59 838 4.6% 0.232.6 MB 4.7% 0.75
Nov 9 02:00:00 - 02:59:59 905 5.0% 0.252.7 MB 4.7% 0.76
Nov 9 03:00:00 - 03:59:59 1008 5.6% 0.283.1 MB 5.5% 0.88
Nov 9 04:00:00 - 04:59:59 1109 6.1% 0.312.9 MB 5.2% 0.83
Nov 9 05:00:00 - 05:59:59 1037 5.7% 0.292.8 MB 4.9% 0.79
Nov 9 06:00:00 - 06:59:59 1134 6.3% 0.323.5 MB 6.2% 1.00
Nov 9 07:00:00 - 07:59:59 1049 5.8% 0.293.4 MB 6.0% 0.97
Nov 9 08:00:00 - 08:59:59 1063 5.9% 0.303.5 MB 6.2% 0.99
Nov 9 09:00:00 - 09:59:59 986 5.4% 0.272.9 MB 5.2% 0.84
Nov 9 10:00:00 - 10:59:59 955 5.3% 0.273.1 MB 5.5% 0.89
Nov 9 11:00:00 - 11:59:59 838 4.6% 0.233.4 MB 5.9% 0.95
Nov 9 12:00:00 - 12:59:59 626 3.5% 0.172.2 MB 3.9% 0.63
Nov 9 13:00:00 - 13:59:59 474 2.6% 0.131.5 MB 2.6% 0.43
Nov 9 14:00:00 - 14:59:59 447 2.5% 0.121.4 MB 2.4% 0.40
Nov 9 15:00:00 - 15:59:59 455 2.5% 0.131.6 MB 2.9% 0.46
Nov 9 16:00:00 - 16:59:59 368 2.0% 0.101.9 MB 3.4% 0.54
Nov 9 17:00:00 - 17:59:59 364 2.0% 0.101.4 MB 2.4% 0.39
Nov 9 18:00:00 - 18:59:59 422 2.3% 0.121.3 MB 2.3% 0.37
Nov 9 19:00:00 - 19:59:59 463 2.6% 0.131.4 MB 2.5% 0.40
Nov 9 20:00:00 - 20:59:59 564 3.1% 0.161.5 MB 2.6% 0.43
Nov 9 21:00:00 - 21:59:59 722 4.0% 0.201.8 MB 3.1% 0.50
Nov 9 22:00:00 - 22:59:59 784 4.3% 0.222.4 MB 4.2% 0.68
Nov 9 23:00:00 - 23:58:02 672 3.7% 0.192.1 MB 3.6% 0.61
TOTAL: 24:00:01 18103 100.0% 0.2156.8 MB 100.0% 0.67
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB29.6 GB 59.3% 0 0.30 2037.60
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB34.1 GB 68.1% 0 0.33 1830.84
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB368.1 MB 0.7% 0 0.0322226.85
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 GB29.5 GB 59.0% 0 0.58 1053.08
TOTAL: 10-500.0 GB93.6 GB 18.7% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2479 13 0 11 0 0 0 0 2455
2bbsnz-out.feed.uzoreto.com 687 15 0 11 0 0 0 0 661
3newsfeed.neva.ru 504 21 0 6 0 0 0 0 477
4news-out.mixmin.net 419 0 0 16 0 0 0 0 403
5bbs-nz-out.news.weretis.net 393 68 0 41 0 0 0 0 284
6news.albasani.net 330 0 0 18 0 0 0 0 312
7feeder.eternal-september.org 277 0 0 16 0 0 0 0 261
8news.redatomik.org 86 0 0 11 0 0 0 0 75
9news.swapon.de 51 0 0 1 0 0 0 0 50
10news-out.aioe.org 45 25 0 12 0 0 0 0 8
11agency-nz-out.news.mb-net.net 30 1 0 21 0 0 0 0 8
12news.freedyn.net 28 2 0 8 0 0 0 0 18
13news.ausics.org 16 0 0 0 0 0 0 0 16
14csiph.com 13 0 0 13 0 0 0 0 0
15feeder.awxg.com 10 0 0 10 0 0 0 0 0
TOTAL: 15 5368 145 0 195 0 0 0 0 5028

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.comp.gis.openstreetmap.tagging 27
alt.music.pink-floyd.publius 24
gmane.linux.kernel 18
gmane.linux.alsa.devel 17
ga.jobs 10
mozilla.dev.builds 6
mozilla.dev.security.policy 5
mozilla.test 4
it.annunci.varie 3
it.annunci.immobiliari 3
mozilla.dev.ui-alerts 3
it.annunci.commerciali 3
mozilla.support.bugzilla 2
mozilla.dev.servo 2
mozilla.release.engineering 2
mozilla.dev.platform 2
fido7.ru.android 1
alt.religion.christian.romman-catholic 1
alt.net.dn42.users 1
corel.international.spanish.graphics 1
TOTAL: 30 145

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3241
EMP (phn path) 590
Binary: misplaced rar 326
User-issued cancel 186
EMP (phl) 182
Too many newsgroups 90
User-issued spam cancel 29
EMP (phn nph) 27
Binary: non-binary distribution 26
Binary Image: misplaced jpg 26
Binary: misplaced par2 24
Binary: misplaced 004 3
Binary Image: misplaced png 3
Binary: misplaced 002 3
HTML post 2
Too many newsgroups (low_xpost) 2
Binary: misplaced 007 1
Binary: misplaced 009 1
Binary: misplaced 037 1
Binary: misplaced 008 1
TOTAL: 26 4770

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
bbsnz-out.feed.uzoreto.com 5
news.albasani.net 1
TOTAL: 2 6
Bad Message-ID's offered
bbsnz-out.feed.uzoreto.com 2
TOTAL: 1 2
Including strange strings
news.albasani.net 81
bbs-nz-out.news.weretis.net 52
news.swapon.de 48
news.redatomik.org 32
news-out.mixmin.net 19
bbsnz-out.feed.uzoreto.com 8
news-out.aioe.org 7
news.snarked.org 3
newsfeed.neva.ru 2
TOTAL: 9 252
TOTAL: 3 260

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1utnut 2697 2080 596 21 0 91 77%03:30:59
2news.ausics.org 16990 1427 15525 38 0 66 8%23:37:30
3awxg 17663 27 17611 8 0 72 0%23:37:30
4news.samoylyk.net 17386 9 16980 1 0 332 0%23:37:30
5weretis.net 14556 5 14397 5 0 88 0%23:37:08
6news.snarked.org 14438 4 14307 2 0 128 0%23:37:30
7csiph.com 18200 2 17990 0 0 66 0%23:37:30
8news.albasani.net 18540 2 16376 634 0 5127 0%23:37:30
9aioe 14916 1 14841 0 0 35 0%23:37:30
10news-in.mixmin.net 16923 1 16901 0 0 44 0%23:37:30
11mb-net.net 18003 1 17984 0 0 91 0%23:37:30
12news.freedyn.net 18144 1 18052 1 0 95 0%23:37:30
13news.redatomik.org 17200 1 17182 0 0 96 0%23:37:30
14neva.ru 16420 1 16413 0 0 59 0%23:37:30
15eternal-september 15345 1 15319 0 0 81 0%23:37:30
16news.uzoreto.com 15592 0 15569 1 0 195 0%23:37:07
17news.swapon.de 1657 0 1655 0 0 0 0%23:34:15
TOTAL: 17 254670 3563 247698 711 0 6666 1%381:26:59
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org9.3 MB776.8 KB10.0 MB0.1 KB/s7.0 KB23:37:30
2utnut5.6 MB69.4 KB5.7 MB0.5 KB/s2.8 KB03:30:59
3awxg105.6 KB38.4 KB144.1 KB0.0 KB/s4.1 KB23:37:30
4weretis.net18.9 KB28.8 KB47.7 KB0.0 KB/s4.8 KB23:37:08
5news.samoylyk.net14.1 KB1.9 KB16.0 KB0.0 KB/s1.6 KB23:37:30
6news.snarked.org11.3 KB4.2 KB15.5 KB0.0 KB/s2.6 KB23:37:30
7csiph.com3.1 KB0.0 KB3.1 KB0.0 KB/s1.6 KB23:37:30
8news.albasani.net2.5 KB1.9 MB1.9 MB0.0 KB/s3.0 KB23:37:30
9aioe1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
10news-in.mixmin.net1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
11mb-net.net1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
12news.freedyn.net1.1 KB32.1 KB33.2 KB0.0 KB/s16.6 KB23:37:30
13news.redatomik.org1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
14neva.ru1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
15eternal-september1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:37:30
16news.uzoreto.com0.0 KB1.1 KB1.1 KB0.0 KB/s1.1 KB23:37:07
17news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:34:15
TOTAL: 1715.0 MB2.8 MB17.8 MB0.0 KB/s4.3 KB381:26:59
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 368 573119.9 MB 3199 0 000:14:24
2vorlon.activateit.net.au 23 11021.6 MB 135 0 000:05:09
360.234.68.122 12 3096.9 KB 3 1 000:45:40
4amigacity.xyz 71 2666.0 KB 21 0 28400:13:02
TOTAL: 4 474 688921.6 MB 3358 1 28403:00:23

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 368 573119.9 MB 3199 0 000:14:24
2*.activateit.net.au 23 11021.6 MB 135 0 000:05:09
3unresolved 18 3096.9 KB 3 1 000:50:57
4*.xyz 71 2666.0 KB 21 0 28400:13:02
5*.cpe.cableone.net 3 00.0 KB 0 0 001:36:48
TOTAL: 5 483 688921.6 MB 3358 1 28403:00:23

NNRP auth users [Top 20]:

UserConn
amigacity 71
amybbs 23
crash 9
malcolmlewis 3
TOTAL: 4 106

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-236-103.cpe.cableone.net 0.056 0.012 0.00301:36:48
60.234.68.122 0.808 0.124 0.02400:45:40
agency.bbs.nz 27.820 6.416 0.00000:14:24
amigacity.xyz 3.932 0.816 0.23400:13:02
vorlon.activateit.net.au 1.496 0.364 0.11900:05:09
118.149.224.70 0.300 0.044 0.00000:05:06
107.170.219.13 0.056 0.008 0.00000:00:09
? 1.268 0.152 0.00000:00:02
TOTAL: 8 35.736 7.936 0.38003:00:23

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.224.70 5
160-3-236-103.cpe.cableone.net 3
107.170.219.13 1
TOTAL: 3 9

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 3
TOTAL: 1 3

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 3
TOTAL: 1 3

Newsgroup request counts (by category):

CategoryCountPct
1rec 2552 37.1%
2comp 882 12.8%
3soc 694 10.1%
4alt 618 9.0%
5uk 586 8.5%
6sci 557 8.1%
7linux 509 7.4%
8talk 178 2.6%
9misc 150 2.2%
10news 55 0.8%
11mozilla 36 0.5%
12nz 24 0.3%
13agency 14 0.2%
14microsoft 9 0.1%
15aus 6 0.1%
16humanities 3 0.0%
TOTAL: 16 6873100.0%

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

NewsgroupCount
1rec.arts.tv 658
2alt.comp.os.windows-10 418
3rec.food.cooking 299
4uk.d-i-y 269
5comp.lang.c 256
6rec.arts.drwho 243
7rec.sport.tennis 200
8linux.debian.bugs.dist 190
9rec.sport.football.college 181
10rec.sport.pro-wrestling 153
11comp.os.linux.advocacy 132
12uk.legal 128
13alt.politics.trump 120
14soc.culture.israel 117
15talk.politics.guns 112
16linux.debian.changes.devel 111
17rec.gambling.poker 109
18soc.culture.china 99
19soc.retirement 97
20sci.math 91
21misc.survivalism 90
22rec.boats 89
23sci.physics.relativity 79
24uk.radio.amateur 74
25soc.culture.indian 71
26soc.culture.polish 70
27linux.debian.bugs.rc 69
28comp.lang.python 68
29sci.physics 63
30rec.outdoors.rv-travel 56
31comp.lang.c++ 56
32sci.electronics.design 56
33rec.arts.sf.written 47
34uk.legal.moderated 47
35soc.history.living 46
36comp.ai.philosophy 45
37soc.culture.brazil 44
38talk.origins 43
39sci.logic 42
40sci.med.pharmacy 39
41sci.med.cardiology 39
42uk.tech.digital-tv 39
43soc.culture.usa 38
44rec.aviation.soaring 37
45sci.lang 37
46rec.sport.soccer 36
47sci.military.naval 34
48alt.conspiracy.jfk 33
49news.lists.filters 32
50soc.culture.greek 31
51rec.games.backgammon 29
52rec.music.rock-pop-r+b.1950s 28
53misc.invest.stocks 28
54rec.photo.digital 28
55linux.debian.user 28
56rec.music.classical.recordings 25
57rec.arts.sf.fandom 25
58comp.arch 25
59nz.general 24
60comp.mobile.android 23
61comp.sys.mac.system 22
62comp.lang.forth 18
63alt.test 18
64mozilla.support.firefox 18
65mozilla.support.thunderbird 18
66comp.sys.mac.misc 18
67rec.autos.sport.f1 18
68rec.woodworking 17
69linux.gentoo.user 16
70linux.debian.devel 16
71rec.games.bridge 16
72soc.culture.quebec 15
73rec.games.pinball 15
74rec.aviation.military 15
75comp.os.linux.misc 15
76comp.lang.ada 14
77rec.audio.opinion 14
78sci.electronics.repair 14
79rec.sport.cricket 14
80comp.sys.mac.apps 14
81agency.test 13
82comp.lang.tcl 13
83rec.sport.golf 13
84talk.politics.misc 13
85uk.tech.broadcast 12
86alt.games.warcraft 12
87linux.debian.maint.python 12
88sci.anthropology.paleo 11
89rec.arts.poems 11
90comp.lang.prolog 11
91sci.bio.paleontology 11
92rec.bicycles.tech 10
93sci.space.policy 10
94comp.os.vms 10
95rec.pets.cats.anecdotes 10
96rec.radio.shortwave 10
97news.software.readers 10
98rec.arts.disney.parks 10
99misc.news.internet.discuss 10
100soc.genealogy.medieval 10
TOTAL: 306 6873