Running bin\geopdvc.pl at Fri Jun 2 03:01:04 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4022 bin\geopdvc.pl: Getting Expedition object for diveno = 4022 and rname = vnta set_expd(): rov = vnta, diveno = 4022 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 = 4022 AND RovName = 'vnta' RS->Fields(0)->Value= 5907 RS->Fields(1)->Value= 1491339643 RS->Fields(2)->Value= 1491344167 RS->Fields(3)->Value= 4/4/2017 9:00:43 PM RS->Fields(4)->Value= 4/4/2017 10:16:07 PM set_expd(): $self->{EndDDD} = 094 set_expd(): $self->{StartEsecs} = 1491338443 set_expd(): $self->{EndEsecs} = 1491345367 set_expd(): $self->{EndDtg} = 4/4/2017 10:16:07 PM _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491338443 & 1491345367 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491345376, self->{EndEsecs} = 1491345367 _readLogr(): #LOGHOST_SYSTEM_UTC = 692 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491338445 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491345366 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491338445 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491345366 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491338323, Refined EndEsecs = 1491345487 _readLogr(): Reading videologr data from 2017094datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491338323 & 1491345487 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491345496, self->{EndEsecs} = 1491345487 _readLogr(): #LOGHOST_SYSTEM_UTC = 716 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491338325 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491345486 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 1491339648, 0, 0, 36.698818, -122.050148, 325.5, 3.70 1491340149, 0, 0, 36.698453, -122.050422, 327.2, 232.85 1491340650, 0, 0, 36.698008, -122.050337, 325.1, 461.43 1491341151, 0, 0, 36.697558, -122.049863, 179.9, 605.26 1491341652, 0, 0, 36.696485, -122.049600, 184.2, 561.25 1491342152, 0, 0, 36.696443, -122.049718, 180.2, 403.20 1491342653, 0, 0, 36.695240, -122.049610, 47.7, 402.75 1491343154, 0, 0, 36.695313, -122.049468, 53.9, 218.36 1491343655, 0, 0, 36.695228, -122.050083, 51.1, 10.29 1491344156, 0, 0, 36.694638, -122.051055, 49.7, 5.07 rovNav(): 094: 4709 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vnta.txt 4709 in tList 4709 in {self->{rovT}} 4709 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491339870 & 1491339840 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 359.302814038007 rovGDCListRefs(): dStats->sample_range = 598.574587311818 rovGDCListRefs(): returning (1st elements): 1491338323 36.696655 -122.047098 353.3 32.2971534967332 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 1491339648, 0, 0, 36.698815, -122.050195, 325.5, 3.70 1491340149, 0, 0, 36.698456, -122.050412, 327.2, 232.85 1491340650, 0, 0, 36.697988, -122.050311, 325.1, 461.43 1491341151, 0, 0, 36.697547, -122.049839, 179.9, 605.26 1491341652, 0, 0, 36.696496, -122.049595, 184.2, 561.25 1491342152, 0, 0, 36.696435, -122.049729, 180.2, 403.20 1491342653, 0, 0, 36.695247, -122.049606, 47.7, 402.75 1491343154, 0, 0, 36.695315, -122.049476, 53.9, 218.36 1491343655, 0, 0, 36.695213, -122.050085, 51.1, 10.29 1491344156, 0, 0, 36.694658, -122.051025, 49.7, 5.07 rovNav(): 094: 4709 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017094vntaedited.txt 4709 in tList 4709 in {self->{rovT}} 4709 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491339870 & 1491339840 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXXXXXXXXX..............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 2382 .........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 359.302813990471 rovGDCListRefs(): dStats->sample_range = 598.574587151708 rovGDCListRefs(): returning (1st elements): 1491338323 36.696646 -122.047073 353.3 32.29715352243 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.69655750 hash{lon} = -122.04984200 latMin = 30.6965575 latMax = 42.6965575 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.6965575 AND 42.6965575 AND Longitude BETWEEN -128.049842 AND -116.049842 Dive lat = 36.69655750, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.04984200 & lat = 36.69655750 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4022rov_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/vnta4022lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 716 shipNav(): $#{$self->{shipLat}}[last] = 36.704803 Position subsampling set to 4 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 = 4710 makeTracks(): Looping through 716 point of ship position data... makeTracks(): posSubsample = 4 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4022tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4022mergedCtdNav.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 9:00:43 PM' AND '4/4/2017 10:16:07 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/4/2017 9:00:43 PM' AND '4/4/2017 10:16:07 PM') _findIndex(): Looking for epoch secs 1491341043 ... Found time 1491341043 after 8 iterations. >>> p = 1590: annotations(): 1590, 1491341043, 04/04/17 21:24:03, V4022-01HD, 00:26:18:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_26_18_18.jpg, 599.969970703125, starttransect, schlin, u, 20066579 _findIndex(): Looking for epoch secs 1491341512 ... Found time 1491341512 after 3 iterations. >>> p = 2059: annotations(): 2059, 1491341512, 04/04/17 21:31:52, V4022-01HD, 00:34:07:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_34_07_04.jpg, 600.450012207031, endtransect, schlin, u, 20066580 _findIndex(): Looking for epoch secs 1491341576 ... Found time 1491341576 after 8 iterations. >>> p = 2122: annotations(): 2122, 1491341576, 04/04/17 21:32:56, V4022-01HD, 00:35:10:22, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_35_10_22.jpg, 586.130004882813, ascend, schlin, u, 20066581 _findIndex(): Looking for epoch secs 1491342067 ... Found time 1491342065 after 6 iterations. >>> p = 2611: annotations(): 2611, 1491342067, 04/04/17 21:41:07, V4022-01HD, 00:43:21:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_43_21_00.jpg, 399.760009765625, starttransect, schlin, u, 20066582 _findIndex(): Looking for epoch secs 1491342480 ... Found time 1491342479 after 8 iterations. >>> p = 3024: annotations(): 3024, 1491342480, 04/04/17 21:48:00, V4022-01HD, 00:50:14:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_50_14_05.jpg, 399.850006103516, endtransect, schlin, u, 20066583 _findIndex(): Looking for epoch secs 1491342737 ... Found time 1491342736 after 8 iterations. >>> p = 3281: annotations(): 3281, 1491342737, 04/04/17 21:52:17, V4022-01HD, 00:54:30:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4022/00_54_30_27.jpg, 376.660003662109, ascend, schlin, u, 20066585 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4022anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 607.1 db", "T: 5.331 C", "S: 34.298", "O: 0.232 ml/l", "L: 82.71 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 404.9 db", "T: 6.721 C", "S: 34.194", "O: 0.724 ml/l", "L: 82.08 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 382.5 db", "T: 6.861 C", "S: 34.186", "O: 0.798 ml/l", "L: 82.07 %"] inline_name = vnta4022anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 607.1 db", "T: 5.394 C", "S: 34.294", "O: 0.241 ml/l", "L: 82.74 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 607.1 db", "T: 5.331 C", "S: 34.298", "O: 0.232 ml/l", "L: 82.71 %"] placeAnnotations(): Placing annotation 3: physical obframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/094/accesses.txt at bin\geopdvc.pl line 625 ject Ancillary data = ["physical object", "P: 591 db", "T: 5.415 C", "S: 34.295", "O: 0.238 ml/l", "L: 82.79 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 404.9 db", "T: 6.721 C", "S: 34.194", "O: 0.724 ml/l", "L: 82.08 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 404.7 db", "T: 6.714 C", "S: 34.193", "O: 0.726 ml/l", "L: 82.2 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 382.5 db", "T: 6.861 C", "S: 34.186", "O: 0.798 ml/l", "L: 82.07 %"] inline_name = vnta4022anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4022... frameGrabs(): matched 00_26_18_18 in [IMG]00_26_18_18.jpg 2017-04-11 00:39 193K  frameGrabs(): matched 00_34_07_04 in [IMG]00_34_07_04.jpg 2017-04-11 00:40 185K  frameGrabs(): matched 00_35_10_22 in [IMG]00_35_10_22.jpg 2017-04-11 00:40 184K  frameGrabs(): matched 00_43_21_00 in [IMG]00_43_21_00.jpg 2017-04-11 00:40 199K  frameGrabs(): matched 00_50_14_05 in [IMG]00_50_14_05.jpg 2017-04-11 00:40 191K  frameGrabs(): matched 00_54_30_27 in [IMG]00_54_30_27.jpg 2017-04-11 00:40 238K  iList = 00_26_18_18.jpg 00_34_07_04.jpg 00_35_10_22.jpg 00_43_21_00.jpg 00_50_14_05.jpg 00_54_30_27.jpg _findIndex(): Looking for epoch secs 1491341043 ... Found time 1491341043 after 8 iterations. >>> p = 1590: frameGrabs(): index = 1590 EXPD::frameGrabs(): 1590: Time=1491341040, Depth=601.74, Lat= 36.697726, Lon=-122.049931, Pres=607.1, Temp=5.394, Sal=34.294, O2=0.241, Light=82.74 _findIndex(): Looking for epoch secs 1491341512 ... Found time 1491341512 after 3 iterations. >>> p = 2059: frameGrabs(): index = 2059 EXPD::frameGrabs(): 2059: Time=1491341505, Depth=601.74, Lat= 36.696630, Lon=-122.049642, Pres=607.1, Temp=5.331, Sal=34.298, O2=0.232, Light=82.71 _findIndex(): Looking for epoch secs 1491341576 ... Found time 1491341576 after 8 iterations. >>> p = 2122: frameGrabs(): index = 2122 EXPD::frameGrabs(): 2122: Time=1491341580, Depth=585.81, Lat= 36.696521, Lon=-122.049606, Pres=591, Temp=5.415, Sal=34.295, O2=0.238, Light=82.79 _findIndex(): Looking for epoch secs 1491342067 ... Found time 1491342065 after 6 iterations. >>> p = 2611: frameGrabs(): index = 2611 EXPD::frameGrabs(): 2611: Time=1491342060, Depth=401.52, Lat= 36.696669, Lon=-122.049631, Pres=404.9, Temp=6.721, Sal=34.194, O2=0.724, Light=82.08 _findIndex(): Looking for epoch secs 1491342480 ... Found time 1491342479 after 8 iterations. >>> p = 3024: frameGrabs(): index = 3024 EXPD::frameGrabs(): 3024: Time=1491342480, Depth=401.33, Lat=36.695319, Lon=-122.049898, Pres=404.7, Temp=6.714, Sal=34.193, O2=0.726, Light=82.2 _findIndex(): Looking for epoch secs 1491342737 ... Found time 1491342736 after 8 iterations. >>> p = 3281: frameGrabs(): index = 3281 EXPD::frameGrabs(): 3281: Time=1491342735, Depth=379.33, Lat= 36.695291, Lon=-122.049527, Pres=382.5, Temp=6.861, Sal=34.186, O2=0.798, Light=82.07 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 6 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_26_18_18.jpg 1491341043 fw= 0.0 cm XplaceImages(): indx = 1590, geoPosition = 36.697726, -122.049931, -599.957829950012, rovDepth = 599.957829950012 inline_name = vnta4022fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_26_18_18.jpg 1491341043 fw= 0.0 cm XplaceImages(): indx = 1590, geoPosition = 36.697726, -122.049931, -599.957829950012, rovDepth = 599.957829950012 placeImages(): Placing image 2 00_35_10_22.jpg 1491341576 fw= 0.0 cm XplaceImages(): indx = 2122, geoPosition = 36.696521, -122.049606, -586.111388766957, rovDepth = 586.111388766957 placeImages(): Placing image 4 00_50_14_05.jpg 1491342480 fw= 0.0 cm XplaceImages(): indx = 3024, geoPosition = 36.695319, -122.049898, -399.879914309954, rovDepth = 399.879914309954 inline_name = vnta4022fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_26_18_18.jpg 1491341043 fw= 0.0 cm XplaceImages(): indx = 1590, geoPosition = 36.697726, -122.049931, -599.957829950012, rovDepth = 599.957829950012 placeImages(): Placing image 1 00_34_07_04.jpg 1491341512 fw= 0.0 cm XplaceImages(): indx = 2059, geoPosition = 36.696630, -122.049642, -600.432948217281, rovDepth = 600.432948217281 placeImages(): Placing image 2 00_35_10_22.jpg 1491341576 fw= 0.0 cm XplaceImages(): indx = 2122, geoPosition = 36.696521, -122.049606, -586.111388766957, rovDepth = 586.111388766957 placeImages(): Placing image 3 00_43_21_00.jpg 1491342067 fw= 0.0 cm XplaceImages(): indx = 2611, geoPosition = 36.696669, -122.049631, -399.770892470363, rovDepth = 399.770892470363 placeImages(): Placing image 4 00_50_14_05.jpg 1491342480 fw= 0.0 cm XplaceImages(): indx = 3024, geoPosition = 36.695319, -122.049898, -399.879914309954, rovDepth = 399.879914309954 placeImages(): Placing image 5 00_54_30_27.jpg 1491342737 fw= 0.0 cm .placeImages(): indx = 3281, geoPosition = 36.695291, -122.049527, -377.439929185412, rovDepth = 377.439929185412 inline_name = vnta4022fg01.wrl bin\geopdvc.pl: Samples: samples(): Executing sql: SELECT DateDiff(ss, '01/01/70', CollectionEventDtg) AS Esecs, CollectionVIMSConcept, FrameGrabImageURL, SampleID, CollectionRefName FROM vwCollSamplePerson WHERE (CollectionEventDTG BETWEEN '4/4/2017 9:00:43 PM' AND '4/4/2017 10:16:07 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4022.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5907,'/3Dreplay/3D.asp?dives=vnta4022','vnta4022','','','',4022 Executing sql: UPDATE Dive SET DiveLatMid = 36.6966, DiveLonMid = -122.0498, DiveDepthMid = -359.3 WHERE RovName = 'vnta' AND DiveNumber = 4022