news.bbs.nz daily logs

Nov 6 23:58:02 -- Nov 7 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 126827 61.1%9.8 MB 38.2%
innd 31494 15.2%5.1 MB 19.8%
innfeed 24698 11.9%5.0 MB 19.3%
inn 23004 11.1%5.6 MB 21.8%
cnfsstat 1440 0.7%251.8 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 207464 100.0%25.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 274826 49.3%
Positive hits 259898 46.7%
Do not exist 22058 4.0%
Cache misses 246 0.0%
TOTAL: 4 557028 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.158 0.0%92 0.000 1.717 15.500
article cleanup00:00:00.195 0.0%22438 0.000 0.009 0.031
article logging00:00:00.597 0.0%22904 0.000 0.026 0.281
article parse00:00:00.677 0.0%71389 0.000 0.009 0.027
article write00:00:01.692 0.0%18110 0.014 0.093 1.783
artlog/artcncl00:00:00.001 0.0%92 0.000 0.011 0.125
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.406 0.0%535600 0.000 0.001 0.005
hisgrep/artcncl00:00:00.002 0.0%92 0.000 0.022 0.333
hishave/artcncl00:00:00.000 0.0%92 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.161 0.0%562825 0.002 0.015 0.775
history sync00:00:00.066 0.0%312 0.000 0.212 13.250
history write00:00:06.356 0.0%22262 0.000 0.286 17.701
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:08.851 99.8%546913 51.219 157.665 372.720
nntp read00:00:04.320 0.0%549095 0.003 0.008 0.016
overview write00:00:51.218 0.1%18110 0.056 2.828 104.547
perl filter00:00:41.204 0.0%22261 1.061 1.851 6.327
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.149 0.0%36220 0.000 0.032 0.176
TOTAL: 24:00:24.70023:59:05.053 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.058 0.0%24175 0.000 0.002 0.018
article prepare00:00:00.002 0.0%6436 0.000 0.000 0.042
article read00:00:00.345 0.0%1509 0.000 0.229 16.200
backlog stats00:00:00.514 0.0%312311 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.169 0.0%259867 0.004 0.008 0.015
data write00:00:02.951 0.0%246568 0.005 0.012 0.022
idle23:54:22.102100.0%312311 153.661 275.565 578.916
status file00:00:06.734 0.0%1894 0.333 3.555 108.265
TOTAL: 23:54:52.52623:54:34.875100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%14 0.000 0.071 0.143
idle30:33:30.167 99.6%133801 0.000 822.192 87394.187
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.715 0.0%280164 0.000 0.006 0.333
readart00:00:02.108 0.0%6709 0.000 0.314 27.000
TOTAL: 30:41:44.18530:33:33.991 99.6%----

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.snarked.org 43 21717 3584 16130 2003 16%35:05:45
2bbs-nz-out.news.weretis.net 7 37115 2610 34236 269 7%47:23:46
3bbsnz-out.feed.uzoreto.com 866 29357 2442 26299 616 8%71:14:33
4feeder.eternal-september.org 1 21390 2437 18676 277 11%24:50:33
5news-out.aioe.org 3 17395 2260 15093 42 12%23:38:48
6news-out.mixmin.net 3 17890 1221 16334 335 6%22:41:37
7news.albasani.net 1 11427 1037 10146 244 9%11:17:17
8newsfeed.neva.ru 144 11849 1011 10513 325 8%73:05:15
9news.redatomik.org 3 20953 807 20116 30 3%30:59:32
10news.swapon.de 4 1690 174 1515 1 10%26:06:34
11news.freedyn.net 3 19469 117 19308 44 0%25:01:16
12csiph.com 1 17382 104 17262 16 0%23:59:56
13agency-nz-out.news.mb-net.net 5 21784 88 21670 26 0%27:59:12
14feeder.awxg.com 2 15589 51 15531 7 0%23:59:52
15news.samoylyk.net 0 3469 5 3464 0 0%08:53:24
16localhost 4 3 3 0 0 100%00:00:00
TOTAL: 16 1090 268479 17951 246293 4235 6%476:17:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbsnz-out.feed.uzoreto.com19.0 MB14.0 KB2.2 MB21.2 MB 89%7.1 KB
2news.snarked.org13.4 MB11.6 KB12.9 MB26.3 MB 51%4.8 KB
3bbs-nz-out.news.weretis.net11.4 MB693.5 KB2.3 MB14.4 MB 79%5.1 KB
4feeder.eternal-september.org7.7 MB291.7 KB929.4 KB8.9 MB 86%3.3 KB
5news.albasani.net7.4 MB213.9 KB642.2 KB8.3 MB 89%6.6 KB
6news-out.aioe.org5.9 MB40.4 KB284.6 KB6.2 MB 94%2.8 KB
7news-out.mixmin.net3.7 MB24.7 KB895.5 KB4.6 MB 80%3.0 KB
8news.redatomik.org2.4 MB450.3 KB478.3 KB3.3 MB 72%4.1 KB
9newsfeed.neva.ru2.3 MB6.3 KB1.4 MB3.8 MB 61%2.9 KB
10news.freedyn.net1.4 MB391.8 KB580.1 KB2.3 MB 59%14.7 KB
11news.swapon.de549.5 KB4.5 KB4.5 KB558.5 KB 98%3.2 KB
12csiph.com318.8 KB56.3 KB56.3 KB431.5 KB 73%3.6 KB
13agency-nz-out.news.mb-net.net215.5 KB22.6 KB113.2 KB351.3 KB 61%3.1 KB
14feeder.awxg.com174.5 KB30.7 KB30.7 KB235.9 KB 73%4.1 KB
15localhost12.6 KB0.0 KB0.0 KB12.6 KB100%4.2 KB
16news.samoylyk.net8.1 KB0.0 KB0.0 KB8.1 KB100%1.6 KB
TOTAL: 1675.9 MB2.2 MB22.8 MB101.0 MB 75%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 6 23:58:02 - 23:59:59 17 0.1% 0.1445.3 KB 0.1% 0.38
Nov 7 00:00:00 - 00:59:59 924 5.1% 0.262.7 MB 3.5% 0.76
Nov 7 01:00:00 - 01:59:59 805 4.4% 0.222.2 MB 2.8% 0.62
Nov 7 02:00:00 - 02:59:59 840 4.6% 0.232.2 MB 2.9% 0.64
Nov 7 03:00:00 - 03:59:59 930 5.1% 0.263.4 MB 4.4% 0.95
Nov 7 04:00:00 - 04:59:59 1255 6.9% 0.3523.3 MB 30.4% 6.64
Nov 7 05:00:00 - 05:59:59 1106 6.1% 0.313.4 MB 4.4% 0.96
Nov 7 06:00:00 - 06:59:59 1049 5.8% 0.293.3 MB 4.3% 0.93
Nov 7 07:00:00 - 07:59:59 1057 5.8% 0.292.9 MB 3.8% 0.83
Nov 7 08:00:00 - 08:59:59 993 5.5% 0.283.1 MB 4.0% 0.88
Nov 7 09:00:00 - 09:59:59 978 5.4% 0.272.9 MB 3.8% 0.83
Nov 7 10:00:00 - 10:59:59 953 5.3% 0.263.9 MB 5.1% 1.10
Nov 7 11:00:00 - 11:59:59 808 4.5% 0.222.5 MB 3.2% 0.70
Nov 7 12:00:00 - 12:59:59 653 3.6% 0.182.3 MB 2.9% 0.64
Nov 7 13:00:00 - 13:59:59 463 2.6% 0.131.7 MB 2.2% 0.47
Nov 7 14:00:00 - 14:59:59 393 2.2% 0.111.5 MB 2.0% 0.43
Nov 7 15:00:00 - 15:59:59 424 2.3% 0.121.8 MB 2.4% 0.51
Nov 7 16:00:00 - 16:59:59 380 2.1% 0.111.4 MB 1.9% 0.41
Nov 7 17:00:00 - 17:59:59 369 2.0% 0.101.7 MB 2.2% 0.48
Nov 7 18:00:00 - 18:59:59 431 2.4% 0.121.4 MB 1.8% 0.40
Nov 7 19:00:00 - 19:59:59 531 2.9% 0.151.5 MB 1.9% 0.42
Nov 7 20:00:00 - 20:59:59 660 3.6% 0.181.9 MB 2.5% 0.54
Nov 7 21:00:00 - 21:59:59 607 3.4% 0.171.6 MB 2.1% 0.47
Nov 7 22:00:00 - 22:59:59 734 4.1% 0.202.0 MB 2.7% 0.58
Nov 7 23:00:00 - 23:58:02 745 4.1% 0.212.2 MB 2.9% 0.65
TOTAL: 24:00:00 18105 100.0% 0.2176.8 MB 100.0% 0.91
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB29.6 GB 59.2% 0 0.28 2157.01
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB34.0 GB 68.0% 0 0.33 1851.87
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB367.8 MB 0.7% 0 0.0511851.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.4 GB 58.8% 0 0.86 703.32
TOTAL: 10-500.0 GB93.4 GB 18.7% 1 0.49-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2042 6 0 8 0 0 0 0 2028
2bbsnz-out.feed.uzoreto.com 619 29 0 5 0 0 0 0 585
3news-out.mixmin.net 468 0 0 7 0 0 0 0 461
4bbs-nz-out.news.weretis.net 367 84 0 35 0 0 0 0 248
5news.albasani.net 361 1 0 14 0 0 0 0 346
6newsfeed.neva.ru 329 32 0 1 0 0 0 0 296
7feeder.eternal-september.org 272 0 0 23 0 0 0 0 249
8news.redatomik.org 135 0 0 16 0 0 0 0 119
9news.swapon.de 62 0 0 0 0 0 0 0 62
10news-out.aioe.org 61 27 0 17 0 0 0 0 17
11news.freedyn.net 41 0 0 12 0 0 0 0 29
12agency-nz-out.news.mb-net.net 24 4 0 12 0 0 0 0 8
13csiph.com 17 0 0 17 0 0 0 0 0
14feeder.awxg.com 9 0 0 9 0 0 0 0 0
TOTAL: 14 4807 183 0 176 0 0 0 0 4448

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.music.pink-floyd.publius 44
gmane.linux.alsa.devel 39
gmane.comp.gis.openstreetmap.tagging 33
gmane.linux.kernel 14
mozilla.dev.platform 7
mozilla.dev.telemetry-alerts 6
mozilla.dev.ui-alerts 5
alt.penthouse.sex.femdom 4
mozilla.dev.l10n 3
al.christnet.bible 3
mozilla.compatibility 3
gmane.comp.gis.openstreetmap.transit 3
mozilla.support.seamonkey 2
mozilla.dev.security.policy 2
mozilla.dev.servo 2
mozilla.support.calendar 2
alt.religion.christian.romman-catholic 1
clari.web.local.louisiana 1
south-wales.test 1
clari.web.local.alabama 1
TOTAL: 27 183

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3274
EMP (phn path) 302
User-issued cancel 125
EMP (phl) 117
EMP (phn nph) 74
Too many newsgroups 47
User-issued spam cancel 30
Binary: non-binary distribution 3
HTML post 2
Binary: misplaced par2 2
Binary: misplaced 014 1
Binary: misplaced rar 1
Too many newsgroups (low_xpost) 1
Binary: misplaced 007 1
TOTAL: 14 3980

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
bbsnz-out.feed.uzoreto.com 1
TOTAL: 1 1
Bad Message-ID's offered
bbsnz-out.feed.uzoreto.com 1
TOTAL: 1 1
Including strange strings
news.redatomik.org 106
news-out.mixmin.net 98
news.albasani.net 97
bbs-nz-out.news.weretis.net 78
news.swapon.de 62
news-out.aioe.org 14
newsfeed.neva.ru 6
bbsnz-out.feed.uzoreto.com 3
feeder.eternal-september.org 2
news.snarked.org 1
TOTAL: 10 467
TOTAL: 3 469

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 16623 656 15951 16 0 0 3%23:59:48
2awxg 17431 60 17353 0 0 0 0%23:59:48
3news.snarked.org 14589 13 14430 0 0 80 0%23:59:37
4eternal-september 15377 5 15360 0 0 0 0%23:59:47
5csiph.com 18288 4 17963 0 0 3 0%23:59:46
6news.redatomik.org 17325 4 17287 1 0 20 0%23:59:48
7weretis.net 15287 4 15119 5 0 0 0%23:59:48
8news.freedyn.net 18070 4 17967 0 0 3 0%23:59:47
9aioe 14654 3 14542 0 0 3 0%23:59:39
10news.samoylyk.net 16707 3 16620 0 0 11 0%23:59:48
11mb-net.net 17946 2 17894 0 0 61 0%23:59:48
12news-in.mixmin.net 16426 2 16389 0 0 54 0%23:59:48
13news.uzoreto.com 15476 1 15472 2 0 0 0%23:59:47
14neva.ru 16580 1 16566 2 0 0 0%23:59:48
15news.albasani.net 18549 0 15982 705 0 5886 0%23:59:42
16news.swapon.de 1668 0 1666 0 0 0 0%23:50:57
TOTAL: 16 250996 762 246561 731 0 6121 0%383:47:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org6.1 MB67.6 KB6.2 MB0.1 KB/s9.4 KB23:59:48
2awxg158.8 KB0.0 KB158.8 KB0.0 KB/s2.6 KB23:59:48
3news.snarked.org28.8 KB0.0 KB28.8 KB0.0 KB/s2.2 KB23:59:37
4eternal-september21.2 KB0.0 KB21.2 KB0.0 KB/s4.2 KB23:59:47
5news.freedyn.net15.7 KB0.0 KB15.7 KB0.0 KB/s3.9 KB23:59:47
6weretis.net14.8 KB11.8 KB26.6 KB0.0 KB/s3.0 KB23:59:48
7news.redatomik.org14.7 KB5.4 KB20.0 KB0.0 KB/s4.0 KB23:59:48
8csiph.com14.1 KB0.0 KB14.1 KB0.0 KB/s3.5 KB23:59:46
9aioe12.6 KB0.0 KB12.6 KB0.0 KB/s4.2 KB23:59:39
10news.samoylyk.net12.6 KB0.0 KB12.6 KB0.0 KB/s4.2 KB23:59:48
11mb-net.net7.8 KB0.0 KB7.8 KB0.0 KB/s3.9 KB23:59:48
12news-in.mixmin.net7.8 KB0.0 KB7.8 KB0.0 KB/s3.9 KB23:59:48
13news.uzoreto.com1.7 KB11.0 KB12.6 KB0.0 KB/s4.2 KB23:59:47
14neva.ru1.7 KB11.0 KB12.6 KB0.0 KB/s4.2 KB23:59:48
15news.albasani.net0.0 KB2.1 MB2.1 MB0.0 KB/s3.0 KB23:59:42
16news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:57
TOTAL: 166.4 MB2.2 MB8.6 MB0.0 KB/s5.9 KB383:47:26
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 388 532418.5 MB 3067 1 600:14:56
2vorlon.activateit.net.au 24 9751.3 MB 146 0 000:04:51
360.234.68.122 13 30116.5 KB 2 2 000:42:57
4amigacity.xyz 72 2040.2 KB 15 0 28800:12:58
5160-3-236-103.cpe.cableone.net 2 23.0 KB 1 0 029:19:19
TOTAL: 5 499 635120.0 MB 3231 3 29430:41:36

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 388 532418.5 MB 3067 1 600:14:56
2*.activateit.net.au 24 9751.3 MB 146 0 000:04:51
3unresolved 21 30116.5 KB 2 2 000:49:21
4*.xyz 72 2040.2 KB 15 0 28800:12:58
5*.cpe.cableone.net 2 23.0 KB 1 0 029:19:19
6*.utcc.utoronto.ca 1 00.0 KB 0 0 000:00:03
TOTAL: 6 508 635120.0 MB 3231 3 29430:41:36

NNRP auth users [Top 20]:

UserConn
amigacity 72
amybbs 24
crash 10
malcolmlewis 2
TOTAL: 4 108

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-236-103.cpe.cableone.net 0.204 0.136 0.00929:19:19
60.234.68.122 0.864 0.132 0.02100:42:57
agency.bbs.nz 29.668 6.688 0.00000:14:56
amigacity.xyz 4.016 0.860 0.38900:12:58
118.149.224.70 0.432 0.060 0.00000:06:23
vorlon.activateit.net.au 1.504 0.396 0.05200:04:51
? 1.184 0.168 0.00000:00:06
nut.utcc.utoronto.ca 0.048 0.008 0.00000:00:03
46.161.27.112 0.052 0.016 0.00000:00:00
TOTAL: 9 37.972 8.464 0.47130:41:36

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.224.70 7
nut.utcc.utoronto.ca 1
46.161.27.112 1
TOTAL: 3 9

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 2
46.161.27.112 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 2
#003 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
46.161.27.112 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1rec 2346 37.0%
2comp 809 12.8%
3soc 671 10.6%
4uk 542 8.6%
5linux 504 8.0%
6sci 494 7.8%
7alt 442 7.0%
8talk 214 3.4%
9misc 157 2.5%
10news 64 1.0%
11nz 33 0.5%
12mozilla 19 0.3%
13agency 16 0.3%
14microsoft 12 0.2%
15aus 12 0.2%
16humanities 2 0.0%
TOTAL: 16 6337100.0%

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

NewsgroupCount
1rec.arts.tv 506
2rec.arts.sf.written 320
3comp.os.linux.advocacy 301
4rec.arts.drwho 254
5uk.d-i-y 234
6alt.comp.os.windows-10 222
7linux.debian.bugs.dist 169
8rec.sport.football.college 152
9rec.food.cooking 140
10uk.legal 135
11rec.sport.tennis 120
12linux.debian.changes.devel 113
13soc.culture.polish 111
14rec.boats 106
15misc.survivalism 93
16sci.electronics.design 92
17sci.physics 90
18comp.lang.c++ 89
19talk.politics.guns 88
20uk.legal.moderated 85
21rec.sport.pro-wrestling 84
22sci.physics.relativity 78
23soc.retirement 77
24linux.debian.bugs.rc 70
25talk.origins 65
26alt.politics.trump 63
27soc.culture.china 61
28rec.music.classical.recordings 60
29soc.culture.israel 59
30sci.lang 56
31news.lists.filters 55
32alt.conspiracy.jfk 52
33soc.penpals 52
34rec.gambling.poker 51
35comp.lang.python 51
36uk.radio.amateur 51
37rec.arts.sf.tv 49
38alt.test 48
39soc.culture.brazil 47
40sci.math 47
41rec.arts.sf.fandom 47
42talk.politics.misc 43
43comp.ai.philosophy 42
44rec.boats.paddle.whitewater 38
45comp.mobile.android 37
46soc.culture.indian 37
47rec.sport.soccer 36
48linux.debian.user 34
49rec.aviation.soaring 34
50nz.general 33
51comp.os.vms 30
52linux.debian.devel 29
53soc.culture.usa 28
54soc.culture.greek 28
55sci.med.cardiology 28
56comp.lang.c 27
57misc.phone.mobile.iphone 26
58rec.gambling.lottery 26
59soc.history.living 26
60rec.bicycles.tech 24
61alt.games.warcraft 24
62rec.outdoors.rv-travel 24
63uk.comp.sys.mac 23
64comp.misc 19
65sci.med.pharmacy 19
66soc.culture.quebec 19
67sci.military.naval 19
68rec.audio.opinion 18
69comp.arch 17
70sci.logic 17
71agency.test 16
72comp.lang.fortran 15
73alt.bbs.synchronet 14
74rec.woodworking 13
75mozilla.support.thunderbird 13
76rec.autos.sport.f1 13
77comp.sys.mac.apps 13
78soc.genealogy.nordic 13
79rec.music.rock-pop-r+b.1950s 13
80rec.crafts.metalworking 13
81soc.history.war.misc 13
82rec.sport.golf 13
83linux.debian.l10n.portuguese 12
84rec.games.trivia 12
85rec.skiing.alpine 12
86linux.debian.user.german 12
87linux.debian.user.french 11
88rec.puzzles.crosswords 11
89soc.culture.japan 11
90sci.bio.paleontology 11
91soc.men 10
92comp.sys.apple2 10
93linux.debian.security 10
94rec.sport.cricket 10
95rec.radio.shortwave 10
96aus.rail 10
97comp.lang.tcl 10
98misc.invest.stocks 10
99rec.crafts.woodturning 9
100comp.databases.ingres 9
TOTAL: 288 6337