Running bin\geopdvc.pl at Fri Jun 2 03:00:15 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4019 bin\geopdvc.pl: Getting Expedition object for diveno = 4019 and rname = vnta set_expd(): rov = vnta, diveno = 4019 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 = 4019 AND RovName = 'vnta' RS->Fields(0)->Value= 5907 RS->Fields(1)->Value= 1491318502 RS->Fields(2)->Value= 1491321866 RS->Fields(3)->Value= 4/4/2017 3:08:22 PM RS->Fields(4)->Value= 4/4/2017 4:04:26 PM set_expd(): $self->{EndDDD} = 094 set_expd(): $self->{StartEsecs} = 1491317302 set_expd(): $self->{EndEsecs} = 1491323066 set_expd(): $self->{EndDtg} = 4/4/2017 4:04:26 PM _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491317302 & 1491323066 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491323072, self->{EndEsecs} = 1491323066 _readLogr(): #LOGHOST_SYSTEM_UTC = 575 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491317311 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491323062 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491317311 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491323062 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491317182, Refined EndEsecs = 1491323186 _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491317182 & 1491323186 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491323192, self->{EndEsecs} = 1491323186 _readLogr(): #LOGHOST_SYSTEM_UTC = 599 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491317191 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491323182 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 1491318538, 0, 0, 36.792747, -121.823960, 67.9, 13.38 1491319038, 0, 0, 36.792258, -121.823317, 209.0, 75.68 1491319539, 0, 0, 36.791702, -121.824005, 233.7, 75.75 1491320040, 0, 0, 36.791345, -121.824465, 202.0, 25.11 1491320541, 0, 0, 36.790780, -121.824792, 205.8, 25.10 1491321042, 0, 0, 36.790120, -121.825085, 236.7, 25.22 1491321543, 0, 0, 36.789520, -121.826368, 86.3, 11.16 rovNav(): 094: 3372 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vnta.txt 3372 in tList 3372 in {self->{rovT}} 3372 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491321750 & 1491321720 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 25.161043385136 rovGDCListRefs(): dStats->sample_range = 73.6768915014158 rovGDCListRefs(): returning (1st elements): 1491318504 36.792427 -121.823917 67.8 2.77928179883933 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 1491318538, 0, 0, 36.792735, -121.823986, 67.9, 13.38 1491319038, 0, 0, 36.792262, -121.823317, 209.0, 75.68 1491319539, 0, 0, 36.791699, -121.824014, 233.7, 75.75 1491320040, 0, 0, 36.791348, -121.824459, 202.0, 25.11 1491320541, 0, 0, 36.790759, -121.824822, 205.8, 25.10 1491321042, 0, 0, 36.790069, -121.825509, 236.7, 25.22 1491321543, 0, 0, 36.789474, -121.826409, 86.3, 11.16 rovNav(): 094: 3372 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt 3372 in tList 3372 in {self->{rovT}} 3372 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491321750 & 1491321720 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXX...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 25.1610433918156 rovGDCListRefs(): dStats->sample_range = 73.6768916188503 rovGDCListRefs(): returning (1st elements): 1491318504 36.792738 -121.823977 67.8 2.77928172235 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79130400 hash{lon} = -121.82456500 latMin = 30.791304 latMax = 42.791304 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.791304 AND 42.791304 AND Longitude BETWEEN -127.824565 AND -115.824565 Dive lat = 36.79130400, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.82456500 & lat = 36.79130400 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4019rov_orig.wrl Making color lookup world... var = Light, vmin = 40, vmax = 100 val = 40, color = .8 .7 0frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 2382 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/vnta4019lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 599 shipNav(): $#{$self->{shipLat}}[last] = 36.791400 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 = 3373 makeTracks(): Looping through 599 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4019tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4019mergedCtdNav.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 3:08:22 PM' AND '4/4/2017 4:04:26 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/4/2017 3:08:22 PM' AND '4/4/2017 4:04:26 PM') _findIndex(): Looking for epoch secs 1491318522 ... Found time 1491318517 after 7 iterations. >>> p = 13: annotations(): 13, 1491318522, 04/04/17 15:08:42, V4019-01HD, 00:03:42:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_03_42_18.jpg, 6.98000001907349, descend, schlin, u, 20066530 _findIndex(): Looking for epoch secs 1491318856 ... Found time 1491318858 after 7 iterations. >>> p = 354: annotations(): 354, 1491318856, 04/04/17 15:14:16, V4019-01HD, 00:09:16:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_09_16_12.jpg, 75.1999969482422, descend, schlin, u, 20066533 _findIndex(): Looking for epoch secs 1491318861 ... Found time 1491318858 after 7 iterations. >>> p = 354: annotations(): 354, 1491318861, 04/04/17 15:14:21, V4019-01HD, 00:09:20:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_09_20_27.jpg, 75.129997253418, starttransect, schlin, u, 20066534 _findIndex(): Looking for epoch secs 1491319058 ... Found time 1491319057 after 6 iterations. >>> p = 552: annotations(): 552, 1491319058, 04/04/17 15:17:38, V4019-01HD, 00:12:37:19, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_12_37_19.jpg, 75.1800003051758, transect, schlin, u, 20066535 _findIndex(): Looking for epoch secs 1491319230 ... Found time 1491319228 after 7 iterations. >>> p = 723: annotations(): 723, 1491319230, 04/04/17 15:20:30, V4019-01HD, 00:15:30:13, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_15_30_13.jpg, 74.9400024414063, transect, schlin, u, 20066536 _findIndex(): Looking for epoch secs 1491319721 ... Found time 1491319717 after 5 iterations. >>> p = 1211: annotations(): 1211, 1491319721, 04/04/17 15:28:41, V4019-01HD, 00:23:40:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_23_40_22.jpg, 75.0100021362305, endtransect, schlin, u, 20066537 _findIndex(): Looking for epoch secs 1491319750 ... Found time 1491319749 after 8 iterations. >>> p = 1243: annotations(): 1243, 1491319750, 04/04/17 15:29:10, V4019-01HD, 00:24:09:01, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_24_09_01.jpg, 75.1399993896484, ascend, schlin, u, 20066538 _findIndex(): Looking for epoch secs 1491320352 ... Found time 1491320350 after 5 iterations. >>> p = 1843: annotations(): 1843, 1491320352, 04/04/17 15:39:12, V4019-01HD, 00:34:10:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_34_10_11.jpg, 25.0799999237061, starttransect, schlin, u, 20066541 _findIndex(): Looking for epoch secs 1491320626 ... Found time 1491320627 after 7 iterations. >>> p = 2120: annotations(): 2120, 1491320626, 04/04/17 15:43:46, V4019-01HD, 00:38:44:15, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_38_44_15.jpg, 25.2900009155273, transect, schlin, u, 20066543 _findIndex(): Looking for epoch secs 1491320692 ... Found time 1491320692 after 6 iterations. >>> p = 2185: annotations(): 2185, 1491320692, 04/04/17 15:44:52, V4019-01HD, 00:39:50:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_39_50_02.jpg, 25.1599998474121, transect, schlin, u, 20066544 _findIndex(): Looking for epoch secs 1491321048 ... Found time 1491321050 after 7 iterations. >>> p = 2542: annotations(): 2542, 1491321048, 04/04/17 15:50:48, V4019-01HD, 00:45:46:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_45_46_10.jpg, 24.8700008392334, transect, schlin, u, 20066545 _findIndex(): Looking for epoch secs 1491321306 ... Found time 1491321305 after 8 iterations. >>> p = 2797: annotations(): 2797, 1491321306, 04/04/17 15:55:06, V4019-01HD, 00:50:04:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_50_04_05.jpg, 25.0100002288818, endtransect, schlin, u, 20066546 _findIndex(): Looking for epoch secs 1491321324 ... Found time 1491321325 after 6 iterations. >>> p = 2817: annotations(): 2817, 1491321324, 04/04/17 15:55:24, V4019-01HD, 00:50:21:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_50_21_24.jpg, 25.1100006103516, ascend, schlin, u, 20066547 _findIndex(): Looking for epoch secs 1491321654 ... Found time 1491321656 after 7 iterations. >>> p = 3147: annotations(): 3147, 1491321654, 04/04/17 16:00:54, V4019-01HD, 00:55:51:22, recovery, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4019/00_55_51_22.jpg, 10.9700002670288, ascend, schlin, u, 20066548 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 9.213 C", "S: 33.865", "O: 2.452 ml/l", "L: 64.97 %"] inline_name = vnta4019anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.998 C", "S: 33.945", "O: 2.306 ml/l", "L: 78.28 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.993 C", "S: 33.939", "O: 2.298 ml/l", "L: 77.45 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 77.3 db", "T: 8.962 C", "S: 33.939", "O: 2.224 ml/l", "L: 76.31 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 9.228 C", "S: 33.86", "O: 2.468 ml/l", "L: 65.38 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 9.213 C", "S: 33.865", "O: 2.452 ml/l", "L: 64.97 %"] placeAnnotations(): Placing annotation 12: physicaframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 625 l object Ancillary data = ["physical object", "P: 26.9 db", "T: 9.321 C", "S: 33.83", "O: 2.542 ml/l", "L: 62.09 %"] placeAnnotations(): Placing annotation 14: Ancillary data = ["", "P: 12.8 db", "T: 9.791 C", "S: 33.689", "O: 3.187 ml/l", "L: 75.17 %"] inline_name = vnta4019anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 26.9 db", "T: 9.214 C", "S: 33.865", "O: 2.449 ml/l", "L: 64.8 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.998 C", "S: 33.945", "O: 2.306 ml/l", "L: 78.28 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.998 C", "S: 33.945", "O: 2.306 ml/l", "L: 78.28 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.993 C", "S: 33.939", "O: 2.298 ml/l", "L: 77.45 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 77.1 db", "T: 8.979 C", "S: 33.94", "O: 2.272 ml/l", "L: 76.98 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 77.3 db", "T: 8.962 C", "S: 33.939", "O: 2.224 ml/l", "L: 76.31 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 76.7 db", "T: 8.968 C", "S: 33.938", "O: 2.232 ml/l", "L: 76.16 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 9.228 C", "S: 33.86", "O: 2.468 ml/l", "L: 65.38 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 26.9 db", "T: 9.211 C", "S: 33.866", "O: 2.448 ml/l", "L: 64.89 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 9.213 C", "S: 33.865", "O: 2.452 ml/l", "L: 64.97 %"] placeAnnotations(): Placing annotation 11: physical object Ancillary data = ["physical object", "P: 26.9 db", "T: 9.348 C", "S: 33.822", "O: 2.581 ml/l", "L: 63.13 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 26.9 db", "T: 9.321 C", "S: 33.83", "O: 2.542 ml/l", "L: 62.09 %"] placeAnnotations(): Placing annotation 13: recovery Ancillary data = ["recovery", "P: 26.8 db", "T: 9.317 C", "S: 33.833", "O: 2.535 ml/l", "L: 61.88 %"] placeAnnotations(): Placing annotation 14: Ancillary data = ["", "P: 12.8 db", "T: 9.791 C", "S: 33.689", "O: 3.187 ml/l", "L: 75.17 %"] inline_name = vnta4019anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4019... frameGrabs(): matched 00_03_42_18 in