Running bin\geopdvc.pl at Mon Mar 27 03:00:04 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3999 bin\geopdvc.pl: Getting Expedition object for diveno = 3999 and rname = vnta set_expd(): rov = vnta, diveno = 3999 set_expd(): Executing sql: SELECT ExpeditionID_FK, DateDiff(ss, '01/01/70', DiveStartDtg), DateDiff(ss, '01/01/70', DiveEndDtg), DiveStartDtg, DiveEndDtg FROM Dive WHERE DiveNumber = 3999 AND RovName = 'vnta' RS->Fields(0)->Value= 5824 RS->Fields(1)->Value= 1485533063 RS->Fields(2)->Value= 1485535951 RS->Fields(3)->Value= 1/27/2017 4:04:23 PM RS->Fields(4)->Value= 1/27/2017 4:52:31 PM set_expd(): $self->{EndDDD} = 027 set_expd(): $self->{StartEsecs} = 1485531863 set_expd(): $self->{EndEsecs} = 1485537151 set_expd(): $self->{EndDtg} = 1/27/2017 4:52:31 PM _readLogr(): Reading videologr data from 2017027datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1485531863 & 1485537151 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1485537159, self->{EndEsecs} = 1485537151 _readLogr(): #LOGHOST_SYSTEM_UTC = 528 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1485531868 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1485537149 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1485531868 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1485537149 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1485531743, Refined EndEsecs = 1485537271 _readLogr(): Reading videologr data from 2017027datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1485531743 & 1485537271 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1485537279, self->{EndEsecs} = 1485537271 _readLogr(): #LOGHOST_SYSTEM_UTC = 552 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1485531748 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1485537269 bin\geopdvc.pl: expdid = 5824 bin\geopdvc.pl: Calling rovNavCTDO to get raw nav data... rovnavCTDO(): calcUTM = 0 rovnavCTDO(): getRaw = 1 rovNav(): 1, calcUTM = 0 rovNav(): 2, getRaw = 1 rovNav(): calcUTM = 0 rovNav(): DDD = 027 (looping to 027) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017027vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017027vnta.txt esecs, east, north, lat, lon, head, pres 1485533584, 0, 0, 36.799500, -121.857607, 37.9, 150.67 1485534085, 0, 0, 36.799843, -121.856792, 99.4, 279.07 1485534585, 0, 0, 36.799863, -121.856588, 107.7, 324.39 1485535086, 0, 0, 36.799997, -121.856653, 315.9, 307.09 1485535587, 0, 0, 36.801078, -121.856738, 75.2, 96.71 rovNav(): 027: 2862 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017027vnta.txt 2862 in tList 2862 in {self->{rovT}} 2862 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1485533175 & 1485534015 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 214.389388110317 rovGDCListRefs(): dStats->sample_range = 323.983147293923 rovGDCListRefs(): returning (1st elements): 1485533063 36.785570 -121.898335 46.6 2.58076449674362 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 027 (looping to 027) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017027vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017027vntaedited.txt esecs, east, north, lat, lon, head, pres 1485533584, 0, 0, 36.799513, -121.857615, 37.9, 150.67 1485534085, 0, 0, 36.799844, -121.856814, 99.4, 279.07 1485534585, 0, 0, 36.799861, -121.856572, 107.7, 324.39 1485535086, 0, 0, 36.800005, -121.856647, 315.9, 307.09 1485535587, 0, 0, 36.801075, -121.856729, 75.2, 96.71 rovNav(): 027: 2862 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017027vntaedited.txt 2862 in tList 2862 in {self->{rovT}} 2862 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1485533175 & 1485534015 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXX...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/027/accesses.txt at bin\geopdvc.pl line 2382 ..........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 214.389388110317 rovGDCListRefs(): dStats->sample_range = 323.983147312029 rovGDCListRefs(): returning (1st elements): 1485533063 36.799360 -121.857864 46.6 2.58076134740215 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79983800 hash{lon} = -121.85686500 latMin = 30.799838 latMax = 42.799838 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.799838 AND 42.799838 AND Longitude BETWEEN -127.856865 AND -115.856865 Dive lat = 36.79983800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.85686500 & lat = 36.79983800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3999rov_orig.wrl Making color lookup world... var = Light, vmin = 40, vmax = 100 val = 40, color = .8 .7 0 val = 70, color = .6 .5 0 val = 85, color = .1 .1 0 val = 100, color = 1 1 1 var = O2, vmin = 0.2, vmax = 5 val = 0.2, color = 1 1 1 val = 0.3, color = 1 1 .2 val = 0.5, color = 1 1 0 val = 0.7, color = 0 1 0 val = 5, color = 0 0 1 var = Sal, vmin = 33, vmax = 35 val = 33, color = 1 1 .1 val = 33.5, color = 1 1 0 val = 34, color = 1 0 0 val = 34.5, color = .2 1 0 val = 35, color = 0 1 0 var = Temp, vmin = 4, vmax = 15 val = 4, color = 0 0 1 val = 8, color = 1 1 0 val = 10, color = 0 1 0 val = 15, color = 1 0 0 World = geo2017/vnta/vnta3999lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 552 shipNav(): $#{$self->{shipLat}}[last] = 36.802660 Position subsampling set to 3 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = .8 .7 0 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = 1 1 1 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1 1 .1 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0 0 1 makeTracks(): n = 2863 makeTracks(): Looping through 552 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3999tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta3999mergedCtdNav.txt... bin\geopdvc.pl: Annotations: annotations(): Executing sql: SELECT DISTINCT DateDiff(ss, '01/01/70', RecordedDate) AS Esecs, CONVERT(CHAR(8), RecordedDate, 1) + ' ' + SUBSTRING(CONVERT(CHAR(19), RecordedDate, 120), 12, 8) AS DateTime, VideoArchiveName AS TapeNum, TapeTimeCode, ConceptName, Image AS StillImageURL, Depth, Latitude, Longitude, CameraDirection AS Direction, ISNULL(Observer, '') AS Observer, AnnotationMode, ObservationID_FK AS ObservationID FROM dbo.Annotations WHERE (ShipName = '') AND (RecordedDate BETWEEN '1/27/2017 4:04:23 PM' AND '1/27/2017 4:52:31 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/27/2017 4:04:23 PM' AND '1/27/2017 4:52:31 PM') _findIndex(): Looking for epoch secs 1485534244 ... Found time 1485534248 after 4 iterations. >>> p = 1162: annotations(): 1162, 1485534244, 01/27/17 16:24:04, V3999-01HD, 00:30:38:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_30_38_04.jpg, 310.010009765625, descend, ventana, u, 20065765 _findIndex(): Looking for epoch secs 1485534346 ... Found time 1485534349 after 7 iterations. >>> p = 1263: annotations(): 1263, 1485534346, 01/27/17 16:25:46, V3999-01HD, 00:32:20:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_32_20_09.jpg, 315.359985351563, descend, ventana, u, 20065766 _findIndex(): Looking for epoch secs 1485534744 ... Found time 1485534740 after 5 iterations. >>> p = 1653: annotations(): 1653, 1485534744, 01/27/17 16:32:24, V3999-01HD, 00:38:58:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_38_58_05.jpg, 325.880004882813, descend, ventana, u, 20065767 _findIndex(): Looking for epoch secs 1485534814 ... Found time 1485534818 after 7 iterations. >>> p = 1731: annotations(): 1731, 1485534814, 01/27/17 16:33:34, V3999-01HD, 00:40:07:23, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_40_07_23.jpg, 326.420013427734, descend, ventana, u, 20065768 _findIndex(): Looking for epoch secs 1485534822 ... Found time 1485534818 after 7 iterations. >>> p = 1731: annotations(): 1731, 1485534822, 01/27/17 16:33:42, V3999-01HD, 00:40:15:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_40_15_09.jpg, 326.179992675781, descend, ventana, u, 20065769 _findIndex(): Looking for epoch secs 1485534848 ... Found time 1485534852 after 6 iterations. >>> p = 1765: annotations(): 1765, 1485534848, 01/27/17 16:34:08, V3999-01HD, 00:40:42:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_40_42_00.jpg, 326.130004882813, descend, ventana, u, 20065770 _findIndex(): Looking for epoch secs 1485534854 ... Found time 1485534852 after 6 iterations. >>> p = 1765: annotations(): 1765, 1485534854, 01/27/17 16:34:14, V3999-01HD, 00:40:47:15, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_40_47_15.jpg, 326.140014648438, descend, ventana, u, 20065771 _findIndex(): Looking for epoch secs 1485534860 ... Found time 1485534863 after 7 iterations. >>> p = 1776: annotations(): 1776, 1485534860, 01/27/17 16:34:20, V3999-01HD, 00:40:53:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_40_53_18.jpg, 326.160003662109, descend, ventana, u, 20065772 _findIndex(): Looking for epoch secs 1485534886 ... Found time 1485534886 after 7 iterations. >>> p = 1799: annotations(): 1799, 1485534886, 01/27/17 16:34:46, V3999-01HD, 00:41:19:07, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3999/00_41_19_07.jpg, 326.470001220703, descend, ventana, u, 20065773 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation.frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/027/accesses.txt at bin\geopdvc.pl line 625 .. inline_name = vnta3999anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 318.8 db", "T: 7.088 C", "S: 34.147", "O: 1.064 ml/l", "L: 74.5 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 329.9 db", "T: 7.074 C", "S: 34.149", "O: 1.051 ml/l", "L: 74.35 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 329.8 db", "T: 7.075 C", "S: 34.149", "O: 1.05 ml/l", "L: 74.02 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 329.9 db", "T: 7.074 C", "S: 34.148", "O: 1.053 ml/l", "L: 73.93 %"] inline_name = vnta3999anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 313.6 db", "T: 7.091 C", "S: 34.145", "O: 1.072 ml/l", "L: 74.36 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 318.8 db", "T: 7.088 C", "S: 34.147", "O: 1.064 ml/l", "L: 74.5 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 329.3 db", "T: 7.069 C", "S: 34.148", "O: 1.054 ml/l", "L: 74.34 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 329.9 db", "T: 7.074 C", "S: 34.149", "O: 1.051 ml/l", "L: 74.35 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 329.9 db", "T: 7.074 C", "S: 34.149", "O: 1.051 ml/l", "L: 74.35 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 329.8 db", "T: 7.075 C", "S: 34.149", "O: 1.05 ml/l", "L: 74.02 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 329.8 db", "T: 7.075 C", "S: 34.149", "O: 1.05 ml/l", "L: 74.02 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 329.9 db", "T: 7.074 C", "S: 34.148", "O: 1.053 ml/l", "L: 73.93 %"] placeAnnotations(): Placing annotation 9: Ancillary data = ["", "P: 329.9 db", "T: 7.08 C", "S: 34.148", "O: 1.053 ml/l", "L: 73.57 %"] inline_name = vnta3999anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta3999... frameGrabs(): matched 00_30_38_04 in