Running bin\geopdvc.pl at Mon Jul 11 03:01:26 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3932 bin\geopdvc.pl: Getting Expedition object for diveno = 3932 and rname = vnta set_expd(): rov = vnta, diveno = 3932 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 = 3932 AND RovName = 'vnta' RS->Fields(0)->Value= 5564 RS->Fields(1)->Value= 1463173086 RS->Fields(2)->Value= 1463175777 RS->Fields(3)->Value= 5/13/2016 8:58:06 PM RS->Fields(4)->Value= 5/13/2016 9:42:57 PM set_expd(): $self->{EndDDD} = 134 set_expd(): $self->{StartEsecs} = 1463171886 set_expd(): $self->{EndEsecs} = 1463176977 set_expd(): $self->{EndDtg} = 5/13/2016 9:42:57 PM _readLogr(): Reading videologr data from 2016134datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1463171886 & 1463176977 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1463176986, self->{EndEsecs} = 1463176977 _readLogr(): #LOGHOST_SYSTEM_UTC = 508 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1463171895 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1463176976 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1463171895 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1463176976 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1463171766, Refined EndEsecs = 1463177097 _readLogr(): Reading videologr data from 2016134datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1463171766 & 1463177097 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1463177106, self->{EndEsecs} = 1463177097 _readLogr(): #LOGHOST_SYSTEM_UTC = 532 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1463171775 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1463177096 bin\geopdvc.pl: expdid = 5564 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 = 134 (looping to 134) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016134vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016134vnta.txt esecs, east, north, lat, lon, head, pres 1463173483, 0, 0, 36.747333, -122.104797, 301.1, 83.76 1463173983, 0, 0, 36.748013, -122.106293, 351.4, 138.05 1463174484, 0, 0, 36.748952, -122.106362, 333.8, 243.30 1463174985, 0, 0, 36.748443, -122.106938, 244.7, 221.00 1463175486, 0, 0, 36.748163, -122.107167, 252.3, 68.93 rovNav(): 134: 2685 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016134vnta.txt 2685 in tList 2685 in {self->{rovT}} 2685 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1463174910 & 1463175390 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 123.403706012891 rovGDCListRefs(): dStats->sample_range = 242.407055137155 rovGDCListRefs(): returning (1st elements): 1463173088 36.747275 -122.104120 342.1 2.68003309492964 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 134 (looping to 134) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016134vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016134vntaedited.txt esecs, east, north, lat, lon, head, pres 1463173483, 0, 0, 36.747330, -122.104793, 301.1, 83.76 1463173983, 0, 0, 36.748018, -122.106304, 351.4, 138.05 1463174484, 0, 0, 36.748961, -122.106379, 333.8, 243.30 1463174985, 0, 0, 36.748445, -122.106953, 244.7, 221.00 1463175486, 0, 0, 36.748163, -122.107154, 252.3, 68.93 rovNav(): 134: 2685 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016134vntaedited.txt 2685 in tList 2685 in {self->{rovT}} 2685 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1463174910 & 1463175390 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXX................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/134/accesses.txt at bin\geopdvc.pl line 2382 ..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 123.403705685437 rovGDCListRefs(): dStats->sample_range = 242.407056314141 rovGDCListRefs(): returning (1st elements): 1463173088 36.747237 -122.104076 342.1 2.68003310393762 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.74818000 hash{lon} = -122.10632700 latMin = 30.74818 latMax = 42.74818 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.74818 AND 42.74818 AND Longitude BETWEEN -128.106327 AND -116.106327 Dive lat = 36.74818000, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.10632700 & lat = 36.74818000 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3932rov_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 = geo2016/vnta/vnta3932lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 532 shipNav(): $#{$self->{shipLat}}[last] = 36.753208 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 = 2686 makeTracks(): Looping through 532 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3932tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2016/vnta/vnta3932mergedCtdNav.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 '5/13/2016 8:58:06 PM' AND '5/13/2016 9:42:57 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '5/13/2016 8:58:06 PM' AND '5/13/2016 9:42:57 PM') _findIndex(): Looking for epoch secs 1463173120 ... Found time 1463173118 after 7 iterations. >>> p = 30: annotations(): 30, 1463173120, 05/13/16 20:58:40, V3932-01HD, 00:02:05:28, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_02_05_28.jpg, 9.89000034332275, launch, robs, u, 20061796 _findIndex(): Looking for epoch secs 1463174519 ... Found time 1463174515 after 4 iterations. >>> p = 1425: annotations(): 1425, 1463174519, 05/13/16 21:21:59, V3932-01HD, 00:25:23:00, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_25_23_00.jpg, 238.339996337891, descend, robs, u, 20061798 _findIndex(): Looking for epoch secs 1463174577 ... Found time 1463174578 after 6 iterations. >>> p = 1488: annotations(): 1488, 1463174577, 05/13/16 21:22:57, V3932-01HD, 00:26:20:14, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_26_20_14.jpg, 236.539993286133, descend, robs, u, 20061799 _findIndex(): Looking for epoch secs 1463174606 ... Found time 1463174609 after 7 iterations. >>> p = 1519: annotations(): 1519, 1463174606, 05/13/16 21:23:26, V3932-01HD, 00:26:49:13, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_26_49_13.jpg, 237.830001831055, descend, robs, u, 20061800 _findIndex(): Looking for epoch secs 1463174616 ... Found time 1463174620 after 6 iterations. >>> p = 1530: annotations(): 1530, 1463174616, 05/13/16 21:23:36, V3932-01HD, 00:26:59:28, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_26_59_28.jpg, 238.119995117188, descend, robs, u, 20061801 _findIndex(): Looking for epoch secs 1463174624 ... Found time 1463174620 after 6 iterations. >>> p = 1530: annotations(): 1530, 1463174624, 05/13/16 21:23:44, V3932-01HD, 00:27:07:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_27_07_27.jpg, 238.190002441406, descend, robs, u, 20061802 _findIndex(): Looking for epoch secs 1463174637 ... Found time 1463174641 after 5 iterations. >>> p = 1551: annotations(): 1551, 1463174637, 05/13/16 21:23:57, V3932-01HD, 00:27:19:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_27_19_27.jpg, 237.710006713867, descend, robs, u, 20061803 _findIndex(): Looking for epoch secs 1463174649 ... Found time 1463174651 after 7 iterations. >>> p = 1561: annotations(): 1561, 1463174649, 05/13/16 21:24:09, V3932-01HD, 00:27:32:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_27_32_12.jpg, 237.830001831055, descend, robs, u, 20061804 _findIndex(): Looking for epoch secs 1463174674 ... Found time 1463174672 after 7 iterations. >>> p = 1582: annotations(): 1582, 1463174674, 05/13/16 21:24:34, V3932-01HD, 00:27:57:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_27_57_26.jpg, 236.520004272461, descend, robs, u, 20061805 _findIndex(): Looking for epoch secs 1463174698 ... Found time 1463174694 after 7 iterations. >>> p = 1603: annotations(): 1603, 1463174698, 05/13/16 21:24:58, V3932-01HD, 00:28:21:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3932/00_28_21_10.jpg, 233.850006103516, descend, robs, u, 20061806 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: AnframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/134/accesses.txt at bin\geopdvc.pl line 625 cillary data = ["", "P: 237.9 db", "T: 7.872 C", "S: 34.087", "O: 1.755 ml/l", "L: 85.32 %"] inline_name = vnta3932anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 242.6 db", "T: 7.824 C", "S: 34.093", "O: 1.716 ml/l", "L: 85.37 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 242.2 db", "T: 7.839 C", "S: 34.09", "O: 1.764 ml/l", "L: 85.44 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 242.3 db", "T: 7.847 C", "S: 34.091", "O: 1.727 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 242 db", "T: 7.852 C", "S: 34.091", "O: 1.728 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 237.9 db", "T: 7.872 C", "S: 34.087", "O: 1.755 ml/l", "L: 85.32 %"] inline_name = vnta3932anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 13.7 db", "T: 13.26 C", "S: 33.384", "O: 8.096 ml/l", "L: 78.6 %"] placeAnnotations(): Placing annotation 2: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 242.6 db", "T: 7.824 C", "S: 34.093", "O: 1.716 ml/l", "L: 85.37 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 240.5 db", "T: 7.834 C", "S: 34.087", "O: 1.776 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 242.2 db", "T: 7.839 C", "S: 34.09", "O: 1.764 ml/l", "L: 85.44 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 242.3 db", "T: 7.847 C", "S: 34.091", "O: 1.727 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 242.3 db", "T: 7.847 C", "S: 34.091", "O: 1.727 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 241.7 db", "T: 7.85 C", "S: 34.091", "O: 1.725 ml/l", "L: 85.41 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 242 db", "T: 7.852 C", "S: 34.091", "O: 1.728 ml/l", "L: 85.42 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 241.3 db", "T: 7.846 C", "S: 34.088", "O: 1.734 ml/l", "L: 85.44 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 237.9 db", "T: 7.872 C", "S: 34.087", "O: 1.755 ml/l", "L: 85.32 %"] inline_name = vnta3932anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/vnta3932... frameGrabs(): matched 00_02_05_28 in