Running bin\geopdvc.pl at Sat Jun 3 03:00:14 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4024 bin\geopdvc.pl: Getting Expedition object for diveno = 4024 and rname = vnta set_expd(): rov = vnta, diveno = 4024 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 = 4024 AND RovName = 'vnta' RS->Fields(0)->Value= 5908 RS->Fields(1)->Value= 1491411810 RS->Fields(2)->Value= 1491414510 RS->Fields(3)->Value= 4/5/2017 5:03:30 PM RS->Fields(4)->Value= 4/5/2017 5:48:30 PM set_expd(): $self->{EndDDD} = 095 set_expd(): $self->{StartEsecs} = 1491410610 set_expd(): $self->{EndEsecs} = 1491415710 set_expd(): $self->{EndDtg} = 4/5/2017 5:48:30 PM _readLogr(): Reading videologr data from 2017095datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491410610 & 1491415710 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491415717, self->{EndEsecs} = 1491415710 _readLogr(): #LOGHOST_SYSTEM_UTC = 509 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491410616 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491415707 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491410616 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491415707 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491410490, Refined EndEsecs = 1491415830 _readLogr(): Reading videologr data from 2017095datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491410490 & 1491415830 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491415837, self->{EndEsecs} = 1491415830 _readLogr(): #LOGHOST_SYSTEM_UTC = 533 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491410496 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491415827 bin\geopdvc.pl: expdid = 5908 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 = 095 (looping to 095) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017095vnta.txt esecs, east, north, lat, lon, head, pres 1491411896, 0, 0, 36.700287, -122.053102, 14.8, 35.27 1491412397, 0, 0, 36.699918, -122.052435, 87.7, 50.92 1491412898, 0, 0, 36.699698, -122.051248, 87.5, 50.89 1491413399, 0, 0, 36.699223, -122.049912, 87.5, 24.88 1491413899, 0, 0, 36.698718, -122.048435, 88.1, 24.99 1491414400, 0, 0, 36.697933, -122.048473, 88.2, 9.54 rovNav(): 095: 2883 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017095vnta.txt 2883 in tList 2883 in {self->{rovT}} 2883 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491412590 & 1491414135 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ..........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 24.8337256854993 rovGDCListRefs(): dStats->sample_range = 48.6813512927593 rovGDCListRefs(): returning (1st elements): 1491410490 36.697950 -122.046898 103.8 4.72479592624958 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 095 (looping to 095) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt esecs, east, north, lat, lon, head, pres 1491411896, 0, 0, 36.700330, -122.053113, 14.8, 35.27 1491412397, 0, 0, 36.699976, -122.052410, 87.7, 50.92 1491412898, 0, 0, 36.699698, -122.051252, 87.5, 50.89 1491413399, 0, 0, 36.699224, -122.049889, 87.5, 24.88 1491413899, 0, 0, 36.698719, -122.048409, 88.1, 24.99 1491414400, 0, 0, 36.697974, -122.048365, 88.2, 9.54 rovNav(): 095: 2883 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt 2883 in tList 2883 in {self->{rovT}} 2883 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491412590 & 1491414135 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXX..............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/095/accesses.txt at bin\geopdvc.pl line 2382 ...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 24.8337256734242 rovGDCListRefs(): dStats->sample_range = 48.6813512341038 rovGDCListRefs(): returning (1st elements): 1491410490 36.697924 -122.046889 103.8 4.72479593710974 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.69937650 hash{lon} = -122.05039750 latMin = 30.6993765 latMax = 42.6993765 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.6993765 AND 42.6993765 AND Longitude BETWEEN -128.0503975 AND -116.0503975 Dive lat = 36.69937650, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.05039750 & lat = 36.69937650 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4024rov_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/vnta4024lookup.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.700258 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 = 2884 makeTracks(): Looping through 533 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4024tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4024mergedCtdNav.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/5/2017 5:03:30 PM' AND '4/5/2017 5:48:30 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/5/2017 5:03:30 PM' AND '4/5/2017 5:48:30 PM') _findIndex(): Looking for epoch secs 1491412025 ... Found time 1491412024 after 7 iterations. >>> p = 393: annotations(): 393, 1491412025, 04/05/17 17:07:05, V4024-01HD, 00:06:52:29, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_06_52_29.jpg, 50.8300018310547, descend, svonthun, u, 20066594 _findIndex(): Looking for epoch secs 1491412905 ... Found time 1491412904 after 7 iterations. >>> p = 1271: annotations(): 1271, 1491412905, 04/05/17 17:21:45, V4024-01HD, 00:21:31:18, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_21_31_18.jpg, 50.5900001525879, descend, svonthun, u, 20066595 _findIndex(): Looking for epoch secs 1491413066 ... Found time 1491413062 after 7 iterations. >>> p = 1429: annotations(): 1429, 1491413066, 04/05/17 17:24:26, V4024-01HD, 00:24:12:29, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_24_12_29.jpg, 24.8899993896484, cruise, svonthun, u, 20066596 _findIndex(): Looking for epoch secs 1491414055 ... Found time 1491414055 after 7 iterations. >>> p = 2420: annotations(): 2420, 1491414055, 04/05/17 17:40:55, V4024-01HD, 00:40:40:29, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_40_40_29.jpg, 24.7700004577637, cruise, svonthun, u, 20066597 _findIndex(): Looking for epoch secs 1491414095 ... Found time 1491414100 after 7 iterations. >>> p = 2465: annotations(): 2465, 1491414095, 04/05/17 17:41:35, V4024-01HD, 00:41:20:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_41_20_12.jpg, 24.8400001525879, cruise, svonthun, u, 20066598 _findIndex(): Looking for epoch secs 1491412233 ... Found time 1491412228 after 7 iterations. >>> p = 596: annotations(): 596, 1491412233, 04/05/17 17:10:33, V4024-01HD, 00:10:11:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4024/00_10_11_10.jpg, 50.6500015258789, cruise, svonthun, u, 20066599 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4024anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: equipment Ancillary data = ["equipment", "P: 52.6 db", "T: 9.604 C", "S: 33.711", "O: 3.325 ml/l", "L: 82.3 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 26.6 db", "T: 10.091 C", "S: 33.612", "O: 3.96 ml/l", "L: 77.6 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 52.5 db", "T: 9.482 C", "S: 33.757", "O: 3.198 ml/l", "L: 82.62 %"] inline_name = vnta4024anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: equipment Ancillary data = ["equipment", "P: 52.5 db", "T: 9.551 C", "S: 33.74", "O: 3.259 ml/l", "L: 82.65 %"] placeAnnotations(): Placing annotation 2: equipment Ancillary data = ["equipment", "P: 52.6 db", "T: 9.604 C", "S: 33.711", "O: 3.325 ml/l", "L: 82.3 %"] placeAnnotations(): Placing annotation 3: equipment Ancillary data = ["equipment", "P: 26.7 db", "T: 10.242 C", "S: 33.587", "O: 4.17 ml/l", "L: 76.26 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/095/accesses.txt at bin\geopdvc.pl line 625 object", "P: 26.6 db", "T: 10.091 C", "S: 33.612", "O: 3.96 ml/l", "L: 77.6 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 26.8 db", "T: 10.117 C", "S: 33.614", "O: 3.917 ml/l", "L: 76.96 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 52.5 db", "T: 9.482 C", "S: 33.757", "O: 3.198 ml/l", "L: 82.62 %"] inline_name = vnta4024anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4024... frameGrabs(): matched 00_06_52_29 in [IMG]00_06_52_29.jpg 2017-04-14 00:06 229K  frameGrabs(): matched 00_10_11_10 in [IMG]00_10_11_10.jpg 2017-04-14 00:06 240K  frameGrabs(): matched 00_21_31_18 in [IMG]00_21_31_18.jpg 2017-04-14 00:06 169K  frameGrabs(): matched 00_24_12_29 in [IMG]00_24_12_29.jpg 2017-04-14 00:06 204K  frameGrabs(): matched 00_40_40_29 in [IMG]00_40_40_29.jpg 2017-04-14 00:06 203K  frameGrabs(): matched 00_41_20_12 in [IMG]00_41_20_12.jpg 2017-04-14 00:06 202K  iList = 00_06_52_29.jpg 00_10_11_10.jpg 00_21_31_18.jpg 00_24_12_29.jpg 00_40_40_29.jpg 00_41_20_12.jpg _findIndex(): Looking for epoch secs 1491412025 ... Found time 1491412024 after 7 iterations. >>> p = 393: frameGrabs(): index = 393 EXPD::frameGrabs(): 393: Time=1491412020, Depth=52.11, Lat=36.700182, Lon=-122.053166, Pres=52.5, Temp=9.551, Sal=33.74, O2=3.259, Light=82.65 _findIndex(): Looking for epoch secs 1491412233 ... Found time 1491412228 after 7 iterations. >>> p = 596: frameGrabs(): index = 596 EXPD::frameGrabs(): 596: Time=1491412230, Depth=52.11, Lat= 36.700075, Lon=-122.052807, Pres=52.5, Temp=9.482, Sal=33.757, O2=3.198, Light=82.62 _findIndex(): Looking for epoch secs 1491412905 ... Found time 1491412904 after 7 iterations. >>> p = 1271: frameGrabs(): index = 1271 EXPD::frameGrabs(): 1271: Time=1491412905, Depth=52.21, Lat= 36.699694, Lon=-122.051238, Pres=52.6, Temp=9.604, Sal=33.711, O2=3.325, Light=82.3 _findIndex(): Looking for epoch secs 1491413066 ... Found time 1491413062 after 7 iterations. >>> p = 1429: frameGrabs(): index = 1429 EXPD::frameGrabs(): 1429: Time=1491413055, Depth=26.5, Lat= 36.699545, Lon=-122.050986, Pres=26.7, Temp=10.242, Sal=33.587, O2=4.17, Light=76.26 _findIndex(): Looking for epoch secs 1491414055 ... Found time 1491414055 after 7 iterations. >>> p = 2420: frameGrabs(): index = 2420 EXPD::frameGrabs(): 2420: Time=1491414060, Depth=26.4, Lat= 36.698457, Lon=-122.048110, Pres=26.6, Temp=10.091, Sal=33.612, O2=3.96, Light=77.6 _findIndex(): Looking for epoch secs 1491414095 ... Found time 1491414100 after 7 iterations. >>> p = 2465: frameGrabs(): index = 2465 EXPD::frameGrabs(): 2465: Time=1491414105, Depth=26.6, Lat= 36.698374, Lon=-122.048134, Pres=26.8, Temp=10.117, Sal=33.614, O2=3.917, Light=76.96 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_06_52_29.jpg 1491412025 fw= 0.0 cm XplaceImages(): indx = 393, geoPosition = 36.700182, -122.053166, -50.5377008279644, rovDepth = 50.5377008279644 inline_name = vnta4024fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_06_52_29.jpg 1491412025 fw= 0.0 cm XplaceImages(): indx = 393, geoPosition = 36.700182, -122.053166, -50.5377008279644, rovDepth = 50.5377008279644 placeImages(): Placing image 2 00_21_31_18.jpg 1491412905 fw= 0.0 cm XplaceImages(): indx = 1271, geoPosition = 36.699694, -122.051238, -50.5873214474499, rovDepth = 50.5873214474499 placeImages(): Placing image 4 00_40_40_29.jpg 1491414055 fw= 0.0 cm .placeImages(): indx = 2420, geoPosition = 36.698457, -122.048110, -24.7741775512979, rovDepth = 24.7741775512979 inline_name = vnta4024fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_06_52_29.jpg 1491412025 fw= 0.0 cm XplaceImages(): indx = 393, geoPosition = 36.700182, -122.053166, -50.5377008279644, rovDepth = 50.5377008279644 placeImages(): Placing image 1 00_10_11_10.jpg 1491412233 fw= 0.0 cm XplaceImages(): indx = 596, geoPosition = 36.700075, -122.052807, -50.5377013060295, rovDepth = 50.5377013060295 placeImages(): Placing image 2 00_21_31_18.jpg 1491412905 fw= 0.0 cm XplaceImages(): indx = 1271, geoPosition = 36.699694, -122.051238, -50.5873214474499, rovDepth = 50.5873214474499 placeImages(): Placing image 3 00_24_12_29.jpg 1491413066 fw= 0.0 cm XplaceImages(): indx = 1429, geoPosition = 36.699545, -122.050986, -24.8535747226017, rovDepth = 24.8535747226017 placeImages(): Placing image 4 00_40_40_29.jpg 1491414055 fw= 0.0 cm XplaceImages(): indx = 2420, geoPosition = 36.698457, -122.048110, -24.7741775512979, rovDepth = 24.7741775512979 placeImages(): Placing image 5 00_41_20_12.jpg 1491414095 fw= 0.0 cm XplaceImages(): indx = 2465, geoPosition = 36.698374, -122.048134, -24.893277065143, rovDepth = 24.893277065143 inline_name = vnta4024fg01.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/5/2017 5:03:30 PM' AND '4/5/2017 5:48:30 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4024.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5908,'/3Dreplay/3D.asp?dives=vnta4024','vnta4024','','','',4024 Executing sql: UPDATE Dive SET DiveLatMid = 36.6994, DiveLonMid = -122.0504, DiveDepthMid = -24.8 WHERE RovName = 'vnta' AND DiveNumber = 4024