news.bbs.geek.nz daily logs

Aug 23 23:58:02 -- Aug 24 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 124017 59.7%10.3 MB 39.0%
innd 30588 14.7%4.9 MB 18.8%
inn 28353 13.6%6.3 MB 24.0%
innfeed 23410 11.3%4.5 MB 17.2%
cnfsstat 1440 0.7%253.0 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 207809 100.0%26.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 269956 48.0%
Positive hits 266325 47.3%
Do not exist 26247 4.7%
Cache misses 211 0.0%
TOTAL: 4 562739 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.199 0.0%186 0.000 1.070 8.556
article cleanup00:00:00.293 0.0%27673 0.000 0.011 0.037
article logging00:00:00.742 0.0%28214 0.000 0.026 0.131
article parse00:00:00.867 0.0%80916 0.000 0.011 0.027
article write00:00:01.723 0.0%20681 0.019 0.083 0.342
artlog/artcncl00:00:00.003 0.0%178 0.000 0.017 0.111
data move00:00:00.325 0.0%531676 0.000 0.001 0.005
hisgrep/artcncl00:00:00.002 0.0%171 0.000 0.012 0.200
hishave/artcncl00:00:00.000 0.0%186 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.323 0.0%570634 0.001 0.015 1.142
history sync00:00:00.022 0.0%333 0.000 0.066 0.667
history write00:00:07.339 0.0%26526 0.015 0.277 24.809
hiswrite/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
idle23:57:13.444 99.8%546061 65.369 157.919 437.521
nntp read00:00:04.977 0.0%551915 0.004 0.009 0.024
overview write00:00:46.502 0.1%20681 0.041 2.249 112.360
perl filter00:00:48.372 0.1%26526 1.068 1.824 4.722
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.375 0.0%41362 0.000 0.033 0.224
TOTAL: 24:00:41.38023:59:14.508 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.061 0.0%27952 0.000 0.002 0.020
article prepare00:00:00.003 0.0%13256 0.000 0.000 0.011
article read00:00:00.680 0.0%3048 0.000 0.223 21.125
backlog stats00:00:00.539 0.0%315933 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.530 0.0%269812 0.000 0.009 0.017
data write00:00:03.068 0.0%260274 0.005 0.012 0.026
idle23:54:29.249100.0%315933 171.682 272.429 673.549
status file00:00:05.986 0.0%1985 0.500 3.016 97.135
TOTAL: 23:54:59.24823:54:42.116100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle28:58:28.010 98.1%138721 0.000 751.927900228.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:52.787 0.1%308110 0.000 0.366 8.613
readart00:00:02.895 0.0%15002 0.000 0.193 36.978
TOTAL: 29:32:42.40829:00:23.692 98.2%----

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 60 19161 6029 12703 429 31%58:18:15
2news-out.mixmin.net 21 21874 4221 16550 1103 19%32:12:24
3feeder.eternal-september.org 80 25187 2232 21958 997 8%52:10:58
4newsfeed.datemas.de 4 21296 1735 19051 510 8%23:39:26
5news.redatomik.org 2 18087 1497 16528 62 8%22:25:04
6newsfeed.neva.ru 156 17273 1220 13593 2460 7%85:42:37
7csiph.com 2 25567 1013 24467 87 3%30:58:29
8news.albasani.net 1 19366 967 18000 399 4%22:09:09
9news-out.aioe.org 4 19972 812 19069 91 4%23:32:26
10news.samoylyk.net 2 26698 289 26059 350 1%25:14:29
11news.swapon.de 5 2032 156 1837 39 7%24:56:06
12feeder.awxg.com 1 18403 145 18186 72 0%23:59:57
13agency-nz-out.news.mb-net.net 22 23963 116 23532 315 0%35:13:39
14news.freedyn.net 2 21718 68 21569 81 0%23:12:51
15localhost 12 11 11 0 0 100%00:00:00
16news.ausics.org 2 57133 10 57113 10 0%78:01:19
TOTAL: 16 376 337741 20521 310215 7005 6%561:47:09
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org22.2 MB267.8 KB2.1 MB24.6 MB 90%3.9 KB
2news-out.mixmin.net12.5 MB382.3 KB2.8 MB15.7 MB 79%3.0 KB
3feeder.eternal-september.org10.8 MB594.0 KB5.8 MB17.2 MB 62%5.4 KB
4newsfeed.datemas.de6.5 MB420.6 KB1.8 MB8.7 MB 75%4.0 KB
5news.redatomik.org3.8 MB235.4 KB243.3 KB4.3 MB 89%2.8 KB
6news.albasani.net3.7 MB358.7 KB1.1 MB5.2 MB 71%3.9 KB
7newsfeed.neva.ru3.6 MB578.4 KB12.5 MB16.7 MB 21%4.6 KB
8csiph.com2.9 MB317.3 KB334.3 KB3.5 MB 81%3.2 KB
9news-out.aioe.org2.2 MB156.7 KB272.9 KB2.6 MB 84%3.0 KB
10news.samoylyk.net1.6 MB369.8 KB1.9 MB3.9 MB 40%6.2 KB
11news.freedyn.net632.9 KB264.0 KB556.3 KB1.4 MB 43%9.8 KB
12feeder.awxg.com494.1 KB257.9 KB261.0 KB1013.0 KB 48%4.7 KB
13news.swapon.de407.3 KB102.8 KB102.8 KB612.9 KB 66%3.1 KB
14agency-nz-out.news.mb-net.net373.3 KB234.4 KB16.5 MB17.1 MB 2%40.6 KB
15news.ausics.org34.8 KB29.0 KB29.0 KB92.8 KB 37%4.6 KB
16localhost15.2 KB0.0 KB0.0 KB15.2 KB100%1.4 KB
TOTAL: 1671.8 MB4.5 MB46.4 MB122.6 MB 58%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 23 23:58:02 - 23:59:59 23 0.1% 0.1952.6 KB 0.1% 0.45
Aug 24 00:00:00 - 00:59:59 1024 5.0% 0.282.9 MB 4.1% 0.84
Aug 24 01:00:00 - 01:59:59 967 4.7% 0.273.5 MB 4.8% 0.98
Aug 24 02:00:00 - 02:59:59 1160 5.6% 0.323.6 MB 5.0% 1.01
Aug 24 03:00:00 - 03:59:59 1195 5.8% 0.334.3 MB 6.0% 1.23
Aug 24 04:00:00 - 04:59:59 1173 5.7% 0.333.6 MB 5.0% 1.02
Aug 24 05:00:00 - 05:59:59 1124 5.4% 0.313.5 MB 4.9% 1.00
Aug 24 06:00:00 - 06:59:59 1090 5.3% 0.303.5 MB 4.8% 0.98
Aug 24 07:00:00 - 07:59:59 1058 5.1% 0.293.7 MB 5.1% 1.04
Aug 24 08:00:00 - 08:59:59 995 4.8% 0.283.3 MB 4.7% 0.95
Aug 24 09:00:00 - 09:59:59 887 4.3% 0.253.3 MB 4.6% 0.94
Aug 24 10:00:00 - 10:59:59 878 4.2% 0.243.6 MB 5.0% 1.02
Aug 24 11:00:00 - 11:59:59 716 3.5% 0.203.0 MB 4.3% 0.86
Aug 24 12:00:00 - 12:59:59 486 2.4% 0.141.7 MB 2.3% 0.47
Aug 24 13:00:00 - 13:59:59 520 2.5% 0.141.7 MB 2.4% 0.50
Aug 24 14:00:00 - 14:59:59 429 2.1% 0.121.6 MB 2.3% 0.46
Aug 24 15:00:00 - 15:59:59 332 1.6% 0.092.0 MB 2.9% 0.58
Aug 24 16:00:00 - 16:59:59 561 2.7% 0.162.7 MB 3.8% 0.78
Aug 24 17:00:00 - 17:59:59 492 2.4% 0.141.5 MB 2.1% 0.42
Aug 24 18:00:00 - 18:59:59 697 3.4% 0.192.5 MB 3.6% 0.72
Aug 24 19:00:00 - 19:59:59 851 4.1% 0.242.7 MB 3.7% 0.76
Aug 24 20:00:00 - 20:59:59 966 4.7% 0.274.0 MB 5.6% 1.13
Aug 24 21:00:00 - 21:59:59 995 4.8% 0.283.7 MB 5.2% 1.05
Aug 24 22:00:00 - 22:59:59 1104 5.3% 0.312.9 MB 4.1% 0.84
Aug 24 23:00:00 - 23:58:02 941 4.6% 0.272.6 MB 3.6% 0.76
TOTAL: 24:00:00 20664 100.0% 0.2471.5 MB 100.0% 0.85
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB19.4 GB 38.9% 0 0.31 1929.36
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB22.6 GB 45.2% 0 0.35 1716.55
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB240.2 MB 0.5% 0 0.0414974.04
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.0 GB 14.0% 0 0.74 824.13
TOTAL: 10-500.0 GB49.3 GB 9.9% 1 0.45-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 2483 153 0 75 1 0 0 0 2254
2news-out.mixmin.net 1387 0 0 111 0 0 0 0 1276
3feeder.eternal-september.org 963 0 0 150 0 0 0 0 813
4newsfeed.datemas.de 644 247 0 113 0 0 0 0 284
5news.albasani.net 468 6 0 101 0 0 0 0 361
6news.snarked.org 433 44 0 80 0 0 0 0 309
7news.samoylyk.net 333 0 0 98 0 0 0 0 235
8agency-nz-out.news.mb-net.net 313 41 0 77 2 0 0 0 193
9news-out.aioe.org 102 14 0 51 0 0 0 0 37
10news.redatomik.org 88 0 0 66 0 0 0 0 22
11news.freedyn.net 81 3 0 52 0 0 0 0 26
12feeder.awxg.com 76 1 0 72 0 0 0 0 3
13news.swapon.de 73 0 0 39 0 0 0 0 34
14csiph.com 67 0 0 62 0 0 0 0 5
15news.ausics.org 2 0 0 2 0 0 0 0 0
TOTAL: 15 7513 509 0 1149 3 0 0 0 5852

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 129
gmane.linux.alsa.devel 58
gmane.linux.kernel 45
it.annunci.varie 41
it.annunci.commerciali 41
it.annunci.immobiliari 39
mozilla.governance 22
mpc.lists.nanog 21
mpc.lists.freebsd.ports.bugs 16
mozilla.dev.platform 15
mpc.lists.freebsd.x11 7
mozilla.dev.media 6
fido7.ru.android 5
mozilla.dev.apps.thunderbird 5
mozilla.dev.ui-alerts 4
mozilla.dev.developer-tools 3
mpc.lists.freebsd.gnome 3
mozilla.dev.l10n 3
al.christnet.bible 3
mozilla.dev.l10n.web 3
TOTAL: 51 509

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

GroupsCount
nz.politics.announce 2
uk.legal.moderated 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4334
EMP (phn path) 490
Binary Image: misplaced jpg 170
EMP (phn nph) 105
Too many newsgroups 77
EMP (phl) 41
Binary: non-binary distribution 24
User-issued cancel 19
Binary: misplaced par2 7
Cancel for rejected article 4
HTML post 4
Binary: misplaced r12 1
Binary: misplaced r19 1
Binary: misplaced r33 1
Binary: misplaced 032 1
Binary: misplaced 027 1
Binary: misplaced r38 1
Binary: misplaced r37 1
Binary: misplaced r15 1
Binary: misplaced sfv 1
TOTAL: 44 5308

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.mixmin.net 282
newsfeed.datemas.de 119
news.albasani.net 52
news.swapon.de 33
news.redatomik.org 17
newsfeed.neva.ru 10
news-out.aioe.org 9
news.samoylyk.net 6
news.snarked.org 5
feeder.eternal-september.org 4
TOTAL: 13 544
TOTAL: 1 544

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 19127 2004 17044 59 0 325 10%23:59:49
2awxg 20259 64 20165 6 0 7 0%23:59:49
3news.freedyn.net 20929 27 20559 1 0 44 0%23:59:48
4news.snarked.org 14477 24 14313 7 0 17 0%23:59:47
5csiph.com 19877 11 19786 1 0 0 0%23:59:49
6mb-net.net 20602 10 20504 0 0 0 0%23:59:48
7aioe 17458 9 17359 2 0 7 0%23:59:48
8news.samoylyk.net 18899 9 18843 1 0 1 0%23:59:47
9news.redatomik.org 18945 9 18910 1 0 0 0%23:59:45
10neva.ru 19030 8 19015 3 0 0 0%23:59:48
11news-in.mixmin.net 15949 6 15899 1 0 83 0%23:59:48
12newsfeed.datemas.de 18750 5 18693 1 0 194 0%23:59:48
13eternal-september 17687 4 17550 1 0 345 0%23:59:48
14news.albasani.net 20776 2 17833 922 0 6451 0%23:59:48
15news.swapon.de 2065 0 2062 0 0 0 0%23:55:24
TOTAL: 15 264830 2192 258535 1006 0 7474 0%359:52:34
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org13.5 MB924.1 KB14.4 MB0.2 KB/s7.2 KB23:59:49
2awxg282.4 KB42.5 KB324.9 KB0.0 KB/s4.6 KB23:59:49
3news.freedyn.net75.8 KB1.8 KB77.6 KB0.0 KB/s2.8 KB23:59:48
4news.snarked.org47.4 KB15.3 KB62.7 KB0.0 KB/s2.0 KB23:59:47
5csiph.com15.1 KB1.8 KB16.8 KB0.0 KB/s1.4 KB23:59:49
6mb-net.net13.1 KB0.0 KB13.1 KB0.0 KB/s1.3 KB23:59:48
7neva.ru12.8 KB3.7 KB16.5 KB0.0 KB/s1.5 KB23:59:48
8aioe11.3 KB8.6 KB19.9 KB0.0 KB/s1.8 KB23:59:48
9news.samoylyk.net11.3 KB1.8 KB13.1 KB0.0 KB/s1.3 KB23:59:47
10news.redatomik.org11.3 KB1.8 KB13.1 KB0.0 KB/s1.3 KB23:59:45
11news-in.mixmin.net7.5 KB1.8 KB9.2 KB0.0 KB/s1.3 KB23:59:48
12newsfeed.datemas.de6.1 KB1.8 KB7.9 KB0.0 KB/s1.3 KB23:59:48
13eternal-september4.7 KB1.4 KB6.1 KB0.0 KB/s1.2 KB23:59:48
14news.albasani.net2.4 KB2.8 MB2.8 MB0.0 KB/s3.1 KB23:59:48
15news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:24
TOTAL: 1514.0 MB3.8 MB17.8 MB0.0 KB/s5.7 KB359:52:34
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 347 650021.6 MB 3258 10 1800:11:46
267-61-21-181.cpe.cableone.net 42 608721.1 MB 1430 1 005:18:40
3163.47.16.169 24 9341.3 MB 150 0 000:04:43
467-61-218-236.cpe.cableone.net 1 37.2 KB 2 0 018:17:49
TOTAL: 4 414 1352443.9 MB 4840 11 1829:32:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 347 650021.6 MB 3258 10 1800:11:46
2*.cpe.cableone.net 45 609021.1 MB 1432 1 028:03:42
3unresolved 39 9341.3 MB 150 0 001:16:57
TOTAL: 3 431 1352443.9 MB 4840 11 1829:32:35

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 18
malcolmlewis 3
TOTAL: 3 45

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-61-218-236.cpe.cableone.net 0.204 0.052 0.00618:17:49
67-61-21-181.cpe.cableone.net 4.604 2.256 0.07705:18:40
67-60-219-146.cpe.cableone.net 0.084 0.020 0.00204:27:12
1.128.96.223 0.400 0.060 0.00000:40:59
118.149.138.46 0.332 0.036 0.00000:25:24
agency.bbs.geek.nz 25.708 6.560 0.00000:11:46
118.149.138.224 0.064 0.004 0.00000:05:01
163.47.16.169 1.536 0.372 0.23700:04:43
94.7.199.37 0.052 0.020 0.00000:00:49
? 1.596 0.156 0.00000:00:09
TOTAL: 10 34.580 9.536 0.32229:32:35

Curious NNRP server explorers [Top 20]:

SystemConn
1.128.96.223 8
118.149.138.46 5
67-60-219-146.cpe.cableone.net 2
94.7.199.37 1
118.149.138.224 1
TOTAL: 5 17

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 5108 37.8%
2comp 2669 19.7%
3alt 1361 10.1%
4soc 1324 9.8%
5sci 1301 9.6%
6talk 701 5.2%
7uk 383 2.8%
8misc 329 2.4%
9news 233 1.7%
10humanities 66 0.5%
11nz 25 0.2%
12agency 7 0.1%
13aus 7 0.1%
14mozilla 5 0.0%
15eternal-september 5 0.0%
TOTAL: 15 13524100.0%

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

NewsgroupCount
1comp.os.linux.advocacy 1105
2rec.arts.tv 882
3rec.arts.drwho 559
4alt.atheism 532
5rec.sport.golf 492
6alt.comp.os.windows-10 431
7talk.politics.guns 363
8sci.electronics.design 328
9comp.lang.c 326
10uk.radio.amateur 312
11soc.culture.israel 305
12rec.sport.tennis 275
13rec.sport.football.college 263
14rec.boats 263
15sci.physics 246
16rec.gambling.poker 232
17rec.food.cooking 229
18rec.photo.digital 216
19news.lists.filters 204
20soc.retirement 203
21rec.sport.pro-wrestling 201
22misc.survivalism 189
23sci.math 181
24comp.lang.python 175
25talk.origins 167
26sci.physics.relativity 164
27soc.culture.polish 161
28comp.mobile.android 141
29talk.politics.misc 137
30rec.arts.sf.written 132
31comp.arch 116
32comp.lang.c++ 112
33rec.crafts.metalworking 88
34alt.bible 87
35soc.culture.china 85
36sci.military.naval 82
37rec.radio.amateur.misc 77
38rec.music.classical.recordings 76
39alt.politics 75
40rec.gambling.lottery 74
41alt.test 71
42sci.med.pharmacy 70
43comp.soft-sys.matlab 70
44rec.arts.tv.uk.coronation-st 69
45rec.arts.sf.fandom 65
46soc.history.what-if 64
47rec.radio.shortwave 62
48soc.genealogy.medieval 62
49humanities.lit.authors.shakespeare 58
50soc.culture.vietnamese 56
51rec.bicycles.tech 56
52rec.outdoors.rv-travel 56
53comp.protocols.dns.bind 54
54misc.health.alternative 54
55sci.astro.amateur 51
56rec.games.pinball 51
57soc.culture.romanian 46
58alt.bbs.mystic 46
59comp.sys.apple2 42
60alt.recovery.aa 41
61comp.lang.forth 40
62rec.autos.sport.f1 38
63rec.games.backgammon 38
64soc.culture.singapore 38
65soc.genealogy.benelux 36
66soc.culture.usa 34
67uk.tech.broadcast 33
68soc.culture.indian 33
69comp.sys.acorn.apps 33
70rec.sport.soccer 33
71soc.culture.jewish 33
72comp.lang.lisp 32
73misc.phone.mobile.iphone 30
74rec.radio.amateur.policy 28
75sci.electronics.repair 28
76comp.lang.tcl 26
77uk.comp.homebuilt 26
78rec.aviation.marketplace 25
79comp.arch.fpga 25
80alt.support.diabetes 25
81rec.arts.disney.parks 23
82soc.culture.bulgaria 22
83misc.news.internet.discuss 22
84rec.aviation.soaring 22
85rec.arts.comics.strips 21
86rec.music.rock-pop-r+b.1950s 21
87sci.astro 21
88comp.misc 20
89rec.music.classical.guitar 19
90rec.arts.movies.past-films 18
91comp.sys.raspberry-pi 18
92sci.logic 18
93soc.culture.brazil 18
94rec.skiing.alpine 18
95sci.lang 17
96talk.politics.mideast 17
97comp.protocols.time.ntp 16
98comp.lang.xharbour 16
99rec.puzzles.crosswords 16
100comp.sys.mac.hardware.storage 16
TOTAL: 309 13524