Running bin\geopdvc.pl at Sat Dec 3 03:00:23 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3966 bin\geopdvc.pl: Getting Expedition object for diveno = 3966 and rname = vnta set_expd(): rov = vnta, diveno = 3966 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 = 3966 AND RovName = 'vnta' RS->Fields(0)->Value= 5641 RS->Fields(1)->Value= 1475686335 RS->Fields(2)->Value= 1475689035 RS->Fields(3)->Value= 10/5/2016 4:52:15 PM RS->Fields(4)->Value= 10/5/2016 5:37:15 PM set_expd(): $self->{EndDDD} = 279 set_expd(): $self->{StartEsecs} = 1475685135 set_expd(): $self->{EndEsecs} = 1475690235 set_expd(): $self->{EndDtg} = 10/5/2016 5:37:15 PM _readLogr(): Reading videologr data from 2016279datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1475685135 & 1475690235 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1475690244, self->{EndEsecs} = 1475690235 _readLogr(): #LOGHOST_SYSTEM_UTC = 509 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1475685143 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1475690234 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1475685143 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1475690234 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1475685015, Refined EndEsecs = 1475690355 _readLogr(): Reading videologr data from 2016279datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1475685015 & 1475690355 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1475690364, self->{EndEsecs} = 1475690355 _readLogr(): #LOGHOST_SYSTEM_UTC = 533 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1475685023 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1475690354 bin\geopdvc.pl: expdid = 5641 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 = 279 (looping to 279) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016279vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016279vnta.txt esecs, east, north, lat, lon, head, pres 1475686442, 0, 0, 36.793430, -121.843713, 297.0, 22.04 1475686942, 0, 0, 36.793303, -121.844278, 191.5, 251.05 1475687443, 0, 0, 36.792995, -121.844233, 178.6, 287.27 1475687944, 0, 0, 36.792995, -121.844258, 276.8, 287.36 1475688445, 0, 0, 36.793315, -121.844677, 286.9, 134.81 1475688962, 0, 0, 36.793105, -121.843990, 281.9, 9.33 rovNav(): 279: 2786 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016279vnta.txt 2786 in tList 2786 in {self->{rovT}} 2786 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1475688000 & 1475688525 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 229.361571430682 rovGDCListRefs(): dStats->sample_range = 282.623962822186 rovGDCListRefs(): returning (1st elements): 1475686339 36.793365 -121.843890 353.2 2.58076271658684 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 279 (looping to 279) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016279vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016279vntaedited.txt esecs, east, north, lat, lon, head, pres 1475686442, 0, 0, 36.793426, -121.843706, 297.0, 22.04 1475686942, 0, 0, 36.793319, -121.844274, 191.5, 251.05 1475687443, 0, 0, 36.792995, -121.844219, 178.6, 287.27 1475687944, 0, 0, 36.792989, -121.844256, 276.8, 287.36 1475688445, 0, 0, 36.793324, -121.844664, 286.9, 134.81 1475688962, 0, 0, 36.793105, -121.843989, 281.9, 9.33 rovNav(): 279: 2786 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016279vntaedited.txt 2786 in tList 2786 in {self->{rovT}} 2786 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1475688000 & 1475688525 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXX.........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/279/accesses.txt at bin\geopdvc.pl line 2382 ..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 229.361571491571 rovGDCListRefs(): dStats->sample_range = 282.623963028903 rovGDCListRefs(): returning (1st elements): 1475686339 36.793494 -121.843746 353.2 2.5807626871257 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79310000 hash{lon} = -121.84425600 latMin = 30.7931 latMax = 42.7931 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.7931 AND 42.7931 AND Longitude BETWEEN -127.844256 AND -115.844256 Dive lat = 36.79310000, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.84425600 & lat = 36.79310000 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3966rov_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/vnta3966lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 533 shipNav(): $#{$self->{shipLat}}[last] = 36.792667 Position subsampling set to 3 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.5003 0.4203 0.0000 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = 0 0 1 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1.0000 0.8660 0.0000 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.7024 0.2976 0.0000 makeTracks(): n = 2787 makeTracks(): Looping through 533 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3966tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2016/vnta/vnta3966mergedCtdNav.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 '10/5/2016 4:52:15 PM' AND '10/5/2016 5:37:15 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '10/5/2016 4:52:15 PM' AND '10/5/2016 5:37:15 PM') _findIndex(): Looking for epoch secs 1475686366 ... Found time 1475686371 after 7 iterations. >>> p = 32: annotations(): 32, 1475686366, 10/05/16 16:52:46, V3966-01HD, 00:00:09:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_00_09_02.jpg, 10.210000038147, descend, ventana, u, 20063887 _findIndex(): Looking for epoch secs 1475686410 ... Found time 1475686405 after 6 iterations. >>> p = 65: annotations(): 65, 1475686410, 10/05/16 16:53:30, V3966-01HD, 00:00:08:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_00_08_02.jpg, 10.210000038147, descend, ventana, u, 20063886 _findIndex(): Looking for epoch secs 1475686412 ... Found time 1475686416 after 7 iterations. >>> p = 76: annotations(): 76, 1475686412, 10/05/16 16:53:32, V3966-01HD, 00:00:10:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_00_10_17.jpg, 10.7399997711182, descend, ventana, u, 20063888 _findIndex(): Looking for epoch secs 1475686668 ... Found time 1475686666 after 6 iterations. >>> p = 326: annotations(): 326, 1475686668, 10/05/16 16:57:48, V3966-01HD, 00:03:35:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_03_35_11.jpg, 132.779998779297, descend, ventana, u, 20063889 _findIndex(): Looking for epoch secs 1475687300 ... Found time 1475687298 after 4 iterations. >>> p = 957: annotations(): 957, 1475687300, 10/05/16 17:08:20, V3966-01HD, 00:14:05:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_14_05_22.jpg, 279.070007324219, descend, ventana, u, 20063890 _findIndex(): Looking for epoch secs 1475687733 ... Found time 1475687735 after 0 iterations. >>> p = 1393: annotations(): 1393, 1475687733, 10/05/16 17:15:33, V3966-01HD, 00:21:18:08, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_18_08.jpg, 284.940002441406, descend, ventana, u, 20063891 _findIndex(): Looking for epoch secs 1475687735 ... Found time 1475687735 after 0 iterations. >>> p = 1393: annotations(): 1393, 1475687735, 10/05/16 17:15:35, V3966-01HD, 00:21:19:23, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_19_23.jpg, 284.920013427734, descend, ventana, u, 20063892 _findIndex(): Looking for epoch secs 1475687743 ... Found time 1475687745 after 7 iterations. >>> p = 1403: annotations(): 1403, 1475687743, 10/05/16 17:15:43, V3966-01HD, 00:21:28:23, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_28_23.jpg, 284.920013427734, descend, ventana, u, 20063893 _findIndex(): Looking for epoch secs 1475687763 ... Found time 1475687767 after 7 iterations. >>> p = 1425: annotations(): 1425, 1475687763, 10/05/16 17:16:03, V3966-01HD, 00:21:48:23, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_48_23.jpg, 284.950012207031, descend, ventana, u, 20063894 _findIndex(): Looking for epoch secs 1475687769 ... Found time 1475687767 after 7 iterations. >>> p = 1425: annotations(): 1425, 1475687769, 10/05/16 17:16:09, V3966-01HD, 00:21:53:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_53_22.jpg, 284.940002441406, descend, ventana, u, 20063895 _findIndex(): Looking for epoch secs 1475687773 ... Found time 1475687778 after 5 iterations. >>> p = 1436: annotations(): 1436, 1475687773, 10/05/16 17:16:13, V3966-01HD, 00:21:58:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_21_58_22.jpg, 284.869995117188, descend, ventana, u, 20063896 _findIndex(): Looking for epoch secs 1475687775 ... Found time 1475687778 after 5 iterations. >>> p = 1436: annotations(): 1436, 1475687775, 10/05/16 17:16:15, V3966-01HD, 00:22:00:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_22_00_22.jpg, 284.929992675781, descend, ventana, u, 20063897 _findIndex(): Looking for epoch secs 1475687777 ... Found time 1475687778 after 5 iterations. >>> p = 1436: annotations(): 1436, 1475687777, 10/05/16 17:16:17, V3966-01HD, 00:22:02:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_22_02_22.jpg, 284.929992675781, descend, ventana, u, 20063898 _findIndex(): Looking for epoch secs 1475687781 ... Found time 1475687778 after 5 iterations. >>> p = 1436: annotations(): 1436, 1475687781, 10/05/16 17:16:21, V3966-01HD, 00:22:06:07, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_22_06_07.jpg, 284.940002441406, descend, ventana, u, 20063899 _findIndex(): Looking for epoch secs 1475687783 ... Found time 1475687778 after 5 iterations. >>> p = 1436: annotations(): 1436, 1475687783, 10/05/16 17:16:23, V3966-01HD, 00:22:07:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_22_07_22.jpg, 284.920013427734, descend, ventana, u, 20063900 _findIndex(): Looking for epoch secs 1475688695 ... Found time 1475688694 after 4 iterations. >>> p = 2350: annotations(): 2350, 1475688695, 10/05/16 17:31:35, V3966-01HD, 00:37:20:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3966/00_37_20_09.jpg, 13.0699996948242, descend, ventana, u, 20063901 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.515 C", "S: 33.99", "O: 1.764 ml/l", "L: 71.88 %"] inline_name = vnta3966anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 12.2 db", "T: 12.83 C", "S: 33.543", "O: 6.197 ml/l", "L: 82.24 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 133.7 db", "T: 10.238 C", "S: 33.734", "O: 2.805 ml/l", "L: 80.73 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.507 C", "S: 33.99", "O: 1.762 ml/l", "L: 71.94 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.511 C", "S: 33.99", "O: 1.761 ml/l", "L: 71.63 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.515 C", "S: 33.99", "O: 1.764 ml/l", "L: 71.88 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 14: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 16: Ancillary data = ["", "P: 16.7 db", "T: 12.148 C", "S: 33.602", "O: 4.586 ml/l", "L: 83.51 %"] inline_name = vnta3966anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 11 db", "T: 13.396 C", "S: 33.537", "O: 7.421 ml/l", "L: 76.01 %"] placeAnnotations(): Placing annotation 2: physical objframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/279/accesses.txt at bin\geopdvc.pl line 625 ect Ancillary data = ["physical object", "P: 12.2 db", "T: 12.83 C", "S: 33.543", "O: 6.197 ml/l", "L: 82.24 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 12.2 db", "T: 12.83 C", "S: 33.543", "O: 6.197 ml/l", "L: 82.24 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 133.7 db", "T: 10.238 C", "S: 33.734", "O: 2.805 ml/l", "L: 80.73 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 282.3 db", "T: 8.576 C", "S: 33.981", "O: 1.799 ml/l", "L: 71.98 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.507 C", "S: 33.99", "O: 1.762 ml/l", "L: 71.94 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.507 C", "S: 33.99", "O: 1.762 ml/l", "L: 71.94 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.511 C", "S: 33.99", "O: 1.761 ml/l", "L: 71.63 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.515 C", "S: 33.99", "O: 1.764 ml/l", "L: 71.88 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.515 C", "S: 33.99", "O: 1.764 ml/l", "L: 71.88 %"] placeAnnotations(): Placing annotation 11: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 13: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 14: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 15: physical object Ancillary data = ["physical object", "P: 288.8 db", "T: 8.516 C", "S: 33.99", "O: 1.764 ml/l", "L: 72.08 %"] placeAnnotations(): Placing annotation 16: Ancillary data = ["", "P: 16.7 db", "T: 12.148 C", "S: 33.602", "O: 4.586 ml/l", "L: 83.51 %"] inline_name = vnta3966anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/vnta3966... frameGrabs(): matched 00_00_08_02 in