Running .\bin\pdkc.pl at Wed Aug 26 08:57:53 2015 on host ephyra-vm2

Command line:
.\bin\pdkc.pl -rov vnta -dive 1573

.\bin\pdkc.pl: Getting Expedition object for diveno = 1573 and rname = vnta
set_expd(): rov = vnta, diveno = 1573
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 = 1573 AND RovName = 'vnta'

RS->Fields(0)->Value= 1764

RS->Fields(1)->Value= 920911440

RS->Fields(2)->Value= 920930940

RS->Fields(3)->Value= 3/8/1999 4:44:00 PM

RS->Fields(4)->Value= 3/8/1999 10:09:00 PM

set_expd(): $self->{EndDDD} = 067
set_expd(): $self->{StartEsecs} = 920910240
set_expd(): $self->{EndEsecs} = 920932140
set_expd(): $self->{EndDtg} = 3/8/1999 10:09:00 PM
_readLogr(): Reading videologr data from 1999067videologr.dat.gz. 
_readLogr(): Expedition start & stop times are 920910240 & 920932140
_readLogr(): Flushing variable names:
_readLogr(): esecs of file line[0] = 920932142, self->{EndEsecs} = 920932140
_readLogr(): #LOGHOST_SYSTEM_UTC = 2186
_readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 920910249
_readLogr(): $LOGHOST_SYSTEM_UTC[end] = 920932132
set_expd(): Calling _refineStartAndEndEsecs...
refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 920910249
refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 920932132
refineStartAndEndEsecs(): setting StartEsecs to 920910240
refineStartAndEndEsecs(): setting EndEsecs to 920910509
refineStartAndEndEsecs(): numMinBack = 17.5166666666667, numMinForward = 20.2
refineStartAndEndEsecs(): Refined StartEsecs = 920910389, Refined EndEsecs = 920932152 
_readLogr(): Reading videologr data from 1999067videologr.dat.gz. 
_readLogr(): Expedition start & stop times are 920910389 & 920932152
_readLogr(): Flushing variable names:
_readLogr(): esecs of file line[0] = 920932162, self->{EndEsecs} = 920932152
_readLogr(): #LOGHOST_SYSTEM_UTC = 2173
_readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 920910399
_readLogr(): $LOGHOST_SYSTEM_UTC[end] = 920932152
rovnavCTDO(): calcUTM = 0
rovnavCTDO(): getRaw = 1
rovNav(): 1, calcUTM = 0
rovNav(): 2, getRaw = 1
rovNav(): calcUTM = 0
rovNav(): DDD = 067 (looping to 067) 
Does file exist?: \\atlas\RovNavEdit\1999\vnta\nav1999067vntaedited.txt
   yes.
EXPD.pm: editNav flag = 
rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\1999\vnta\nav1999067vnta.txt
esecs, east, north, lat, lon, head, pres
920916888,  591874, 4067925,  36.752632, -121.970760, 354.7,  416.77
920921893,  591873, 4067976,  36.753090, -121.970767, 125.4,  426.13
920926898,  591868, 4068060,  36.753847, -121.970813, 208.8,  438.48
rovNav(): 067: 1898  in tList after reading from \\atlas\RovNavEdit\1999\vnta\nav1999067vnta.txt
1898 in tList
1898 in {self->{rovT}}
1898 in {self->{rovH}}
rovCTDO(): ROV CTDO start & stop times are   920921640 & 920926305
rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.):
........XX..............................................................................XX........................................................................................................................................XX...............................................................................................................................................................X.....................................................................................................................X..................................................................................................................XX................................................................................................X....................................................................................................................................XX.........................................................X........................................................................................................................................XX.......................................X.............................................................................X...........................................................X...........................................................X......................................X.................................................................................................................................................................................X..................XX...................................................................................................X.........................................................XX.................................................................................................XX..................X......................................X.......................................XX................X
rovGDCListRefs(): dMedian = 415.500100584102
rovGDCListRefs(): dStats->sample_range = 450.113921840732
rovGDCListRefs(): returning (1st elements): 920911452  36.791312 -121.991437 102.5 3.89098426032382
rovNav(): 1, doNotRefineStartEnd = 1
rovNav(): 2, calcUTM = 0
rovNav(): calcUTM = 0
rovNav(): DDD = 067 (looping to 067) 
Does file exist?: \\atlas\RovNavEdit\1999\vnta\nav1999067vntaedited.txt
   yes.
EXPD.pm: editNav flag = 
rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\1999\vnta\nav1999067vntaedited.txt
esecs, east, north, lat, lon, head, pres
920916888,       0,       0,  36.752588, -121.970695, 354.7,  416.78
920921893,       0,       0,  36.753110, -121.970711, 125.4,  426.13
920926898,       0,       0,  36.753766, -121.970864, 208.8,  438.48
rovNav(): 067: 1898  in tList after reading from \\atlas\RovNavEdit\1999\vnta\nav1999067vntaedited.txt
1898 in tList
1898 in {self->{rovT}}
1898 in {self->{rovH}}
rovGDCListRefs(): dMedian = 415.500102642223
rovGDCListRefs(): dStats->sample_range = 450.113928340836
rovGDCListRefs(): returning (1st elements): 920911452  36.763685 -121.979941 102.5 3.8909937714891

.\bin\pdkc.pl: -------------------------------------------------------------------------------

Position subsampling set to 1
reportText = ARRAY(0x26213d4)
reportText = 
Building KML for framegrabs
placeKmlImages(): Looking in \\atlas\framegrabs\Ventana\stills\1999\vnta1573 for .jpgs...
placeKmlImages(): imageFile = 00_34_49_09.jpg
placeKmlImages(): isotime = 1999-03-08T17:15:33Z, latitude = 36.7541388888889, longitude = -121.967966666667, altitude = -355.6
placeKmlImages(): imageFile = 04?15?11?22.jpg
placeKmlImages(): imageFile = 03?46?59?13.jpg
placeKmlImages(): imageFile = 04_35_33_06.jpg
placeKmlImages(): isotime = 1999-03-08T21:20:09Z, latitude = 36.7537416666667, longitude = -121.971275, altitude = -447.6
placeKmlImages(): imageFile = 04?48?35?06.jpg
placeKmlImages(): imageFile = 02?00?20?18.jpg
placeKmlImages(): imageFile = 00?26?05?16.jpg
placeKmlImages(): imageFile = 02?51?05?00.jpg
placeKmlImages(): imageFile = 03?19?49?16.jpg
placeKmlImages(): imageFile = 04?39?29?22.jpg
placeKmlImages(): imageFile = 02?19?52?25.jpg
placeKmlImages(): imageFile = 02_27_50_24.jpg
placeKmlImages(): isotime = 1999-03-08T19:09:39Z, latitude = 36.7530472222222, longitude = -121.970486111111, altitude = -410.9
placeKmlImages(): imageFile = 00?49?47?03.jpg
placeKmlImages(): imageFile = 03?27?06?03.jpg
placeKmlImages(): imageFile = 00?59?50?00.jpg
placeKmlImages(): imageFile = 04?45?07?12.jpg
placeKmlImages(): imageFile = 02?06?48?22.jpg
placeKmlImages(): imageFile = 01?17?35?20.jpg
placeKmlImages(): imageFile = 04?43?51?15.jpg
placeKmlImages(): imageFile = 00?56?23?06.jpg
placeKmlImages(): imageFile = 01?40?48?23.jpg
placeKmlImages(): imageFile = 03?01?03?27.jpg
placeKmlImages(): imageFile = 02_30_12_04.jpg
placeKmlImages(): isotime = 1999-03-08T19:12:00Z, latitude = 36.7529472222222, longitude = -121.970336111111, altitude = -398.8
placeKmlImages(): imageFile = 04?08?59?04.jpg
placeKmlImages(): imageFile = 03?07?58?14.jpg
placeKmlImages(): imageFile = 03?17?34?05.jpg
placeKmlImages(): imageFile = 04?01?35?02.jpg
placeKmlImages(): imageFile = 00_33_22_05.jpg
placeKmlImages(): isotimeCannot opendir \\atlas\digitalImages\Ventana\1999\vnta1573: No such file or directory
 = 1999-03-08T17:14:02Z, latitude = 36.7542333333333, longitude = -121.967772222222, altitude = -355.2
placeKmlImages(): imageFile = 01?46?45?13.jpg
placeKmlImages(): imageFile = 03?43?43?19.jpg
placeKmlImages(): imageFile = 01?41?15?07.jpg
placeKmlImages(): imageFile = 03?37?32?15.jpg
placeKmlImages(): imageFile = 02_39_48_10.jpg
placeKmlImages(): isotime = 1999-03-08T19:21:29Z, latitude = 36.7543611111111, longitude = -121.969641666667, altitude = -393.1
placeKmlImages(): imageFile = 03?22?18?12.jpg
placeKmlImages(): imageFile = 04?58?34?03.jpg
placeKmlImages(): imageFile = 01?44?28?18.jpg
placeKmlImages(): imageFile = 04?37?41?26.jpg
placeKmlImages(): imageFile = 01_53_42_11.jpg
placeKmlImages(): isotime = 1999-03-08T18:34:57Z, latitude = 36.7535638888889, longitude = -121.970861111111, altitude = -419.8
placeKmlImages(): imageFile = 04?38?59?09.jpg
placeKmlImages(): imageFile = 00?06?16?08.jpg
placeKmlImages(): imageFile = 02?43?57?12.jpg
placeKmlImages(): imageFile = 03?57?41?24.jpg
placeKmlImages(): imageFile = 02?03?47?13.jpg
placeKmlImages(): imageFile = 03?51?34?05.jpg
placeKmlImages(): imageFile = 01_59_35_03.jpg
placeKmlImages(): isotime = 1999-03-08T18:40:58Z, latitude = 36.7536055555556, longitude = -121.970811111111, altitude = -411.9
placeKmlImages(): imageFile = 01?17?46?20.jpg
placeKmlImages(): imageFile = 04?19?27?29.jpg
placeKmlImages(): imageFile = 00?25?50?17.jpg
placeKmlImages(): imageFile = 02?00?08?04.jpg
placeKmlImages(): imageFile = 04?58?19?18.jpg
placeKmlImages(): imageFile = 04?48?24?22.jpg
placeKmlImages(): imageFile = 00_43_35_22.jpg
placeKmlImages(): isotime = 1999-03-08T17:24:12Z, latitude = 36.754025, longitude = -121.969213888889, altitude = -382.8
placeKmlImages(): imageFile = 02_32_07_10.jpg
placeKmlImages(): isotime = 1999-03-08T19:13:50Z, latitude = 36.7531277777778, longitude = -121.970033333333, altitude = 0
placeKmlImages(): imageFile = 04?12?40?11.jpg
placeKmlImages(): imageFile = 01_15_22_20.jpg
placeKmlImages(): isotime = 1999-03-08T17:56:35Z, latitude = 36.7522777777778, longitude = -121.970080555556, altitude = -389.8
placeKmlImages(): imageFile = 00_40_59_00.jpg
placeKmlImages(): isotime = 1999-03-08T17:21:42Z, latitude = 36.7540277777778, longitude = -121.968963888889, altitude = -375.7
placeKmlImages(): imageFile = 03?29?41?29.jpg
placeKmlImages(): imageFile = 01_07_25_19.jpg
placeKmlImages(): isotime = 1999-03-08T17:48:45Z, latitude = 36.7528472222222, longitude = -121.970336111111, altitude = -398.6
placeKmlImages(): imageFile = 02?43?53?28.jpg
placeKmlImages(): imageFile = 04?51?21?16.jpg
placeKmlImages(): imageFile = 04?21?26?11.jpg
placeKmlImages(): imageFile = 04?27?25?14.jpg
placeKmlImages(): imageFile = 01?55?46?27.jpg
placeKmlImages(): imageFile = 04?50?33?03.jpg
placeKmlImages(): imageFile = 04?22?59?06.jpg
placeKmlImages(): imageFile = 04?55?51?23.jpg
placeKmlImages(): imageFile = 02?15?29?18.jpg
placeKmlImages(): imageFile = 00_35_38_18.jpg
placeKmlImages(): isotime = 1999-03-08T17:16:23Z, latitude = 36.7540611111111, longitude = -121.968072222222, altitude = -356.7
placeKmlImages(): imageFile = 01?00?14?14.jpg
placeKmlImages(): imageFile = 01?17?29?06.jpg
placeKmlImages(): imageFile = 02_29_09_28.jpg
placeKmlImages(): isotime = 1999-03-08T19:10:59Z, latitude = 36.7529861111111, longitude = -121.970427777778, altitude = -405.6
Building KML for digitalImages (Coolpix)
placeKmlImages(): Looking in \\atlas\digitalImages\Ventana\1999\vnta1573 for .jpgs...
placeKmlImages(): *** No EXIF GPS data found.  Do you need to run dsibydive for this dive? ***
Writing file geo1999/vnta/vnta1573.kml...
Writing to file geo1999/vnta/vnta1573_pub.kml...

Adding record to ExpeditionData table...
Executing sql:
exec insertExpeditionData 'GoogleEarth','','',1764,'/3Dreplay/geo1999/vnta/vnta1573.kmz','V1573 Tour','','','',1573