Running bin\geopdvc.pl at Sun Aug 23 03:00:18 2015 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3839 bin\geopdvc.pl: Getting Expedition object for diveno = 3839 and rname = vnta set_expd(): rov = vnta, diveno = 3839 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 = 3839 AND RovName = 'vnta' RS->Fields(0)->Value= 5295 RS->Fields(1)->Value= 1435260300 RS->Fields(2)->Value= 1435266660 RS->Fields(3)->Value= 6/25/2015 7:25:00 PM RS->Fields(4)->Value= 6/25/2015 9:11:00 PM set_expd(): $self->{EndDDD} = 176 set_expd(): $self->{StartEsecs} = 1435259100 set_expd(): $self->{EndEsecs} = 1435267860 set_expd(): $self->{EndDtg} = 6/25/2015 9:11:00 PM _readLogr(): Reading videologr data from 2015176datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1435259100 & 1435267860 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1435267868, self->{EndEsecs} = 1435267860 _readLogr(): #LOGHOST_SYSTEM_UTC = 875 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1435259106 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1435267858 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1435259106 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1435267858 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1435258980, Refined EndEsecs = 1435267980 _readLogr(): Reading videologr data from 2015176datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1435258980 & 1435267980 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1435267988, self->{EndEsecs} = 1435267980 _readLogr(): #LOGHOST_SYSTEM_UTC = 899 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1435258986 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1435267978 bin\geopdvc.pl: expdid = 5295 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 = 176 (looping to 176) Does file exist?: \\atlas\RovNavEdit\2015\vnta\nav2015176vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2015\vnta\nav2015176vnta.txt esecs, east, north, lat, lon, head, pres 1435260870, 0, 0, 36.699910, -122.048203, 336.4, 101.15 1435261371, 0, 0, 36.702255, -122.049263, 336.6, 101.13 1435261872, 0, 0, 36.702422, -122.049353, 145.5, 130.44 1435262373, 0, 0, 36.700725, -122.047783, 145.9, 201.85 1435262874, 0, 0, 36.699798, -122.046800, 349.1, 287.18 1435263374, 0, 0, 36.702113, -122.047790, 349.0, 302.38 1435263875, 0, 0, 36.702895, -122.047867, 73.2, 303.09 1435264376, 0, 0, 36.702487, -122.047673, 157.6, 300.71 1435264877, 0, 0, 36.700727, -122.046968, 110.0, 301.23 1435265378, 0, 0, 36.700440, -122.046353, 259.6, 279.67 1435265879, 0, 0, 36.700443, -122.047658, 260.4, 108.08 rovNav(): 176: 5762 in tList after reading from \\atlas\RovNavEdit\2015\vnta\nav2015176vnta.txt 5762 in tList 5762 in {self->{rovT}} 5762 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are & rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): rovGDCListRefs(): dMedian = 209.235034289338 rovGDCListRefs(): dStats->sample_range = 302.816616075239 rovGDCListRefs(): returning (1st elements): 1435260494 36.691693 -122.037308 234.3 2.50137761536509 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 176 (looping to 176) Does file exist?: \\atlas\RovNavEdit\2015\vnta\nav2015176vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2015\vnta\nav2frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/176/accesses.txt at bin\geopdvc.pl line 2382 015176vntaedited.txt esecs, east, north, lat, lon, head, pres 1435260870, 0, 0, 36.699910, -122.048203, 336.4, 101.15 1435261371, 0, 0, 36.702255, -122.049263, 336.6, 101.13 1435261872, 0, 0, 36.702422, -122.049353, 145.5, 130.44 1435262373, 0, 0, 36.700725, -122.047783, 145.9, 201.85 1435262874, 0, 0, 36.699798, -122.046800, 349.1, 287.18 1435263374, 0, 0, 36.702113, -122.047790, 349.0, 302.38 1435263875, 0, 0, 36.702895, -122.047867, 73.2, 303.09 1435264376, 0, 0, 36.702487, -122.047673, 157.6, 300.71 1435264877, 0, 0, 36.700727, -122.046968, 110.0, 301.23 1435265378, 0, 0, 36.700440, -122.046353, 259.6, 279.67 1435265879, 0, 0, 36.700443, -122.047658, 260.4, 108.08 rovNav(): 176: 5762 in tList after reading from \\atlas\RovNavEdit\2015\vnta\nav2015176vntaedited.txt 5762 in tList 5762 in {self->{rovT}} 5762 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are & rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): rovGDCListRefs(): dMedian = 209.235034289338 rovGDCListRefs(): dStats->sample_range = 302.816616075239 rovGDCListRefs(): returning (1st elements): 1435260494 36.691693 -122.037308 234.3 2.50137761536509 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.70091500 hash{lon} = -122.04771300 latMin = 30.700915 latMax = 42.700915 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.700915 AND 42.700915 AND Longitude BETWEEN -128.047713 AND -116.047713 Dive lat = 36.70091500, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.04771300 & lat = 36.70091500 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3839rov_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 = geo2015/vnta/vnta3839lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 899 shipNav(): $#{$self->{shipLat}}[last] = 36.688673 Position subsampling set to 5 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 = 5763 makeTracks(): Looping through 899 point of ship position data... makeTracks(): posSubsample = 5 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3839tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2015/vnta/vnta3839mergedCtdNav.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 = '') AframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/176/accesses.txt at bin\geopdvc.pl line 625 ND (RecordedDate BETWEEN '6/25/2015 7:25:00 PM' AND '6/25/2015 9:11:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '6/25/2015 7:25:00 PM' AND '6/25/2015 9:11:00 PM') bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/vnta3839... frameGrabs(): matched 00_00_37_06 in [IMG]00_00_37_06.jpg14-Jul-2015 01:40 178K frameGrabs(): matched 00_07_38_25 in [IMG]00_07_38_25.jpg14-Jul-2015 01:40 263K frameGrabs(): matched 00_17_39_08 in [IMG]00_17_39_08.jpg14-Jul-2015 01:41 262K frameGrabs(): matched 00_23_04_10 in [IMG]00_23_04_10.jpg14-Jul-2015 01:41 323K frameGrabs(): matched 00_38_28_19 in [IMG]00_38_28_19.jpg14-Jul-2015 01:41 249K frameGrabs(): matched 00_43_02_02 in [IMG]00_43_02_02.jpg14-Jul-2015 01:41 253K frameGrabs(): matched 00_53_01_27 in [IMG]00_53_01_27.jpg14-Jul-2015 01:41 254K frameGrabs(): matched 00_59_45_22 in [IMG]00_59_45_22.jpg14-Jul-2015 01:41 330K frameGrabs(): matched 01_02_19_13 in [IMG]01_02_19_13.jpg14-Jul-2015 01:41 302K frameGrabs(): matched 01_20_08_07 in [IMG]01_20_08_07.jpg14-Jul-2015 01:41 276K iList = 00_00_37_06.jpg 00_07_38_25.jpg 00_17_39_08.jpg 00_23_04_10.jpg 00_38_28_19.jpg 00_43_02_02.jpg 00_53_01_27.jpg 00_59_45_22.jpg 01_02_19_13.jpg 01_20_08_07.jpg _findIndex(): Looking for epoch secs 1435260404 ... *** Warning: Position time is 90 seconds away from search time. *** *** search time of 1435260404 precedes start of ROV nav: 1435260494 *** >>> p = 0: frameGrabs(): index = 0 EXPD::frameGrabs(): 0: Time=, Depth=, Lat=36.691693, Lon=-122.037308, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435260826 ... Found time 1435260831 after 7 iterations. >>> p = 337: frameGrabs(): index = 337 EXPD::frameGrabs(): 337: Time=, Depth=, Lat= 36.699763, Lon=-122.048128, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435261426 ... Found time 1435261428 after 8 iterations. >>> p = 933: frameGrabs(): index = 933 EXPD::frameGrabs(): 933: Time=, Depth=, Lat= 36.702172, Lon=-122.049320, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435261751 ... Found time 1435261756 after 4 iterations. >>> p = 1260: frameGrabs(): index = 1260 EXPD::frameGrabs(): 1260: Time=, Depth=, Lat=36.702422, Lon=-122.049353, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435262675 ... Found time 1435262679 after 7 iterations. >>> p = 2182: frameGrabs(): index = 2182 EXPD::frameGrabs(): 2182: Time=, Depth=, Lat= 36.699575, Lon=-122.046635, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435262975 ... Found time 1435262973 after 6 iterations. >>> p = 2475: frameGrabs(): index = 2475 EXPD::frameGrabs(): 2475: Time=, Depth=, Lat=36.699980, Lon=-122.046918, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435263575 ... Found time 1435263571 after 8 iterations. >>> p = 3072: frameGrabs(): index = 3072 EXPD::frameGrabs(): 3072: Time=, Depth=, Lat= 36.703242, Lon=-122.048078, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435263979 ... Found time 1435263976 after 8 iterations. >>> p = 3477: frameGrabs(): index = 3477 EXPD::frameGrabs(): 3477: Time=, Depth=, Lat= 36.702935, Lon=-122.047812, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435264132 ... Found time 1435264134 after 8 iterations. >>> p = 3634: frameGrabs(): index = 3634 EXPD::frameGrabs(): 3634: Time=, Depth=, Lat= 36.702883, Lon=-122.047865, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1435265201 ... Found time 1435265204 after 7 iterations. >>> p = 4703: frameGrabs(): index = 4703 EXPD::frameGrabs(): 4703: Time=, Depth=, Lat= 36.700505, Lon=-122.045845, Pres=, Temp=, Sal=, O2=, Light= bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 10 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_00_37_06.jpg 1435260404 fw= 0.0 cm placeImages(): indx = 0, geoPosition = 36.691693, -122.037308, -2.50137761536509, rovDepth = 2.50137761536509 inline_name = vnta3839fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_00_37_06.jpg 1435260404 fw= 0.0 cm placeImages(): indx = 0, geoPosition = 36.691693, -122.037308, -2.50137761536509, rovDepth = 2.50137761536509 placeImages(): Placing image 2 00_17_39_08.jpg 1435261426 fw= 0.0 cm placeImages(): indx = 933, geoPosition = 36.702172, -122.049320, -100.358425850931, rovDepth = 100.358425850931 placeImages(): Placing image 4 00_38_28_19.jpg 1435262675 fw= 0.0 cm placeImages(): indx = 2182, geoPosition = 36.699575, -122.046635, -200.191489631814, rovDepth = 200.191489631814 placeImages(): Placing image 6 00_53_01_27.jpg 1435263575 fw= 0.0 cm placeImages(): indx = 3072, geoPosition = 36.703242, -122.048078, -299.836877704985, rovDepth = 299.836877704985 placeImages(): Placing image 8 01_02_19_13.jpg 1435264132 fw= 0.0 cm placeImages(): indx = 3634, geoPosition = 36.702883, -122.047865, -299.708036783762, rovDepth = 299.708036783762 inline_name = vnta3839fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_00_37_06.jpg 1435260404 fw= 0.0 cm placeImages(): indx = 0, geoPosition = 36.691693, -122.037308, -2.50137761536509, rovDepth = 2.50137761536509 placeImages(): Placing image 1 00_07_38_25.jpg 1435260826 fw= 0.0 cm placeImages(): indx = 337, geoPosition = 36.699763, -122.048128, -100.487423572961, rovDepth = 100.487423572961 placeImages(): Placing image 2 00_17_39_08.jpg 1435261426 fw= 0.0 cm placeImages(): indx = 933, geoPosition = 36.702172, -122.049320, -100.358425850931, rovDepth = 100.358425850931 placeImages(): Placing image 3 00_23_04_10.jpg 1435261751 fw= 0.0 cm placeImages(): indx = 1260, geoPosition = 36.702422, -122.049353, -96.6279963249122, rovDepth = 96.6279963249122 placeImages(): Placing image 4 00_38_28_19.jpg 1435262675 fw= 0.0 cm placeImages(): indx = 2182, geoPosition = 36.699575, -122.046635, -200.191489631814, rovDepth = 200.191489631814 placeImages(): Placing image 5 00_43_02_02.jpg 1435262975 fw= 0.0 cm placeImages(): indx = 2475, geoPosition = 36.699980, -122.046918, -299.886522023833, rovDepth = 299.886522023833 placeImages(): Placing image 6 00_53_01_27.jpg 1435263575 fw= 0.0 cm placeImages(): indx = 3072, geoPosition = 36.703242, -122.048078, -299.836877704985, rovDepth = 299.836877704985 placeImages(): Placing image 7 00_59_45_22.jpg 1435263979 fw= 0.0 cm placeImages(): indx = 3477, geoPosition = 36.702935, -122.047812, -303.583424609444, rovDepth = 303.583424609444 placeImages(): Placing image 8 01_02_19_13.jpg 1435264132 fw= 0.0 cm placeImages(): indx = 3634, geoPosition = 36.702883, -122.047865, -299.708036783762, rovDepth = 299.708036783762 placeImages(): Placing image 9 01_20_08_07.jpg 1435265201 fw= 0.0 cm placeImages(): indx = 4703, geoPosition = 36.700505, -122.045845, -301.918369597147, rovDepth = 301.918369597147 inline_name = vnta3839fg01.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 '6/25/2015 7:25:00 PM' AND '6/25/2015 9:11:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2015/vnta/vnta3839.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5295,'/3Dreplay/3D.asp?dives=vnta3839','vnta3839','','','',3839 Executing sql: UPDATE Dive SET DiveLatMid = 36.7009, DiveLonMid = -122.0477, DiveDepthMid = -209.2 WHERE RovName = 'vnta' AND DiveNumber = 3839