news.bbs.geek.nz daily logs

Jul 11 23:58:02 -- Jul 12 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 107792 54.8%8.6 MB 33.7%
innd 32401 16.5%5.2 MB 20.5%
inn 31744 16.1%6.9 MB 27.1%
innfeed 23422 11.9%4.5 MB 17.8%
cnfsstat 1440 0.7%250.1 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 196800 100.0%25.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 310815 49.0%
Positive hits 294056 46.3%
Do not exist 29497 4.6%
Cache misses 464 0.1%
TOTAL: 4 634832 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.166 0.0%149 0.000 1.114 36.000
article cleanup00:00:00.283 0.0%31142 0.000 0.009 0.039
article logging00:00:00.770 0.0%31588 0.000 0.024 0.059
article parse00:00:00.954 0.0%84885 0.000 0.011 0.028
article write00:00:01.685 0.0%22667 0.026 0.074 0.340
artlog/artcncl00:00:00.005 0.0%135 0.000 0.037 1.000
data move00:00:00.517 0.0%588474 0.000 0.001 0.008
hisgrep/artcncl00:00:00.000 0.0%146 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%149 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.312 0.0%644134 0.001 0.013 1.031
history sync00:00:00.011 0.0%350 0.000 0.031 0.500
history write00:00:07.742 0.0%29878 0.012 0.259 21.229
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:57:39.757 99.8%606419 85.827 142.244 754.490
nntp read00:00:03.266 0.0%607889 0.003 0.005 0.015
overview write00:00:41.894 0.0%22667 0.041 1.848 67.477
perl filter00:01:02.327 0.1%29878 1.360 2.086 4.837
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.367 0.0%45334 0.005 0.030 0.083
TOTAL: 24:00:48.77923:59:49.056 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.072 0.0%34488 0.000 0.002 0.018
article prepare00:00:00.000 0.0%11356 0.000 0.000 0.000
article read00:00:00.291 0.0%2642 0.000 0.110 10.467
backlog stats00:00:00.344 0.0%345356 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.669 0.0%288750 0.001 0.006 0.010
data write00:00:03.218 0.0%278309 0.006 0.012 0.019
idle23:52:38.694100.0%345356 122.735 248.899 825.070
status file00:00:15.582 0.0%2003 0.600 7.779 351.528
TOTAL: 23:53:17.07323:52:59.870100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%6 0.000 0.000 0.000
idle15:50:18.963 97.7%115290 0.000 494.570 85402.723
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.226 0.0%247839 0.000 0.005 0.333
readart00:00:03.126 0.0%9621 0.000 0.325 48.310
TOTAL: 16:12:25.13415:50:23.315 97.7%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 64 25073 5938 16136 2999 23%67:02:38
2news.redatomik.org 3 21566 5041 16419 106 23%24:49:42
3newsfeed.datemas.de 4 24005 3301 19972 732 13%25:33:37
4news.albasani.net 1 19469 1639 17100 730 8%20:35:05
5csiph.com 3 22340 1508 20730 102 6%24:46:48
6feeder.eternal-september.org 109 28695 1466 26481 748 5%67:29:07
7newsfeed.neva.ru 143 15008 1239 12529 1240 8%91:24:17
8news-out.mixmin.net 21 26438 985 24950 503 3%35:14:09
9news.freedyn.net 4 25524 487 24810 227 1%24:25:44
10news-out.aioe.org 3 19678 375 19223 80 1%20:21:41
11news.samoylyk.net 4 33228 267 32422 539 0%29:53:10
12news.ausics.org 1 166481 235 166180 66 0%208:17:40
13feeder.awxg.com 2 20549 150 20308 91 0%24:36:17
14news.swapon.de 4 2524 99 2399 26 3%35:08:51
15agency-nz-out.news.mb-net.net 15 23473 50 23311 112 0%30:29:07
16localhost 6 5 4 0 1 80%00:00:00
TOTAL: 16 387 474056 22784 442970 8302 4%730:07:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org25.2 MB678.4 KB24.0 MB49.8 MB 50%5.7 KB
2news.redatomik.org12.1 MB584.4 KB632.8 KB13.2 MB 91%2.6 KB
3newsfeed.datemas.de11.4 MB797.1 KB2.8 MB15.0 MB 76%3.8 KB
4feeder.eternal-september.org6.6 MB757.0 KB7.0 MB14.3 MB 46%6.6 KB
5news.albasani.net5.2 MB695.6 KB1.9 MB7.7 MB 66%3.3 KB
6news-out.mixmin.net4.6 MB383.0 KB1.3 MB6.3 MB 72%4.3 KB
7csiph.com3.8 MB412.9 KB437.0 KB4.7 MB 82%3.0 KB
8newsfeed.neva.ru3.6 MB187.2 KB5.4 MB9.3 MB 39%3.8 KB
9news.freedyn.net2.1 MB566.2 KB921.4 KB3.6 MB 59%5.1 KB
10news-out.aioe.org1.2 MB201.3 KB222.0 KB1.6 MB 74%3.7 KB
11news.samoylyk.net1.1 MB1.3 MB2.9 MB5.3 MB 20%6.7 KB
12news.ausics.org635.0 KB237.1 KB377.9 KB1.2 MB 50%4.2 KB
13feeder.awxg.com454.1 KB440.7 KB444.8 KB1.3 MB 33%5.6 KB
14news.swapon.de250.7 KB86.5 KB86.5 KB423.7 KB 59%3.4 KB
15agency-nz-out.news.mb-net.net131.8 KB501.9 KB1.2 MB1.8 MB 7%11.3 KB
16localhost8.3 KB0.0 KB2.0 KB10.3 KB 80%2.1 KB
TOTAL: 1678.3 MB7.7 MB49.4 MB135.4 MB 57%4.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 11 23:58:02 - 23:59:59 27 0.1% 0.2389.2 KB 0.1% 0.76
Jul 12 00:00:00 - 00:59:59 1089 4.8% 0.302.7 MB 3.5% 0.78
Jul 12 01:00:00 - 01:59:59 1314 5.8% 0.363.9 MB 5.0% 1.11
Jul 12 02:00:00 - 02:59:59 1431 6.3% 0.404.9 MB 6.3% 1.39
Jul 12 03:00:00 - 03:59:59 1390 6.1% 0.396.8 MB 8.8% 1.94
Jul 12 04:00:00 - 04:59:59 1250 5.5% 0.354.0 MB 5.1% 1.13
Jul 12 05:00:00 - 05:59:59 1210 5.3% 0.344.4 MB 5.7% 1.25
Jul 12 06:00:00 - 06:59:59 1201 5.3% 0.334.2 MB 5.4% 1.19
Jul 12 07:00:00 - 07:59:59 1240 5.5% 0.343.5 MB 4.5% 1.00
Jul 12 08:00:00 - 08:59:59 1229 5.4% 0.344.1 MB 5.3% 1.17
Jul 12 09:00:00 - 09:59:59 1164 5.1% 0.324.1 MB 5.2% 1.16
Jul 12 10:00:00 - 10:59:59 785 3.5% 0.222.8 MB 3.7% 0.81
Jul 12 11:00:00 - 11:59:59 642 2.8% 0.182.0 MB 2.6% 0.58
Jul 12 12:00:00 - 12:59:59 597 2.6% 0.171.9 MB 2.4% 0.53
Jul 12 13:00:00 - 13:59:59 534 2.4% 0.152.5 MB 3.2% 0.71
Jul 12 14:00:00 - 14:59:59 462 2.0% 0.131.9 MB 2.4% 0.53
Jul 12 15:00:00 - 15:59:59 428 1.9% 0.121.5 MB 1.9% 0.41
Jul 12 16:00:00 - 16:59:59 529 2.3% 0.152.4 MB 3.1% 0.69
Jul 12 17:00:00 - 17:59:59 615 2.7% 0.172.7 MB 3.5% 0.78
Jul 12 18:00:00 - 18:59:59 692 3.1% 0.192.9 MB 3.7% 0.81
Jul 12 19:00:00 - 19:59:59 837 3.7% 0.232.2 MB 2.8% 0.62
Jul 12 20:00:00 - 20:59:59 1064 4.7% 0.303.0 MB 3.8% 0.85
Jul 12 21:00:00 - 21:59:59 1050 4.6% 0.293.2 MB 4.2% 0.92
Jul 12 22:00:00 - 22:59:59 953 4.2% 0.262.5 MB 3.3% 0.72
Jul 12 23:00:00 - 23:58:02 951 4.2% 0.273.3 MB 4.2% 0.97
TOTAL: 24:00:00 22684 100.0% 0.2677.5 MB 100.0% 0.92
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB18.4 GB 36.7% 0 0.35 1713.58
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB21.4 GB 42.8% 0 0.40 1528.30
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB227.4 MB 0.4% 0 0.0512699.17
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 GB4.5 GB 9.1% 0 0.77 787.91
TOTAL: 10-500.0 GB44.5 GB 8.9% 1 0.50-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 3018 40 0 127 0 0 0 0 2851
2newsfeed.neva.ru 1253 31 0 53 0 0 0 0 1169
3feeder.eternal-september.org 855 0 0 162 0 0 0 0 693
4news.albasani.net 846 0 0 114 0 0 0 0 732
5newsfeed.datemas.de 817 178 0 97 0 0 0 0 542
6news.samoylyk.net 637 0 0 124 0 0 0 0 513
7news-out.mixmin.net 505 0 0 92 0 0 0 0 413
8news.redatomik.org 299 0 0 79 0 0 0 0 220
9news.freedyn.net 231 20 0 65 0 0 0 0 146
10agency-nz-out.news.mb-net.net 134 16 0 81 1 0 0 0 36
11csiph.com 107 0 0 93 0 0 0 0 14
12news-out.aioe.org 81 2 0 67 0 0 0 0 12
13feeder.awxg.com 79 1 0 76 0 0 0 0 2
14news.swapon.de 39 0 0 25 0 0 0 0 14
15news.ausics.org 23 0 0 9 0 0 0 0 14
16localhost 1 0 0 0 0 0 0 0 1
TOTAL: 16 8925 288 0 1264 1 0 0 0 7372

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.commerciali 42
it.annunci.varie 40
it.annunci.immobiliari 38
mozilla.support.seamonkey 29
mozilla.dev.platform 27
mpc.lists.nanog 16
gmane.linux.alsa.devel 16
mozilla.dev.security.policy 12
mpc.lists.freebsd.gnome 6
mpc.lists.freebsd.x11 5
mozilla.dev.developer-tools 4
mpc.lists.freebsd.gecko 4
eagle.support.eng 3
mozilla.support.bugzilla 3
mozilla.dev.l10n 2
mozilla.dev.ui-alerts 2
usc.forsale 2
mozilla.dev.media 2
gmane.comp.gis.openstreetmap.tagging 2
mozilla.release.engineering 2
TOTAL: 48 288

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
nz.politics.announce 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5846
EMP (phn path) 666
EMP (phn nph) 197
EMP (phl) 84
Too many newsgroups 47
User-issued cancel 29
Too many newsgroups (low_xpost) 18
Binary: non-binary distribution 16
Binary Image: misplaced jpg 10
EMP (fsl) 4
Too many newsgroups (meow) 3
HTML post 2
User-issued spam cancel 2
Binary: misplaced arj 1
TOTAL: 14 6925

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.redatomik.org 190
news.albasani.net 95
newsfeed.datemas.de 95
news-out.mixmin.net 23
news.swapon.de 14
news.snarked.org 10
newsfeed.neva.ru 8
news-out.aioe.org 4
news.samoylyk.net 4
feeder.eternal-september.org 2
TOTAL: 12 447
TOTAL: 1 447

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 21105 1111 19857 131 0 2 5%23:59:48
2awxg 22208 97 22033 7 0 439 0%23:59:46
3news.snarked.org 16783 64 16482 22 0 691 0%23:59:46
4news.freedyn.net 22602 47 22141 2 0 593 0%23:59:46
5news.samoylyk.net 20987 7 20872 1 0 733 0%23:59:39
6aioe 18848 5 18682 0 0 253 0%23:59:46
7neva.ru 20690 5 20678 0 0 0 0%23:59:46
8eternal-september 18815 4 18772 0 0 396 0%23:59:46
9csiph.com 21316 4 21228 0 0 442 0%23:59:46
10news.redatomik.org 17430 4 17416 0 0 313 0%23:59:46
11newsfeed.datemas.de 19313 3 19280 0 0 584 0%23:59:46
12mb-net.net 22676 3 22605 0 0 553 0%23:59:46
13news-in.mixmin.net 20488 2 20444 0 0 403 0%23:59:46
14news.albasani.net 22021 1 19185 1217 0 6583 0%23:59:46
15news.swapon.de 2032 0 2011 0 0 41 0%23:59:46
TOTAL: 15 287314 1357 281686 1380 0 12026 0%359:56:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org4.8 MB8.5 MB13.3 MB0.2 KB/s11.0 KB23:59:48
2awxg609.8 KB33.4 KB643.2 KB0.0 KB/s6.2 KB23:59:46
3news.snarked.org325.8 KB67.1 KB392.9 KB0.0 KB/s4.6 KB23:59:46
4news.freedyn.net168.3 KB7.6 KB175.9 KB0.0 KB/s3.6 KB23:59:46
5news.samoylyk.net15.1 KB35.8 KB50.9 KB0.0 KB/s6.4 KB23:59:39
6neva.ru11.7 KB0.0 KB11.7 KB0.0 KB/s2.3 KB23:59:46
7aioe10.8 KB0.0 KB10.8 KB0.0 KB/s2.2 KB23:59:46
8eternal-september8.3 KB0.0 KB8.3 KB0.0 KB/s2.1 KB23:59:46
9csiph.com8.3 KB0.0 KB8.3 KB0.0 KB/s2.1 KB23:59:46
10news.redatomik.org8.3 KB0.0 KB8.3 KB0.0 KB/s2.1 KB23:59:46
11newsfeed.datemas.de6.0 KB0.0 KB6.0 KB0.0 KB/s2.0 KB23:59:46
12mb-net.net5.5 KB0.0 KB5.5 KB0.0 KB/s1.8 KB23:59:46
13news-in.mixmin.net4.3 KB0.0 KB4.3 KB0.0 KB/s2.1 KB23:59:46
14news.albasani.net2.0 KB3.8 MB3.8 MB0.0 KB/s3.2 KB23:59:46
15news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
TOTAL: 156.0 MB12.4 MB18.4 MB0.0 KB/s6.9 KB359:56:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 354 695022.1 MB 3550 4 200:11:53
2163.47.16.169 23 12741.7 MB 149 0 000:05:27
360.234.244.55 6 1441.0 KB 1 0 000:20:24
TOTAL: 3 383 823823.9 MB 3700 4 216:12:18

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 354 695022.1 MB 3550 4 200:11:53
2unresolved 38 12881.8 MB 150 0 000:45:18
3*.cpe.cableone.net 3 00.0 KB 0 0 015:13:24
4*.208-100-26.static.steadfastdns.net 1 00.0 KB 0 0 000:00:01
TOTAL: 4 396 823823.9 MB 3700 4 216:12:18

NNRP auth users [Top 20]:

UserConn
amybbs 23
crash 4
malcolmlewis 3
TOTAL: 3 30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-61-218-236.cpe.cableone.net 0.192 0.060 0.00715:13:24
60.234.244.55 0.408 0.060 0.01100:20:24
118.149.241.237 0.216 0.040 0.00000:14:17
agency.bbs.geek.nz 25.364 6.092 0.00000:11:53
163.47.16.169 1.312 0.404 0.18500:05:27
118.149.216.142 0.060 0.012 0.00000:05:01
? 1.856 0.188 0.00000:01:40
203.118.151.117 0.080 0.008 0.00000:00:07
92.42.107.186 0.172 0.020 0.00000:00:01
ip237.208-100-26.static.steadfastdns.net 0.068 0.004 0.00000:00:01
TOTAL: 10 29.728 6.888 0.20316:12:18

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.241.237 4
67-61-218-236.cpe.cableone.net 3
92.42.107.186 3
203.118.151.117 1
ip237.208-100-26.static.steadfastdns.net 1
118.149.216.142 1
TOTAL: 6 13

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
92.42.107.186 7
60.234.244.55 1
TOTAL: 2 8

NNRP unrecognized commands (by command):

CommandCount
À 2
#026#003#001 2
Host: 219.89.83.33:119 1
XFEATURE COMPRESS GZIP TERMINATOR 1
GET / HTTP/1.1 1
Connection: Keep-Alive 1
TOTAL: 6 8

NNRP client timeouts [Top 20]:

SystemConnPeer
92.42.107.186 2 2
67-61-218-236.cpe.cableone.net 1 0
TOTAL: 2 3 2

Newsgroup request counts (by category):

CategoryCountPct
1rec 3505 42.6%
2comp 1359 16.5%
3soc 898 10.9%
4sci 879 10.7%
5alt 818 9.9%
6talk 234 2.8%
7misc 196 2.4%
8news 157 1.9%
9uk 98 1.2%
10nz 38 0.5%
11aus 23 0.3%
12humanities 17 0.2%
13agency 8 0.1%
14eternal-september 2 0.0%
TOTAL: 14 8232100.0%

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

NewsgroupCount
1rec.arts.tv 864
2alt.comp.os.windows-10 677
3rec.sport.tennis 416
4rec.arts.drwho 349
5rec.arts.sf.written 302
6comp.lang.c 270
7comp.lang.python 260
8sci.physics 207
9rec.bicycles.tech 165
10sci.electronics.design 164
11rec.food.cooking 162
12comp.os.linux.advocacy 160
13talk.politics.misc 159
14soc.retirement 157
15rec.sport.golf 154
16soc.culture.african.american 150
17soc.culture.israel 145
18rec.sport.football.college 124
19soc.culture.polish 116
20alt.test 103
21sci.physics.relativity 99
22rec.arts.sf.fandom 83
23uk.radio.amateur 80
24news.lists.filters 71
25talk.politics.guns 70
26news.admin.net-abuse.email 68
27comp.lang.c++ 67
28misc.survivalism 67
29misc.test 64
30sci.med.pharmacy 63
31sci.math 61
32comp.os.vms 58
33comp.sys.raspberry-pi 58
34rec.gambling.poker 56
35comp.sys.apple2 55
36soc.culture.china 53
37rec.autos.sport.f1 50
38sci.logic 48
39rec.aviation.soaring 47
40soc.culture.singapore 45
41sci.math.num-analysis 45
42sci.military.naval 44
43rec.photo.digital 41
44rec.sport.pro-wrestling 39
45rec.boats 38
46comp.sys.mac.system 38
47rec.woodworking 36
48sci.math.symbolic 36
49comp.os.linux.misc 34
50rec.crafts.metalworking 34
51soc.penpals 34
52comp.protocols.time.ntp 32
53rec.outdoors.rv-travel 28
54rec.sport.soccer 28
55rec.skiing.alpine 27
56rec.music.rock-pop-r+b.1950s 26
57alt.os.linux.ubuntu 26
58rec.audio.pro 26
59soc.culture.vietnamese 26
60nz.general 26
61comp.lang.ada 25
62comp.lang.forth 25
63soc.culture.indian 22
64rec.radio.amateur.homebrew 22
65comp.soft-sys.matlab 22
66soc.culture.usa 22
67comp.mobile.ipad 22
68sci.astro.amateur 22
69rec.music.dylan 22
70rec.arts.movies.past-films 20
71rec.arts.tv.uk.coronation-st 18
72comp.lang.fortran 18
73comp.lang.lisp 18
74rec.games.pinball 17
75misc.immigration.usa 17
76rec.arts.comics.strips 16
77rec.games.trivia 16
78comp.lang.java.programmer 16
79misc.health.alternative 16
80comp.soft-sys.sas 15
81rec.radio.amateur.dx 15
82rec.audio.opinion 15
83comp.mobile.android 15
84sci.electronics.basics 15
85soc.culture.brazil 15
86sci.space.policy 14
87rec.radio.amateur.antenna 14
88rec.radio.shortwave 14
89soc.history.medieval 14
90rec.arts.sf.science 13
91rec.arts.movies.current-films 13
92humanities.lit.authors.shakespeare 13
93comp.arch 12
94comp.protocols.dns.bind 12
95soc.genealogy.jewish 12
96soc.genealogy.medieval 12
97rec.arts.tv.soaps.cbs 10
98aus.electronics 10
99rec.music.beatles 10
100rec.arts.disney.parks 10
TOTAL: 296 8232