Running bin\geopdvc.pl at Fri Dec 2 03:00:03 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3963 bin\geopdvc.pl: Getting Expedition object for diveno = 3963 and rname = vnta set_expd(): rov = vnta, diveno = 3963 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 = 3963 AND RovName = 'vnta' RS->Fields(0)->Value= 5641 RS->Fields(1)->Value= 1475609760 RS->Fields(2)->Value= 1475611080 RS->Fields(3)->Value= 10/4/2016 7:36:00 PM RS->Fields(4)->Value= 10/4/2016 7:58:00 PM set_expd(): $self->{EndDDD} = 278 set_expd(): $self->{StartEsecs} = 1475608560 set_expd(): $self->{EndEsecs} = 1475612280 set_expd(): $self->{EndDtg} = 10/4/2016 7:58:00 PM _readLogr(): Reading videologr data from 2016278datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1475608560 & 1475612280 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1475612290, self->{EndEsecs} = 1475612280 _readLogr(): #LOGHOST_SYSTEM_UTC = 371 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1475608570 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1475612280 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1475608570 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1475612280 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1475608440, Refined EndEsecs = 1475612400 _readLogr(): Reading videologr data from 2016278datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1475608440 & 1475612400 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1475612410, self->{EndEsecs} = 1475612400 _readLogr(): #LOGHOST_SYSTEM_UTC = 395 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1475608450 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1475612400 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 = 278 (looping to 278) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016278vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016278vnta.txt esecs, east, north, lat, lon, head, pres 1475610261, 0, 0, 36.751845, -121.838705, 114.0, 30.01 1475610762, 0, 0, 36.749987, -121.839008, 298.2, 7.61 rovNav(): 278: 1311 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016278vnta.txt 1311 in tList 1311 in {self->{rovT}} 1311 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1475610840 & 1475610915 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ..............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XX rovGDCListRefs(): dMedian = 28.0839881072419 rovGDCListRefs(): dStats->sample_range = 27.4037969402592 rovGDCListRefs(): returning (1st elements): 1475609761 36.751790 -121.838560 0.1 2.68003202461418 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 278 (looping to 278) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016278vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016278vntaedited.txt esecs, east, north, lat, lon, head, pres 1475610261, 0, 0, 36.751834, -121.838707, 114.0, 30.01 1475610762, 0, 0, 36.751747, -121.838889, 298.2, 7.61 rovNav(): 278: 1311 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016278vntaedited.txt 1311 in tList 1311 in {self->{rovT}} 1311 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1475610840 & 1475610915 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXX......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XX rovGDCListRefs(): dMedian = 28.0839881358118 rovGDCListRefs(): dStats->sample_range = 27.4037969484373 rovGDCListRefs(): returning (1st elements): 1475609761 36.751847 -121.838577 0.1 2.68003201110158 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.75180600 hash{lon} = -121.83878000 latMin = 30.751806 latMax = 42.751806 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.751806 AND 42.751806 AND Longitude BETWEEN -127.83878 AND -115.83878 Dive lat = 36.75180600, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.83878000 & lat = 36.75180600 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3963rov_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/vnta3963lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNavframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/278/accesses.txt at bin\geopdvc.pl line 2382 (): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 395 shipNav(): $#{$self->{shipLat}}[last] = 36.780115 Position subsampling set to 2 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.4320 0.3656 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.8740 0.0000 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.6348 0.3652 0.0000 makeTracks(): n = 1312 makeTracks(): Looping through 395 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3963tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2016/vnta/vnta3963mergedCtdNav.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/4/2016 7:36:00 PM' AND '10/4/2016 7:58:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '10/4/2016 7:36:00 PM' AND '10/4/2016 7:58:00 PM') _findIndex(): Looking for epoch secs 1475609852 ... Found time 1475609852 after 6 iterations. >>> p = 91: annotations(): 91, 1475609852, 10/04/16 19:37:32, V3963-01HD, 00:00:23:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_00_23_18.jpg, 7.32999992370605, descend, ventana, u, 20063828 _findIndex(): Looking for epoch secs 1475610034 ... Found time 1475610037 after 6 iterations. >>> p = 275: annotations(): 275, 1475610034, 10/04/16 19:40:34, V3963-01HD, 00:03:26:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_03_26_00.jpg, 28.2199993133545, descend, ventana, u, 20063829 _findIndex(): Looking for epoch secs 1475610046 ... Found time 1475610048 after 4 iterations. >>> p = 286: annotations(): 286, 1475610046, 10/04/16 19:40:46, V3963-01HD, 00:03:37:20, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_03_37_20.jpg, 28.3099994659424, descend, ventana, u, 20063830 _findIndex(): Looking for epoch secs 1475610060 ... Found time 1475610058 after 6 iterations. >>> p = 296: annotations(): 296, 1475610060, 10/04/16 19:41:00, V3963-01HD, 00:03:51:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_03_51_09.jpg, 27.9500007629395, descend, ventana, u, 20063831 _findIndex(): Looking for epoch secs 1475610066 ... Found time 1475610068 after 5 iterations. >>> p = 306: annotations(): 306, 1475610066, 10/04/16 19:41:06, V3963-01HD, 00:03:57:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_03_57_26.jpg, 28.0200004577637, descend, ventana, u, 20063832 _findIndex(): Looking for epoch secs 1475610072 ... Found time 1475610068 after 5 iterations. >>> p = 306: annotations(): 306, 1475610072, 10/04/16 19:41:12, V3963-01HD, 00:04:03:13, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_04_03_13.jpg, 28.1200008392334, descend, ventana, u, 20063833 _findIndex(): Looking for epoch secs 1475610204 ... Found time 1475610201 after 6 iterations. >>> p = 439: annotations(): 439, 1475610204, 10/04/16 19:43:24, V3963-01HD, 00:06:14:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3963/00_06_14_17.jpg, 29.7299995422363, descend, ventana, u, 20063834 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3963anno10.wrl bin\geopdvc.pl: Calling placeAframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/278/accesses.txt at bin\geopdvc.pl line 625 nnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 30.1 db", "T: 11.642 C", "S: 33.604", "O: 3.929 ml/l", "L: 81.91 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 30 db", "T: 11.651 C", "S: 33.603", "O: 3.935 ml/l", "L: 82.02 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 30 db", "T: 11.654 C", "S: 33.601", "O: 3.951 ml/l", "L: 82.04 %"] inline_name = vnta3963anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 9 db", "T: 13.361 C", "S: 33.556", "O: 6.669 ml/l", "L: 73.62 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 30.1 db", "T: 11.642 C", "S: 33.604", "O: 3.929 ml/l", "L: 81.91 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 30.2 db", "T: 11.644 C", "S: 33.602", "O: 3.938 ml/l", "L: 81.87 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 30 db", "T: 11.651 C", "S: 33.603", "O: 3.935 ml/l", "L: 82.02 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 30 db", "T: 11.654 C", "S: 33.601", "O: 3.951 ml/l", "L: 82.04 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 30 db", "T: 11.654 C", "S: 33.601", "O: 3.951 ml/l", "L: 82.04 %"] placeAnnotations(): Placing annotation 7: Ancillary data = ["", "P: 31.7 db", "T: 11.49 C", "S: 33.612", "O: 3.687 ml/l", "L: 80.92 %"] inline_name = vnta3963anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/vnta3963... frameGrabs(): matched 00_00_23_18 in [IMG]00_00_23_18.jpg10-Oct-2016 16:15 262K frameGrabs(): matched 00_03_26_00 in [IMG]00_03_26_00.jpg10-Oct-2016 16:15 325K frameGrabs(): matched 00_03_37_20 in [IMG]00_03_37_20.jpg10-Oct-2016 16:15 305K frameGrabs(): matched 00_03_51_09 in [IMG]00_03_51_09.jpg10-Oct-2016 16:15 300K frameGrabs(): matched 00_03_57_26 in [IMG]00_03_57_26.jpg10-Oct-2016 16:15 284K frameGrabs(): matched 00_04_03_13 in [IMG]00_04_03_13.jpg10-Oct-2016 16:15 284K frameGrabs(): matched 00_06_14_17 in [IMG]00_06_14_17.jpg10-Oct-2016 16:15 331K iList = 00_00_23_18.jpg 00_03_26_00.jpg 00_03_37_20.jpg 00_03_51_09.jpg 00_03_57_26.jpg 00_04_03_13.jpg 00_06_14_17.jpg _findIndex(): Looking for epoch secs 1475609852 ... Found time 1475609852 after 6 iterations. >>> p = 91: frameGrabs(): index = 91 EXPD::frameGrabs(): 91: Time=1475609850, Depth=8.93, Lat= 36.751716, Lon=-121.838703, Pres=9, Temp=13.361, Sal=33.556, O2=6.669, Light=73.62 _findIndex(): Looking for epoch secs 1475610034 ... Found time 1475610037 after 6 iterations. >>> p = 275: frameGrabs(): index = 275 EXPD::frameGrabs(): 275: Time=1475610030, Depth=29.88, Lat= 36.751630, Lon=-121.838811, Pres=30.1, Temp=11.642, Sal=33.604, O2=3.929, Light=81.91 _findIndex(): Looking for epoch secs 1475610046 ... Found time 1475610048 after 4 iterations. >>> p = 286: frameGrabs(): index = 286 EXPD::frameGrabs(): 286: Time=1475610045, Depth=29.98, Lat=36.751663, Lon=-121.838797, Pres=30.2, Temp=11.644, Sal=33.602, O2=3.938, Light=81.87 _findIndex(): Looking for epoch secs 1475610060 ... Found time 1475610058 after 6 iterations. >>> p = 296: frameGrabs(): index = 296 EXPD::frameGrabs(): 296: Time=1475610060, Depth=29.78, Lat=36.751696, Lon=-121.838787, Pres=30, Temp=11.651, Sal=33.603, O2=3.935, Light=82.02 _findIndex(): Looking for epoch secs 1475610066 ... Found time 1475610068 after 5 iterations. >>> p = 306: frameGrabs(): index = 306 EXPD::frameGrabs(): 306: Time=1475610075, Depth=29.78, Lat=36.751735, Lon=-121.838778, Pres=30, Temp=11.654, Sal=33.601, O2=3.951, Light=82.04 _findIndex(): Looking for epoch secs 1475610072 ... Found time 1475610068 after 5 iterations. >>> p = 306: frameGrabs(): index = 306 EXPD::frameGrabs(): 306: Time=1475610075, Depth=29.78, Lat=36.751735, Lon=-121.838778, Pres=30, Temp=11.654, Sal=33.601, O2=3.951, Light=82.04 _findIndex(): Looking for epoch secs 1475610204 ... Found time 1475610201 after 6 iterations. >>> p = 439: frameGrabs(): index = 439 EXPD::frameGrabs(): 439: Time=1475610195, Depth=31.46, Lat= 36.751853, Lon=-121.838716, Pres=31.7, Temp=11.49, Sal=33.612, O2=3.687, Light=80.92 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 7 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_00_23_18.jpg 1475609852 fw= 0.0 cm XplaceImages(): indx = 91, geoPosition = 36.751716, -121.838703, -7.32533719970934, rovDepth = 7.32533719970934 inline_name = vnta3963fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_00_23_18.jpg 1475609852 fw= 0.0 cm XplaceImages(): indx = 91, geoPosition = 36.751716, -121.838703, -7.32533719970934, rovDepth = 7.32533719970934 placeImages(): Placing image 2 00_03_37_20.jpg 1475610046 fw= 0.0 cm XplaceImages(): indx = 286, geoPosition = 36.751663, -121.838797, -28.2080475569757, rovDepth = 28.2080475569757 placeImages(): Placing image 4 00_03_57_26.jpg 1475610066 fw= 0.0 cm XplaceImages(): indx = 306, geoPosition = 36.751735, -121.838778, -27.9797783945788, rovDepth = 27.9797783945788 placeImages(): Placing image 6 00_06_14_17.jpg 1475610204 fw= 0.0 cm XplaceImages(): indx = 439, geoPosition = 36.751853, -121.838716, -29.666977557419, rovDepth = 29.666977557419 inline_name = vnta3963fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_00_23_18.jpg 1475609852 fw= 0.0 cm XplaceImages(): indx = 91, geoPosition = 36.751716, -121.838703, -7.32533719970934, rovDepth = 7.32533719970934 placeImages(): Placing image 1 00_03_26_00.jpg 1475610034 fw= 0.0 cm .placeImages(): indx = 275, geoPosition = 36.751630, -121.838811, -28.3370691261119, rovDepth = 28.3370691261119 placeImages(): Placing image 2 00_03_37_20.jpg 1475610046 fw= 0.0 cm .placeImages(): indx = 286, geoPosition = 36.751663, -121.838797, -28.2080475569757, rovDepth = 28.2080475569757 placeImages(): Placing image 3 00_03_51_09.jpg 1475610060 fw= 0.0 cm .placeImages(): indx = 296, geoPosition = 36.751696, -121.838787, -28.0492516871293, rovDepth = 28.0492516871293 placeImages(): Placing image 4 00_03_57_26.jpg 1475610066 fw= 0.0 cm .placeImages(): indx = 306, geoPosition = 36.751735, -121.838778, -27.9797783945788, rovDepth = 27.9797783945788 placeImages(): Placing image 5 00_04_03_13.jpg 1475610072 fw= 0.0 cm .placeImages(): indx = 306, geoPosition = 36.751735, -121.838778, -27.9797783945788, rovDepth = 27.9797783945788 placeImages(): Placing image 6 00_06_14_17.jpg 1475610204 fw= 0.0 cm .placeImages(): indx = 439, geoPosition = 36.751853, -121.838716, -29.666977557419, rovDepth = 29.666977557419 inline_name = vnta3963fg01.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 '10/4/2016 7:36:00 PM' AND '10/4/2016 7:58:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2016/vnta/vnta3963.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5641,'/3Dreplay/3D.asp?dives=vnta3963','vnta3963','','','',3963 Executing sql: UPDATE Dive SET DiveLatMid = 36.7518, DiveLonMid = -121.8388, DiveDepthMid = -28.1 WHERE RovName = 'vnta' AND DiveNumber = 3963