news.bbs.geek.nz daily logs

Feb 2 23:58:01 -- Feb 3 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 134569 61.6%11.2 MB 41.1%
innd 31244 14.3%5.1 MB 18.8%
inn 27808 12.7%6.2 MB 22.7%
innfeed 23337 10.7%4.5 MB 16.5%
cnfsstat 1440 0.7%250.0 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 218399 100.0%27.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 251048 47.6%
Positive hits 249990 47.4%
Do not exist 26005 4.9%
Cache misses 247 0.0%
TOTAL: 4 527290 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.122 0.0%125 0.000 8.976 64.643
article cleanup00:00:00.245 0.0%27145 0.000 0.009 0.043
article logging00:00:00.857 0.0%27678 0.000 0.031 0.661
article parse00:00:00.729 0.0%56469 0.000 0.013 0.031
article write00:00:01.727 0.0%19886 0.010 0.087 2.348
artlog/artcncl00:00:00.002 0.0%124 0.000 0.016 0.143
data move00:00:00.323 0.0%504436 0.000 0.001 0.002
hisgrep/artcncl00:00:00.580 0.0%121 0.000 4.793 41.429
hishave/artcncl00:00:00.186 0.0%125 0.000 1.488 13.286
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:12.171 0.0%534688 0.001 0.023 1.853
history sync00:00:00.023 0.0%362 0.000 0.064 0.500
history write00:00:09.625 0.0%26357 0.010 0.365 25.263
hiswrite/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
idle23:56:50.216 99.7%513230 95.920 167.976 529.735
nntp read00:00:03.850 0.0%517011 0.003 0.007 0.016
overview write00:01:18.125 0.1%19886 0.042 3.929 313.076
perl filter00:00:49.903 0.1%26355 1.105 1.893 24.836
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.027 0.0%39772 0.000 0.026 0.132
TOTAL: 24:00:51.75823:59:30.711 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.064 0.0%27780 0.000 0.002 0.018
article prepare00:00:00.001 0.0%11936 0.000 0.000 0.006
article read00:00:00.252 0.0%2649 0.000 0.095 3.444
backlog stats00:00:00.538 0.0%317216 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.401 0.0%266617 0.003 0.009 0.017
data write00:00:02.802 0.0%250895 0.003 0.011 0.019
idle23:53:01.206 99.9%317216 179.515 271.049 601.750
status file00:00:22.428 0.0%1891 0.400 11.860 496.214
TOTAL: 23:53:53.35423:53:29.692100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%8 0.000 0.000 0.000
idle19:44:50.690 95.5%150983 0.000 470.852 75405.250
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:34.037 0.0%388045 0.000 0.088 1.595
readart00:00:07.083 0.0%16251 0.000 0.436 25.800
TOTAL: 20:40:52.68519:45:31.810 95.5%----

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 21 14716 5143 9365 208 34%24:46:58
2news-out.mixmin.net 31 21745 3981 16550 1214 18%33:59:57
3news-out.aioe.org 2 18637 2576 15944 117 13%22:53:53
4feeder.eternal-september.org 98 24423 2101 21014 1308 8%65:09:09
5newsfeed.datemas.de 2 21102 1367 19248 487 6%23:49:17
6csiph.com 2 22672 1253 21349 70 5%27:41:39
7newsfeed.neva.ru 155 18132 1131 14080 2921 6%92:24:38
8news.redatomik.org 1 20434 987 19285 162 4%25:34:58
9news.albasani.net 1 21877 550 21007 320 2%24:18:15
10news.swapon.de 4 1799 231 1563 5 12%24:02:24
11news.freedyn.net 3 30663 179 30327 157 0%31:22:04
12news.samoylyk.net 2 20330 101 20171 58 0%23:59:54
13agency-nz-out.news.mb-net.net 27 21583 71 21413 99 0%32:08:45
14feeder.awxg.com 1 18000 65 17889 46 0%23:59:56
15localhost 11 10 9 0 1 90%00:00:01
16news.ausics.org 5 20166 2 20160 4 0%36:23:18
TOTAL: 16 366 296289 19747 269365 7177 6%512:35:06
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org16.6 MB28.9 KB789.1 KB17.3 MB 95%3.3 KB
2news-out.mixmin.net11.4 MB406.3 KB2.6 MB14.5 MB 79%2.9 KB
3newsfeed.datemas.de8.3 MB175.4 KB8.2 MB16.7 MB 49%9.2 KB
4feeder.eternal-september.org7.7 MB586.7 KB4.2 MB12.5 MB 61%3.8 KB
5news-out.aioe.org6.5 MB122.6 KB280.7 KB6.9 MB 94%2.6 KB
6news.albasani.net3.7 MB227.3 KB983.9 KB4.9 MB 75%5.8 KB
7csiph.com3.4 MB148.9 KB216.1 KB3.8 MB 90%2.9 KB
8newsfeed.neva.ru2.7 MB139.4 KB9.9 MB12.7 MB 21%3.2 KB
9news.redatomik.org2.5 MB189.0 KB332.2 KB3.0 MB 83%2.7 KB
10news.freedyn.net1.3 MB484.0 KB931.8 KB2.7 MB 48%8.1 KB
11news.samoylyk.net630.7 KB425.6 KB1.3 MB2.4 MB 26%15.2 KB
12news.swapon.de565.0 KB6.3 KB7.2 KB578.5 KB 97%2.5 KB
13feeder.awxg.com215.4 KB147.9 KB150.8 KB514.1 KB 41%4.6 KB
14agency-nz-out.news.mb-net.net198.6 KB182.0 KB372.2 KB752.8 KB 26%4.4 KB
15localhost17.7 KB0.0 KB2.2 KB19.9 KB 89%2.0 KB
16news.ausics.org2.4 KB8.1 KB14.6 KB25.1 KB 9%4.2 KB
TOTAL: 1665.8 MB3.2 MB30.2 MB99.2 MB 66%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 2 23:58:01 - 23:59:59 30 0.2% 0.2589.4 KB 0.1% 0.75
Feb 3 00:00:00 - 00:59:59 946 4.8% 0.262.6 MB 4.0% 0.75
Feb 3 01:00:00 - 01:59:59 832 4.2% 0.233.5 MB 5.3% 0.99
Feb 3 02:00:00 - 02:59:59 1013 5.1% 0.282.6 MB 3.9% 0.74
Feb 3 03:00:00 - 03:59:59 1121 5.6% 0.313.5 MB 5.4% 1.01
Feb 3 04:00:00 - 04:59:59 1155 5.8% 0.323.3 MB 5.0% 0.93
Feb 3 05:00:00 - 05:59:59 1201 6.0% 0.334.2 MB 6.3% 1.19
Feb 3 06:00:00 - 06:59:59 1163 5.8% 0.323.4 MB 5.1% 0.96
Feb 3 07:00:00 - 07:59:59 1061 5.3% 0.293.4 MB 5.2% 0.97
Feb 3 08:00:00 - 08:59:59 1113 5.6% 0.314.1 MB 6.3% 1.17
Feb 3 09:00:00 - 09:59:59 903 4.5% 0.252.4 MB 3.7% 0.69
Feb 3 10:00:00 - 10:59:59 937 4.7% 0.263.1 MB 4.7% 0.88
Feb 3 11:00:00 - 11:59:59 978 4.9% 0.273.2 MB 4.9% 0.92
Feb 3 12:00:00 - 12:59:59 729 3.7% 0.202.3 MB 3.5% 0.65
Feb 3 13:00:00 - 13:59:59 639 3.2% 0.182.3 MB 3.4% 0.64
Feb 3 14:00:00 - 14:59:59 531 2.7% 0.151.6 MB 2.4% 0.45
Feb 3 15:00:00 - 15:59:59 532 2.7% 0.151.7 MB 2.5% 0.47
Feb 3 16:00:00 - 16:59:59 389 2.0% 0.111.3 MB 2.0% 0.37
Feb 3 17:00:00 - 17:59:59 429 2.2% 0.121.5 MB 2.3% 0.44
Feb 3 18:00:00 - 18:59:59 463 2.3% 0.132.4 MB 3.6% 0.67
Feb 3 19:00:00 - 19:59:59 502 2.5% 0.142.2 MB 3.4% 0.63
Feb 3 20:00:00 - 20:59:59 714 3.6% 0.203.1 MB 4.7% 0.89
Feb 3 21:00:00 - 21:59:59 736 3.7% 0.202.1 MB 3.3% 0.61
Feb 3 22:00:00 - 22:59:59 892 4.5% 0.253.0 MB 4.6% 0.86
Feb 3 23:00:00 - 23:58:01 882 4.4% 0.252.8 MB 4.3% 0.83
TOTAL: 24:00:00 19891 100.0% 0.2365.8 MB 100.0% 0.78
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB23.4 GB 46.8% 0 0.28 2134.02
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB27.1 GB 54.3% 0 0.38 1578.50
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB289.7 MB 0.6% 0 0.0323703.70
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 GB16.1 GB 32.2% 0 0.62 979.22
TOTAL: 10-500.0 GB66.9 GB 13.4% 1 0.42-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 2964 19 0 47 0 0 0 0 2898
2news-out.mixmin.net 1524 0 0 115 0 0 0 0 1409
3feeder.eternal-september.org 1333 0 0 117 0 0 0 0 1216
4newsfeed.datemas.de 543 126 0 60 0 0 0 0 357
5news.albasani.net 335 5 0 68 0 0 0 0 262
6news.redatomik.org 246 0 0 63 0 0 0 0 183
7news.snarked.org 208 39 0 14 0 0 0 0 155
8news-out.aioe.org 155 10 0 47 0 0 0 0 98
9news.freedyn.net 152 8 0 42 0 0 0 0 102
10agency-nz-out.news.mb-net.net 100 18 0 56 0 0 0 0 26
11news.samoylyk.net 69 0 0 57 0 0 0 0 12
12csiph.com 62 0 0 41 0 0 0 0 21
13feeder.awxg.com 50 1 0 49 0 0 0 0 0
14news.swapon.de 48 0 0 4 0 0 0 0 44
15news.ausics.org 3 0 0 2 0 0 0 0 1
16localhost 1 0 0 0 0 0 0 0 1
TOTAL: 16 7793 226 0 782 0 0 0 0 6785

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.immobiliari 33
it.annunci.commerciali 33
it.annunci.varie 29
mozilla.dev.platform 19
mpc.lists.freebsd.gnome 13
gmane.linux.alsa.devel 12
fido7.ru.android 11
mpc.lists.nanog 11
mozilla.dev.ui-alerts 6
gmane.comp.gis.openstreetmap.tagging 6
mozilla.dev.l10n 4
mozilla.support.seamonkey 4
south-wales.test 3
al.christnet.bible 3
mozilla.dev.developer-tools 3
nemo.dev.serveur 3
mozilla.community.balkans 3
gmane.linux.alsa.user 2
mpc.lists.freebsd.pf 2
mpc.lists.freebsd.office 2
TOTAL: 44 226

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5297
EMP (phn path) 399
EMP (phn nph) 200
EMP (phl) 117
Binary: non-binary distribution 87
Binary: misplaced rar 45
Too many newsgroups 44
User-issued cancel 43
Binary: misplaced par2 5
Too many newsgroups (low_xpost) 4
User-issued spam cancel 3
Binary Image: misplaced gif 2
HTML post 2
Cancel for rejected article 1
TOTAL: 14 6249

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
news.freedyn.net 2
TOTAL: 1 2
Including strange strings
news-out.mixmin.net 307
news.redatomik.org 83
newsfeed.datemas.de 44
news.swapon.de 42
news-out.aioe.org 36
news.albasani.net 14
newsfeed.neva.ru 3
news.snarked.org 2
news.samoylyk.net 1
news.freedyn.net 1
TOTAL: 11 534
TOTAL: 2 536

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 17982 1668 16285 29 0 0 9%23:59:35
2awxg 19537 166 19307 5 0 10 0%23:59:42
3news.freedyn.net 19763 15 19715 0 0 0 0%23:59:37
4aioe 16333 13 16253 0 0 0 0%23:59:34
5news.redatomik.org 19347 12 18912 4 0 6 0%23:59:38
6csiph.com 18708 10 18522 0 0 6 0%23:59:38
7mb-net.net 19852 10 19781 0 0 0 0%23:59:38
8news.samoylyk.net 17979 10 17904 1 0 0 0%23:59:38
9news.snarked.org 14696 9 14678 1 0 45 0%23:57:42
10news-in.mixmin.net 15208 9 15171 0 0 0 0%23:59:33
11eternal-september 16873 8 16812 0 0 86 0%23:59:38
12news.albasani.net 21196 6 18083 900 0 7656 0%23:59:38
13neva.ru 18434 5 18415 5 0 0 0%23:59:38
14newsfeed.datemas.de 18541 3 18393 1 0 285 0%23:59:38
15news.swapon.de 1696 0 1674 1 0 31 0%23:41:17
TOTAL: 15 256145 1944 249905 947 0 8125 0%359:34:04
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org14.3 MB717.9 KB15.0 MB0.2 KB/s9.0 KB23:59:35
2awxg547.0 KB17.5 KB564.5 KB0.0 KB/s3.3 KB23:59:42
3news.freedyn.net47.6 KB0.0 KB47.6 KB0.0 KB/s3.2 KB23:59:37
4aioe26.2 KB0.0 KB26.2 KB0.0 KB/s2.0 KB23:59:34
5news.redatomik.org23.6 KB14.1 KB37.6 KB0.0 KB/s2.4 KB23:59:38
6csiph.com18.9 KB0.0 KB18.9 KB0.0 KB/s1.9 KB23:59:38
7mb-net.net18.9 KB0.0 KB18.9 KB0.0 KB/s1.9 KB23:59:38
8news.samoylyk.net18.9 KB5.5 KB24.4 KB0.0 KB/s2.2 KB23:59:38
9news-in.mixmin.net17.7 KB0.0 KB17.7 KB0.0 KB/s2.0 KB23:59:33
10news.snarked.org16.7 KB2.2 KB18.9 KB0.0 KB/s1.9 KB23:57:42
11eternal-september14.5 KB0.0 KB14.5 KB0.0 KB/s1.8 KB23:59:38
12neva.ru10.0 KB8.9 KB18.9 KB0.0 KB/s1.9 KB23:59:38
13news.albasani.net9.1 KB3.2 MB3.3 MB0.0 KB/s3.7 KB23:59:38
14newsfeed.datemas.de4.5 KB1.8 KB6.2 KB0.0 KB/s1.6 KB23:59:38
15news.swapon.de0.0 KB1.3 KB1.3 KB0.0 KB/s1.3 KB23:41:17
TOTAL: 1515.0 MB4.0 MB19.0 MB0.0 KB/s6.7 KB359:34:04
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-21-181.cpe.cableone.net 7 828627.7 MB 1157 3 1601:48:33
2agency.bbs.geek.nz 385 607319.9 MB 3552 4 000:12:25
3vorlon.activateit.net.au 24 9451.2 MB 148 0 000:04:58
4192.168.1.9 2 3221.4 MB 14 0 000:00:15
5192.168.1.7 1 221756.7 KB 6 0 000:00:06
660.234.68.122 7 1754.5 KB 1 2 000:26:01
714.250.197.104.bc.googleusercontent.com 3 1011.7 KB 2 0 000:06:10
TOTAL: 7 429 1587451.1 MB 4880 9 1620:52:49

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 9 828627.7 MB 1157 3 1618:57:13
2*.bbs.geek.nz 385 607319.9 MB 3552 4 000:12:25
3*.activateit.net.au 24 9451.2 MB 148 0 000:04:58
4unresolved 20 5602.2 MB 21 2 001:31:59
5*.250.197.104.bc.googleusercontent.com 3 1011.7 KB 2 0 000:06:10
TOTAL: 5 441 1587451.1 MB 4880 9 1620:52:49

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 7
bpanther 7
paulh 3
malcolmlewis 2
TOTAL: 5 43

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.188 0.084 0.03917:08:40
67-61-21-181.cpe.cableone.net 2.200 1.752 0.01501:48:33
118.149.187.131 0.252 0.036 0.00000:34:10
118.149.148.111 0.444 0.044 0.00000:31:25
60.234.68.122 0.512 0.096 0.01300:26:01
agency.bbs.geek.nz 29.064 7.096 0.00000:12:25
14.250.197.104.bc.googleusercontent.com 0.332 0.076 0.00000:06:10
vorlon.activateit.net.au 1.512 0.436 0.14700:04:58
192.168.1.9 0.224 0.080 0.00600:00:15
192.168.1.7 0.112 0.080 0.00300:00:06
? 1.712 0.180 0.00000:00:02
TOTAL: 11 36.552 9.960 0.22320:52:49

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.148.111 7
118.149.187.131 3
67-60-219-146.cpe.cableone.net 2
TOTAL: 3 12

NNRP gethostbyaddr failures [Top 20]:

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
118.149.187.131 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1rec 4722 29.6%
2alt 2400 15.1%
3soc 1761 11.1%
4comp 1758 11.0%
5sci 1493 9.4%
6uk 1251 7.9%
7linux 977 6.1%
8talk 716 4.5%
9misc 282 1.8%
10nz 238 1.5%
11news 165 1.0%
12mozilla 84 0.5%
13microsoft 25 0.2%
14agency 19 0.1%
15perl 16 0.1%
16humanities 10 0.1%
17aus 9 0.1%
TOTAL: 17 15926100.0%

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

NewsgroupCount
1rec.arts.tv 922
2uk.d-i-y 584
3alt.fan.rush-limbaugh 517
4rec.food.cooking 455
5alt.atheism 441
6comp.lang.python 414
7rec.drugs.cannabis 325
8linux.debian.bugs.dist 312
9rec.arts.drwho 305
10soc.retirement 301
11sci.electronics.design 297
12sci.physics 285
13linux.debian.changes.devel 280
14alt.comp.os.windows-10 276
15rec.sport.tennis 269
16talk.politics.misc 268
17soc.culture.polish 264
18rec.sport.golf 260
19talk.politics.guns 245
20rec.arts.sf.written 233
21alt.politics.trump 232
22uk.legal 231
23nz.general 207
24sci.math 196
25alt.politics 191
26talk.origins 188
27sci.physics.relativity 184
28alt.bible.prophecy 181
29rec.sport.football.college 156
30soc.culture.israel 150
31linux.debian.bugs.rc 146
32soc.culture.china 131
33rec.gambling.poker 130
34rec.outdoors.rv-travel 125
35sci.logic 122
36comp.lang.c 121
37news.lists.filters 119
38misc.survivalism 115
39comp.lang.c++ 114
40rec.aviation.soaring 109
41rec.boats 108
42uk.legal.moderated 104
43uk.comp.sys.mac 90
44rec.photo.digital 86
45uk.radio.amateur 86
46alt.conspiracy 86
47rec.radio.shortwave 86
48comp.sys.mac.system 85
49rec.aquaria.marine.misc 84
50soc.culture.usa 83
51alt.test 80
52uk.rec.cars.maintenance 76
53comp.lang.forth 76
54rec.music.rock-pop-r+b.1950s 76
55soc.culture.singapore 75
56soc.culture.indian 74
57comp.sys.mac.apps 74
58alt.support.diabetes 74
59sci.med.cardiology 73
60rec.aviation.marketplace 70
61comp.sys.raspberry-pi 70
62comp.protocols.time.ntp 64
63misc.phone.mobile.iphone 64
64comp.sys.mac.advocacy 64
65sci.military.naval 63
66comp.os.vms 63
67rec.autos.sport.f1 62
68alt.recovery.aa 61
69comp.sys.apple2 58
70rec.sport.soccer 57
71comp.mobile.ipad 56
72mozilla.support.firefox 54
73sci.astro.amateur 53
74rec.bicycles.tech 50
75uk.rec.driving 47
76sci.lang 46
77sci.med.pharmacy 46
78rec.woodworking 46
79rec.music.classical.recordings 45
80linux.gentoo.user 44
81comp.os.linux.advocacy 42
82alt.society.liberalism 40
83rec.arts.disney.parks 40
84soc.history.what-if 40
85rec.crafts.metalworking 38
86comp.sys.acorn.apps 38
87linux.debian.devel 37
88rec.arts.sf.fandom 36
89misc.health.alternative 36
90comp.os.linux.misc 35
91soc.culture.african.american 33
92soc.culture.australian 32
93comp.lang.fortran 31
94linux.debian.user 30
95soc.culture.spain 30
96soc.culture.netherlands 30
97rec.games.pinball 30
98rec.audio.opinion 30
99comp.arch 30
100soc.culture.jewish 29
TOTAL: 385 15926