news.bbs.geek.nz daily logs

Dec 22 23:58:02 -- Dec 23 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 126539 60.2%10.4 MB 40.2%
innd 33557 16.0%5.3 MB 20.5%
inn 25694 12.2%5.7 MB 21.8%
innfeed 22939 10.9%4.3 MB 16.6%
cnfsstat 1440 0.7%254.0 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 210170 100.0%25.9 MB100.0%

History cache:

ReasonCount%Count
Negative hits 245139 50.4%
Positive hits 216712 44.6%
Do not exist 24026 4.9%
Cache misses 237 0.0%
TOTAL: 4 486114 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.725 0.0%122 0.000 5.943 46.462
article cleanup00:00:00.229 0.0%25048 0.000 0.009 0.040
article logging00:00:00.693 0.0%25608 0.000 0.027 0.447
article parse00:00:00.841 0.0%83592 0.000 0.010 0.029
article write00:00:01.304 0.0%18135 0.015 0.072 0.235
artlog/artcncl00:00:00.001 0.0%103 0.000 0.010 1.000
data move00:00:00.419 0.0%498614 0.000 0.001 0.015
hisgrep/artcncl00:00:00.121 0.0%108 0.000 1.120 9.231
hishave/artcncl00:00:00.465 0.0%122 0.000 3.811 35.769
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.697 0.0%488656 0.000 0.012 1.001
history sync00:00:00.017 0.0%361 0.000 0.047 1.000
history write00:00:06.850 0.0%24139 0.007 0.284 22.079
hiswrite/artcncl00:00:00.001 0.0%14 0.000 0.071 0.125
idle23:58:11.403 99.8%513729 58.317 167.971 537.398
nntp read00:00:02.982 0.0%514003 0.003 0.006 0.018
overview write00:00:34.767 0.0%18135 0.074 1.917 53.484
perl filter00:00:47.913 0.1%24139 1.298 1.985 13.967
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.209 0.0%36270 0.000 0.033 0.099
TOTAL: 24:00:49.28423:59:55.637 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.046 0.0%22107 0.000 0.002 0.024
article prepare00:00:00.000 0.0%3540 0.000 0.000 0.000
article read00:00:00.103 0.0%828 0.000 0.124 4.222
backlog stats00:00:00.275 0.0%275499 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.358 0.0%226546 0.000 0.006 0.015
data write00:00:02.512 0.0%211887 0.005 0.012 0.022
idle23:53:29.421100.0%275499 202.013 312.195 746.496
status file00:00:13.348 0.0%1800 0.600 7.416 306.108
TOTAL: 23:54:06.45023:53:47.063100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle29:18:56.662 96.2%139600 0.000 755.993600328.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:54.385 0.0%306734 0.000 0.177 3.641
readart00:00:01.516 0.0%13860 0.000 0.109 6.500
TOTAL: 30:27:55.48429:19:52.563 96.3%----

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-out.mixmin.net 14 19616 4683 13984 949 23%31:16:20
2news.snarked.org 43 14046 4422 9373 251 31%48:48:44
3news-out.aioe.org 2 14244 2333 11823 88 16%16:46:45
4feeder.eternal-september.org 45 21319 2198 18005 1116 10%37:51:42
5csiph.com 3 18034 1184 16796 54 6%24:18:15
6newsfeed.neva.ru 161 14713 1129 10797 2787 7%96:41:29
7news.albasani.net 2 22409 666 21419 324 2%28:28:51
8news.redatomik.org 1 13157 382 12652 123 2%14:04:47
9news.freedyn.net 2 24085 260 23638 187 1%30:10:10
10newsfeed.datemas.de 1 13789 259 13384 146 1%13:49:51
11news.swapon.de 3 1575 192 1376 7 12%22:43:42
12feeder.awxg.com 2 16186 107 16027 52 0%23:59:40
13agency-nz-out.news.mb-net.net 23 20900 83 20593 224 0%34:43:28
14news.samoylyk.net 1 21384 41 20808 535 0%21:32:37
15localhost 5 4 3 0 1 75%00:00:00
16news.ausics.org 617 0 0 0 0 0%21:54:01
TOTAL: 16 925 235461 17942 210675 6844 7%467:10:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org13.8 MB251.2 KB868.0 KB14.9 MB 92%3.3 KB
2news-out.mixmin.net12.0 MB248.4 KB2.0 MB14.3 MB 84%2.6 KB
3feeder.eternal-september.org10.2 MB375.1 KB4.2 MB14.8 MB 68%4.6 KB
4news-out.aioe.org5.5 MB133.7 KB222.8 KB5.9 MB 94%2.5 KB
5news.albasani.net4.9 MB354.6 KB1.0 MB6.3 MB 77%6.5 KB
6csiph.com3.3 MB216.2 KB227.5 KB3.8 MB 88%3.1 KB
7newsfeed.datemas.de2.9 MB321.7 KB771.5 KB3.9 MB 72%10.0 KB
8newsfeed.neva.ru2.8 MB527.8 KB12.3 MB15.6 MB 18%4.1 KB
9news.freedyn.net2.4 MB597.3 KB1.1 MB4.1 MB 58%9.3 KB
10news.redatomik.org884.8 KB265.7 KB456.3 KB1.6 MB 55%3.2 KB
11news.swapon.de515.1 KB8.1 KB22.2 KB545.4 KB 94%2.7 KB
12feeder.awxg.com317.3 KB206.0 KB207.9 KB731.3 KB 43%4.6 KB
13agency-nz-out.news.mb-net.net248.6 KB323.1 KB11.3 MB11.9 MB 2%39.6 KB
14news.samoylyk.net110.9 KB700.6 KB18.1 MB18.9 MB 0%33.6 KB
15localhost6.7 KB0.0 KB2.2 KB8.8 KB 75%2.2 KB
16news.ausics.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1659.8 MB4.4 MB52.8 MB117.1 MB 51%4.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 22 23:58:02 - 23:59:59 22 0.1% 0.1949.6 KB 0.1% 0.42
Dec 23 00:00:00 - 00:59:59 815 4.5% 0.231.9 MB 3.1% 0.53
Dec 23 01:00:00 - 01:59:59 868 4.8% 0.242.8 MB 4.6% 0.79
Dec 23 02:00:00 - 02:59:59 844 4.7% 0.232.2 MB 3.7% 0.63
Dec 23 03:00:00 - 03:59:59 985 5.4% 0.273.0 MB 4.9% 0.85
Dec 23 04:00:00 - 04:59:59 1090 6.0% 0.303.8 MB 6.3% 1.08
Dec 23 05:00:00 - 05:59:59 1126 6.2% 0.313.3 MB 5.5% 0.94
Dec 23 06:00:00 - 06:59:59 1115 6.1% 0.313.6 MB 6.0% 1.03
Dec 23 07:00:00 - 07:59:59 1062 5.9% 0.293.2 MB 5.3% 0.92
Dec 23 08:00:00 - 08:59:59 1025 5.6% 0.283.6 MB 5.9% 1.02
Dec 23 09:00:00 - 09:59:59 977 5.4% 0.272.9 MB 4.7% 0.81
Dec 23 10:00:00 - 10:59:59 904 5.0% 0.253.5 MB 5.8% 0.99
Dec 23 11:00:00 - 11:59:59 881 4.9% 0.243.8 MB 6.3% 1.09
Dec 23 12:00:00 - 12:59:59 740 4.1% 0.212.9 MB 4.8% 0.83
Dec 23 13:00:00 - 13:59:59 589 3.2% 0.162.4 MB 4.0% 0.68
Dec 23 14:00:00 - 14:59:59 484 2.7% 0.131.5 MB 2.5% 0.42
Dec 23 15:00:00 - 15:59:59 459 2.5% 0.132.1 MB 3.5% 0.60
Dec 23 16:00:00 - 16:59:59 479 2.6% 0.131.9 MB 3.1% 0.53
Dec 23 17:00:00 - 17:59:59 428 2.4% 0.121.4 MB 2.4% 0.41
Dec 23 18:00:00 - 18:59:59 353 1.9% 0.101.6 MB 2.7% 0.46
Dec 23 19:00:00 - 19:59:59 327 1.8% 0.091.1 MB 1.8% 0.31
Dec 23 20:00:00 - 20:59:59 441 2.4% 0.121.7 MB 2.7% 0.47
Dec 23 21:00:00 - 21:59:59 673 3.7% 0.191.8 MB 3.0% 0.51
Dec 23 22:00:00 - 22:59:59 730 4.0% 0.202.2 MB 3.6% 0.61
Dec 23 23:00:00 - 23:58:02 729 4.0% 0.212.3 MB 3.8% 0.67
TOTAL: 24:00:00 18146 100.0% 0.2160.4 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB22.4 GB 44.8% 0 0.28 2145.70
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB26.0 GB 51.9% 0 0.36 1670.62
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB279.2 MB 0.5% 0 0.0319758.57
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 GB13.8 GB 27.6% 0 0.58 1044.25
TOTAL: 10-500.0 GB62.4 GB 12.5% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 2781 55 0 65 0 0 0 0 2661
2news-out.mixmin.net 1326 0 0 64 0 0 0 0 1262
3feeder.eternal-september.org 1127 0 0 111 0 0 0 0 1016
4news.samoylyk.net 549 0 0 88 0 0 0 0 461
5news.albasani.net 323 21 0 79 0 0 0 0 223
6news.snarked.org 251 26 0 67 0 0 0 0 158
7agency-nz-out.news.mb-net.net 219 27 0 67 0 0 0 0 125
8newsfeed.datemas.de 214 49 0 86 0 0 0 0 79
9news.freedyn.net 173 25 0 62 0 0 0 0 86
10news.redatomik.org 170 0 0 64 0 0 0 0 106
11news-out.aioe.org 147 16 0 53 0 0 0 0 78
12news.swapon.de 57 0 0 4 0 0 0 0 53
13feeder.awxg.com 55 0 0 49 0 0 0 0 6
14csiph.com 52 0 0 49 0 0 0 0 3
15localhost 1 0 0 0 0 0 0 0 1
TOTAL: 15 7445 219 0 908 0 0 0 0 6318

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.varie 69
mpc.lists.freebsd.ports.bugs 37
gmane.linux.alsa.devel 20
ab.test 19
mpc.lists.nanog 17
mozilla.support.seamonkey 13
mozilla.test 6
mozilla.dev.ui-alerts 4
gmane.linux.kernel 4
fido7.ru.android 3
gmane.comp.gis.openstreetmap.tagging 3
mozilla.dev.security.policy 2
mpc.lists.freebsd.x11 2
mozilla.dev.apps.seamonkey 2
albasani.test 1
mozilla.moss 1
eagle.userchat.eng 1
demos.local.statistics 1
alt.penthouse.sex.femdom 1
clari.web.local.louisiana 1
TOTAL: 32 219

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4582
EMP (phn path) 337
EMP (phn nph) 256
Binary Image: misplaced jpg 193
Binary: misplaced rar 159
Poison newsgroup 84
Too many newsgroups 76
EMP (phl) 36
User-issued cancel 13
Binary: misplaced par2 8
Binary: non-binary distribution 6
Binary: misplaced PAR2 3
User-issued spam cancel 2
Binary Image: misplaced png 1
Binary: misplaced BRK 1
Binary: misplaced 078 1
HTML post 1
TOTAL: 17 5759

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.mixmin.net 386
news.swapon.de 49
news-out.aioe.org 40
news.albasani.net 37
news.redatomik.org 25
newsfeed.datemas.de 7
newsfeed.neva.ru 7
feeder.awxg.com 4
news.snarked.org 2
news.samoylyk.net 1
TOTAL: 11 559
TOTAL: 1 559

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1awxg 17598 86 17496 4 0 8 0%23:59:31
2news.snarked.org 13551 6 13473 11 0 0 0%23:59:43
3news.freedyn.net 17947 4 17876 0 0 2 0%23:59:43
4csiph.com 17028 4 16984 0 0 0 0%23:59:43
5aioe 14522 4 14455 0 0 0 0%23:59:43
6news.redatomik.org 17634 4 17583 1 0 0 0%23:59:43
7news-in.mixmin.net 12737 3 12723 0 0 0 0%23:59:43
8news.samoylyk.net 16679 3 16539 0 0 2 0%23:59:14
9neva.ru 16746 2 16727 1 0 0 0%23:59:35
10mb-net.net 18067 2 18031 1 0 0 0%23:59:43
11eternal-september 15320 1 15296 0 0 0 0%23:59:43
12news.albasani.net 18059 0 16335 694 0 3943 0%23:59:43
13news.swapon.de 1491 0 1491 0 0 0 0%23:50:37
14news.ausics.org 0 0 0 0 0 16584 0%23:59:44
15newsfeed.datemas.de 17725 0 17690 0 0 83 0%23:59:43
TOTAL: 15 215104 119 212699 712 0 20622 0%359:45:51
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1awxg324.0 KB27.7 KB351.7 KB0.0 KB/s3.9 KB23:59:31
2news.snarked.org14.4 KB47.4 KB61.8 KB0.0 KB/s3.6 KB23:59:43
3news.freedyn.net9.7 KB0.0 KB9.7 KB0.0 KB/s2.4 KB23:59:43
4csiph.com9.3 KB0.0 KB9.3 KB0.0 KB/s2.3 KB23:59:43
5aioe8.7 KB0.0 KB8.7 KB0.0 KB/s2.2 KB23:59:43
6news.redatomik.org7.9 KB2.7 KB10.6 KB0.0 KB/s2.1 KB23:59:43
7news-in.mixmin.net6.7 KB0.0 KB6.7 KB0.0 KB/s2.2 KB23:59:43
8news.samoylyk.net6.7 KB0.0 KB6.7 KB0.0 KB/s2.2 KB23:59:14
9mb-net.net4.5 KB5.2 KB9.7 KB0.0 KB/s3.2 KB23:59:43
10neva.ru4.4 KB2.2 KB6.7 KB0.0 KB/s2.2 KB23:59:35
11eternal-september2.2 KB0.0 KB2.2 KB0.0 KB/s2.2 KB23:59:43
12news.albasani.net0.0 KB1.4 MB1.4 MB0.0 KB/s2.1 KB23:59:43
13news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:37
14news.ausics.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
15newsfeed.datemas.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
TOTAL: 15398.5 KB1.5 MB1.9 MB0.0 KB/s2.4 KB359:45:51
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 352 632022.4 MB 3307 0 000:12:33
267-61-21-181.cpe.cableone.net 7 520118.6 MB 747 3 1601:52:20
3vorlon.activateit.net.au 24 6821019.0 KB 116 0 000:03:57
4192.168.1.9 1 2061.2 MB 13 0 000:00:13
5gps.mdwainwright.ca 2 59.2 KB 4 0 001:00:50
TOTAL: 5 386 1241443.2 MB 4187 3 1630:39:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 352 632022.4 MB 3307 0 000:12:33
2*.cpe.cableone.net 9 520118.6 MB 747 3 1628:03:30
3*.activateit.net.au 24 6821019.0 KB 116 0 000:03:57
4unresolved 14 2061.2 MB 13 0 001:18:57
5*.mdwainwright.ca 2 59.2 KB 4 0 001:00:50
TOTAL: 5 401 1241443.2 MB 4187 3 1630:39:52

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 7
malcolmlewis 2
paulh 1
TOTAL: 4 34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.220 0.096 0.00926:11:09
67-61-21-181.cpe.cableone.net 1.092 0.748 0.02101:52:20
gps.mdwainwright.ca 0.108 0.016 0.00001:00:50
118.149.248.180 0.284 0.056 0.00000:39:10
118.149.225.114 0.248 0.024 0.00000:20:05
agency.bbs.geek.nz 25.320 6.028 0.00000:12:33
118.149.238.7 0.192 0.016 0.00000:11:58
118.149.200.184 0.120 0.028 0.00000:07:29
vorlon.activateit.net.au 1.368 0.364 0.16600:03:57
192.168.1.9 0.116 0.044 0.00300:00:13
? 1.708 0.232 0.00000:00:03
TOTAL: 11 30.776 7.652 0.19930:39:52

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.225.114 4
118.149.248.180 4
118.149.238.7 3
118.149.200.184 2
67-60-219-146.cpe.cableone.net 2
TOTAL: 5 15

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
gps.mdwainwright.ca 2 0
118.149.248.180 1 1
TOTAL: 2 3 1

Newsgroup request counts (by category):

CategoryCountPct
1rec 3671 29.6%
2alt 1732 14.0%
3comp 1597 12.9%
4soc 1312 10.6%
5uk 1262 10.2%
6sci 867 7.0%
7linux 786 6.3%
8talk 593 4.8%
9misc 261 2.1%
10news 122 1.0%
11mozilla 91 0.7%
12nz 55 0.4%
13aus 35 0.3%
14eternal-september 12 0.1%
15humanities 10 0.1%
16microsoft 6 0.0%
17us 2 0.0%
TOTAL: 17 12414100.0%

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

NewsgroupCount
1rec.arts.tv 936
2alt.fan.rush-limbaugh 482
3uk.d-i-y 454
4rec.food.cooking 429
5uk.legal 382
6alt.atheism 372
7soc.retirement 295
8comp.lang.python 287
9talk.origins 283
10linux.debian.bugs.dist 263
11rec.sport.football.college 205
12rec.boats 198
13talk.politics.guns 188
14soc.culture.israel 175
15comp.os.linux.advocacy 170
16linux.debian.changes.devel 169
17alt.comp.os.windows-10 169
18uk.radio.amateur 160
19soc.culture.polish 138
20sci.electronics.design 137
21rec.arts.sf.written 136
22misc.survivalism 132
23rec.sport.golf 132
24sci.physics 130
25sci.physics.relativity 113
26rec.photo.digital 107
27rec.sport.tennis 104
28talk.politics.misc 103
29linux.debian.bugs.rc 102
30comp.mobile.android 102
31soc.culture.china 101
32soc.culture.indian 100
33comp.lang.c 100
34alt.politics.trump 100
35comp.lang.idl-pvwave 95
36rec.gambling.poker 91
37alt.test 88
38sci.military.naval 86
39comp.lang.forth 86
40rec.arts.movies.current-films 80
41comp.sys.mac.apps 79
42rec.arts.drwho 75
43comp.sys.mac.system 75
44rec.music.classical.recordings 73
45soc.culture.vietnamese 71
46uk.tech.digital-tv 70
47linux.debian.user 69
48sci.math 69
49alt.bible 69
50comp.sys.mac.advocacy 66
51rec.arts.sf.fandom 64
52sci.logic 61
53soc.culture.usa 61
54news.lists.filters 59
55alt.tv.days-of-our-lives 58
56uk.comp.sys.mac 58
57sci.med.cardiology 57
58alt.support.diabetes 55
59nz.general 53
60comp.sys.apple2 52
61mozilla.support.firefox 51
62soc.penpals 50
63soc.culture.brazil 50
64rec.aviation.soaring 49
65rec.arts.sf.tv 49
66rec.crafts.metalworking 49
67rec.radio.shortwave 49
68alt.politics 46
69rec.sport.pro-wrestling 46
70alt.rush-limbaugh 44
71rec.aquaria.marine.misc 44
72rec.games.pinball 44
73sci.bio.paleontology 43
74misc.phone.mobile.iphone 43
75alt.os.linux.ubuntu 42
76comp.mobile.ipad 42
77uk.legal.moderated 40
78mozilla.general 40
79rec.aviation.marketplace 40
80rec.sport.soccer 39
81uk.tech.broadcast 37
82rec.outdoors.rv-travel 37
83alt.astronomy 36
84rec.radio.amateur.homebrew 36
85rec.arts.poems 36
86comp.os.vms 35
87sci.electronics.repair 35
88uk.rec.cycling 35
89comp.sys.ibm.pc.games.action 34
90comp.os.linux.misc 34
91comp.lang.ada 32
92rec.music.makers.guitar.jazz 32
93rec.radio.amateur.misc 32
94comp.protocols.time.ntp 32
95news.groups.questions 31
96misc.invest.stocks 31
97sci.med.pharmacy 31
98soc.culture.romanian 31
99rec.bicycles.tech 30
100soc.culture.singapore 30
TOTAL: 356 12414