Running bin\geopdvc.pl at Fri Jun 2 03:00:26 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4020 bin\geopdvc.pl: Getting Expedition object for diveno = 4020 and rname = vnta set_expd(): rov = vnta, diveno = 4020 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 = 4020 AND RovName = 'vnta' RS->Fields(0)->Value= 5907 RS->Fields(1)->Value= 1491323172 RS->Fields(2)->Value= 1491324323 RS->Fields(3)->Value= 4/4/2017 4:26:12 PM RS->Fields(4)->Value= 4/4/2017 4:45:23 PM set_expd(): $self->{EndDDD} = 094 set_expd(): $self->{StartEsecs} = 1491321972 set_expd(): $self->{EndEsecs} = 1491325523 set_expd(): $self->{EndDtg} = 4/4/2017 4:45:23 PM _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491321972 & 1491325523 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491325532, self->{EndEsecs} = 1491325523 _readLogr(): #LOGHOST_SYSTEM_UTC = 354 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491321982 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491325522 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491321982 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491325522 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491321852, Refined EndEsecs = 1491325643 _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491321852 & 1491325643 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491325652, self->{EndEsecs} = 1491325643 _readLogr(): #LOGHOST_SYSTEM_UTC = 378 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491321862 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491325642 bin\geopdvc.pl: expdid = 5907 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 = 094 (looping to 094) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017094vnta.txt esecs, east, north, lat, lon, head, pres 1491323349, 0, 0, 36.791708, -121.827205, 124.5, 5.21 1491323850, 0, 0, 36.790908, -121.826978, 124.7, 5.12 rovNav(): 094: 1163 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vnta.txt 1163 in tList 1163 in {self->{rovT}} 1163 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491324270 & 1491323730 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 5.16149402444822 rovGDCListRefs(): dStats->sample_range = 5.18128067910994 rovGDCListRefs(): rovDepth (paroscientific) range is less than 1. Using rovctd calculated depth geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 094 (looping to 094) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt esecs, east, north, lat, lon, head, pres 1491323349, 0, 0, 36.791701, -121.827205, 124.5, 5.21 1491323850, 0, 0, 36.790902, -121.826957, 124.7, 5.12 rovNav(): 094: 1163 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt 1163 in tList 1163 in {self->{rovT}} 1163 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491324270 & 1491323730 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXX....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 5.16149402239287 rovGDCListRefs(): dStats->sample_range = 5.18128067772396 rovGDCListRefs(): rovDepth (paroscientific) range is less than 1. Using rovctd calculated depth bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79104450 hash{lon} = -121.82705750 latMin = 30.7910445 latMax = 42.7910445 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.7910445 AND 42.7910445 AND Longitude BETWEEN -127.8270575 AND -115.8270575 Dive lat = 36.79104450, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.82705750 & lat = 36.79104450 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4020rov_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/vnta4020lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 378 shipNav(): $#{$self->{shipLat}}[last] = 36.774579 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = .8 .7 0 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 2382 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 = 1164 makeTracks(): Looping through 378 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4020tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4020mergedCtdNav.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 '4/4/2017 4:26:12 PM' AND '4/4/2017 4:45:23 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/4/2017 4:26:12 PM' AND '4/4/2017 4:45:23 PM') _findIndex(): Looking for epoch secs 1491323357 ... Found time 1491323357 after 5 iterations. >>> p = 199: annotations(): 199, 1491323357, 04/04/17 16:29:17, V4020-01HD, 00:05:11:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_05_11_26.jpg, 5.01999998092651, cruise, schlin, u, 20066550 _findIndex(): Looking for epoch secs 1491323360 ... Found time 1491323357 after 5 iterations. >>> p = 199: annotations(): 199, 1491323360, 04/04/17 16:29:20, V4020-01HD, 00:05:14:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_05_14_26.jpg, 5.03999996185303, cruise, schlin, u, 20066551 _findIndex(): Looking for epoch secs 1491323364 ... Found time 1491323366 after 6 iterations. >>> p = 208: annotations(): 208, 1491323364, 04/04/17 16:29:24, V4020-01HD, 00:05:18:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_05_18_26.jpg, 5.1399998664856, starttransect, schlin, u, 20066552 _findIndex(): Looking for epoch secs 1491323592 ... Found time 1491323593 after 2 iterations. >>> p = 435: annotations(): 435, 1491323592, 04/04/17 16:33:12, V4020-01HD, 00:09:06:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_09_06_04.jpg, 5.05999994277954, transect, schlin, u, 20066553 _findIndex(): Looking for epoch secs 1491323661 ... Found time 1491323666 after 3 iterations. >>> p = 508: annotations(): 508, 1491323661, 04/04/17 16:34:21, V4020-01HD, 00:10:16:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_10_16_02.jpg, 5.23999977111816, transect, schlin, u, 20066554 _findIndex(): Looking for epoch secs 1491323917 ... Found time 1491323921 after 4 iterations. >>> p = 762: annotations(): 762, 1491323917, 04/04/17 16:38:37, V4020-01HD, 00:14:31:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_14_31_09.jpg, 5.11999988555908, transect, schlin, u, 20066555 _findIndex(): Looking for epoch secs 1491323925 ... Found time 1491323921 after 4 iterations. >>> p = 762: annotations(): 762, 1491323925, 04/04/17 16:38:45, V4020-01HD, 00:14:38:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_14_38_24.jpg, 5.21999979019165, endtransect, schlin, u, 20066556 _findIndex(): Looking for epoch secs 1491323974 ... Found time 1491323976 after 5 iterations. >>> p = 817: annotations(): 817, 1491323974, 04/04/17 16:39:34, V4020-01HD, 00:15:27:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_15_27_22.jpg, 4.94000005722046, starttransect, schlin, u, 20066557 _findIndex(): Looking for epoch secs 1491324044 ... Found time 1491324049 after 5 iterations. >>> p = 890: annotations(): 890, 1491324044, 04/04/17 16:40:44, V4020-01HD, 00:16:frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 625 38:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_16_38_05.jpg, 5.30999994277954, endtransect, schlin, u, 20066558 _findIndex(): Looking for epoch secs 1491324070 ... Found time 1491324067 after 4 iterations. >>> p = 908: annotations(): 908, 1491324070, 04/04/17 16:41:10, V4020-01HD, 00:17:04:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4020/00_17_04_04.jpg, 5.05000019073486, ascend, schlin, u, 20066559 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 6.8 db", "T: 10.188 C", "S: 33.586", "O: 3.695 ml/l", "L: 76.26 %"] inline_name = vnta4020anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.236 C", "S: 33.573", "O: 3.808 ml/l", "L: 76.05 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.218 C", "S: 33.577", "O: 3.755 ml/l", "L: 76.08 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.995 C", "S: 33.386", "O: 4.381 ml/l", "L: 65.42 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.403 C", "S: 33.534", "O: 3.917 ml/l", "L: 69.79 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 6.8 db", "T: 10.188 C", "S: 33.586", "O: 3.695 ml/l", "L: 76.26 %"] inline_name = vnta4020anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.236 C", "S: 33.573", "O: 3.808 ml/l", "L: 76.05 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.236 C", "S: 33.573", "O: 3.808 ml/l", "L: 76.05 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.226 C", "S: 33.576", "O: 3.797 ml/l", "L: 76 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.218 C", "S: 33.577", "O: 3.755 ml/l", "L: 76.08 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.209 C", "S: 33.579", "O: 3.737 ml/l", "L: 76.24 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.995 C", "S: 33.386", "O: 4.381 ml/l", "L: 65.42 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.995 C", "S: 33.386", "O: 4.381 ml/l", "L: 65.42 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 6.7 db", "T: 10.403 C", "S: 33.534", "O: 3.917 ml/l", "L: 69.79 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 6.8 db", "T: 10.199 C", "S: 33.583", "O: 3.705 ml/l", "L: 76.13 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 6.8 db", "T: 10.188 C", "S: 33.586", "O: 3.695 ml/l", "L: 76.26 %"] inline_name = vnta4020anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4020... frameGrabs(): matched 00_05_11_26 in