news.bbs.geek.nz daily logs

Dec 5 23:58:01 -- Dec 6 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 150165 63.3%12.4 MB 42.9%
innd 32280 13.6%5.2 MB 17.9%
inn 30177 12.7%6.5 MB 22.5%
innfeed 23159 9.8%4.6 MB 15.9%
cnfsstat 1440 0.6%254.5 KB 0.9%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 237222 100.0%28.9 MB100.0%

History cache:

ReasonCount%Count
Negative hits 290474 49.6%
Positive hits 260768 44.5%
Do not exist 28306 4.8%
Cache misses 6341 1.1%
TOTAL: 4 585889 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.494 0.0%160 0.000 3.087 54.000
article cleanup00:00:00.281 0.0%29362 0.000 0.010 0.029
article logging00:00:00.761 0.0%30027 0.000 0.025 0.127
article parse00:00:00.912 0.0%80126 0.000 0.011 0.028
article write00:00:01.568 0.0%20539 0.000 0.076 0.909
artlog/artcncl00:00:00.002 0.0%157 0.000 0.013 0.500
data move00:00:00.406 0.0%554801 0.000 0.001 0.008
hisgrep/artcncl00:00:00.135 0.0%152 0.000 0.888 10.231
hishave/artcncl00:00:00.079 0.0%160 0.000 0.494 6.077
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.260 0.0%587068 0.001 0.011 0.549
history sync00:00:00.011 0.0%371 0.000 0.030 0.667
history write00:00:06.381 0.0%28348 0.018 0.225 15.552
hiswrite/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
idle23:58:01.548 99.8%571618 85.761 150.943 402.396
nntp read00:00:03.527 0.0%573487 0.002 0.006 0.018
overview write00:00:34.195 0.0%20539 0.052 1.665 30.885
perl filter00:00:54.271 0.1%28348 1.428 1.914 6.679
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.325 0.0%41078 0.004 0.032 0.115
TOTAL: 24:00:48.87823:59:52.156 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.063 0.0%26799 0.000 0.002 0.019
article prepare00:00:00.001 0.0%8188 0.000 0.000 0.009
article read00:00:00.313 0.0%1915 0.000 0.163 8.000
backlog stats00:00:00.332 0.0%324487 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.488 0.0%273179 0.002 0.005 0.010
data write00:00:02.998 0.0%258450 0.006 0.012 0.018
idle23:55:39.227100.0%324487 153.669 265.463 565.581
status file00:00:12.746 0.0%1861 0.769 6.849 220.000
TOTAL: 23:56:13.30123:55:57.168100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle32:56:31.399 98.7%168183 0.000 705.133 90446.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:13.655 0.1%374041 0.000 0.197 2.000
readart00:00:01.424 0.0%17871 0.000 0.080 9.000
TOTAL: 33:22:00.79332:57:46.478 98.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
1news.snarked.org 35 15946 5245 10405 296 32%37:47:12
2news-out.mixmin.net 13 22195 3254 18163 778 14%27:21:31
3newsfeed.datemas.de 3 21124 2720 17953 451 12%23:54:45
4news.albasani.net 1 21596 2360 18613 623 10%24:08:10
5feeder.eternal-september.org 59 30685 1896 27379 1410 6%47:16:30
6newsfeed.neva.ru 157 15248 1324 9779 4145 8%81:08:26
7news-out.aioe.org 4 20058 1207 18791 60 6%24:11:47
8news.redatomik.org 1 18326 1142 17107 77 6%22:58:20
9csiph.com 3 16579 708 15825 46 4%18:33:52
10news.swapon.de 5 1773 171 1593 9 9%25:41:16
11news.freedyn.net 2 34235 118 34000 117 0%34:14:46
12agency-nz-out.news.mb-net.net 15 23066 115 22814 137 0%28:31:04
13feeder.awxg.com 1 17770 98 17617 55 0%23:59:57
14news.samoylyk.net 2 22701 71 22218 412 0%18:25:13
15localhost 1 0 0 0 0 0%00:00:00
TOTAL: 15 302 281302 20429 252257 8616 7%438:12:49
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org16.9 MB414.3 KB1.5 MB18.7 MB 90%3.5 KB
2newsfeed.datemas.de12.5 MB415.2 KB1.9 MB14.8 MB 84%4.8 KB
3news-out.mixmin.net9.4 MB503.9 KB2.4 MB12.2 MB 76%3.1 KB
4news.albasani.net9.0 MB469.6 KB1.6 MB11.1 MB 81%3.8 KB
5feeder.eternal-september.org7.3 MB857.8 KB4.8 MB12.9 MB 56%4.0 KB
6newsfeed.neva.ru3.2 MB278.1 KB23.7 MB27.2 MB 11%5.1 KB
7news-out.aioe.org3.0 MB242.2 KB247.0 KB3.5 MB 86%2.8 KB
8news.redatomik.org2.7 MB448.3 KB454.2 KB3.6 MB 75%3.0 KB
9csiph.com2.0 MB232.0 KB238.6 KB2.5 MB 81%3.4 KB
10news.freedyn.net836.5 KB928.4 KB1.0 MB2.8 MB 29%12.0 KB
11agency-nz-out.news.mb-net.net619.6 KB344.8 KB844.3 KB1.8 MB 34%7.2 KB
12news.swapon.de479.6 KB9.3 KB427.8 KB916.7 KB 52%5.1 KB
13feeder.awxg.com295.4 KB291.0 KB291.7 KB878.1 KB 33%5.7 KB
14news.samoylyk.net222.2 KB392.8 KB4.6 MB5.2 MB 4%11.1 KB
15localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1568.3 MB5.7 MB43.9 MB117.9 MB 57%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 5 23:58:01 - 23:59:59 28 0.1% 0.2466.2 KB 0.1% 0.56
Dec 6 00:00:00 - 00:59:59 861 4.2% 0.242.2 MB 3.2% 0.63
Dec 6 01:00:00 - 01:59:59 998 4.9% 0.283.1 MB 4.6% 0.89
Dec 6 02:00:00 - 02:59:59 952 4.6% 0.262.4 MB 3.5% 0.68
Dec 6 03:00:00 - 03:59:59 1013 4.9% 0.283.7 MB 5.4% 1.06
Dec 6 04:00:00 - 04:59:59 1140 5.6% 0.323.0 MB 4.4% 0.86
Dec 6 05:00:00 - 05:59:59 1182 5.8% 0.334.3 MB 6.3% 1.22
Dec 6 06:00:00 - 06:59:59 1405 6.8% 0.394.2 MB 6.1% 1.20
Dec 6 07:00:00 - 07:59:59 1196 5.8% 0.333.4 MB 5.0% 0.98
Dec 6 08:00:00 - 08:59:59 1146 5.6% 0.323.9 MB 5.7% 1.12
Dec 6 09:00:00 - 09:59:59 1106 5.4% 0.313.5 MB 5.1% 0.99
Dec 6 10:00:00 - 10:59:59 1153 5.6% 0.323.4 MB 5.0% 0.98
Dec 6 11:00:00 - 11:59:59 858 4.2% 0.242.3 MB 3.3% 0.65
Dec 6 12:00:00 - 12:59:59 694 3.4% 0.192.0 MB 2.9% 0.57
Dec 6 13:00:00 - 13:59:59 676 3.3% 0.192.6 MB 3.8% 0.75
Dec 6 14:00:00 - 14:59:59 623 3.0% 0.172.3 MB 3.4% 0.67
Dec 6 15:00:00 - 15:59:59 426 2.1% 0.121.3 MB 1.9% 0.38
Dec 6 16:00:00 - 16:59:59 473 2.3% 0.132.2 MB 3.2% 0.63
Dec 6 17:00:00 - 17:59:59 450 2.2% 0.122.9 MB 4.3% 0.84
Dec 6 18:00:00 - 18:59:59 431 2.1% 0.123.2 MB 4.6% 0.90
Dec 6 19:00:00 - 19:59:59 533 2.6% 0.152.1 MB 3.1% 0.61
Dec 6 20:00:00 - 20:59:59 664 3.2% 0.182.4 MB 3.5% 0.69
Dec 6 21:00:00 - 21:59:59 700 3.4% 0.191.8 MB 2.6% 0.51
Dec 6 22:00:00 - 22:59:59 932 4.5% 0.263.1 MB 4.5% 0.88
Dec 6 23:00:00 - 23:58:01 896 4.4% 0.262.9 MB 4.3% 0.87
TOTAL: 24:00:00 20536 100.0% 0.2468.7 MB 100.0% 0.81
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB22.0 GB 44.0% 0 0.32 1892.34
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB25.5 GB 51.0% 0 0.35 1711.27
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB274.6 MB 0.5% 0 0.0415407.41
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 GB12.9 GB 25.8% 0 0.66 919.42
TOTAL: 10-500.0 GB60.7 GB 12.1% 1 0.43-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 4142 48 0 65 0 0 0 0 4029
2feeder.eternal-september.org 1422 0 0 149 0 0 0 0 1273
3news-out.mixmin.net 923 0 0 94 0 0 0 0 829
4news.albasani.net 897 4 0 80 0 0 0 0 813
5newsfeed.datemas.de 647 188 0 88 0 0 0 0 371
6news.samoylyk.net 527 0 0 74 0 0 0 0 453
7news.snarked.org 297 75 0 66 0 0 0 0 156
8news.redatomik.org 158 0 0 81 0 0 0 0 77
9agency-nz-out.news.mb-net.net 137 33 0 71 0 0 0 0 33
10news.freedyn.net 87 10 0 60 0 0 0 0 17
11news-out.aioe.org 75 9 0 57 0 0 0 0 9
12feeder.awxg.com 68 3 0 64 0 0 0 0 1
13csiph.com 63 0 0 60 0 0 0 0 3
14news.swapon.de 40 0 0 4 0 0 0 0 36
15news.ausics.org 1 0 0 1 0 0 0 0 0
TOTAL: 15 9484 370 0 1014 0 0 0 0 8100

Unwanted newsgroups [Top 20]:

NewsgroupCount
mpc.lists.nanog 49
gmane.linux.alsa.devel 40
ut.test 39
it.annunci.varie 33
it.annunci.immobiliari 33
it.annunci.commerciali 32
mozilla.dev.platform 24
gmane.linux.kernel 12
nemo.test2 11
mozilla.support.seamonkey 11
mpc.lists.freebsd.office 10
gmane.comp.gis.openstreetmap.tagging 9
mozilla.support.bugzilla 8
nemo.dev.passerelle 7
mozilla.dev.ui-alerts 6
nemo.test 4
mozilla.dev.apps.seamonkey 4
mpc.lists.freebsd.gecko 2
mpc.lists.freebsd.x11 2
mozilla.dev.builds 2
TOTAL: 48 370

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6242
Binary: misplaced rar 420
EMP (phn path) 358
EMP (phn nph) 268
Too many newsgroups 60
User-issued cancel 31
EMP (phl) 20
Binary: misplaced par2 9
HTML post 9
Binary: non-binary distribution 7
Binary: misplaced PAR2 3
Too many newsgroups (low_xpost) 2
Cancel for rejected article 2
Binary: misplaced 030 1
MI5 Filter 1
Binary: misplaced 073 1
Binary Image: misplaced png 1
TOTAL: 17 7435

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.albasani.net 276
news-out.mixmin.net 149
newsfeed.datemas.de 115
news.redatomik.org 73
news.swapon.de 31
news-out.aioe.org 7
newsfeed.neva.ru 5
feeder.eternal-september.org 3
news.samoylyk.net 2
news.snarked.org 2
TOTAL: 12 665
TOTAL: 1 665

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 18722 654 17948 120 0 0 3%23:59:48
2awxg 19984 91 19865 5 0 8 0%23:59:47
3news.snarked.org 15229 6 15114 5 0 2 0%23:59:48
4csiph.com 19792 2 19695 0 0 5 0%23:59:45
5aioe 17530 0 17439 0 0 0 0%23:59:48
6neva.ru 18860 0 18852 0 0 0 0%23:59:48
7newsfeed.datemas.de 17815 0 17804 0 0 0 0%23:59:48
8news.redatomik.org 19339 0 19311 0 0 0 0%23:59:48
9eternal-september 17836 0 17770 0 0 2 0%23:59:48
10news.albasani.net 19638 0 16832 1076 0 6268 0%23:59:47
11news.samoylyk.net 18700 0 18645 0 0 9 0%23:59:47
12news.swapon.de 1681 0 1681 0 0 0 0%23:54:59
13news.freedyn.net 20507 0 20431 1 0 0 0%23:59:48
14news-in.mixmin.net 16368 0 16350 0 0 6 0%23:59:48
15mb-net.net 20453 0 20410 0 0 0 0%23:59:48
TOTAL: 15 262454 753 258147 1207 0 6300 0%359:52:05
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org4.2 MB8.5 MB12.7 MB0.2 KB/s16.8 KB23:59:48
2awxg333.4 KB20.7 KB354.0 KB0.0 KB/s3.7 KB23:59:47
3news.snarked.org19.6 KB20.7 KB40.3 KB0.0 KB/s3.7 KB23:59:48
4csiph.com3.5 KB0.0 KB3.5 KB0.0 KB/s1.8 KB23:59:45
5aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
6neva.ru0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
7newsfeed.datemas.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
8news.redatomik.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
9eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
10news.albasani.net0.0 KB3.0 MB3.0 MB0.0 KB/s2.8 KB23:59:47
11news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
12news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:59
13news.freedyn.net0.0 KB89.7 KB89.7 KB0.0 KB/s89.7 KB23:59:48
14news-in.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
15mb-net.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
TOTAL: 154.5 MB11.6 MB16.2 MB0.0 KB/s8.4 KB359:52:05
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-21-181.cpe.cableone.net 7 872331.6 MB 1132 0 1402:30:15
2agency.bbs.geek.nz 385 753526.8 MB 4253 0 000:14:25
3103.39.238.13 23 10961.5 MB 144 0 000:05:17
4vorlon.activateit.net.au 1 4054.9 KB 5 0 000:00:11
TOTAL: 4 416 1739460.0 MB 5534 0 1433:21:53

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 8 872331.6 MB 1132 0 1432:39:11
2*.bbs.geek.nz 385 753526.8 MB 4253 0 000:14:25
3unresolved 29 10961.5 MB 144 0 000:28:02
4*.activateit.net.au 1 4054.9 KB 5 0 000:00:11
TOTAL: 4 423 1739460.0 MB 5534 0 1433:21:53

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 7
malcolmlewis 1
TOTAL: 3 32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.088 0.064 0.00330:08:56
67-61-21-181.cpe.cableone.net 1.440 1.124 0.02102:30:15
agency.bbs.geek.nz 27.684 7.028 0.00000:14:25
202.174.187.54 0.184 0.016 0.00000:13:42
103.39.238.13 1.336 0.352 0.17900:05:17
118.149.187.60 0.060 0.016 0.00000:05:02
118.149.133.120 0.128 0.008 0.00000:03:59
vorlon.activateit.net.au 0.056 0.016 0.00300:00:11
? 1.436 0.148 0.00000:00:02
TOTAL: 9 32.412 8.772 0.20633:21:53

Curious NNRP server explorers [Top 20]:

SystemConn
202.174.187.54 3
118.149.133.120 2
118.149.187.60 1
67-60-219-146.cpe.cableone.net 1
TOTAL: 4 7

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 5665 32.6%
2alt 2171 12.5%
3comp 1922 11.0%
4uk 1588 9.1%
5soc 1549 8.9%
6sci 1509 8.7%
7linux 1356 7.8%
8talk 938 5.4%
9misc 334 1.9%
10news 230 1.3%
11mozilla 61 0.4%
12microsoft 40 0.2%
13aus 15 0.1%
14humanities 12 0.1%
15nz 3 0.0%
16eternal-september 1 0.0%
TOTAL: 16 17394100.0%

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

NewsgroupCount
1rec.arts.tv 1281
2uk.d-i-y 587
3linux.debian.bugs.dist 564
4alt.fan.rush-limbaugh 539
5alt.atheism 536
6rec.arts.drwho 455
7uk.radio.amateur 391
8talk.origins 383
9rec.sport.football.college 378
10alt.comp.os.windows-10 371
11rec.arts.sf.written 357
12rec.food.cooking 355
13comp.lang.python 348
14comp.lang.c 313
15talk.politics.guns 307
16sci.electronics.design 303
17rec.boats 290
18uk.legal 283
19soc.retirement 253
20rec.sport.tennis 252
21rec.sport.pro-wrestling 238
22sci.math 235
23comp.os.linux.advocacy 230
24soc.culture.israel 225
25talk.politics.misc 221
26news.lists.filters 214
27sci.med.pharmacy 186
28sci.physics 180
29sci.physics.relativity 178
30linux.debian.changes.devel 160
31soc.culture.polish 160
32rec.bicycles.tech 141
33rec.sport.soccer 141
34misc.survivalism 137
35soc.culture.indian 136
36alt.politics.trump 128
37rec.sport.golf 126
38linux.debian.bugs.rc 124
39soc.culture.china 121
40comp.mobile.android 112
41rec.sport.cricket 106
42linux.gentoo.user 99
43alt.support.diabetes 97
44alt.politics 92
45rec.radio.shortwave 92
46rec.photo.digital 89
47alt.test 87
48soc.culture.brazil 86
49alt.bible 81
50uk.rec.cars.maintenance 79
51rec.music.classical.recordings 78
52rec.crafts.metalworking 78
53rec.gambling.poker 75
54comp.arch 72
55sci.med.cardiology 71
56rec.aviation.marketplace 70
57rec.aquaria.marine.misc 70
58comp.protocols.time.ntp 70
59uk.legal.moderated 69
60rec.arts.sf.fandom 69
61comp.os.vms 68
62misc.taxes.moderated 68
63linux.debian.devel 65
64misc.phone.mobile.iphone 65
65soc.culture.vietnamese 64
66rec.outdoors.rv-travel 62
67comp.sys.mac.advocacy 60
68linux.gentoo.dev 58
69rec.games.pinball 57
70soc.culture.singapore 55
71comp.lang.c++ 54
72sci.logic 54
73rec.autos.sport.f1 54
74soc.genealogy.medieval 54
75soc.culture.usa 52
76alt.recovery.aa 50
77comp.mobile.ipad 49
78soc.culture.greek 48
79rec.aviation.soaring 47
80linux.debian.user 46
81rec.arts.movies.current-films 45
82uk.tech.digital-tv 45
83rec.drugs.cannabis 44
84comp.lang.tcl 44
85sci.astro.amateur 43
86uk.comp.sys.mac 43
87sci.electronics.repair 43
88sci.lang 41
89comp.lang.ada 40
90sci.military.naval 40
91uk.rec.cycling 38
92soc.culture.bulgaria 38
93linux.debian.user.french 38
94rec.autos.tech 37
95mozilla.support.firefox 36
96comp.lang.idl-pvwave 35
97alt.politics.usa 34
98rec.games.bridge 31
99comp.sys.apple2 31
100rec.arts.sf.tv 29
TOTAL: 360 17394