news.bbs.geek.nz daily logs

Aug 28 23:58:01 -- Aug 29 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 146876 63.9%12.2 MB 42.8%
innd 29504 12.8%4.8 MB 17.0%
inn 28755 12.5%6.7 MB 23.4%
innfeed 23376 10.2%4.5 MB 15.9%
cnfsstat 1440 0.6%250.1 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 229952 100.0%28.4 MB100.0%

History cache:

ReasonCount%Count
Negative hits 287399 47.8%
Positive hits 287005 47.7%
Do not exist 26675 4.4%
Cache misses 242 0.0%
TOTAL: 4 601321 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.133 0.0%178 0.000 0.747 2.941
article cleanup00:00:00.278 0.0%28055 0.000 0.010 0.035
article logging00:00:00.688 0.0%28608 0.000 0.024 0.063
article parse00:00:00.834 0.0%68508 0.000 0.012 0.027
article write00:00:04.810 0.0%22505 0.018 0.214 15.593
artlog/artcncl00:00:00.002 0.0%166 0.000 0.012 1.000
data move00:00:00.391 0.0%547958 0.000 0.001 0.005
hisgrep/artcncl00:00:00.003 0.0%172 0.000 0.017 0.125
hishave/artcncl00:00:00.000 0.0%178 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.769 0.0%604574 0.001 0.013 0.594
history sync00:00:00.020 0.0%371 0.000 0.054 0.500
history write00:00:05.312 0.0%26805 0.014 0.198 9.696
hiswrite/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
idle23:57:19.100 99.8%559453 94.275 154.149 463.488
nntp read00:00:04.881 0.0%565380 0.004 0.009 0.020
overview write00:00:43.010 0.0%22505 0.144 1.911 51.050
perl filter00:00:55.164 0.1%26805 1.120 2.058 26.816
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.359 0.0%45010 0.005 0.030 0.213
TOTAL: 24:00:45.55223:59:23.754 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.067 0.0%30246 0.000 0.002 0.017
article prepare00:00:00.000 0.0%10320 0.000 0.000 0.000
article read00:00:00.514 0.0%2361 0.000 0.218 12.148
backlog stats00:00:00.590 0.0%335728 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.814 0.0%291859 0.005 0.010 0.017
data write00:00:03.263 0.0%281786 0.005 0.012 0.020
idle23:54:15.679100.0%335728 139.895 256.326 760.303
status file00:00:07.079 0.0%1973 0.333 3.588 106.270
TOTAL: 23:54:48.41923:54:30.006100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle28:07:27.912 97.8%162613 0.000 622.631 90876.940
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:03:14.736 0.2%359425 0.000 0.542 35.964
readart00:00:06.640 0.0%16086 0.000 0.413 43.075
TOTAL: 28:45:09.83928:10:49.288 98.0%----

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 58 20650 6703 13381 566 32%60:15:32
2news-out.mixmin.net 22 24628 3842 19924 862 15%32:20:42
3news.redatomik.org 1 21369 2724 18547 98 12%23:59:25
4feeder.eternal-september.org 107 26559 2463 23411 685 9%58:32:31
5newsfeed.datemas.de 3 22061 2133 19430 498 9%22:42:55
6newsfeed.neva.ru 152 14890 1280 12029 1581 8%87:25:01
7news-out.aioe.org 5 22629 796 21751 82 3%24:45:04
8news.albasani.net 1 24544 613 23576 355 2%25:09:43
9csiph.com 2 19115 602 18430 83 3%20:10:43
10news.freedyn.net 3 26406 535 25598 273 2%26:44:34
11news.samoylyk.net 2 20360 254 19823 283 1%17:09:50
12news.swapon.de 10 2125 151 1952 22 7%26:16:54
13feeder.awxg.com 1 19663 144 19444 75 0%23:59:57
14agency-nz-out.news.mb-net.net 29 22508 66 22334 108 0%35:08:11
15localhost 1 0 0 0 0 0%00:00:00
TOTAL: 15 397 287507 22306 259630 5571 7%484:41:02
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org21.7 MB379.9 KB2.3 MB24.3 MB 89%3.4 KB
2news-out.mixmin.net11.7 MB422.3 KB2.2 MB14.3 MB 82%3.1 KB
3newsfeed.datemas.de10.7 MB488.6 KB1.7 MB12.9 MB 83%5.0 KB
4feeder.eternal-september.org8.1 MB631.1 KB4.2 MB12.8 MB 62%4.2 KB
5news.redatomik.org6.2 MB387.7 KB391.0 KB7.0 MB 89%2.5 KB
6news.albasani.net4.4 MB638.2 KB1.2 MB6.3 MB 70%6.6 KB
7newsfeed.neva.ru3.5 MB262.2 KB7.7 MB11.5 MB 30%4.1 KB
8news.freedyn.net2.6 MB292.8 KB848.0 KB3.7 MB 69%4.7 KB
9news-out.aioe.org2.4 MB218.7 KB258.9 KB2.9 MB 83%3.3 KB
10news.samoylyk.net2.1 MB376.9 KB4.5 MB7.0 MB 30%13.3 KB
11csiph.com1.6 MB294.6 KB295.7 KB2.2 MB 73%3.2 KB
12feeder.awxg.com562.2 KB285.0 KB318.2 KB1.1 MB 48%5.3 KB
13agency-nz-out.news.mb-net.net510.0 KB265.3 KB434.7 KB1.2 MB 42%7.0 KB
14news.swapon.de391.8 KB66.5 KB66.5 KB524.9 KB 74%3.0 KB
15localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1576.5 MB4.9 MB26.2 MB107.6 MB 71%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 28 23:58:01 - 23:59:59 26 0.1% 0.2258.0 KB 0.1% 0.49
Aug 29 00:00:00 - 00:59:59 1033 4.6% 0.292.9 MB 3.7% 0.82
Aug 29 01:00:00 - 01:59:59 1138 5.1% 0.324.0 MB 5.2% 1.14
Aug 29 02:00:00 - 02:59:59 1249 5.6% 0.353.7 MB 4.7% 1.04
Aug 29 03:00:00 - 03:59:59 1267 5.6% 0.354.8 MB 6.2% 1.37
Aug 29 04:00:00 - 04:59:59 1249 5.6% 0.353.8 MB 5.0% 1.09
Aug 29 05:00:00 - 05:59:59 1354 6.0% 0.384.2 MB 5.5% 1.20
Aug 29 06:00:00 - 06:59:59 1184 5.3% 0.333.9 MB 5.1% 1.12
Aug 29 07:00:00 - 07:59:59 1126 5.0% 0.313.7 MB 4.8% 1.05
Aug 29 08:00:00 - 08:59:59 1109 4.9% 0.312.9 MB 3.8% 0.83
Aug 29 09:00:00 - 09:59:59 1120 5.0% 0.313.2 MB 4.1% 0.91
Aug 29 10:00:00 - 10:59:59 858 3.8% 0.243.8 MB 4.9% 1.09
Aug 29 11:00:00 - 11:59:59 605 2.7% 0.174.2 MB 5.4% 1.19
Aug 29 12:00:00 - 12:59:59 666 3.0% 0.182.6 MB 3.4% 0.74
Aug 29 13:00:00 - 13:59:59 598 2.7% 0.172.9 MB 3.8% 0.84
Aug 29 14:00:00 - 14:59:59 486 2.2% 0.141.5 MB 1.9% 0.43
Aug 29 15:00:00 - 15:59:59 403 1.8% 0.111.9 MB 2.5% 0.54
Aug 29 16:00:00 - 16:59:59 607 2.7% 0.172.4 MB 3.1% 0.68
Aug 29 17:00:00 - 17:59:59 540 2.4% 0.152.1 MB 2.7% 0.58
Aug 29 18:00:00 - 18:59:59 708 3.1% 0.202.3 MB 3.0% 0.66
Aug 29 19:00:00 - 19:59:59 876 3.9% 0.242.4 MB 3.1% 0.68
Aug 29 20:00:00 - 20:59:59 945 4.2% 0.263.1 MB 4.0% 0.88
Aug 29 21:00:00 - 21:59:59 1136 5.1% 0.323.7 MB 4.7% 1.04
Aug 29 22:00:00 - 22:59:59 1222 5.4% 0.344.0 MB 5.1% 1.13
Aug 29 23:00:00 - 23:58:01 986 4.4% 0.283.3 MB 4.2% 0.96
TOTAL: 24:00:00 22491 100.0% 0.2677.4 MB 100.0% 0.92
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB19.6 GB 39.1% 0 0.33 1825.81
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB22.7 GB 45.4% 0 0.35 1743.48
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB242.3 MB 0.5% 0 0.0416888.89
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 GB7.3 GB 14.6% 0 0.80 763.12
TOTAL: 10-500.0 GB49.8 GB 10.0% 1 0.48-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 1558 121 0 69 0 0 0 0 1368
2news-out.mixmin.net 1048 0 0 102 0 0 0 0 946
3feeder.eternal-september.org 676 0 0 155 0 0 0 0 521
4news.snarked.org 581 33 0 94 0 0 0 0 454
5newsfeed.datemas.de 558 195 0 109 0 0 0 0 254
6news.albasani.net 366 2 0 126 0 0 0 0 238
7news.samoylyk.net 340 0 0 116 0 0 0 0 224
8news.redatomik.org 299 0 0 93 0 0 0 0 206
9news.freedyn.net 250 19 0 60 0 0 0 0 171
10agency-nz-out.news.mb-net.net 101 16 0 63 1 0 0 0 21
11csiph.com 91 0 0 89 0 0 0 0 2
12feeder.awxg.com 82 0 0 78 0 0 0 0 4
13news-out.aioe.org 81 8 0 68 0 0 0 0 5
14news.swapon.de 58 0 0 20 0 0 0 0 38
15news.ausics.org 9 0 0 8 0 0 0 0 1
TOTAL: 15 6098 394 0 1250 1 0 0 0 4453

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 61
it.annunci.commerciali 37
mpc.lists.freebsd.ports.bugs 37
it.annunci.immobiliari 36
it.annunci.varie 32
alt.biniaries.erotica.wet-n-messy 14
mpc.lists.nanog 13
eagle.support.ger 11
mozilla.dev.platform 9
gmane.comp.handhelds.openmoko.community 9
eagle.support.eng 8
gmane.linux.kernel 8
mozilla.dev.security.policy 8
mpc.lists.freebsd.gnome 7
fido7.ru.android 7
gmane.comp.gis.openstreetmap.tagging 6
alt.bbs.files-distribution 5
alt.bbs.files-announce 5
mozilla.dev.builds 5
mpc.lists.freebsd.x11 4
TOTAL: 64 394

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

GroupsCount
nz.politics.announce 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3386
EMP (phn path) 321
Too many newsgroups 65
EMP (phn nph) 65
User-issued cancel 17
Binary: non-binary distribution 14
EMP (phl) 12
Too many newsgroups (low_xpost) 5
Cancel for rejected article 4
Binary: misplaced par2 3
HTML post 3
Poison newsgroup 2
Binary Image: misplaced jpg 1
Binary: misplaced 005 1
Too many newsgroups (meow) 1
Binary: misplaced 033 1
TOTAL: 16 3901

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.mixmin.net 205
news.redatomik.org 204
newsfeed.datemas.de 49
news.swapon.de 38
news.albasani.net 31
newsfeed.neva.ru 9
news.snarked.org 5
news-out.aioe.org 3
feeder.eternal-september.org 3
feeder.awxg.com 3
TOTAL: 11 552
TOTAL: 1 552

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 20861 1076 19678 107 0 0 5%23:59:40
2awxg 21850 93 21739 4 0 14 0%23:59:48
3news.freedyn.net 22302 24 21937 1 0 92 0%23:59:48
4aioe 18670 3 18573 0 0 0 0%23:59:46
5news.snarked.org 15413 1 15338 9 0 21 0%23:59:47
6csiph.com 22061 0 21921 0 0 0 0%23:59:47
7newsfeed.datemas.de 20243 0 20240 0 0 0 0%23:59:48
8eternal-september 18947 0 18907 0 0 0 0%23:59:20
9news.albasani.net 23431 0 19997 1120 0 7672 0%23:59:29
10news.swapon.de 2000 0 2000 0 0 0 0%23:56:17
11news.redatomik.org 19703 0 19681 0 0 0 0%23:59:48
12news.samoylyk.net 20625 0 20583 0 0 0 0%23:59:46
13neva.ru 20682 0 20682 0 0 18 0%23:59:47
14news-in.mixmin.net 18105 0 18099 0 0 0 0%23:59:46
15mb-net.net 22509 0 22369 0 0 59 0%23:59:47
TOTAL: 15 287402 1197 281744 1241 0 7876 0%359:52:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org6.6 MB9.2 MB15.8 MB0.2 KB/s13.7 KB23:59:40
2awxg388.4 KB21.7 KB410.1 KB0.0 KB/s4.2 KB23:59:48
3news.snarked.org220.7 KB17.3 KB237.9 KB0.0 KB/s23.8 KB23:59:47
4news.freedyn.net73.1 KB10.4 KB83.5 KB0.0 KB/s3.3 KB23:59:48
5aioe7.1 KB0.0 KB7.1 KB0.0 KB/s2.4 KB23:59:46
6csiph.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
7newsfeed.datemas.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
8eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:20
9news.albasani.net0.0 KB3.8 MB3.8 MB0.0 KB/s3.5 KB23:59:29
10news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:17
11news.redatomik.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
12news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
13neva.ru0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
14news-in.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
15mb-net.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
TOTAL: 157.2 MB13.1 MB20.3 MB0.0 KB/s8.5 KB359:52:24
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-21-181.cpe.cableone.net 21 716329.5 MB 1730 0 003:41:32
2agency.bbs.geek.nz 396 664123.9 MB 3721 0 2400:12:45
3163.47.16.169 24 12161.9 MB 156 0 000:05:13
467-60-219-146.cpe.cableone.net 4 00.0 KB 2 0 024:12:39
TOTAL: 4 445 1502055.3 MB 5609 0 2428:45:02

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 25 716329.5 MB 1732 0 027:54:11
2*.bbs.geek.nz 396 664123.9 MB 3721 0 2400:12:45
3unresolved 31 12161.9 MB 156 0 000:38:02
TOTAL: 3 452 1502055.3 MB 5609 0 2428:45:02

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 21
malcolmlewis 4
TOTAL: 3 49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.324 0.132 0.02324:12:39
67-61-21-181.cpe.cableone.net 4.020 2.564 1.03503:41:32
118.149.133.158 0.304 0.052 0.00000:26:28
agency.bbs.geek.nz 30.780 7.280 0.00000:12:45
163.47.16.169 1.532 0.408 0.11300:05:13
118.149.191.9 0.072 0.008 0.00000:03:18
118.149.172.27 0.052 0.012 0.00000:03:01
? 1.400 0.144 0.00000:00:02
TOTAL: 8 38.484 10.600 1.17128:45:02

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.133.158 5
118.149.191.9 1
118.149.172.27 1
TOTAL: 3 7

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 6425 42.0%
2comp 2182 14.3%
3sci 1530 10.0%
4soc 1523 10.0%
5alt 1510 9.9%
6talk 917 6.0%
7misc 570 3.7%
8uk 263 1.7%
9news 234 1.5%
10nz 75 0.5%
11mozilla 42 0.3%
12aus 13 0.1%
13humanities 4 0.0%
TOTAL: 13 15288100.0%

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

NewsgroupCount
1rec.arts.tv 1884
2alt.atheism 837
3rec.sport.tennis 661
4talk.politics.guns 585
5comp.os.linux.advocacy 543
6comp.lang.c 502
7rec.arts.drwho 442
8sci.physics 386
9misc.survivalism 364
10soc.culture.israel 280
11soc.retirement 274
12sci.physics.relativity 260
13comp.lang.python 240
14uk.radio.amateur 236
15rec.food.cooking 234
16soc.culture.polish 211
17sci.math 210
18rec.aquaria.marine.misc 210
19news.lists.filters 210
20sci.electronics.design 210
21alt.comp.os.windows-10 205
22rec.gambling.poker 192
23rec.boats 191
24talk.politics.misc 179
25rec.sport.football.college 168
26rec.sport.golf 152
27alt.politics 151
28rec.photo.digital 142
29talk.origins 129
30rec.crafts.metalworking 118
31rec.aviation.marketplace 116
32misc.health.alternative 111
33rec.arts.sf.written 110
34rec.arts.movies.current-films 108
35soc.culture.china 104
36soc.culture.usa 101
37soc.culture.indian 101
38rec.games.pinball 97
39comp.lang.c++ 96
40rec.arts.movies.past-films 94
41rec.bicycles.tech 92
42rec.outdoors.rv-travel 87
43rec.gambling.lottery 83
44alt.test 82
45sci.logic 77
46sci.astro.amateur 77
47rec.autos.sport.f1 75
48comp.mobile.android 70
49nz.general 69
50rec.sport.soccer 66
51rec.music.rock-pop-r+b.1950s 63
52rec.radio.shortwave 61
53soc.history.what-if 58
54rec.sport.cricket 57
55comp.soft-sys.sas 56
56rec.music.dylan 56
57sci.electronics.repair 52
58comp.lang.fortran 51
59rec.audio.opinion 50
60rec.sport.pro-wrestling 50
61rec.arts.sf.fandom 48
62comp.protocols.time.ntp 48
63rec.arts.sf.tv 47
64alt.books.reviews 47
65rec.music.classical.recordings 47
66rec.aviation.soaring 46
67soc.genealogy.medieval 42
68comp.lang.tcl 42
69alt.support.diabetes 41
70alt.bible 40
71comp.soft-sys.matlab 40
72soc.culture.bulgaria 38
73sci.med.pharmacy 38
74sci.med.cardiology 35
75soc.culture.romanian 35
76rec.arts.disney.parks 34
77comp.soft-sys.math.scilab 34
78comp.sys.mac.system 33
79alt.recovery.aa 33
80comp.lang.forth 32
81comp.sys.apple2.programmer 30
82soc.culture.singapore 30
83rec.autos.marketplace 30
84rec.games.backgammon 28
85soc.culture.brazil 27
86rec.aviation.student 26
87comp.arch 26
88sci.math.num-analysis 26
89sci.military.naval 26
90mozilla.general 25
91misc.phone.mobile.iphone 24
92soc.penpals 24
93comp.os.vms 23
94comp.lang.javascript 23
95soc.history.medieval 22
96rec.woodworking 22
97misc.taxes.moderated 22
98rec.autos.tech 22
99alt.os.linux.ubuntu 21
100comp.lang.lisp 21
TOTAL: 329 15288