Running bin\geopdvc.pl at Fri May 13 03:01:28 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3909 bin\geopdvc.pl: Getting Expedition object for diveno = 3909 and rname = vnta set_expd(): rov = vnta, diveno = 3909 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 = 3909 AND RovName = 'vnta' RS->Fields(0)->Value= 5515 RS->Fields(1)->Value= 1458074248 RS->Fields(2)->Value= 1458076493 RS->Fields(3)->Value= 3/15/2016 8:37:28 PM RS->Fields(4)->Value= 3/15/2016 9:14:53 PM set_expd(): $self->{EndDDD} = 075 set_expd(): $self->{StartEsecs} = 1458073048 set_expd(): $self->{EndEsecs} = 1458077693 set_expd(): $self->{EndDtg} = 3/15/2016 9:14:53 PM _readLogr(): Reading videologr data from 2016075datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1458073048 & 1458077693 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1458077700, self->{EndEsecs} = 1458077693 _readLogr(): #LOGHOST_SYSTEM_UTC = 464 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1458073049 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1458077690 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1458073049 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1458077690 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1458072928, Refined EndEsecs = 1458077813 _readLogr(): Reading videologr data from 2016075datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1458072928 & 1458077813 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1458077820, self->{EndEsecs} = 1458077813 _readLogr(): #LOGHOST_SYSTEM_UTC = 488 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1458072929 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1458077810 bin\geopdvc.pl: expdid = 5515 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 = 075 (looping to 075) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016075vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016075vnta.txt esecs, east, north, lat, lon, head, pres 1458074359, 0, 0, 36.749130, -122.107598, 85.9, 35.80 1458074860, 0, 0, 36.748543, -122.107142, 41.1, 206.88 1458075361, 0, 0, 36.749082, -122.106722, 92.2, 231.28 1458075862, 0, 0, 36.748843, -122.108347, 269.6, 149.78 1458076363, 0, 0, 36.748323, -122.107953, 323.2, 11.49 rovNav(): 075: 2241 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016075vnta.txt 2241 in tList 2241 in {self->{rovT}} 2241 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1458075705 & 1458075285 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 153.052708470754 rovGDCListRefs(): dStats->sample_range = 237.499505300626 rovGDCListRefs(): returning (1st elements): 1458074249 36.695650 -122.045203 32.4 2.58078502158796 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 075 (looping to 075) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016075vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016075vntaedited.txt esecs, east, north, lat, lon, head, pres 1458074359, 0, 0, 36.748444, -122.108141, 85.9, 35.80 1458074860, 0, 0, 36.748551, -122.107121, 41.1, 206.88 1458075361, 0, 0, 36.749091, -122.106728, 92.2, 231.28 1458075862, 0, 0, 36.748846, -122.108326, 269.6, 149.78 1458076363, 0, 0, 36.748307, -122.107954, 323.2, 11.49 rovNav(): 075: 2241 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016075vntaedited.txt 2241 in tList 2241 in {self->{rovT}} 2241 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1458075705 & 1458075285 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXX......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/075/accesses.txt at bin\geopdvc.pl line 2382 ............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 153.052708430156 rovGDCListRefs(): dStats->sample_range = 237.499516940441 rovGDCListRefs(): returning (1st elements): 1458074249 36.748396 -122.108864 32.4 2.58077298426867 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.74873150 hash{lon} = -122.10778750 latMin = 30.7487315 latMax = 42.7487315 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.7487315 AND 42.7487315 AND Longitude BETWEEN -128.1077875 AND -116.1077875 Dive lat = 36.74873150, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.10778750 & lat = 36.74873150 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3909rov_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/vnta3909lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 488 shipNav(): $#{$self->{shipLat}}[last] = 36.749185 Position subsampling set to 2 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 = 2242 makeTracks(): Looping through 488 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3909tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2016/vnta/vnta3909mergedCtdNav.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 '3/15/2016 8:37:28 PM' AND '3/15/2016 9:14:53 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '3/15/2016 8:37:28 PM' AND '3/15/2016 9:14:53 PM') _findIndex(): Looking for epoch secs 1458074286 ... Found time 1458074284 after 5 iterations. >>> p = 35: annotations(): 35, 1458074286, 03/15/16 20:38:06, V3909-01HD, 00:01:49:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_01_49_27.jpg, 10.4899997711182, launch, robs, u, 20060979 _findIndex(): Looking for epoch secs 1458075120 ... Found time 1458075116 after 7 iterations. >>> p = 866: annotations(): 866, 1458075120, 03/15/16 20:52:00, V3909-01HD, 00:15:43:16, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_15_43_16.jpg, 239.539993286133, cruise, robs, u, 20060983 _findIndex(): Looking for epoch secs 1458075178 ... Found time 1458075178 after 6 iterations. >>> p = 927: annotations(): 927, 1458075178, 03/15/16 20:52:58, V3909-01HD, 00:16:40:17, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_16_40_17.jpg, 238.369995117188, cruise, robs, u, 20060984 _findIndex(): Looking for epoch secs 1458075212 ... Found time 1458075213 after 6 iterations. >>> p = 962: annotations(): 962, 1458075212, 03/15/16 20:53:32, V3909-01HD, 00:17:15:02, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_17_15_02.jpg, 237.039993286133, cruise, robs, u, 20060985 _findIndex(): Looking for epoch secs 1458075236 ... Found time 1458075231 after 3 iterations. >>> p = 980: annotations(): 980, 1458075236, 03/15/16 20:53:56, V3909-01HD, 00:17:38:17, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_17_38_17.jpg, 237.490005493164, cruise, robs, u, 20060986 _findIndex(): Looking for epoch secs 1458075288 ... Found time 1458075283 after 6 iterations. >>> p = 1032: annotations(): 1032, 1458075288, 03/15/16 20:54:48, V3909-01HD, 00:18:30:17, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_18_30_17.jpg, 237.690002441406, cruise, robs, u, 20060987 _findIndex(): Looking for epoch secs 1458075355 ... Found time 1458075353 after 6 iterations. >>> p = 1102: annotations(): 1102, 1458075355, 03/15/16 20:55:55, V3909-01HD, 00:19:38:01, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3909/00_19_38_01.jpg, 230.300003051758, ascend, robs, u, 20060988 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3909anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 243.3 db", "T: 8.008 C", "S: 34.051", "O: 2.088 ml/l", "L: 86 %"] placeAnnotations(): Placing annotation 4: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 240.6 db", "T: 8.024 C", "S: 34.05", "O: 2.089 ml/l", "L: 86.05 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 241.4 db", "T: 8.018 C", "S: 34.048", "O: 2.097 ml/l", "L: 86.04 %"] inline_name = vnta3909anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 12.3 db", "T: 13.621 C", "S: 33.046", "O: 6.717 ml/l", "L: 83.06 %"] placeAnnotations(): Placing annotation 2: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 243.3 db", "T: 8.008 C", "S: 34.051", "O: 2.088 ml/l", "L: 86 %"] placeAnnotations(): Placing annotation 3: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 242.1 db", "T: 8.024 C", "S: 34.051", "O: 2.074 ml/l", "L: 86 %"] placeAnnotations(): Placing annotation 4: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 240.6 db", "T: 8.024 C", "S: 34.05", "O: 2.089 ml/l", "L: 86.05 %"] placeAnnotations(): Placing annotation 5: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 240.9 db", "T: 8.025 C", "S: 34.05", "O: 2.091 ml/l", "L: 86.04 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 241.4 db", "T: 8.018 C", "S: 34.048", "O: 2.097 ml/frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/075/accesses.txt at bin\geopdvc.pl line 625 l", "L: 86.04 %"] placeAnnotations(): Placing annotation 7: Ancillary data = ["", "P: 233.6 db", "T: 8.042 C", "S: 34.049", "O: 2.105 ml/l", "L: 85.98 %"] inline_name = vnta3909anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/vnta3909... frameGrabs(): matched 00_01_49_27 in [IMG]00_01_49_27.jpg24-Mar-2016 00:10 269K frameGrabs(): matched 00_15_43_16 in [IMG]00_15_43_16.jpg24-Mar-2016 00:10 458K frameGrabs(): matched 00_16_40_17 in [IMG]00_16_40_17.jpg24-Mar-2016 00:10 394K frameGrabs(): matched 00_17_15_02 in [IMG]00_17_15_02.jpg24-Mar-2016 00:10 467K frameGrabs(): matched 00_17_38_17 in [IMG]00_17_38_17.jpg24-Mar-2016 00:10 547K frameGrabs(): matched 00_18_30_17 in [IMG]00_18_30_17.jpg24-Mar-2016 00:10 532K frameGrabs(): matched 00_19_38_01 in [IMG]00_19_38_01.jpg24-Mar-2016 00:10 489K iList = 00_01_49_27.jpg 00_15_43_16.jpg 00_16_40_17.jpg 00_17_15_02.jpg 00_17_38_17.jpg 00_18_30_17.jpg 00_19_38_01.jpg _findIndex(): Looking for epoch secs 1458074286 ... Found time 1458074284 after 5 iterations. >>> p = 35: frameGrabs(): index = 35 EXPD::frameGrabs(): 35: Time=1458074280, Depth=12.21, Lat= 36.748391, Lon=-122.108859, Pres=12.3, Temp=13.621, Sal=33.046, O2=6.717, Light=83.06 _findIndex(): Looking for epoch secs 1458075120 ... Found time 1458075116 after 7 iterations. >>> p = 866: frameGrabs(): index = 866 EXPD::frameGrabs(): 866: Time=1458075120, Depth=241.36, Lat=36.749034, Lon=-122.106660, Pres=243.3, Temp=8.008, Sal=34.051, O2=2.088, Light=86 _findIndex(): Looking for epoch secs 1458075178 ... Found time 1458075178 after 6 iterations. >>> p = 927: frameGrabs(): index = 927 EXPD::frameGrabs(): 927: Time=1458075180, Depth=240.17, Lat= 36.749110, Lon=-122.106603, Pres=242.1, Temp=8.024, Sal=34.051, O2=2.074, Light=86 _findIndex(): Looking for epoch secs 1458075212 ... Found time 1458075213 after 6 iterations. >>> p = 962: frameGrabs(): index = 962 EXPD::frameGrabs(): 962: Time=1458075210, Depth=238.69, Lat=36.749119, Lon=-122.106567, Pres=240.6, Temp=8.024, Sal=34.05, O2=2.089, Light=86.05 _findIndex(): Looking for epoch secs 1458075236 ... Found time 1458075231 after 3 iterations. >>> p = 980: frameGrabs(): index = 980 EXPD::frameGrabs(): 980: Time=1458075225, Depth=238.98, Lat=36.749125, Lon=-122.106555, Pres=240.9, Temp=8.025, Sal=34.05, O2=2.091, Light=86.04 _findIndex(): Looking for epoch secs 1458075288 ... Found time 1458075283 after 6 iterations. >>> p = 1032: frameGrabs(): index = 1032 EXPD::frameGrabs(): 1032: Time=1458075285, Depth=239.48, Lat=36.749108, Lon=-122.106562, Pres=241.4, Temp=8.018, Sal=34.048, O2=2.097, Light=86.04 _findIndex(): Looking for epoch secs 1458075355 ... Found time 1458075353 after 6 iterations. >>> p = 1102: frameGrabs(): index = 1102 EXPD::frameGrabs(): 1102: Time=1458075360, Depth=231.75, Lat=36.749094, Lon=-122.106707, Pres=233.6, Temp=8.042, Sal=34.049, O2=2.105, Light=85.98 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_01_49_27.jpg 1458074286 fw= 0.0 cm XplaceImages(): indx = 35, geoPosition = 36.748391, -122.108859, -10.6603657464442, rovDepth = 10.6603657464442 inline_name = vnta3909fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_01_49_27.jpg 1458074286 fw= 0.0 cm XplaceImages(): indx = 35, geoPosition = 36.748391, -122.108859, -10.6603657464442, rovDepth = 10.6603657464442 placeImages(): Placing image 2 00_16_40_17.jpg 1458075178 fw= 0.0 cm XplaceImages(): indx = 927, geoPosition = 36.749110, -122.106603, -238.365037045493, rovDepth = 238.365037045493 placeImages(): Placing image 4 00_17_38_17.jpg 1458075236 fw= 0.0 cm XplaceImages(): indx = 980, geoPosition = 36.749125, -122.106555, -237.809823500845, rovDepth = 237.809823500845 placeImages(): Placing image 6 00_19_38_01.jpg 1458075355 fw= 0.0 cm XplaceImages(): indx = 1102, geoPosition = 36.749094, -122.106707, -230.720808344714, rovDepth = 230.720808344714 inline_name = vnta3909fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_01_49_27.jpg 1458074286 fw= 0.0 cm XplaceImages(): indx = 35, geoPosition = 36.748391, -122.108859, -10.6603657464442, rovDepth = 10.6603657464442 placeImages(): Placing image 1 00_15_43_16.jpg 1458075120 fw= 0.0 cm XplaceImages(): indx = 866, geoPosition = 36.749034, -122.106660, -239.713407361412, rovDepth = 239.713407361412 placeImages(): Placing image 2 00_16_40_17.jpg 1458075178 fw= 0.0 cm XplaceImages(): indx = 927, geoPosition = 36.749110, -122.106603, -238.365037045493, rovDepth = 238.365037045493 placeImages(): Placing image 3 00_17_15_02.jpg 1458075212 fw= 0.0 cm .placeImages(): indx = 962, geoPosition = 36.749119, -122.106567, -237.076146689527, rovDepth = 237.076146689527 placeImages(): Placing image 4 00_17_38_17.jpg 1458075236 fw= 0.0 cm .placeImages(): indx = 980, geoPosition = 36.749125, -122.106555, -237.809823500845, rovDepth = 237.809823500845 placeImages(): Placing image 5 00_18_30_17.jpg 1458075288 fw= 0.0 cm .placeImages(): indx = 1032, geoPosition = 36.749108, -122.106562, -237.829652928306, rovDepth = 237.829652928306 placeImages(): Placing image 6 00_19_38_01.jpg 1458075355 fw= 0.0 cm .placeImages(): indx = 1102, geoPosition = 36.749094, -122.106707, -230.720808344714, rovDepth = 230.720808344714 inline_name = vnta3909fg01.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 '3/15/2016 8:37:28 PM' AND '3/15/2016 9:14:53 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2016/vnta/vnta3909.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5515,'/3Dreplay/3D.asp?dives=vnta3909','vnta3909','','','',3909 Executing sql: UPDATE Dive SET DiveLatMid = 36.7487, DiveLonMid = -122.1078, DiveDepthMid = -153.1 WHERE RovName = 'vnta' AND DiveNumber = 3909