news.bbs.nz daily logs

Apr 23 23:58:02 -- Apr 24 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 131751 62.2%10.3 MB 39.6%
innd 28747 13.6%4.7 MB 18.0%
inn 26140 12.3%6.1 MB 23.4%
innfeed 23687 11.2%4.7 MB 18.0%
cnfsstat 1440 0.7%249.1 KB 0.9%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 211766 100.0%26.0 MB100.0%

History cache:

ReasonCount%Count
Negative hits 279096 49.3%
Positive hits 261988 46.3%
Do not exist 24818 4.4%
Cache misses 95 0.0%
TOTAL: 4 565997 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.101 0.0%235 0.000 0.430 3.143
article cleanup00:00:00.250 0.0%25205 0.000 0.010 0.040
article logging00:00:00.630 0.0%25889 0.000 0.024 0.110
article parse00:00:00.736 0.0%80987 0.000 0.009 0.034
article write00:00:01.607 0.0%20367 0.015 0.079 1.182
artlog/artcncl00:00:00.004 0.0%232 0.000 0.017 1.000
data move00:00:00.436 0.0%562474 0.000 0.001 0.010
hisgrep/artcncl00:00:00.004 0.0%226 0.000 0.018 0.500
hishave/artcncl00:00:00.000 0.0%235 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.001 0.0%568274 0.002 0.014 0.715
history sync00:00:00.015 0.0%367 0.000 0.041 0.500
history write00:00:05.040 0.0%24911 0.011 0.202 12.052
hiswrite/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
idle23:57:46.955 99.8%572505 95.854 150.683 410.756
nntp read00:00:04.062 0.0%574306 0.004 0.007 0.029
overview write00:00:38.178 0.0%20367 0.066 1.875 45.264
perl filter00:00:45.698 0.1%24911 1.028 1.834 18.865
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.980 0.0%40734 0.000 0.024 0.106
TOTAL: 24:00:51.66123:59:32.697 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.066 0.0%28140 0.000 0.002 0.021
article prepare00:00:00.001 0.0%6800 0.000 0.000 0.014
article read00:00:00.210 0.0%1657 0.000 0.127 15.333
backlog stats00:00:00.479 0.0%323004 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.572 0.0%271390 0.002 0.009 0.016
data write00:00:03.376 0.0%256215 0.004 0.013 0.027
idle23:53:02.331 99.9%323004 166.694 266.196 578.787
status file00:00:30.874 0.0%2007 0.467 15.383 599.375
TOTAL: 23:54:03.01423:53:39.909100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.002 0.0%26 0.000 0.077 0.333
idle58:41:17.605 98.6%172190 0.000 1227.003 95242.530
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:10:15.075 0.3%424501 0.000 1.449 6.330
readart00:00:41.293 0.0%39737 0.000 1.039 28.000
TOTAL: 59:30:32.91558:52:13.975 98.9%----

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 47 24378 6950 14595 2833 28%45:39:55
2news-out.aioe.org 2 20927 3382 17436 109 16%24:42:35
3news.albasani.net 1 22053 2233 19337 483 10%24:59:15
4feeder.eternal-september.org 18 23171 2192 20811 168 9%30:00:28
5news.redatomik.org 1 16132 1342 14769 21 8%20:24:17
6newsfeed.neva.ru 150 16155 1222 14595 338 7%75:05:58
7newsfeed.datemas.de 4 19147 1108 17600 439 5%21:56:39
8csiph.com 4 22145 1021 21108 16 4%27:00:56
9news-out.mixmin.net 5 19742 660 18735 347 3%24:48:18
10news.swapon.de 7 1656 177 1475 4 10%24:05:16
11news.samoylyk.net 19 20234 70 20147 17 0%26:39:31
12agency-nz-out.news.mb-net.net 8 19724 64 19599 61 0%23:10:16
13feeder.awxg.com 1 17589 62 17512 15 0%23:59:57
14news.freedyn.net 1 20925 17 20888 20 0%22:50:07
15news.ausics.org 1 6771 4 6767 0 0%09:22:45
16localhost 1 0 0 0 0 0%00:00:00
TOTAL: 16 270 270749 20504 245374 4871 7%424:46:13
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org23.7 MB179.4 KB42.9 MB66.8 MB 35%7.0 KB
2news-out.aioe.org7.7 MB34.5 KB550.7 KB8.3 MB 93%2.4 KB
3feeder.eternal-september.org6.4 MB225.4 KB2.2 MB8.7 MB 72%3.8 KB
4news.albasani.net5.7 MB63.3 KB977.9 KB6.8 MB 84%2.5 KB
5csiph.com3.4 MB39.3 KB43.0 KB3.5 MB 97%3.4 KB
6newsfeed.datemas.de3.3 MB57.3 KB3.1 MB6.4 MB 50%4.3 KB
7news.redatomik.org3.0 MB35.3 KB45.6 KB3.1 MB 97%2.3 KB
8newsfeed.neva.ru2.8 MB64.4 KB1.3 MB4.1 MB 68%2.7 KB
9news-out.mixmin.net2.3 MB49.9 KB693.8 KB3.0 MB 75%3.0 KB
10news.swapon.de482.0 KB0.0 KB9.6 KB491.7 KB 98%2.7 KB
11news.samoylyk.net197.2 KB44.7 KB118.9 KB360.8 KB 54%4.1 KB
12feeder.awxg.com167.6 KB28.2 KB36.3 KB232.1 KB 72%3.0 KB
13agency-nz-out.news.mb-net.net156.4 KB54.4 KB215.3 KB426.1 KB 36%3.4 KB
14news.freedyn.net42.8 KB31.3 KB141.6 KB215.8 KB 19%5.8 KB
15news.ausics.org22.5 KB0.0 KB0.0 KB22.5 KB100%5.6 KB
16localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1659.4 MB907.3 KB52.2 MB112.4 MB 52%4.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 23 23:58:02 - 23:59:59 22 0.1% 0.1972.8 KB 0.1% 0.62
Apr 24 00:00:00 - 00:59:59 971 4.8% 0.272.5 MB 4.2% 0.70
Apr 24 01:00:00 - 01:59:59 970 4.8% 0.273.1 MB 5.2% 0.87
Apr 24 02:00:00 - 02:59:59 1084 5.3% 0.303.0 MB 5.1% 0.85
Apr 24 03:00:00 - 03:59:59 1128 5.5% 0.313.5 MB 5.8% 0.98
Apr 24 04:00:00 - 04:59:59 1260 6.2% 0.353.1 MB 5.2% 0.88
Apr 24 05:00:00 - 05:59:59 1177 5.8% 0.333.1 MB 5.2% 0.88
Apr 24 06:00:00 - 06:59:59 1131 5.5% 0.313.4 MB 5.8% 0.98
Apr 24 07:00:00 - 07:59:59 1111 5.4% 0.313.2 MB 5.4% 0.90
Apr 24 08:00:00 - 08:59:59 1061 5.2% 0.293.5 MB 5.8% 0.98
Apr 24 09:00:00 - 09:59:59 797 3.9% 0.222.1 MB 3.5% 0.59
Apr 24 10:00:00 - 10:59:59 708 3.5% 0.201.9 MB 3.2% 0.54
Apr 24 11:00:00 - 11:59:59 569 2.8% 0.162.1 MB 3.5% 0.59
Apr 24 12:00:00 - 12:59:59 465 2.3% 0.131.5 MB 2.5% 0.43
Apr 24 13:00:00 - 13:59:59 493 2.4% 0.141.9 MB 3.2% 0.53
Apr 24 14:00:00 - 14:59:59 512 2.5% 0.142.5 MB 4.2% 0.71
Apr 24 15:00:00 - 15:59:59 408 2.0% 0.111.2 MB 2.1% 0.35
Apr 24 16:00:00 - 16:59:59 406 2.0% 0.111.5 MB 2.6% 0.43
Apr 24 17:00:00 - 17:59:59 711 3.5% 0.202.1 MB 3.6% 0.60
Apr 24 18:00:00 - 18:59:59 689 3.4% 0.192.1 MB 3.5% 0.59
Apr 24 19:00:00 - 19:59:59 831 4.1% 0.232.1 MB 3.6% 0.60
Apr 24 20:00:00 - 20:59:59 900 4.4% 0.252.4 MB 4.0% 0.67
Apr 24 21:00:00 - 21:59:59 996 4.9% 0.282.5 MB 4.3% 0.72
Apr 24 22:00:00 - 22:59:59 1035 5.1% 0.292.4 MB 4.0% 0.68
Apr 24 23:00:00 - 23:58:01 954 4.7% 0.272.7 MB 4.6% 0.80
TOTAL: 23:59:59 20389 100.0% 0.2459.3 MB 100.0% 0.70
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB25.3 GB 50.6% 0 0.29 2102.35
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB29.2 GB 58.5% 0 0.34 1787.29
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB319.1 MB 0.6% 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 GB20.2 GB 40.3% 0 0.61 988.90
TOTAL: 10-500.0 GB75.0 GB 15.0% 1 0.39-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2791 118 0 28 0 0 0 0 2645
2news.albasani.net 690 5 0 26 0 0 0 0 659
3newsfeed.datemas.de 556 145 0 29 0 0 0 0 382
4news-out.mixmin.net 399 0 0 19 0 0 0 0 380
5newsfeed.neva.ru 339 42 0 27 1 0 0 0 269
6news.redatomik.org 191 0 0 21 0 0 0 0 170
7news-out.aioe.org 173 74 0 16 0 0 0 0 83
8feeder.eternal-september.org 163 0 0 37 0 0 0 0 126
9agency-nz-out.news.mb-net.net 66 19 0 25 0 0 0 0 22
10news.swapon.de 63 0 0 0 0 0 0 0 63
11news.samoylyk.net 24 0 0 22 0 0 0 0 2
12news.freedyn.net 24 3 0 11 0 0 0 0 10
13feeder.awxg.com 24 4 0 19 0 0 0 0 1
14csiph.com 16 0 0 14 0 0 0 0 2
TOTAL: 14 5519 410 0 294 1 0 0 0 4814

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.commerciali 55
it.annunci.immobiliari 54
gmane.linux.alsa.devel 53
mpc.lists.freebsd.ports.bugs 51
it.annunci.varie 50
gmane.comp.gis.openstreetmap.tagging 20
mozilla.support.seamonkey 16
gmane.linux.kernel 13
mozilla.dev.security.policy 9
eagle.support.eng 8
mozilla.dev.platform 7
mpc.lists.nanog 6
mozilla.tools.marionette 5
mpc.lists.freebsd.gnome 5
mozilla.dev.ui-alerts 4
mozilla.dev.l10n.web 4
mpc.lists.freebsd.wireless 3
mozilla.support.bugzilla 3
mozilla.dev.developer-tools 3
mpc.lists.freebsd.office 3
TOTAL: 50 410

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

GroupsCount
it.scienza.astronomia 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3523
Binary: misplaced rar 313
Too many newsgroups 59
EMP (phn path) 50
Binary: non-binary distribution 44
EMP (phl) 34
Binary: misplaced par2 28
Binary Image: misplaced jpg 28
User-issued cancel 25
EMP (phn nph) 21
Too many newsgroups (meow) 2
HTML post 1
User-issued spam cancel 1
Binary: misplaced 036 1
TOTAL: 14 4130

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.albasani.net 242
news.redatomik.org 162
newsfeed.datemas.de 92
news-out.aioe.org 66
news.swapon.de 61
news-out.mixmin.net 39
news.snarked.org 14
newsfeed.neva.ru 5
feeder.eternal-september.org 1
feeder.awxg.com 1
TOTAL: 11 684
TOTAL: 1 684

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 18972 867 18076 29 0 3 4%23:59:48
2awxg 20043 20 20016 3 0 0 0%23:59:47
3aioe 16023 10 15920 1 0 0 0%23:59:47
4news.freedyn.net 20597 6 20367 1 0 31 0%23:59:47
5news.snarked.org 13500 6 13299 4 0 161 0%23:59:47
6eternal-september 17798 2 17782 0 0 0 0%23:59:46
7news.swapon.de 1507 0 1507 0 0 0 0%23:32:02
8csiph.com 19490 0 19407 0 0 112 0%23:59:46
9news.redatomik.org 18900 0 18891 0 0 0 0%23:59:40
10mb-net.net 20355 0 20296 0 0 0 0%23:59:47
11newsfeed.datemas.de 19174 0 19116 1 0 0 0%23:59:47
12news.albasani.net 19640 0 17433 677 2 5679 0%23:59:18
13news-in.mixmin.net 18596 0 18577 0 0 0 0%23:59:47
14neva.ru 18746 0 18729 1 0 0 0%23:59:47
15news.samoylyk.net 19015 0 18901 0 0 1881 0%23:59:42
TOTAL: 15 262356 911 258317 717 2 7867 0%359:28:18
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org5.6 MB482.6 KB6.0 MB0.1 KB/s6.9 KB23:59:48
2awxg43.7 KB143.4 KB187.1 KB0.0 KB/s8.1 KB23:59:47
3news.freedyn.net43.7 KB6.8 KB50.5 KB0.0 KB/s7.2 KB23:59:47
4eternal-september41.9 KB0.0 KB41.9 KB0.0 KB/s20.9 KB23:59:46
5aioe28.3 KB1.9 KB30.2 KB0.0 KB/s2.7 KB23:59:47
6news.snarked.org17.9 KB27.0 KB44.9 KB0.0 KB/s4.5 KB23:59:47
7news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:32:02
8csiph.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
9news.redatomik.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:40
10mb-net.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
11newsfeed.datemas.de0.0 KB1.5 KB1.5 KB0.0 KB/s1.5 KB23:59:47
12news.albasani.net0.0 KB1.8 MB1.8 MB0.0 KB/s2.7 KB23:59:18
13news-in.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
14neva.ru0.0 KB2.3 KB2.3 KB0.0 KB/s2.3 KB23:59:47
15news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
TOTAL: 155.7 MB2.5 MB8.2 MB0.0 KB/s5.1 KB359:28:18
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-82-242.cpe.cableone.net 6 31475103.2 MB 757 0 1204:51:44
2agency.bbs.nz 384 643319.9 MB 3537 0 000:12:51
3vorlon.activateit.net.au 24 11261.4 MB 148 0 000:05:21
460.234.68.122 10 49202.0 KB 1 0 000:41:01
567-60-219-146.cpe.cableone.net 3 35.2 KB 3 0 053:01:58
TOTAL: 5 427 39086124.7 MB 4446 0 1259:30:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 9 31478103.2 MB 760 0 1257:53:42
2*.bbs.nz 384 643319.9 MB 3537 0 000:12:51
3*.activateit.net.au 24 11261.4 MB 148 0 000:05:21
4unresolved 21 49202.0 KB 1 0 001:17:53
5*.hsd1.or.comcast.net 24 00.0 KB 0 0 000:00:33
TOTAL: 5 462 39086124.7 MB 4446 0 1259:30:25

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 8
bpanther 6
malcolmlewis 3
TOTAL: 4 41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.300 0.116 0.01453:01:58
67-61-82-242.cpe.cableone.net 2.904 6.080 0.01604:51:44
60.234.68.122 0.756 0.096 0.02200:41:01
118.149.198.203 0.320 0.044 0.00000:21:32
agency.bbs.nz 28.948 7.112 0.00000:12:51
118.149.250.255 0.132 0.008 0.00000:10:03
vorlon.activateit.net.au 1.452 0.464 0.19200:05:21
118.149.167.250 0.060 0.012 0.00000:05:01
c-73-11-56-74.hsd1.or.comcast.net 1.220 0.252 0.00000:00:33
107.170.195.8 0.056 0.008 0.00000:00:09
94.166.190.216 0.040 0.020 0.00000:00:02
94.162.187.204 0.060 0.000 0.00000:00:02
? 1.044 0.132 0.00000:00:01
TOTAL: 13 37.292 14.344 0.24459:30:25

Curious NNRP server explorers [Top 20]:

SystemConn
c-73-11-56-74.hsd1.or.comcast.net 24
118.149.198.203 5
118.149.250.255 2
94.162.187.204 1
107.170.195.8 1
118.149.167.250 1
94.166.190.216 1
TOTAL: 7 35

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1uk 9840 25.2%
2talk 9725 24.9%
3soc 9441 24.2%
4rec 3847 9.8%
5alt 1964 5.0%
6comp 1262 3.2%
7sci 1247 3.2%
8linux 979 2.5%
9misc 277 0.7%
10news 213 0.5%
11mozilla 95 0.2%
12us 61 0.2%
13microsoft 31 0.1%
14nz 28 0.1%
15agency 23 0.1%
16humanities 14 0.0%
17aus 7 0.0%
18perl 6 0.0%
TOTAL: 18 39060100.0%

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

NewsgroupCount
1talk.politics.guns 5522
2uk.d-i-y 4319
3uk.legal 3142
4soc.retirement 3069
5talk.politics.misc 2387
6soc.history.what-if 1623
7talk.origins 1585
8rec.arts.tv 942
9uk.radio.amateur 816
10soc.penpals 713
11uk.tech.digital-tv 599
12soc.genealogy.britain 536
13soc.history 524
14alt.comp.os.windows-10 509
15soc.genealogy.medieval 452
16rec.arts.drwho 384
17linux.debian.bugs.dist 362
18soc.women 335
19alt.fan.rush-limbaugh 299
20rec.food.cooking 294
21alt.atheism 288
22comp.os.linux.advocacy 240
23uk.tech.broadcast 232
24uk.comp.sys.mac 231
25rec.sport.pro-wrestling 228
26soc.culture.china 224
27sci.physics 214
28linux.debian.bugs.rc 211
29news.lists.filters 206
30soc.history.medieval 199
31soc.culture.israel 198
32sci.logic 192
33soc.singles 180
34rec.gambling.poker 178
35linux.debian.changes.devel 175
36sci.electronics.design 164
37rec.boats 162
38rec.sport.football.college 150
39rec.arts.sf.written 150
40sci.physics.relativity 148
41misc.survivalism 145
42comp.lang.c++ 138
43comp.sys.raspberry-pi 137
44soc.genealogy.jewish 135
45comp.lang.c 131
46alt.conspiracy.jfk 129
47soc.culture.indian 126
48soc.culture.brazil 121
49uk.rec.cycling 112
50talk.bizarre 110
51sci.math 107
52uk.rec.cycling.moderated 105
53rec.sport.golf 105
54rec.sport.tennis 98
55alt.politics.trump 93
56soc.veterans 92
57sci.math.num-analysis 91
58soc.men 89
59sci.med.pharmacy 89
60rec.drugs.cannabis 87
61alt.conspiracy 86
62comp.lang.python 86
63uk.rec.cars.maintenance 86
64rec.bicycles.tech 86
65soc.culture.polish 82
66alt.bible.prophecy 82
67mozilla.general 80
68soc.history.living 76
69sci.med.cardiology 76
70alt.politics 75
71rec.aquaria.marine.misc 75
72alt.recovery.aa 71
73rec.aviation.soaring 67
74soc.culture.new-zealand 63
75soc.history.war.us-revolution 62
76rec.music.classical.recordings 59
77rec.crafts.metalworking 58
78alt.test 56
79rec.photo.digital 54
80sci.military.naval 53
81alt.bible 52
82uk.music.charts 52
83alt.society.liberalism 51
84comp.ai.philosophy 51
85soc.culture.usa 51
86rec.aviation.marketplace 50
87rec.arts.sf.tv 48
88rec.games.pinball 46
89uk.comp.homebuilt 45
90rec.radio.shortwave 44
91comp.sys.mac.system 43
92rec.autos.sport.f1 43
93soc.genealogy.benelux 41
94comp.sys.apple2 39
95uk.legal.moderated 39
96linux.debian.user 38
97linux.debian.devel 37
98soc.culture.vietnamese 34
99misc.invest.stocks 34
100rec.woodworking 33
TOTAL: 411 39060