news.bbs.nz daily logs

Sep 1 23:58:01 -- Sep 2 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 124256 60.1%9.6 MB 36.9%
innd 34434 16.7%5.5 MB 21.2%
innfeed 26226 12.7%5.3 MB 20.4%
inn 20344 9.8%5.3 MB 20.5%
cnfsstat 1440 0.7%255.8 KB 1.0%
controlchan 5 0.0%0.7 KB 0.0%
TOTAL: 6 206705 100.0%26.0 MB100.0%

History cache:

ReasonCount%Count
Negative hits 272726 48.4%
Positive hits 264895 47.0%
Do not exist 19625 3.5%
Cache misses 6221 1.1%
TOTAL: 4 563467 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.619 0.0%341 0.000 1.815 92.000
article cleanup00:00:00.198 0.0%19858 0.000 0.010 0.041
article logging00:00:00.615 0.0%20004 0.000 0.031 0.330
article parse00:00:00.649 0.0%62641 0.000 0.010 0.031
article write00:00:04.244 0.0%16267 0.000 0.261 21.090
artlog/artcncl00:00:00.004 0.0%341 0.000 0.012 0.013
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.323 0.0%542962 0.000 0.001 0.006
hisgrep/artcncl00:00:00.018 0.0%339 0.000 0.053 14.000
hishave/artcncl00:00:00.003 0.0%341 0.000 0.009 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.430 0.0%565356 0.001 0.017 0.809
history sync00:00:03.012 0.0%373 0.000 8.075 852.333
history write00:00:05.891 0.0%19695 0.006 0.299 17.353
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:57:44.953 99.8%553952 70.018 155.726 479.706
nntp read00:00:03.028 0.0%554588 0.002 0.005 0.015
overview write00:00:53.073 0.1%16267 0.048 3.263 119.424
perl filter00:00:40.027 0.0%19695 1.260 2.032 6.496
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.851 0.0%32535 0.000 0.026 0.097
TOTAL: 24:00:58.09823:59:46.938 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.074 0.0%30209 0.000 0.002 0.029
article prepare00:00:00.000 0.0%7928 0.000 0.000 0.000
article read00:00:00.269 0.0%1840 0.000 0.146 8.316
backlog stats00:00:00.287 0.0%304212 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.533 0.0%250340 0.001 0.006 0.012
data write00:00:03.171 0.0%236603 0.006 0.013 0.125
idle24:05:22.898100.0%304212 113.498 285.074 651.079
status file00:00:07.285 0.0%1921 0.900 3.792 224.857
TOTAL: 24:05:52.69824:05:35.517100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%15 0.000 0.000 0.000
idle37:30:16.621 99.6%131031 0.000 1030.417 75209.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.401 0.0%275779 0.000 0.005 0.333
readart00:00:01.744 0.0%6626 0.000 0.263 14.500
TOTAL: 37:40:06.75437:30:19.766 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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001011
TOTAL001011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1bbs-nz-out.news.weretis.net 3 31971 3669 28144 158 11%46:10:38
2bbsnz-out.feed.uzoreto.com 1119 25464 2930 21470 1064 11%95:35:05
3news.snarked.org 31 18421 2318 14901 1202 12%31:50:23
4feeder.eternal-september.org 4 17812 1958 15687 167 10%23:56:35
5news-out.aioe.org 2 15629 1632 13984 13 10%23:41:12
6news-out.mixmin.net 2 18291 1297 16508 486 7%24:59:48
7newsfeed.neva.ru 145 9557 755 8714 88 7%72:02:00
8news.redatomik.org 2 15783 446 15309 28 2%24:00:00
9news.albasani.net 1 29150 374 28635 141 1%39:08:04
10csiph.com 1 16147 189 15951 7 1%23:59:56
11newsfeed.datemas.de 2 17253 157 17026 70 0%23:59:25
12news.swapon.de 5 1581 141 1437 3 8%21:30:05
13agency-nz-out.news.mb-net.net 4 16777 58 16699 20 0%23:23:50
14news.samoylyk.net 3 15999 24 15970 5 0%13:16:27
15feeder.awxg.com 2 13362 21 13334 7 0%23:59:54
16news.freedyn.net 1 17833 6 17822 5 0%23:58:40
17localhost 2 1 1 0 0 100%00:00:00
TOTAL: 17 1329 281031 15976 261591 3464 5%535:32:02
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net17.5 MB147.9 KB858.2 KB18.5 MB 94%5.0 KB
2bbsnz-out.feed.uzoreto.com8.8 MB56.2 KB8.5 MB17.3 MB 50%4.4 KB
3news.snarked.org7.8 MB110.8 KB10.5 MB18.5 MB 42%5.4 KB
4feeder.eternal-september.org5.9 MB71.8 KB467.4 KB6.4 MB 91%3.1 KB
5news-out.mixmin.net4.4 MB50.9 KB1004.0 KB5.4 MB 80%3.1 KB
6news-out.aioe.org4.1 MB11.1 KB67.6 KB4.1 MB 98%2.6 KB
7newsfeed.neva.ru2.3 MB5.5 KB242.7 KB2.5 MB 90%3.1 KB
8news.albasani.net1.7 MB63.2 KB489.5 KB2.3 MB 76%4.5 KB
9news.redatomik.org1.3 MB31.5 KB60.5 KB1.4 MB 93%2.9 KB
10newsfeed.datemas.de498.7 KB39.0 KB1.1 MB1.6 MB 30%7.2 KB
11csiph.com465.5 KB17.7 KB17.7 KB500.9 KB 92%2.6 KB
12news.swapon.de333.6 KB2.9 KB8.5 KB345.0 KB 96%2.4 KB
13agency-nz-out.news.mb-net.net168.2 KB32.2 KB69.3 KB269.7 KB 62%3.5 KB
14feeder.awxg.com113.6 KB22.1 KB22.1 KB157.8 KB 72%5.6 KB
15news.samoylyk.net56.6 KB10.8 KB15.6 KB83.0 KB 68%2.9 KB
16news.freedyn.net19.7 KB6.8 KB13.4 KB39.9 KB 49%3.6 KB
17localhost1.0 KB0.0 KB0.0 KB1.0 KB100%1.0 KB
TOTAL: 1755.4 MB680.4 KB23.4 MB79.4 MB 69%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 1 23:58:01 - 23:59:59 26 0.2% 0.2288.0 KB 0.2% 0.74
Sep 2 00:00:00 - 00:59:59 809 5.0% 0.222.2 MB 4.0% 0.63
Sep 2 01:00:00 - 01:59:59 909 5.6% 0.253.1 MB 5.6% 0.89
Sep 2 02:00:00 - 02:59:59 979 6.0% 0.272.6 MB 4.7% 0.75
Sep 2 03:00:00 - 03:59:59 919 5.7% 0.262.9 MB 5.3% 0.84
Sep 2 04:00:00 - 04:59:59 999 6.1% 0.283.8 MB 6.8% 1.08
Sep 2 05:00:00 - 05:59:59 917 5.6% 0.252.5 MB 4.5% 0.72
Sep 2 06:00:00 - 06:59:59 861 5.3% 0.242.6 MB 4.7% 0.74
Sep 2 07:00:00 - 07:59:59 813 5.0% 0.232.2 MB 4.0% 0.64
Sep 2 08:00:00 - 08:59:59 921 5.7% 0.263.2 MB 5.7% 0.90
Sep 2 09:00:00 - 09:59:59 625 3.8% 0.172.0 MB 3.6% 0.58
Sep 2 10:00:00 - 10:59:59 569 3.5% 0.161.7 MB 3.0% 0.48
Sep 2 11:00:00 - 11:59:59 472 2.9% 0.131.3 MB 2.3% 0.37
Sep 2 12:00:00 - 12:59:59 386 2.4% 0.111.0 MB 1.9% 0.30
Sep 2 13:00:00 - 13:59:59 525 3.2% 0.151.9 MB 3.4% 0.54
Sep 2 14:00:00 - 14:59:59 389 2.4% 0.112.3 MB 4.2% 0.66
Sep 2 15:00:00 - 15:59:59 364 2.2% 0.102.5 MB 4.5% 0.71
Sep 2 16:00:00 - 16:59:59 606 3.7% 0.174.2 MB 7.4% 1.18
Sep 2 17:00:00 - 17:59:59 345 2.1% 0.101.5 MB 2.7% 0.44
Sep 2 18:00:00 - 18:59:59 464 2.9% 0.131.5 MB 2.6% 0.42
Sep 2 19:00:00 - 19:59:59 603 3.7% 0.171.9 MB 3.5% 0.55
Sep 2 20:00:00 - 20:59:59 682 4.2% 0.192.1 MB 3.8% 0.60
Sep 2 21:00:00 - 21:59:59 642 3.9% 0.182.0 MB 3.5% 0.56
Sep 2 22:00:00 - 22:59:59 713 4.4% 0.202.2 MB 3.9% 0.62
Sep 2 23:00:00 - 23:58:01 724 4.5% 0.212.4 MB 4.2% 0.69
TOTAL: 24:00:00 16262 100.0% 0.1955.9 MB 100.0% 0.66
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB28.2 GB 56.3% 0 0.28 2154.47
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB32.5 GB 64.9% 0 0.28 2203.49
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB359.2 MB 0.7% 0 0.0235565.42
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.4 GB 52.8% 0 0.55 1102.44
TOTAL: 10-500.0 GB87.4 GB 17.5% 1 0.37-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 1306 1 0 7 0 0 0 0 1298
2bbsnz-out.feed.uzoreto.com 1077 18 0 10 0 0 0 0 1049
3news-out.mixmin.net 515 0 0 12 0 0 0 0 503
4bbs-nz-out.news.weretis.net 209 35 0 39 0 0 0 0 135
5news.albasani.net 180 1 0 9 0 0 0 0 170
6feeder.eternal-september.org 179 0 0 19 0 0 0 0 160
7newsfeed.neva.ru 88 7 0 3 0 0 0 0 78
8newsfeed.datemas.de 41 0 0 13 0 0 0 0 28
9news.redatomik.org 41 0 0 11 0 0 0 0 30
10news.swapon.de 37 0 0 2 0 0 0 0 35
11agency-nz-out.news.mb-net.net 19 1 0 9 0 0 0 0 9
12news-out.aioe.org 18 10 0 5 0 0 0 0 3
13feeder.awxg.com 10 0 0 9 0 0 0 0 1
14csiph.com 8 0 0 8 0 0 0 0 0
15news.samoylyk.net 8 0 0 5 0 0 0 0 3
16news.freedyn.net 5 0 0 2 0 0 0 0 3
TOTAL: 16 3741 73 0 163 0 0 0 0 3505

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.comp.gis.openstreetmap.tagging 23
mozilla.support.seamonkey 16
mozilla.dev.l10n 6
mozilla.support.calendar 4
mozilla.dev.ui-alerts 3
gmane.linux.alsa.devel 2
nemo.abuse 2
al.christnet.bible 2
mozilla.support.bugzilla 2
sonoma.general 1
rec.photo.equipment.nikon 1
mozilla.community.indonesia 1
mozilla.join 1
alt.fan.john 1
a.bsu.religion 1
eagle.support.eng 1
alt.religion.christian.romman-catholic 1
corel.graphic_apps.draw10-import-export 1
isp.test 1
demos.local.statistics 1
TOTAL: 22 73

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2788
EMP (phn path) 338
EMP (phl) 83
Too many newsgroups 39
User-issued cancel 36
Binary: non-binary distribution 32
Binary: misplaced par2 18
EMP (phn nph) 18
User-issued spam cancel 6
Binary: misplaced rar 1
Binary Image: misplaced jpg 1
TOTAL: 11 3360

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
bbs-nz-out.news.weretis.net 38
news.swapon.de 33
news-out.mixmin.net 28
news.redatomik.org 14
news.albasani.net 13
bbsnz-out.feed.uzoreto.com 11
news-out.aioe.org 3
newsfeed.datemas.de 2
agency-nz-out.news.mb-net.net 1
news.snarked.org 1
TOTAL: 11 145
TOTAL: 1 145

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 14440 948 13468 24 0 39 6%23:59:48
2news.freedyn.net 16476 70 16176 7 0 391 0%23:59:43
3awxg 15759 57 15690 3 0 64 0%23:59:48
4news.redatomik.org 15959 22 15757 9 0 383 0%23:59:47
5news.snarked.org 13906 8 13800 7 0 84 0%23:59:48
6news.samoylyk.net 18874 7 18700 5 0 1808 0%23:57:42
7weretis.net 12043 4 11876 4 0 25 0%23:59:48
8eternal-september 14002 3 13946 0 0 0 0%23:59:47
9neva.ru 15297 2 15291 0 0 0 0%23:59:48
10aioe 13724 2 13660 0 0 12 0%23:59:48
11news.uzoreto.com 13282 1 13281 0 0 57 0%23:59:47
12news-in.mixmin.net 14748 1 14738 0 0 40 0%23:59:48
13newsfeed.datemas.de 16221 1 16198 1 0 163 0%23:59:48
14mb-net.net 16240 1 16192 0 0 80 0%23:59:48
15csiph.com 16118 1 16070 0 0 26 0%23:59:43
16news.albasani.net 17947 0 14998 669 0 6426 0%23:59:48
17news.swapon.de 1473 0 1472 0 0 0 0%23:49:04
TOTAL: 17 246509 1128 241313 729 0 9598 0%407:43:33
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org6.7 MB311.9 KB7.0 MB0.1 KB/s7.4 KB23:59:48
2news.freedyn.net320.6 KB24.3 KB344.8 KB0.0 KB/s4.5 KB23:59:43
3awxg268.4 KB18.1 KB286.5 KB0.0 KB/s4.8 KB23:59:48
4news.samoylyk.net87.9 KB11.3 KB99.2 KB0.0 KB/s8.3 KB23:57:42
5news.redatomik.org74.1 KB43.6 KB117.6 KB0.0 KB/s3.8 KB23:59:47
6news.snarked.org26.0 KB12.0 KB38.0 KB0.0 KB/s2.5 KB23:59:48
7weretis.net7.5 KB11.5 KB19.0 KB0.0 KB/s2.4 KB23:59:48
8eternal-september3.7 KB0.0 KB3.7 KB0.0 KB/s1.2 KB23:59:47
9aioe3.2 KB0.0 KB3.2 KB0.0 KB/s1.6 KB23:59:48
10neva.ru2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:48
11news.uzoreto.com1.0 KB0.0 KB1.0 KB0.0 KB/s1.0 KB23:59:47
12news-in.mixmin.net1.0 KB0.0 KB1.0 KB0.0 KB/s1.0 KB23:59:48
13newsfeed.datemas.de1.0 KB6.6 KB7.6 KB0.0 KB/s3.8 KB23:59:48
14mb-net.net1.0 KB0.0 KB1.0 KB0.0 KB/s1.0 KB23:59:48
15csiph.com1.0 KB0.0 KB1.0 KB0.0 KB/s1.0 KB23:59:43
16news.albasani.net0.0 KB3.6 MB3.6 MB0.0 KB/s5.5 KB23:59:48
17news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:49:04
TOTAL: 177.5 MB4.0 MB11.5 MB0.0 KB/s6.4 KB407:43:33
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 408 488418.7 MB 2993 1 9600:17:22
2vorlon.activateit.net.au 24 10691.4 MB 138 0 000:05:17
3192.168.1.7 1 244857.5 KB 6 0 000:00:07
460.234.68.122 10 2874.6 KB 2 0 000:30:03
5160-3-245-118.cpe.cableone.net 3 524.4 KB 3 0 036:38:54
TOTAL: 5 446 623021.0 MB 3142 1 9637:40:00

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 408 488418.7 MB 2993 1 9600:17:22
2*.activateit.net.au 24 10691.4 MB 138 0 000:05:17
3unresolved 19 272932.1 KB 8 0 000:38:23
4*.cpe.cableone.net 3 524.4 KB 3 0 036:38:54
TOTAL: 4 454 623021.0 MB 3142 1 9637:40:00

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 8
malcolmlewis 3
paulh 1
TOTAL: 4 36

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-245-118.cpe.cableone.net 0.384 0.112 0.01236:38:54
60.234.68.122 0.684 0.096 0.02300:30:03
agency.bbs.nz 28.644 6.452 0.00000:17:22
118.149.133.157 0.468 0.092 0.00000:08:12
vorlon.activateit.net.au 1.396 0.364 0.12800:05:17
192.168.1.7 0.136 0.036 0.00300:00:07
? 1.624 0.192 0.00000:00:02
TOTAL: 7 33.336 7.344 0.16637:40:00

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.133.157 8
TOTAL: 1 8

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 2
TOTAL: 1 2

Newsgroup request counts (by category):

CategoryCountPct
1rec 2467 39.7%
2comp 803 12.9%
3sci 633 10.2%
4soc 510 8.2%
5alt 427 6.9%
6uk 420 6.8%
7linux 316 5.1%
8misc 251 4.0%
9nz 148 2.4%
10talk 135 2.2%
11news 48 0.8%
12mozilla 22 0.4%
13agency 13 0.2%
14microsoft 12 0.2%
15humanities 7 0.1%
16aus 3 0.0%
TOTAL: 16 6215100.0%

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

NewsgroupCount
1rec.arts.tv 850
2rec.arts.drwho 317
3uk.d-i-y 226
4rec.sport.tennis 198
5comp.lang.python 182
6rec.food.cooking 177
7alt.comp.os.windows-10 162
8linux.debian.bugs.dist 130
9sci.physics 129
10comp.lang.c 109
11nz.general 109
12misc.survivalism 100
13comp.lang.c++ 99
14rec.sport.football.college 98
15uk.legal 97
16alt.politics.trump 97
17rec.arts.sf.written 96
18sci.physics.relativity 91
19rec.drugs.cannabis 90
20soc.culture.israel 89
21sci.med.cardiology 86
22soc.retirement 84
23rec.sport.pro-wrestling 82
24sci.logic 79
25sci.math 67
26comp.os.linux.advocacy 67
27uk.radio.amateur 65
28soc.culture.china 64
29comp.mobile.android 64
30linux.debian.changes.devel 60
31soc.culture.polish 59
32talk.politics.guns 59
33sci.electronics.design 48
34rec.outdoors.rv-travel 45
35talk.origins 43
36misc.invest.stocks 41
37alt.test 40
38linux.debian.bugs.rc 38
39rec.gambling.poker 35
40alt.os.linux.ubuntu 34
41comp.lang.tcl 33
42comp.lang.forth 32
43misc.phone.mobile.iphone 31
44rec.music.classical.recordings 31
45news.lists.filters 29
46rec.autos.sport.f1 27
47rec.aviation.soaring 26
48sci.anthropology.paleo 26
49soc.penpals 26
50comp.os.vms 25
51rec.bicycles.tech 25
52rec.arts.poems 24
53rec.music.rock-pop-r+b.1950s 23
54comp.os.linux.misc 23
55alt.conspiracy 22
56misc.health.alternative 21
57nz.comp 21
58misc.facts.straight-dope 21
59soc.culture.indian 20
60sci.military.naval 20
61rec.audio.opinion 19
62rec.boats 19
63comp.lang.misc 19
64soc.culture.brazil 18
65alt.conspiracy.jfk 18
66sci.lang 18
67talk.bizarre 18
68nz.tech 16
69soc.culture.usa 16
70soc.genealogy.britain 16
71rec.sport.soccer 16
72sci.bio.paleontology 15
73rec.photo.digital 15
74linux.debian.user 15
75alt.games.warcraft 14
76talk.politics.misc 14
77linux.debian.l10n.german 14
78mozilla.support.thunderbird 14
79comp.ai.philosophy 13
80sci.math.num-analysis 13
81comp.arch 13
82agency.test 13
83alt.books.reviews 12
84soc.genealogy.medieval 12
85soc.history.living 12
86rec.nude 12
87rec.games.pinball 12
88soc.culture.vietnamese 10
89uk.comp.sys.mac 10
90rec.food.marketplace 10
91news.software.readers 10
92comp.sys.ibm.pc.games.action 10
93soc.culture.quebec 10
94rec.sport.billiard 10
95rec.music.dylan 10
96rec.radio.amateur.misc 9
97linux.debian.user.french 9
98rec.arts.disney.parks 9
99rec.radio.shortwave 9
100comp.sys.apple2 8
TOTAL: 333 6215