news.bbs.geek.nz daily logs

Jun 11 23:58:02 -- Jun 12 23:58:09

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 318570 78.4%28.6 MB 64.2%
innd 33513 8.2%5.1 MB 11.3%
inn 29712 7.3%6.3 MB 14.1%
innfeed 23304 5.7%4.4 MB 9.9%
cnfsstat 1440 0.4%246.1 KB 0.5%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 406541 100.0%44.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 292026 49.6%
Positive hits 269115 45.7%
Do not exist 27933 4.7%
Cache misses 193 0.0%
TOTAL: 4 589267 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.673 0.0%145 0.000 11.538 116.000
article cleanup00:00:00.260 0.0%28871 0.000 0.009 0.032
article logging00:00:02.087 0.0%29355 0.000 0.071 1.339
article parse00:00:00.796 0.0%72091 0.000 0.011 0.029
article write00:00:09.530 0.0%19659 0.006 0.485 10.374
artlog/artcncl00:00:00.004 0.0%145 0.000 0.028 1.000
data move00:00:00.401 0.0%551812 0.000 0.001 0.007
hisgrep/artcncl00:00:00.744 0.0%141 0.000 5.277 13.857
hishave/artcncl00:00:00.254 0.0%145 0.000 1.752 32.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:41.332 0.4%589517 0.003 0.579 2.080
history sync00:00:01.741 0.0%366 0.000 4.757 861.500
history write00:06:14.600 0.4%27937 0.016 13.409 55.216
hiswrite/artcncl00:00:00.157 0.0%4 0.000 39.250 116.000
idle23:32:06.519 98.7%554591 95.174 152.773 311.306
nntp read00:00:04.862 0.0%570326 0.003 0.009 0.045
overview write00:03:08.785 0.2%19659 1.007 9.603 58.000
perl filter00:01:09.871 0.1%27937 1.101 2.501 23.913
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.712 0.0%39318 0.000 0.018 0.068
TOTAL: 23:50:54.43523:48:44.328 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.064 0.0%26039 0.000 0.002 0.020
article prepare00:00:00.000 0.0%6516 0.000 0.000 0.000
article read00:00:01.908 0.0%1388 0.000 1.375 18.000
backlog stats00:00:00.582 0.0%306186 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.604 0.0%261190 0.004 0.010 0.021
data write00:00:02.953 0.0%246734 0.005 0.012 0.055
idle23:53:59.139 99.9%306186 183.492 281.003 679.501
status file00:00:27.235 0.0%1893 0.333 14.387 338.114
TOTAL: 23:54:53.41623:54:34.485100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.152 0.0%11 9.500 13.818 34.000
idle29:27:12.962 80.1%174806 0.000 606.575 77029.764
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:24:36.868 1.1%1444312 0.000 1.023 6997.000
readart06:27:02.945 17.5%11022827 0.000 2.107 89.500
TOTAL: 36:45:33.04036:18:52.927 98.8%----

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
1newsfeed.datemas.de 4 21441 5744 14766 931 26%24:28:07
2news.snarked.org 63 24463 5384 13628 5451 22%59:40:34
3news-out.aioe.org 5 21165 1619 19396 150 7%25:43:23
4news.redatomik.org 1 12113 1550 10523 40 12%14:33:27
5news.albasani.net 1 13381 1387 11493 501 10%13:49:17
6csiph.com 4 20996 1157 19775 64 5%28:18:17
7newsfeed.neva.ru 155 16079 763 14781 535 4%82:21:43
8feeder.eternal-september.org 111 26730 731 25487 512 2%62:41:50
9news-out.mixmin.net 19 21411 419 20676 316 1%29:23:59
10news.freedyn.net 4 21602 399 20909 294 1%24:35:29
11news.samoylyk.net 1 34865 92 34280 493 0%33:27:19
12feeder.awxg.com 2 15428 55 15317 56 0%23:59:41
13news.swapon.de 6 1764 51 1700 13 2%30:09:00
14agency-nz-out.news.mb-net.net 15 20070 41 19955 74 0%31:11:24
15news.ausics.org 1 54280 24 54243 13 0%76:14:12
16localhost 5 4 4 0 0 100%00:00:00
TOTAL: 16 397 325792 19420 296929 9443 5%560:37:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org19.8 MB745.3 KB32.6 MB53.1 MB 37%5.0 KB
2newsfeed.datemas.de16.2 MB364.4 KB2.8 MB19.4 MB 83%3.0 KB
3news-out.aioe.org4.3 MB184.5 KB361.7 KB4.8 MB 88%2.8 KB
4news.albasani.net3.8 MB144.5 KB902.1 KB4.8 MB 78%2.6 KB
5news.redatomik.org3.6 MB115.3 KB115.3 KB3.9 MB 94%2.5 KB
6feeder.eternal-september.org3.0 MB968.4 KB55.8 MB59.7 MB 4%49.2 KB
7csiph.com2.7 MB234.0 KB250.6 KB3.1 MB 84%2.6 KB
8newsfeed.neva.ru2.0 MB276.0 KB2.4 MB4.7 MB 42%3.7 KB
9news-out.mixmin.net1.5 MB285.3 KB913.4 KB2.7 MB 56%3.8 KB
10news.freedyn.net1.3 MB277.1 KB1.5 MB3.1 MB 43%4.5 KB
11news.samoylyk.net502.1 KB763.1 KB3.2 MB4.4 MB 11%7.7 KB
12feeder.awxg.com169.0 KB283.6 KB283.6 KB736.3 KB 22%6.6 KB
13news.swapon.de145.1 KB46.3 KB47.3 KB238.7 KB 60%3.7 KB
14agency-nz-out.news.mb-net.net106.5 KB250.8 KB437.0 KB794.3 KB 13%6.9 KB
15news.ausics.org70.6 KB57.8 KB57.8 KB186.2 KB 37%5.0 KB
16localhost5.0 KB0.0 KB0.0 KB5.0 KB100%1.2 KB
TOTAL: 1659.3 MB4.9 MB101.6 MB165.7 MB 35%5.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 11 23:58:02 - 23:59:59 23 0.1% 0.1960.6 KB 0.1% 0.51
Jun 12 00:00:00 - 00:59:59 769 3.9% 0.212.1 MB 3.4% 0.59
Jun 12 01:00:00 - 01:59:59 926 4.7% 0.262.8 MB 4.6% 0.80
Jun 12 02:00:00 - 02:59:59 987 5.0% 0.273.2 MB 5.2% 0.90
Jun 12 03:00:00 - 03:59:59 1020 5.2% 0.283.1 MB 5.0% 0.88
Jun 12 04:00:00 - 04:59:59 1044 5.3% 0.293.1 MB 5.0% 0.87
Jun 12 05:00:00 - 05:59:59 1013 5.1% 0.282.7 MB 4.4% 0.77
Jun 12 06:00:00 - 06:59:59 990 5.0% 0.283.4 MB 5.6% 0.97
Jun 12 07:00:00 - 07:59:59 1037 5.2% 0.292.5 MB 4.1% 0.72
Jun 12 08:00:00 - 08:59:59 1003 5.1% 0.283.3 MB 5.4% 0.94
Jun 12 09:00:00 - 09:59:59 1024 5.2% 0.282.8 MB 4.6% 0.81
Jun 12 10:00:00 - 10:59:59 804 4.1% 0.222.1 MB 3.4% 0.58
Jun 12 11:00:00 - 11:59:59 665 3.4% 0.181.9 MB 3.1% 0.55
Jun 12 12:00:00 - 12:59:59 519 2.6% 0.141.6 MB 2.6% 0.45
Jun 12 13:00:00 - 13:59:59 418 2.1% 0.121.3 MB 2.1% 0.37
Jun 12 14:00:00 - 14:59:59 381 1.9% 0.111.2 MB 2.0% 0.35
Jun 12 15:00:00 - 15:59:59 438 2.2% 0.121.5 MB 2.5% 0.43
Jun 12 16:00:00 - 16:59:59 598 3.0% 0.172.9 MB 4.7% 0.82
Jun 12 17:00:00 - 17:59:59 527 2.7% 0.151.9 MB 3.1% 0.55
Jun 12 18:00:00 - 18:59:59 689 3.5% 0.192.2 MB 3.6% 0.62
Jun 12 19:00:00 - 19:59:59 955 4.8% 0.272.8 MB 4.5% 0.79
Jun 12 20:00:00 - 20:59:59 966 4.9% 0.272.8 MB 4.5% 0.79
Jun 12 21:00:00 - 21:59:59 1013 5.1% 0.284.2 MB 6.9% 1.19
Jun 12 22:00:00 - 22:59:59 1001 5.1% 0.282.8 MB 4.5% 0.79
Jun 12 23:00:00 - 23:58:09 995 5.0% 0.292.9 MB 4.8% 0.87
TOTAL: 24:00:07 19805 100.0% 0.2361.1 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB17.6 GB 35.2% 0 0.26 2354.32
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB20.5 GB 41.0% 0 0.31 1949.51
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB220.4 MB 0.4% 0 0.0323111.11
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 GB2.7 GB 5.4% 0 0.66 919.21
TOTAL: 10-500.0 GB41.1 GB 8.2% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 5505 69 0 119 0 0 0 0 5317
2newsfeed.datemas.de 1136 200 0 63 0 0 0 0 873
3news.albasani.net 696 1 0 80 0 0 0 0 615
4newsfeed.neva.ru 543 71 0 61 0 0 0 0 411
5news.samoylyk.net 368 0 0 91 0 0 0 0 277
6news-out.mixmin.net 309 0 0 73 0 0 0 0 236
7feeder.eternal-september.org 308 0 0 117 0 0 0 0 191
8news.freedyn.net 302 8 0 53 0 0 0 0 241
9news.redatomik.org 176 0 0 66 0 0 0 0 110
10news-out.aioe.org 175 19 0 47 0 0 0 0 109
11agency-nz-out.news.mb-net.net 86 11 0 49 0 0 0 0 26
12feeder.awxg.com 70 1 0 68 0 0 0 0 1
13csiph.com 63 0 0 57 0 0 0 0 6
14news.swapon.de 24 0 0 16 0 0 0 0 8
15news.ausics.org 1 0 0 1 0 0 0 0 0
TOTAL: 15 9762 380 0 961 0 0 0 0 8421

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 119
mpc.lists.freebsd.ports.bugs 52
it.annunci.immobiliari 32
it.annunci.commerciali 31
it.annunci.varie 26
gmane.linux.alsa.devel 26
gmane.comp.gis.openstreetmap.tagging 9
mozilla.support.seamonkey 9
mozilla.dev.l10n 9
gmane.comp.gis.openstreetmap.imports 7
mozilla.dev.ui-alerts 7
mpc.lists.nanog 6
mozilla.dev.telemetry-alerts 5
mozilla.support.bugzilla 3
fido7.ru.android 3
zz 3
mozilla.dev.l10n.sr 3
mpc.lists.freebsd.x11 2
eagle.support.eng 2
mozilla.dev.security.policy 2
TOTAL: 44 380

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6998
EMP (phn path) 591
EMP (phl) 163
EMP (phn nph) 86
Too many newsgroups 31
Binary: non-binary distribution 30
User-issued cancel 17
Binary Image: misplaced jpg 9
Poison newsgroup 5
HTML post 4
User-issued spam cancel 1
Binary: misplaced bz2 1
TOTAL: 12 7936

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.datemas.de 212
news.redatomik.org 110
news.albasani.net 104
news-out.aioe.org 26
news-out.mixmin.net 9
news.swapon.de 8
news.snarked.org 8
newsfeed.neva.ru 3
news.samoylyk.net 1
agency-nz-out.news.mb-net.net 1
TOTAL: 13 485
TOTAL: 1 485

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 18199 543 17590 66 0 0 2%23:59:50
2awxg 19253 112 19132 3 0 11 0%23:59:50
3news.snarked.org 14313 11 14155 7 0 29 0%23:59:44
4mb-net.net 19755 9 19706 1 0 0 0%23:59:50
5news.freedyn.net 19505 8 19381 1 0 10 0%23:59:46
6aioe 16824 6 16715 0 0 0 0%23:59:50
7news.samoylyk.net 18147 4 18121 1 0 0 0%23:59:49
8news.redatomik.org 18188 4 18154 0 0 0 0%23:59:47
9news-in.mixmin.net 18266 4 18255 0 0 0 0%23:59:50
10csiph.com 18621 4 18526 0 0 2 0%23:59:50
11newsfeed.datemas.de 13969 4 13963 0 0 0 0%23:59:41
12news.albasani.net 19597 3 16745 722 0 6180 0%23:59:49
13eternal-september 16802 3 16763 0 0 79 0%23:59:50
14neva.ru 18540 1 18531 4 0 0 0%23:59:50
15news.swapon.de 1656 1 1652 0 0 0 0%23:52:17
TOTAL: 15 251635 717 247389 805 0 6311 0%359:49:33
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org3.1 MB4.8 MB7.9 MB0.1 KB/s13.3 KB23:59:50
2awxg670.3 KB16.4 KB686.7 KB0.0 KB/s6.0 KB23:59:50
3news.snarked.org26.8 KB26.7 KB53.5 KB0.0 KB/s3.0 KB23:59:44
4mb-net.net15.9 KB4.3 KB20.3 KB0.0 KB/s2.0 KB23:59:50
5news.freedyn.net15.4 KB4.2 KB19.6 KB0.0 KB/s2.2 KB23:59:46
6neva.ru8.9 KB5.0 KB13.9 KB0.0 KB/s2.8 KB23:59:50
7aioe8.6 KB0.0 KB8.6 KB0.0 KB/s1.4 KB23:59:50
8news.albasani.net6.4 KB3.1 MB3.1 MB0.0 KB/s4.4 KB23:59:49
9news.samoylyk.net5.0 KB2.9 KB7.9 KB0.0 KB/s1.6 KB23:59:49
10news.redatomik.org5.0 KB0.0 KB5.0 KB0.0 KB/s1.2 KB23:59:47
11news-in.mixmin.net5.0 KB0.0 KB5.0 KB0.0 KB/s1.2 KB23:59:50
12csiph.com5.0 KB0.0 KB5.0 KB0.0 KB/s1.2 KB23:59:50
13newsfeed.datemas.de5.0 KB0.0 KB5.0 KB0.0 KB/s1.2 KB23:59:41
14eternal-september3.0 KB0.0 KB3.0 KB0.0 KB/s1.0 KB23:59:50
15news.swapon.de1.2 KB0.0 KB1.2 KB0.0 KB/s1.2 KB23:52:17
TOTAL: 153.8 MB8.0 MB11.9 MB0.0 KB/s8.0 KB359:49:33
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 356 556916.9 MB 3062 4 000:12:27
2kosh.activateit.net.au 24 11451.5 MB 140 0 000:05:13
3mtrlpq2314w-lp130-05-70-55-69-218.dsl.bell.ca 112 56202.7 KB 3 0 002:01:27
460.234.244.55 7 1989.8 KB 1 0 000:25:30
567-61-218-236.cpe.cableone.net 3 13.6 KB 1 0 014:48:06
TOTAL: 5 502 679018.7 MB 3207 4 036:45:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 356 556916.9 MB 3062 4 000:12:27
2*.activateit.net.au 24 11451.5 MB 140 0 000:05:13
3*.dsl.bell.ca 112 56202.7 KB 3 0 002:01:27
4unresolved 12 1989.8 KB 1 0 000:44:53
5*.cpe.cableone.net 3 13.6 KB 1 0 014:48:06
6*.dyn.optonline.net 6 00.0 KB 0 0 018:53:07
7*.neo.res.rr.com 2 00.0 KB 0 0 000:00:07
TOTAL: 7 515 679018.7 MB 3207 4 036:45:25

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 5
malcolmlewis 3
TOTAL: 3 32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ool-457809ae.dyn.optonline.net 151.000 811.832 0.00018:53:07
67-61-218-236.cpe.cableone.net 0.184 0.052 0.00214:48:06
mtrlpq2314w-lp130-05-70-55-69-218.dsl.bell.ca 15.616 3.160 0.00002:01:27
60.234.244.55 0.476 0.072 0.01400:25:30
agency.bbs.geek.nz 27.140 6.580 0.00000:12:27
118.149.170.112 0.184 0.020 0.00000:12:26
118.149.244.159 0.128 0.032 0.00000:06:55
kosh.activateit.net.au 1.644 0.500 0.72400:05:13
cpe-24-210-204-30.neo.res.rr.com 0.428 0.052 0.00000:00:07
? 1.596 0.172 0.00000:00:02
TOTAL: 10 198.396 822.472 0.74036:45:25

Curious NNRP server explorers [Top 20]:

SystemConn
ool-457809ae.dyn.optonline.net 6
118.149.170.112 3
cpe-24-210-204-30.neo.res.rr.com 2
118.149.244.159 2
TOTAL: 4 13

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
ool-457809ae.dyn.optonline.net 6 4
mtrlpq2314w-lp130-05-70-55-69-218.dsl.bell.ca 5 5
TOTAL: 2 11 9

Newsgroup request counts (by category):

CategoryCountPct
1rec 3751 55.3%
2soc 764 11.3%
3comp 644 9.5%
4sci 550 8.1%
5alt 498 7.3%
6talk 235 3.5%
7misc 105 1.5%
8uk 98 1.4%
9news 69 1.0%
10nz 38 0.6%
11agency 9 0.1%
12aus 7 0.1%
13humanities 7 0.1%
14eternal-september 5 0.1%
TOTAL: 14 6780100.0%

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

NewsgroupCount
1rec.arts.tv 1011
2rec.sport.tennis 826
3rec.arts.drwho 449
4rec.arts.sf.written 317
5alt.home.repair 205
6soc.retirement 157
7sci.electronics.design 125
8alt.comp.os.windows-10 125
9soc.culture.polish 109
10rec.gambling.poker 107
11comp.lang.c 107
12talk.origins 103
13sci.math 98
14sci.physics.relativity 96
15comp.os.linux.advocacy 88
16talk.politics.guns 85
17soc.culture.china 80
18soc.culture.israel 78
19uk.radio.amateur 77
20rec.sport.pro-wrestling 73
21sci.physics 69
22rec.music.dylan 65
23news.lists.filters 60
24rec.boats 59
25rec.sport.soccer 58
26comp.lang.c++ 57
27alt.california 54
28alt.os.linux.ubuntu 52
29comp.sys.mac.advocacy 48
30rec.food.cooking 47
31comp.lang.forth 44
32misc.survivalism 44
33rec.music.classical.guitar 43
34rec.arts.movies.past-films 42
35rec.arts.comics.strips 42
36rec.bicycles.tech 40
37alt.test 36
38soc.culture.usa 35
39rec.photo.digital 34
40talk.politics.misc 33
41comp.mobile.android 33
42sci.military.naval 32
43nz.general 32
44rec.woodworking 32
45comp.sys.apple2 30
46soc.culture.indian 30
47rec.aviation.soaring 30
48rec.crafts.metalworking 30
49rec.music.classical.recordings 28
50rec.outdoors.rv-travel 27
51comp.lang.python 26
52sci.electronics.repair 25
53soc.culture.vietnamese 23
54soc.culture.singapore 23
55soc.penpals 22
56rec.autos.sport.f1 22
57rec.games.pinball 21
58comp.internet.services.blog.livejournal 21
59soc.culture.venezuela 21
60sci.med.pharmacy 20
61rec.music.rock-pop-r+b.1950s 20
62comp.lang.fortran 19
63rec.audio.pro 19
64rec.sport.football.college 18
65rec.arts.movies.current-films 18
66soc.history.medieval 18
67misc.invest.stocks 17
68rec.radio.amateur.dx 16
69soc.culture.romanian 16
70soc.culture.brazil 15
71sci.crypt 15
72rec.music.beatles 14
73uk.comp.home-networking 14
74rec.radio.amateur.misc 14
75rec.arts.tv.uk.coronation-st 14
76soc.history.what-if 13
77comp.lang.lisp 13
78soc.men 12
79comp.sys.apple2.programmer 12
80soc.culture.bulgaria 11
81sci.logic 11
82rec.games.bridge 11
83misc.health.alternative 11
84talk.bizarre 10
85rec.arts.animation 10
86rec.sport.golf 10
87comp.sys.cbm 10
88soc.culture.turkish 10
89agency.test 9
90soc.culture.african.american 9
91comp.os.linux.misc 9
92misc.health.diabetes 9
93soc.culture.cuba 9
94soc.culture.british 9
95rec.org.mensa 8
96comp.unix.programmer 8
97rec.nude 8
98comp.sys.acorn.apps 8
99sci.math.symbolic 8
100comp.soft-sys.math.scilab 8
TOTAL: 292 6780