news.bbs.geek.nz daily logs

Sep 15 23:58:01 -- Sep 16 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 133376 61.7%11.2 MB 41.8%
innd 30810 14.2%5.1 MB 18.9%
inn 28066 13.0%6.1 MB 22.7%
innfeed 22627 10.5%4.2 MB 15.7%
cnfsstat 1440 0.7%252.0 KB 0.9%
controlchan 5 0.0%0.7 KB 0.0%
TOTAL: 6 216324 100.0%26.8 MB100.0%

History cache:

ReasonCount%Count
Negative hits 272640 49.2%
Positive hits 238069 42.9%
Do not exist 26661 4.8%
Cache misses 17254 3.1%
TOTAL: 4 554624 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.160 0.0%166 0.000 0.964 4.200
article cleanup00:00:00.298 0.0%27453 0.000 0.011 0.053
article logging00:00:00.689 0.0%27922 0.000 0.025 0.187
article parse00:00:00.992 0.0%71075 0.002 0.014 0.033
article write00:00:01.353 0.0%19354 0.000 0.070 0.331
artlog/artcncl00:00:00.003 0.0%158 0.000 0.019 1.000
data move00:00:00.346 0.0%510988 0.000 0.001 0.005
hisgrep/artcncl00:00:00.002 0.0%158 0.000 0.013 0.100
hishave/artcncl00:00:00.000 0.0%166 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.487 0.0%555772 0.002 0.012 0.764
history sync00:00:00.007 0.0%300 0.000 0.023 0.500
history write00:00:06.945 0.0%26714 0.012 0.260 15.400
hiswrite/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
idle23:57:16.022 99.8%526434 70.716 163.812 480.386
nntp read00:00:03.129 0.0%527588 0.003 0.006 0.013
overview write00:00:45.205 0.1%19354 0.080 2.336 93.223
perl filter00:01:03.684 0.1%26714 1.465 2.384 18.358
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.958 0.0%38709 0.004 0.025 0.127
TOTAL: 24:00:22.42623:59:26.280 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.625 0.0%2545025 0.000 0.001 0.011
article prepare00:00:00.002 0.0%17530 0.000 0.000 0.036
article read00:00:02.085 0.0%4276 0.000 0.488 52.000
backlog stats00:00:01.041 0.0%1268430 0.000 0.001 0.003
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:05.033 0.0%821121 0.002 0.006 0.012
data write00:00:05.070 0.0%807293 0.003 0.006 0.018
idle23:50:00.961 99.9%1268430 28.542 67.643 473.664
status file00:00:12.221 0.0%1745 0.769 7.003 280.364
TOTAL: 23:51:19.05923:50:29.038 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%23 0.000 0.043 0.500
idle27:02:00.150 95.5%147713 0.000 658.846 76761.612
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:44.885 0.0%325671 0.000 0.138 3.943
readart00:00:03.435 0.0%13272 0.000 0.259 47.065
TOTAL: 28:17:55.17627:02:48.471 95.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
group-admin@isc.org001011
TOTAL001011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.mixmin.net 10 20734 4763 14971 1000 22%27:04:44
2csiph.com 1 18955 3383 15536 36 17%23:54:47
3news.redatomik.org 3 18925 2551 16085 289 13%23:49:20
4newsfeed.datemas.de 3 20182 2199 17424 559 10%23:32:29
5feeder.eternal-september.org 105 24702 1916 21600 1186 7%53:37:02
6newsfeed.neva.ru 152 10296 1424 5158 3714 13%88:07:47
7news-out.aioe.org 2 24339 1140 23111 88 4%33:59:12
8news.snarked.org 19 19723 916 18688 119 4%16:55:35
9news.albasani.net 3 24060 429 23460 171 1%20:50:58
10news.freedyn.net 2 21683 224 21322 137 1%24:03:54
11news.samoylyk.net 1 25467 162 24715 590 0%23:16:21
12feeder.awxg.com 1 17874 134 17699 41 0%23:59:57
13news.swapon.de 9 1455 114 1321 20 7%25:24:18
14agency-nz-out.news.mb-net.net 16 21444 81 21209 154 0%32:54:18
15localhost 1 0 0 0 0 0%00:00:00
TOTAL: 15 328 269839 19436 242299 8104 7%441:30:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.mixmin.net13.9 MB302.4 KB2.5 MB16.7 MB 83%3.0 KB
2newsfeed.datemas.de10.3 MB199.9 KB2.0 MB12.5 MB 82%4.6 KB
3feeder.eternal-september.org10.1 MB534.8 KB6.3 MB16.9 MB 59%5.6 KB
4csiph.com10.1 MB156.1 KB163.9 KB10.4 MB 96%3.1 KB
5news.redatomik.org6.5 MB205.0 KB976.7 KB7.7 MB 84%2.8 KB
6newsfeed.neva.ru3.9 MB205.1 KB17.7 MB21.8 MB 17%4.4 KB
7news.albasani.net3.6 MB331.6 KB718.8 KB4.6 MB 77%7.9 KB
8news-out.aioe.org3.5 MB259.6 KB286.5 KB4.0 MB 86%3.3 KB
9news.snarked.org3.4 MB49.2 KB561.2 KB4.0 MB 84%3.9 KB
10news.freedyn.net1.8 MB225.4 KB459.0 KB2.4 MB 72%6.9 KB
11news.samoylyk.net1.0 MB223.7 KB6.7 MB8.0 MB 13%10.9 KB
12news.swapon.de388.4 KB122.8 KB122.8 KB634.1 KB 61%4.7 KB
13feeder.awxg.com373.6 KB134.6 KB161.3 KB669.5 KB 55%3.8 KB
14agency-nz-out.news.mb-net.net219.6 KB407.7 KB807.7 KB1.4 MB 15%6.1 KB
15localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1569.0 MB3.3 MB39.4 MB111.7 MB 61%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 15 23:58:01 - 23:59:59 30 0.2% 0.2566.5 KB 0.1% 0.56
Sep 16 00:00:00 - 00:59:59 923 4.8% 0.262.4 MB 3.5% 0.69
Sep 16 01:00:00 - 01:59:59 1116 5.8% 0.314.3 MB 6.2% 1.22
Sep 16 02:00:00 - 02:59:59 1354 7.0% 0.384.4 MB 6.3% 1.24
Sep 16 03:00:00 - 03:59:59 1406 7.3% 0.395.1 MB 7.4% 1.46
Sep 16 04:00:00 - 04:59:59 1250 6.5% 0.353.3 MB 4.7% 0.93
Sep 16 05:00:00 - 05:59:59 1123 5.8% 0.313.7 MB 5.3% 1.04
Sep 16 06:00:00 - 06:59:59 1056 5.5% 0.294.3 MB 6.2% 1.23
Sep 16 07:00:00 - 07:59:59 931 4.8% 0.263.3 MB 4.7% 0.93
Sep 16 08:00:00 - 08:59:59 915 4.7% 0.253.1 MB 4.5% 0.88
Sep 16 09:00:00 - 09:59:59 907 4.7% 0.253.8 MB 5.5% 1.09
Sep 16 10:00:00 - 10:59:59 782 4.0% 0.223.3 MB 4.8% 0.95
Sep 16 11:00:00 - 11:59:59 671 3.5% 0.192.5 MB 3.7% 0.72
Sep 16 12:00:00 - 12:59:59 479 2.5% 0.131.5 MB 2.1% 0.42
Sep 16 13:00:00 - 13:59:59 468 2.4% 0.132.6 MB 3.7% 0.74
Sep 16 14:00:00 - 14:59:59 433 2.2% 0.122.0 MB 2.9% 0.56
Sep 16 15:00:00 - 15:59:59 456 2.4% 0.132.1 MB 3.1% 0.61
Sep 16 16:00:00 - 16:59:59 332 1.7% 0.091.6 MB 2.3% 0.46
Sep 16 17:00:00 - 17:59:59 403 2.1% 0.111.4 MB 2.0% 0.39
Sep 16 18:00:00 - 18:59:59 511 2.6% 0.142.8 MB 4.0% 0.79
Sep 16 19:00:00 - 19:59:59 644 3.3% 0.182.1 MB 3.1% 0.60
Sep 16 20:00:00 - 20:59:59 735 3.8% 0.202.1 MB 3.0% 0.60
Sep 16 21:00:00 - 21:59:59 795 4.1% 0.222.1 MB 3.0% 0.60
Sep 16 22:00:00 - 22:59:59 816 4.2% 0.232.3 MB 3.4% 0.67
Sep 16 23:00:00 - 23:58:01 815 4.2% 0.233.0 MB 4.3% 0.88
TOTAL: 24:00:00 19351 100.0% 0.2269.3 MB 100.0% 0.82
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB20.0 GB 40.1% 0 0.32 1868.40
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB23.2 GB 46.5% 0 0.40 1532.40
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB247.3 MB 0.5% 0 0.06 9413.15
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 GB8.3 GB 16.7% 0 0.67 910.35
TOTAL: 10-500.0 GB51.9 GB 10.4% 1 0.46-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 3632 59 0 48 0 0 0 0 3525
2feeder.eternal-september.org 1262 0 0 130 0 0 0 0 1132
3news-out.mixmin.net 1233 0 0 74 0 0 0 0 1159
4newsfeed.datemas.de 635 191 0 55 0 0 0 0 389
5news.samoylyk.net 592 0 0 59 0 0 0 0 533
6news.redatomik.org 375 0 0 48 0 0 0 0 327
7news.albasani.net 236 8 0 44 0 0 0 0 184
8agency-nz-out.news.mb-net.net 154 26 0 86 3 0 0 0 39
9news.snarked.org 125 6 0 13 0 0 0 0 106
10news.freedyn.net 123 7 0 33 0 0 0 0 83
11news-out.aioe.org 70 6 0 58 0 0 0 0 6
12feeder.awxg.com 41 6 0 33 0 0 0 0 2
13csiph.com 40 0 0 35 0 0 0 0 5
14news.swapon.de 37 0 0 18 0 0 0 0 19
15news.ausics.org 2 0 0 2 0 0 0 0 0
TOTAL: 15 8557 309 0 736 3 0 0 0 7509

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.commerciali 40
it.annunci.immobiliari 40
it.annunci.varie 37
mozilla.dev.platform 34
mpc.lists.nanog 26
mozilla.dev.security.policy 14
ut.test 14
mozilla.support.seamonkey 13
mozilla.dev.l10n.sr 12
gmane.comp.gis.openstreetmap.tagging 8
gmane.linux.alsa.devel 6
mpc.lists.freebsd.ports.bugs 6
mozilla.dev.ui-alerts 6
mpc.lists.freebsd.gecko 4
alt.net.dn42.users 4
aioe.helpdesk 4
mozilla.compatibility 4
fido7.ru.android 3
al.christnet.bible 3
alt.penthouse.sex.femdom 2
TOTAL: 45 309

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

GroupsCount
nz.politics.announce 3
TOTAL: 1 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6380
EMP (phn path) 301
EMP (phn nph) 235
Too many newsgroups 45
Too many newsgroups (low_xpost) 31
User-issued cancel 15
Binary: non-binary distribution 14
HTML post 4
Poison newsgroup 3
Binary: misplaced 016 2
Binary Image: misplaced jpg 2
Binary: misplaced 009 1
EMP (phl) 1
Cancel for rejected article 1
Binary: misplaced 022 1
Binary: misplaced 043 1
Binary: misplaced par2 1
Binary: misplaced 021 1
Binary: misplaced 024 1
TOTAL: 19 7040

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.mixmin.net 221
news.redatomik.org 92
newsfeed.datemas.de 77
news.albasani.net 39
news.swapon.de 19
newsfeed.neva.ru 9
news-out.aioe.org 3
csiph.com 3
feeder.eternal-september.org 3
news.samoylyk.net 2
TOTAL: 11 469
TOTAL: 1 469

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.snarked.org2526723 1770 20594 270 302500708 0%23:59:43
2news.ausics.org 17296 471 16794 31 0 0 2%23:59:45
3awxg 18759 72 18674 3 0 11 0%23:59:43
4news.redatomik.org 16935 16 16826 1 0 179 0%23:59:43
5news.albasani.net 34991 11 33032 1717 1 3909 0%23:57:49
6aioe 15978 5 15899 0 0 0 0%23:59:43
7news.freedyn.net 19364 3 19124 1 0 25 0%23:59:43
8csiph.com 16082 0 16013 0 0 0 0%23:59:37
9eternal-september 16772 0 16597 0 0 141 0%23:59:43
10newsfeed.datemas.de 17154 0 17085 0 0 1 0%23:59:37
11news-in.mixmin.net 14144 0 14117 0 0 0 0%23:59:40
12news.samoylyk.net 17193 0 17142 0 0 0 0%23:59:39
13neva.ru 17561 0 17558 0 0 0 0%23:59:43
14news.swapon.de 1372 0 1372 0 0 0 0%23:56:30
15mb-net.net 19331 0 19256 0 0 5 0%23:59:43
TOTAL: 152769655 2348 260083 2023 312504979 0%359:50:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org11.6 MB2.1 MB13.7 MB0.2 KB/s28.0 KB23:59:45
2news.snarked.org6.3 MB969.6 KB7.3 MB0.1 KB/s3.6 KB23:59:43
3awxg511.5 KB11.4 KB522.9 KB0.0 KB/s7.0 KB23:59:43
4news.redatomik.org39.9 KB5.9 KB45.8 KB0.0 KB/s2.7 KB23:59:43
5news.albasani.net24.3 KB6.0 MB6.0 MB0.1 KB/s3.5 KB23:57:49
6aioe9.3 KB0.0 KB9.3 KB0.0 KB/s1.9 KB23:59:43
7news.freedyn.net8.1 KB4.3 KB12.4 KB0.0 KB/s3.1 KB23:59:43
8csiph.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:37
9eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
10newsfeed.datemas.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:37
11news-in.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:40
12news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:39
13neva.ru0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
14news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:30
15mb-net.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
TOTAL: 1518.5 MB9.0 MB27.5 MB0.0 KB/s6.4 KB359:50:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-21-181.cpe.cableone.net 24 634822.5 MB 1745 0 003:36:40
2agency.bbs.geek.nz 344 542519.0 MB 3280 0 000:10:25
3163.47.16.169 24 10931.5 MB 134 0 000:04:59
460.234.244.55 7 51150.3 KB 1 0 000:35:55
TOTAL: 4 399 1291743.1 MB 5160 0 028:29:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 28 634822.5 MB 1745 0 026:12:29
2*.bbs.geek.nz 344 542519.0 MB 3280 0 000:10:25
3unresolved 45 11441.7 MB 135 0 002:06:46
4*.shodan.io 1 00.0 KB 0 0 000:00:05
TOTAL: 4 418 1291743.1 MB 5160 0 028:29:50

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 24
crash 7
malcolmlewis 4
TOTAL: 4 59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.344 0.108 0.01422:35:49
67-61-21-181.cpe.cableone.net 3.504 2.344 0.09303:36:40
118.149.171.61 0.420 0.056 0.00000:49:21
60.234.244.55 0.560 0.064 0.02100:35:55
118.149.151.86 0.300 0.044 0.00000:28:35
agency.bbs.geek.nz 24.412 5.648 0.00000:10:25
118.149.240.196 0.168 0.036 0.00000:07:54
163.47.16.169 1.344 0.444 1.05900:04:59
census1.shodan.io 0.052 0.004 0.00000:00:05
? 2.036 0.260 0.00000:00:03
TOTAL: 10 33.140 9.008 1.18728:29:50

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.171.61 6
118.149.151.86 5
67-60-219-146.cpe.cableone.net 4
118.149.240.196 3
census1.shodan.io 1
TOTAL: 5 19

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.244.55 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
118.149.171.61 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1rec 4720 36.6%
2soc 1770 13.7%
3comp 1631 12.6%
4sci 1541 12.0%
5alt 1389 10.8%
6talk 757 5.9%
7misc 514 4.0%
8news 215 1.7%
9uk 186 1.4%
10nz 39 0.3%
11eternal-september 38 0.3%
12agency 28 0.2%
13mozilla 27 0.2%
14aus 21 0.2%
15humanities 18 0.1%
TOTAL: 15 12894100.0%

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

NewsgroupCount
1rec.arts.tv 1131
2alt.comp.os.windows-10 520
3rec.arts.drwho 477
4alt.atheism 378
5comp.lang.c 358
6rec.sport.tennis 342
7talk.politics.guns 300
8misc.survivalism 298
9soc.culture.israel 292
10rec.food.cooking 286
11sci.physics 267
12soc.retirement 257
13sci.electronics.design 254
14sci.physics.relativity 250
15rec.aquaria.marine.misc 240
16talk.origins 239
17comp.lang.python 231
18sci.math 212
19soc.culture.polish 205
20news.lists.filters 188
21talk.politics.misc 186
22rec.arts.sf.written 177
23soc.culture.usa 163
24comp.os.linux.advocacy 156
25rec.sport.pro-wrestling 156
26rec.boats 156
27rec.outdoors.rv-travel 154
28rec.aviation.marketplace 144
29soc.culture.indian 136
30misc.health.alternative 126
31rec.sport.football.college 120
32rec.gambling.poker 118
33alt.politics 118
34uk.radio.amateur 114
35soc.culture.china 112
36soc.culture.singapore 109
37rec.bicycles.tech 102
38alt.bible 96
39comp.os.vms 94
40comp.sys.mac.apps 85
41sci.logic 80
42sci.military.naval 79
43comp.sys.apple2 77
44soc.culture.bulgaria 74
45rec.photo.digital 73
46sci.med.cardiology 71
47alt.test 70
48sci.lang 69
49comp.lang.fortran 68
50alt.recovery.aa 66
51comp.lang.forth 64
52sci.math.num-analysis 64
53rec.radio.amateur.misc 57
54uk.comp.os.linux 52
55soc.culture.quebec 50
56alt.support.diabetes 49
57soc.culture.jewish 48
58soc.history.what-if 47
59comp.sys.mac.system 45
60rec.sport.soccer 45
61rec.sport.golf 42
62rec.aviation.soaring 42
63nz.general 39
64rec.gambling.lottery 38
65rec.games.pinball 38
66rec.arts.movies.current-films 33
67rec.games.video.sony 33
68comp.lang.tcl 33
69alt.astronomy 33
70comp.protocols.time.ntp 32
71rec.music.dylan 32
72rec.music.classical.recordings 30
73rec.audio.opinion 30
74misc.transport.urban-transit 29
75sci.electronics.repair 29
76sci.med.pharmacy 29
77agency.test 28
78comp.soft-sys.sas 27
79eternal-september.support 27
80alt.os.linux.ubuntu 27
81comp.sys.mac.advocacy 27
82rec.radio.amateur.policy 27
83rec.autos.sport.f1 26
84rec.radio.shortwave 26
85rec.music.classical.guitar 26
86comp.soft-sys.matlab 25
87rec.arts.sf.fandom 24
88rec.games.backgammon 23
89soc.women 23
90rec.arts.movies.past-films 23
91sci.space.policy 21
92rec.arts.comics.strips 21
93rec.radio.info 21
94comp.misc 20
95rec.arts.sf.tv 20
96soc.culture.brazil 20
97comp.soft-sys.math.scilab 20
98aus.electronics 20
99uk.comp.homebuilt 20
100comp.sys.acorn.networking 19
TOTAL: 326 12894