INFO 2024-11-13 19:22:52,548 process.py process_mission():385 [1] self.commandline = 'src/data/process_dorado.py --mission 2024.317.01 -v' INFO 2024-11-13 19:22:52,549 process.py process_mission():390 [1] dorado_info[mission]["comment"] = 'Monterey Bay Diamond Mission - 31724G Biolume, ISUS, and LISST payloads removed - ctdToUse = ctd1 ' INFO 2024-11-13 19:22:52,549 process.py process_mission():402 [1] Processing Monterey Bay Diamond mission 2024.317.01 by user docker_user on host e26964f16ba6 INFO 2024-11-13 19:22:52,549 process.py download_process():152 [1] Download and processing steps for 2024.317.01 INFO 2024-11-13 19:22:52,549 logs2netcdfs.py download_process_logs():664 [1] src_dir = '/Volumes/AUVCTD/missionlogs/2024/2024317/2024.317.01' INFO 2024-11-13 19:22:52,549 logs2netcdfs.py download_process_logs():692 [1] Rsyncing /Volumes/AUVCTD/missionlogs/2024/2024317/2024.317.01 to /data/auv_data/dorado/missionlogs/2024.317.01 INFO 2024-11-13 19:23:04,568 logs2netcdfs.py download_process_logs():700 [1] Processing mission: dorado 2024.317.01 INFO 2024-11-13 19:23:04,568 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/ctdDriver.log (29811174 bytes) INFO 2024-11-13 19:23:09,929 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/ctdDriver2.log (29811165 bytes) INFO 2024-11-13 19:23:15,263 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/gps.log (1687748 bytes) INFO 2024-11-13 19:23:15,707 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/hydroscatlog.log (26074714 bytes) INFO 2024-11-13 19:23:22,141 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/navigation.log (150547280 bytes) INFO 2024-11-13 19:23:49,152 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/parosci.log (37087286 bytes) INFO 2024-11-13 19:23:56,428 logs2netcdfs.py _process_log_file():644 [1] Non-monotonic increasing time indices in /data/auv_data/dorado/missionlogs/2024.317.01/parosci.log: [18775 18776] INFO 2024-11-13 19:23:56,506 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/FLBBCD2K.log (28989747 bytes) INFO 2024-11-13 19:24:05,387 logs2netcdfs.py _process_log_file():644 [1] Non-monotonic increasing time indices in /data/auv_data/dorado/missionlogs/2024.317.01/FLBBCD2K.log: [ 1 3 6 165 168 170 172 175 179 180 188 192 197 208 74601 176539 176545] INFO 2024-11-13 19:24:05,484 logs2netcdfs.py download_process_logs():709 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/tailCone.log (18634311 bytes) INFO 2024-11-13 19:24:10,838 logs2netcdfs.py download_process_logs():726 [1] Time to process: 66.27 seconds INFO 2024-11-13 19:24:10,838 process.py download_process():181 [1] Processing file /data/auv_data/dorado/missionlogs/2024.317.01/lopc.bin (102043504 bytes) INFO 2024-11-13 19:24:10,838 lopcToNetCDF.py main():2282 [1] Processing begun: Wed Nov 13 19:24:10 2024 INFO 2024-11-13 19:24:10,838 lopcToNetCDF.py constructTimestampList():1300 [1] parosci_nc = /data/auv_data/dorado/missionnetcdfs/2024.317.01/parosci.nc INFO 2024-11-13 19:24:10,838 lopcToNetCDF.py constructTimestampList():1301 [1] Using NetCDF4 to get start and end epoch seconds for this mission from this URL: INFO 2024-11-13 19:24:10,838 lopcToNetCDF.py constructTimestampList():1304 [1] /data/auv_data/dorado/missionnetcdfs/2024.317.01/parosci.nc INFO 2024-11-13 19:24:10,842 lopcToNetCDF.py constructTimestampList():1321 [1] From associated parosci.nc file: sensor_on_time = 1731435282.4, sensor_off_time = 1731512917.7 INFO 2024-11-13 19:24:10,842 lopcToNetCDF.py constructTimestampList():1325 [1] Duration is 77635 seconds. Expecting to read 155270 L frames from the lopc.bin file. INFO 2024-11-13 19:24:10,842 lopcToNetCDF.py constructTimestampList():1335 [1] recCount not passed in, assuming we'll have 155270 records from the lopc.bin file. INFO 2024-11-13 19:24:12,478 lopcToNetCDF.py main():2304 [1] Examined sibling parosci.nc file to find startTime = 2024-11-12 18:14:42 Z and endTime = 2024-11-13 15:48:37 Z with 155270 records expected to be read from lopc.bin INFO 2024-11-13 19:24:12,478 lopcToNetCDF.py unpackLOPCbin():727 [1] >>> Unpacking LOPC data from /data/auv_data/dorado/missionlogs/2024.317.01/lopc.bin INFO 2024-11-13 19:24:12,478 lopcToNetCDF.py unpackLOPCbin():728 [1] >>> Will write to NetCDF file /data/auv_data/dorado/missionnetcdfs/2024.317.01/lopc.nc WARNING 2024-11-13 19:24:12,478 lopcToNetCDF.py readCframeData():667 [1] Unable to parse a float from the string str[:70] = INFO 2024-11-13 19:24:12,479 lopcToNetCDF.py openNetCDFFile():1858 [1] Will output NetCDF file to /data/auv_data/dorado/missionnetcdfs/2024.317.01/lopc.nc INFO 2024-11-13 19:24:12,479 lopcToNetCDF.py openNetCDFFile():1888 [1] missionName = 2024.317.01 INFO 2024-11-13 19:24:12,479 lopcToNetCDF.py openNetCDFFile():1907 [1] Writing bin axis for len(self.dataStructure['binSizeList']) = 994 INFO 2024-11-13 19:24:12,533 lopcToNetCDF.py unpackLOPCbin():953 [1] L Frames sample M Frames ShortLFrame LBeforeMEnd MBeforeLEnd UnknownFrame Garbled L INFO 2024-11-13 19:24:12,533 lopcToNetCDF.py unpackLOPCbin():971 [1] Count Count Count Error Error Error Error Error INFO 2024-11-13 19:24:12,533 lopcToNetCDF.py unpackLOPCbin():989 [1] -------- -------- -------- ----------- ----------- ----------- ----------- ------------ INFO 2024-11-13 19:24:26,060 lopcToNetCDF.py unpackLOPCbin():1019 [1] 10000 10145 23068 0 0 0 0 0 13.6 seconds, last L Frame: 155270 INFO 2024-11-13 19:24:52,764 lopcToNetCDF.py unpackLOPCbin():1019 [1] 20000 20145 73112 0 0 0 0 0 26.7 seconds, last L Frame: 155270 INFO 2024-11-13 19:25:16,641 lopcToNetCDF.py unpackLOPCbin():1019 [1] 30000 30145 117903 0 0 0 0 0 23.9 seconds, last L Frame: 155270 INFO 2024-11-13 19:25:39,007 lopcToNetCDF.py unpackLOPCbin():1019 [1] 40000 40145 159643 0 0 0 0 0 22.4 seconds, last L Frame: 155270 INFO 2024-11-13 19:25:55,346 lopcToNetCDF.py unpackLOPCbin():1019 [1] 50000 50145 189235 0 0 0 0 0 16.3 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:08,600 lopcToNetCDF.py unpackLOPCbin():1019 [1] 60000 60145 212671 0 0 0 0 0 13.3 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:19,422 lopcToNetCDF.py unpackLOPCbin():1019 [1] 70000 4609 230988 0 0 0 0 0 10.8 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:30,204 lopcToNetCDF.py unpackLOPCbin():1019 [1] 80000 14609 249182 0 0 0 0 0 10.8 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:36,504 lopcToNetCDF.py unpackLOPCbin():1019 [1] 90000 24609 258524 0 0 0 0 0 6.3 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:44,671 lopcToNetCDF.py unpackLOPCbin():1019 [1] 100000 34609 271184 0 0 0 0 0 8.2 seconds, last L Frame: 155270 INFO 2024-11-13 19:26:54,440 lopcToNetCDF.py unpackLOPCbin():1019 [1] 110000 44609 287009 0 0 0 0 0 9.8 seconds, last L Frame: 155270 INFO 2024-11-13 19:27:05,966 lopcToNetCDF.py unpackLOPCbin():1019 [1] 120000 54609 306431 0 0 0 0 0 11.5 seconds, last L Frame: 155270 INFO 2024-11-13 19:27:14,817 lopcToNetCDF.py unpackLOPCbin():1019 [1] 130000 64609 319965 0 0 0 0 0 8.9 seconds, last L Frame: 155270 INFO 2024-11-13 19:27:26,594 lopcToNetCDF.py unpackLOPCbin():1019 [1] 140000 9073 340414 0 0 0 0 0 11.8 seconds, last L Frame: 155270 INFO 2024-11-13 19:27:38,858 lopcToNetCDF.py unpackLOPCbin():1019 [1] 150000 19073 362323 0 0 0 0 0 12.3 seconds, last L Frame: 155270 INFO 2024-11-13 19:27:47,044 lopcToNetCDF.py main():2327 [1] >>> Done reading file. INFO 2024-11-13 19:27:47,044 lopcToNetCDF.py main():2328 [1] lFrameCount = 155289, mFrameCount = 377949 INFO 2024-11-13 19:27:47,044 lopcToNetCDF.py constructTimestampList():1300 [1] parosci_nc = /data/auv_data/dorado/missionnetcdfs/2024.317.01/parosci.nc INFO 2024-11-13 19:27:47,044 lopcToNetCDF.py constructTimestampList():1301 [1] Using NetCDF4 to get start and end epoch seconds for this mission from this URL: INFO 2024-11-13 19:27:47,044 lopcToNetCDF.py constructTimestampList():1304 [1] /data/auv_data/dorado/missionnetcdfs/2024.317.01/parosci.nc INFO 2024-11-13 19:27:47,047 lopcToNetCDF.py constructTimestampList():1321 [1] From associated parosci.nc file: sensor_on_time = 1731435282.4, sensor_off_time = 1731512917.7 INFO 2024-11-13 19:27:47,048 lopcToNetCDF.py constructTimestampList():1325 [1] Duration is 77635 seconds. Expecting to read 155270 L frames from the lopc.bin file. INFO 2024-11-13 19:27:47,048 lopcToNetCDF.py constructTimestampList():1335 [1] recCount not passed in, assuming we'll have 155270 records from the lopc.bin file. INFO 2024-11-13 19:27:47,062 lopcToNetCDF.py constructTimestampList():1344 [1] Calling correctSampleCountList() with sampleCountList[0] = 146 and len(sampleCountList) = 155289 INFO 2024-11-13 19:27:47,074 lopcToNetCDF.py correctSampleCountList():1724 [1] Found overflows at indices: [ 65390 130926] INFO 2024-11-13 19:27:47,074 lopcToNetCDF.py correctSampleCountList():1729 [1] Assigning values from slice starting at index 65390, i = 1 INFO 2024-11-13 19:27:47,078 lopcToNetCDF.py correctSampleCountList():1729 [1] Assigning values from slice starting at index 130926, i = 2 INFO 2024-11-13 19:27:47,079 lopcToNetCDF.py correctSampleCountList():1737 [1] Original sampleCountList = [ 146 147 148 ... 155434 155435 155436] INFO 2024-11-13 19:27:47,079 lopcToNetCDF.py correctSampleCountList():1738 [1] Found 0 0 values at indices: [] INFO 2024-11-13 19:27:47,080 lopcToNetCDF.py correctSampleCountList():1774 [1] Found 0 stuck values at indices: [] INFO 2024-11-13 19:27:47,080 lopcToNetCDF.py deSpike():1574 [1] d_sampleCountList = [1 1 1 ... 1 1 1] INFO 2024-11-13 19:27:47,080 lopcToNetCDF.py deSpike():1576 [1] Found 0 spike indicators at indices: [] INFO 2024-11-13 19:27:47,081 lopcToNetCDF.py correctSampleCountList():1803 [1] After despike() sampleCountList = [ 146 147 148 ... 155434 155435 155436] INFO 2024-11-13 19:27:47,081 lopcToNetCDF.py constructTimestampList():1349 [1] correctSampleCountList() returned correctedSampleCountList = [ 146 147 148 ... 155434 155435 155436] with len(correctedSampleCountList) = 155289 INFO 2024-11-13 19:27:47,081 lopcToNetCDF.py constructTimestampList():1356 [1] Subtracting 146 from all values of correctedSampleCountList INFO 2024-11-13 19:27:47,081 lopcToNetCDF.py constructTimestampList():1361 [1] Constructing timestampArray from instrument corrected sampleCount and constant deltaT = 0.500000 INFO 2024-11-13 19:27:47,081 lopcToNetCDF.py constructTimestampList():1365 [1] New re-zeroed correctedSampleCountList = [0.00000e+00 5.00000e-01 1.00000e+00 ... 7.76440e+04 7.76445e+04 7.76450e+04] INFO 2024-11-13 19:27:47,082 lopcToNetCDF.py constructTimestampList():1376 [1] timestampArray = [1.73143528e+09 1.73143528e+09 1.73143528e+09 ... 1.73151293e+09 1.73151293e+09 1.73151293e+09] INFO 2024-11-13 19:27:47,082 lopcToNetCDF.py constructTimestampList():1377 [1] timestampArray[:2] = [1731435282.4 1731435282.9, ..., timestampArray[-2:] = 1731512926.9 1731512927.4] INFO 2024-11-13 19:27:47,379 lopcToNetCDF.py constructTimestampList():1388 [1] timestampList[:2] = [1731435282.366458, 1731435282.866458], ..., timestampList[-2:] = [1731512926.866458, 1731512927.366458]] INFO 2024-11-13 19:27:47,379 lopcToNetCDF.py constructTimestampList():1392 [1] Subsampling correctedSampleCountList (len = 155289) according to what got written to the netCDF file by the binning interval INFO 2024-11-13 19:27:47,379 lopcToNetCDF.py constructTimestampList():1396 [1] lFrameCountWrittenList[:2] = [20, 41], ... lFrameCountWrittenList[-2:] = [155252, 155273] INFO 2024-11-13 19:27:47,380 lopcToNetCDF.py constructTimestampList():1420 [1] len(subSampledCorrectedSampleCountList) = 7394 INFO 2024-11-13 19:27:47,380 lopcToNetCDF.py constructTimestampList():1437 [1] len(timestampList) = 155289 INFO 2024-11-13 19:27:47,380 lopcToNetCDF.py constructTimestampList():1438 [1] timestampList[:2] = [1731435282.366458, 1731435282.866458], ..., timestampList[-2:] = [1731512926.866458, 1731512927.366458]] INFO 2024-11-13 19:27:47,380 lopcToNetCDF.py constructTimestampList():1443 [1] Subsampling timestampList (len = 155289) according to what got written to the netCDF file by the binning interval INFO 2024-11-13 19:27:47,382 lopcToNetCDF.py constructTimestampList():1453 [1] Taking indices [[19, 40] ... [155251, 155272]] from timestampList to create subSampledTimestampList INFO 2024-11-13 19:27:47,390 lopcToNetCDF.py constructTimestampList():1458 [1] len(subSampledTimestampList) = 7394 INFO 2024-11-13 19:27:47,391 lopcToNetCDF.py constructTimestampList():1470 [1] Finding elements of cFrameEsecsList that != -9999 INFO 2024-11-13 19:27:47,472 lopcToNetCDF.py closeNetCDFFile():2103 [1] Writing time axis for len(tsList) = 7394 INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2106 [1] tsList[:1] = [1731435291.866458], ..., tsList[-2:] = [1731512908.866458, 1731512919.366458] INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2109 [1] Begin time = 2024-11-12 18:14:51 Z INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2113 [1] End time = 2024-11-13 15:48:39 Z INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2118 [1] Writing time axis for len(cFrameEsecsList) = 7394 INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2121 [1] cFrameEsecsList[:1] = [1.73143535e+09], ..., cFrameEsecsList[-2:] = [1.73144743e+09 1.73144744e+09] INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2125 [1] Begin time = 2024-11-12 18:15:49 Z INFO 2024-11-13 19:27:47,473 lopcToNetCDF.py closeNetCDFFile():2129 [1] End time = 2024-11-12 21:37:20 Z INFO 2024-11-13 19:27:47,480 lopcToNetCDF.py main():2369 [1] Created file: /data/auv_data/dorado/missionnetcdfs/2024.317.01/lopc.nc INFO 2024-11-13 19:27:47,480 lopcToNetCDF.py main():2372 [1] Processing finished: Wed Nov 13 19:27:47 2024 Elapsed processing time from start of processing = 216 seconds INFO 2024-11-13 19:27:47,485 process.py calibrate():206 [1] Calibration steps for 2024.317.01 INFO 2024-11-13 19:27:48,402 calibrate.py _nudge_pos():1584 [1] seg# end_sec_diff end_lon_diff end_lat_diff len(segi) seg_min u_drift (cm/s) v_drift (cm/s) start datetime of segment INFO 2024-11-13 19:27:48,405 calibrate.py _nudge_pos():1608 [1] - - - 0 0.00 - - - INFO 2024-11-13 19:27:50,445 calibrate.py _nudge_pos():1703 [1] 170: 0.020 -0.0000365 0.0009496 4881 16.27 -0.333 10.818 2024-11-12T18:33:34.098929152 INFO 2024-11-13 19:28:04,551 calibrate.py _nudge_pos():1703 [1] 1133: 0.180 0.0009831 0.0006786 6150 20.50 7.117 6.135 2024-11-12T19:10:06.098766848 INFO 2024-11-13 19:28:09,839 calibrate.py _nudge_pos():1703 [1] 1488: 0.100 0.0042629 0.0004436 8301 27.67 22.868 2.971 2024-11-12T19:43:40.300784640 INFO 2024-11-13 19:28:10,408 calibrate.py _nudge_pos():1703 [1] 1527: 0.020 0.0037947 -0.0011451 8756 29.18 19.303 -7.271 2024-11-12T20:13:29.498216192 INFO 2024-11-13 19:28:15,740 calibrate.py _nudge_pos():1703 [1] 1888: 0.160 0.0035727 -0.0011622 8020 26.73 19.847 -8.057 2024-11-12T20:46:13.503672576 INFO 2024-11-13 19:28:16,747 calibrate.py _nudge_pos():1703 [1] 1956: 0.100 0.0034929 -0.0011837 7506 25.02 20.737 -8.768 2024-11-12T21:12:21.696243968 INFO 2024-11-13 19:28:21,959 calibrate.py _nudge_pos():1703 [1] 2307: 0.020 0.0034873 -0.0009969 7366 24.55 21.101 -7.524 2024-11-12T21:42:44.901345792 INFO 2024-11-13 19:28:23,020 calibrate.py _nudge_pos():1703 [1] 2376: 0.130 0.0036240 -0.0011448 7530 25.10 21.456 -8.452 2024-11-12T22:08:58.903519488 INFO 2024-11-13 19:28:28,519 calibrate.py _nudge_pos():1703 [1] 2747: 0.040 0.0029994 -0.0010287 6286 20.95 21.278 -9.098 2024-11-12T22:36:06.102048512 INFO 2024-11-13 19:28:29,104 calibrate.py _nudge_pos():1703 [1] 2787: 0.150 0.0033809 -0.0008669 7525 25.08 20.039 -6.405 2024-11-12T23:01:50.096277760 INFO 2024-11-13 19:28:34,433 calibrate.py _nudge_pos():1703 [1] 3148: 0.130 0.0006892 0.0002289 9606 32.02 3.199 1.325 2024-11-12T23:39:51.300004608 INFO 2024-11-13 19:28:34,914 calibrate.py _nudge_pos():1703 [1] 3180: 0.060 0.0004536 -0.0000194 7486 24.95 2.702 -0.144 2024-11-13T00:05:19.495315968 INFO 2024-11-13 19:28:40,152 calibrate.py _nudge_pos():1703 [1] 3530: 0.160 0.0003474 -0.0000646 7215 24.05 2.146 -0.498 2024-11-13T00:35:11.502555136 INFO 2024-11-13 19:28:40,903 calibrate.py _nudge_pos():1703 [1] 3580: 0.070 0.0006262 -0.0001583 6516 21.72 4.283 -1.351 2024-11-13T00:57:43.699923200 INFO 2024-11-13 19:28:46,541 calibrate.py _nudge_pos():1703 [1] 3958: 0.200 0.0005083 0.0000907 7245 24.15 3.127 0.696 2024-11-13T01:28:09.694833920 INFO 2024-11-13 19:28:46,882 calibrate.py _nudge_pos():1703 [1] 3980: 0.080 0.0007012 0.0006023 7321 24.40 4.268 4.574 2024-11-13T01:52:54.903090176 INFO 2024-11-13 19:28:52,182 calibrate.py _nudge_pos():1703 [1] 4325: 0.070 0.0001924 0.0005310 7526 25.08 1.139 3.922 2024-11-13T02:23:44.096411904 INFO 2024-11-13 19:28:53,139 calibrate.py _nudge_pos():1703 [1] 4388: 0.160 -0.0000590 0.0002988 7200 24.00 -0.365 2.307 2024-11-13T02:48:46.103517184 INFO 2024-11-13 19:28:58,807 calibrate.py _nudge_pos():1703 [1] 4763: 0.040 0.0006428 0.0005791 5266 17.55 5.437 6.114 2024-11-13T03:12:33.295748096 INFO 2024-11-13 19:29:04,182 calibrate.py _nudge_pos():1703 [1] 5117: 0.190 0.0056835 -0.0025392 8430 28.10 30.025 -16.746 2024-11-13T03:46:32.296066816 INFO 2024-11-13 19:29:04,918 calibrate.py _nudge_pos():1703 [1] 5165: 0.190 0.0001230 -0.0000198 30 0.10 188.954 -37.926 2024-11-13T03:47:25.302435584 INFO 2024-11-13 19:29:05,009 calibrate.py _nudge_pos():1703 [1] 5171: 0.120 0.0044365 -0.0001319 9036 30.12 21.859 -0.811 2024-11-13T04:17:37.498291712 INFO 2024-11-13 19:29:12,019 calibrate.py _nudge_pos():1703 [1] 5632: 0.040 0.0044053 -0.0002817 7186 23.95 27.288 -2.180 2024-11-13T04:49:15.698256128 INFO 2024-11-13 19:29:12,814 calibrate.py _nudge_pos():1703 [1] 5684: 0.160 0.0042727 -0.0003892 7190 23.96 26.446 -3.010 2024-11-13T05:14:04.696252928 INFO 2024-11-13 19:29:20,356 calibrate.py _nudge_pos():1703 [1] 6172: 0.110 0.0057204 -0.0013964 8085 26.95 31.479 -9.602 2024-11-13T05:49:08.902104832 INFO 2024-11-13 19:29:21,677 calibrate.py _nudge_pos():1703 [1] 6258: 0.030 0.0049083 -0.0005122 7316 24.38 29.842 -3.892 2024-11-13T06:14:57.096046336 INFO 2024-11-13 19:29:27,482 calibrate.py _nudge_pos():1703 [1] 6634: 0.180 0.0056015 -0.0011660 8125 27.08 30.658 -7.978 2024-11-13T06:48:17.099036672 INFO 2024-11-13 19:29:28,254 calibrate.py _nudge_pos():1703 [1] 6684: 0.080 0.0053228 -0.0008904 7416 24.72 31.912 -6.675 2024-11-13T07:13:49.294074112 INFO 2024-11-13 19:29:33,812 calibrate.py _nudge_pos():1703 [1] 7044: 0.200 0.0003022 0.0002106 5895 19.65 2.280 1.987 2024-11-13T07:39:27.298713600 INFO 2024-11-13 19:29:34,492 calibrate.py _nudge_pos():1703 [1] 7088: 0.110 0.0003285 0.0002760 7320 24.40 1.996 2.097 2024-11-13T08:04:34.495463680 INFO 2024-11-13 19:29:40,001 calibrate.py _nudge_pos():1703 [1] 7442: 0.030 0.0001031 0.0002082 7461 24.87 0.615 1.551 2024-11-13T08:35:19.699058432 INFO 2024-11-13 19:29:40,931 calibrate.py _nudge_pos():1703 [1] 7501: 0.140 0.0003908 0.0003675 7035 23.45 2.471 2.904 2024-11-13T08:59:44.698699008 INFO 2024-11-13 19:29:46,631 calibrate.py _nudge_pos():1703 [1] 7866: 0.070 0.0005411 0.0005619 7460 24.86 3.227 4.188 2024-11-13T09:30:40.901540608 INFO 2024-11-13 19:29:47,661 calibrate.py _nudge_pos():1703 [1] 7932: 0.150 0.0006802 0.0004053 6985 23.28 4.334 3.226 2024-11-13T09:55:02.901386752 INFO 2024-11-13 19:29:53,585 calibrate.py _nudge_pos():1703 [1] 8313: 0.100 0.0002469 0.0005164 6295 20.98 1.746 4.561 2024-11-13T10:22:22.099094016 INFO 2024-11-13 19:29:59,172 calibrate.py _nudge_pos():1703 [1] 8670: 0.050 0.0010432 0.0004305 7156 23.85 6.490 3.344 2024-11-13T10:52:09.296662528 INFO 2024-11-13 19:30:19,179 calibrate.py _nudge_pos():1703 [1] 9946: 0.170 0.0015751 -0.0012446 3930 13.10 17.844 -17.610 2024-11-13T11:26:30.295474176 INFO 2024-11-13 19:30:39,891 calibrate.py _nudge_pos():1703 [1] 11263: 0.100 0.0002967 -0.0010699 3120 10.40 4.234 -19.069 2024-11-13T11:58:50.502561024 INFO 2024-11-13 19:30:59,751 calibrate.py _nudge_pos():1703 [1] 12524: 0.060 0.0008910 -0.0012501 4570 15.23 8.680 -15.210 2024-11-13T12:35:04.703618048 INFO 2024-11-13 19:31:23,461 calibrate.py _nudge_pos():1703 [1] 14022: 0.030 0.0002327 -0.0009024 2596 8.65 3.991 -19.332 2024-11-13T13:08:41.895431168 INFO 2024-11-13 19:31:55,888 calibrate.py _nudge_pos():1703 [1] 16073: 0.160 -0.0001047 0.0001169 265 0.88 -17.649 24.606 2024-11-13T13:43:44.901365248 INFO 2024-11-13 19:32:59,553 calibrate.py _nudge_pos():1703 [1] 20062: 0.090 0.0000568 -0.0000062 105 0.35 24.300 -3.302 2024-11-13T14:50:34.296702976 INFO 2024-11-13 19:33:33,005 calibrate.py _nudge_pos():1703 [1] 22149: 0.040 -0.0001431 -0.0002579 590 1.96 -10.816 -24.340 2024-11-13T15:27:18.495704832 INFO 2024-11-13 19:33:53,623 calibrate.py _nudge_pos():1760 [1] 23381: - - - 1 0.00 - - INFO 2024-11-13 19:33:53,623 calibrate.py _nudge_pos():1766 [1] Points in final series = 386750 INFO 2024-11-13 19:34:08,276 calibrate.py _hs2_process():2276 [1] Number of bad hs2 points: 1 of 310401 INFO 2024-11-13 19:34:11,207 calibrate.py _geometric_depth_correction():3257 [1] hs2: d_beg_time_diff: -9 seconds, d_end_time_diff: -1 seconds, INFO 2024-11-13 19:34:15,046 calibrate.py _geometric_depth_correction():3257 [1] ctd1: d_beg_time_diff: 0 seconds, d_end_time_diff: -1 seconds, INFO 2024-11-13 19:34:17,963 calibrate.py _geometric_depth_correction():3257 [1] ctd1: d_beg_time_diff: 0 seconds, d_end_time_diff: -1 seconds, INFO 2024-11-13 19:34:17,968 calibrate.py _ctd_process():2779 [1] Performing range checking of ['ctd1_salinity'] in 2024.317.01/ctd1.nc INFO 2024-11-13 19:34:17,970 calibrate.py _range_qc_combined_nc():894 [1] Setting 0 ctd1_salinity values to NaN INFO 2024-11-13 19:34:17,971 calibrate.py _range_qc_combined_nc():908 [1] Checked for data outside of these variables and ranges: [('ctd1_salinity', Range(min=30, max=40))] INFO 2024-11-13 19:34:17,971 calibrate.py _range_qc_combined_nc():934 [1] Done range checking ctd1 INFO 2024-11-13 19:34:21,601 calibrate.py _geometric_depth_correction():3257 [1] ctd2: d_beg_time_diff: 0 seconds, d_end_time_diff: -1 seconds, INFO 2024-11-13 19:34:24,614 calibrate.py _geometric_depth_correction():3257 [1] ctd2: d_beg_time_diff: 0 seconds, d_end_time_diff: -1 seconds, INFO 2024-11-13 19:34:24,620 calibrate.py _ctd_process():2779 [1] Performing range checking of ['ctd2_salinity'] in 2024.317.01/ctd2.nc INFO 2024-11-13 19:34:24,622 calibrate.py _range_qc_combined_nc():894 [1] Setting 0 ctd2_salinity values to NaN INFO 2024-11-13 19:34:24,623 calibrate.py _range_qc_combined_nc():908 [1] Checked for data outside of these variables and ranges: [('ctd2_salinity', Range(min=30, max=40))] INFO 2024-11-13 19:34:24,623 calibrate.py _range_qc_combined_nc():934 [1] Done range checking ctd2 ERROR 2024-11-13 19:34:24,623 calibrate.py process_logs():3378 [1] Error processing isus: isus has no orig_data - likely a missing or zero-sized .log file in missionlogs/2024.317.01 ERROR 2024-11-13 19:34:24,624 calibrate.py process_logs():3378 [1] Error processing biolume: biolume has no orig_data - likely a missing or zero-sized .log file in missionlogs/2024.317.01 INFO 2024-11-13 19:35:02,549 calibrate.py write_netcdf():3344 [1] Writing calibrated instrument data to /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_cal.nc INFO 2024-11-13 19:35:02,822 calibrate.py write_netcdf():3348 [1] Data variables written: ctd1_conductivity, ctd1_conductivity_onboard, ctd1_depth, ctd1_dissolvedO2, ctd1_flow1, ctd1_oxygen_mll, ctd1_oxygen_umolkg, ctd1_salinity, ctd1_salinity_onboard, ctd1_temperature, ctd1_temperature_onboard, ctd1_time, ctd2_conductivity, ctd2_conductivity_onboard, ctd2_depth, ctd2_flow2, ctd2_par, ctd2_salinity, ctd2_salinity_onboard, ctd2_temperature, ctd2_temperature_onboard, ctd2_time, depth_filtdepth, depth_filtpres, depth_time, ecopuck_bbp700, ecopuck_cdom, ecopuck_chl, ecopuck_time, gps_latitude, gps_longitude, gps_time, hs2_bbp420, hs2_bbp700, hs2_depth, hs2_fl700, hs2_time, lopc_LCcount, lopc_countListSum, lopc_flowSpeed, lopc_nonTransCount, lopc_time, lopc_transCount, navigation_depth, navigation_latitude, navigation_longitude, navigation_mWaterSpeed, navigation_pitch, navigation_posx, navigation_posy, navigation_roll, navigation_time, navigation_yaw, nudged_latitude, nudged_longitude, tailcone_propRpm, tailcone_time, time INFO 2024-11-13 19:35:02,836 process.py align():230 [1] Alignment steps for 2024.317.01 INFO 2024-11-13 19:35:02,925 align.py process_cal():145 [1] Processing dorado_2024.317.01_cal.nc from /data/auv_data/dorado/missionnetcdfs/2024.317.01 INFO 2024-11-13 19:35:03,028 align.py process_cal():221 [1] Using pitch corrected navigation_depth: mDepth from navigation.nc INFO 2024-11-13 19:35:03,040 align.py process_cal():282 [1] navigation_roll: instrument_sample_rate_hz = 5.00 INFO 2024-11-13 19:35:03,304 align.py process_cal():221 [1] Using pitch corrected navigation_depth: mDepth from navigation.nc INFO 2024-11-13 19:35:03,321 align.py process_cal():282 [1] navigation_pitch: instrument_sample_rate_hz = 5.00 INFO 2024-11-13 19:35:03,550 align.py process_cal():221 [1] Using pitch corrected navigation_depth: mDepth from navigation.nc INFO 2024-11-13 19:35:03,565 align.py process_cal():282 [1] navigation_yaw: instrument_sample_rate_hz = 5.00 INFO 2024-11-13 19:35:03,707 align.py process_cal():168 [1] Skipping navigation_posx INFO 2024-11-13 19:35:03,707 align.py process_cal():168 [1] Skipping navigation_posy INFO 2024-11-13 19:35:03,806 align.py process_cal():221 [1] Using pitch corrected navigation_depth: mDepth from navigation.nc INFO 2024-11-13 19:35:03,822 align.py process_cal():282 [1] navigation_mWaterSpeed: instrument_sample_rate_hz = 5.00 INFO 2024-11-13 19:35:04,097 align.py process_cal():221 [1] Using pitch corrected hs2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to hs2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:04,109 align.py process_cal():282 [1] hs2_bbp420: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:04,310 align.py process_cal():221 [1] Using pitch corrected hs2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to hs2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:04,320 align.py process_cal():282 [1] hs2_bbp700: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:04,516 align.py process_cal():221 [1] Using pitch corrected hs2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to hs2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:04,528 align.py process_cal():282 [1] hs2_fl700: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:04,720 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:04,730 align.py process_cal():282 [1] ctd1_temperature: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:04,922 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:04,932 align.py process_cal():282 [1] ctd1_conductivity: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:05,122 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:05,133 align.py process_cal():282 [1] ctd1_salinity: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:05,323 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:05,334 align.py process_cal():282 [1] ctd1_temperature_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:05,523 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:05,533 align.py process_cal():282 [1] ctd1_conductivity_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:05,725 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:05,736 align.py process_cal():282 [1] ctd1_salinity_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:05,925 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:05,935 align.py process_cal():282 [1] ctd1_dissolvedO2: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:06,126 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:06,137 align.py process_cal():282 [1] ctd1_oxygen_mll: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:06,329 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:06,340 align.py process_cal():282 [1] ctd1_oxygen_umolkg: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:06,532 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:06,543 align.py process_cal():282 [1] ctd1_flow1: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:06,736 align.py process_cal():221 [1] Using pitch corrected ctd1_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd1_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:06,748 align.py process_cal():282 [1] ctd1_depth: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:06,942 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:06,953 align.py process_cal():282 [1] ctd2_temperature: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:07,148 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:07,159 align.py process_cal():282 [1] ctd2_conductivity: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:07,351 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:07,362 align.py process_cal():282 [1] ctd2_salinity: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:07,554 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:07,565 align.py process_cal():282 [1] ctd2_temperature_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:07,758 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:07,769 align.py process_cal():282 [1] ctd2_conductivity_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:07,963 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:07,974 align.py process_cal():282 [1] ctd2_salinity_onboard: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:08,166 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:08,177 align.py process_cal():282 [1] ctd2_flow2: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:08,369 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:08,380 align.py process_cal():282 [1] ctd2_depth: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:08,571 align.py process_cal():221 [1] Using pitch corrected ctd2_depth: Variable depth_filtdepth from dorado_2024.317.01_cal.nc linearly interpolated to ctd2_time and corrected for pitch using SensorOffset(x=4.5, y=0.0) INFO 2024-11-13 19:35:08,582 align.py process_cal():282 [1] ctd2_par: instrument_sample_rate_hz = 4.00 INFO 2024-11-13 19:35:08,828 align.py process_cal():282 [1] lopc_countListSum: instrument_sample_rate_hz = 0.10 INFO 2024-11-13 19:35:09,062 align.py process_cal():282 [1] lopc_transCount: instrument_sample_rate_hz = 0.10 INFO 2024-11-13 19:35:09,202 align.py process_cal():282 [1] lopc_nonTransCount: instrument_sample_rate_hz = 0.10 INFO 2024-11-13 19:35:09,342 align.py process_cal():282 [1] lopc_LCcount: instrument_sample_rate_hz = 0.10 INFO 2024-11-13 19:35:09,483 align.py process_cal():282 [1] lopc_flowSpeed: instrument_sample_rate_hz = 0.10 INFO 2024-11-13 19:35:09,639 align.py process_cal():282 [1] ecopuck_bbp700: instrument_sample_rate_hz = 10.37 INFO 2024-11-13 19:35:10,129 align.py process_cal():282 [1] ecopuck_cdom: instrument_sample_rate_hz = 10.37 INFO 2024-11-13 19:35:10,597 align.py process_cal():282 [1] ecopuck_chl: instrument_sample_rate_hz = 10.37 INFO 2024-11-13 19:35:11,055 align.py process_cal():282 [1] tailcone_propRpm: instrument_sample_rate_hz = 5.00 INFO 2024-11-13 19:35:11,223 align.py write_netcdf():367 [1] Writing aligned data to /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_align.nc INFO 2024-11-13 19:35:11,474 align.py write_netcdf():372 [1] Data variables written: ctd1_conductivity, ctd1_conductivity_onboard, ctd1_depth, ctd1_dissolvedO2, ctd1_flow1, ctd1_latitude, ctd1_longitude, ctd1_oxygen_mll, ctd1_oxygen_umolkg, ctd1_salinity, ctd1_salinity_onboard, ctd1_temperature, ctd1_temperature_onboard, ctd1_time, ctd2_conductivity, ctd2_conductivity_onboard, ctd2_depth, ctd2_flow2, ctd2_latitude, ctd2_longitude, ctd2_par, ctd2_salinity, ctd2_salinity_onboard, ctd2_temperature, ctd2_temperature_onboard, ctd2_time, ecopuck_bbp700, ecopuck_cdom, ecopuck_chl, ecopuck_depth, ecopuck_latitude, ecopuck_longitude, ecopuck_time, hs2_bbp420, hs2_bbp700, hs2_depth, hs2_fl700, hs2_latitude, hs2_longitude, hs2_time, lopc_LCcount, lopc_countListSum, lopc_depth, lopc_flowSpeed, lopc_latitude, lopc_longitude, lopc_nonTransCount, lopc_time, lopc_transCount, navigation_depth, navigation_latitude, navigation_longitude, navigation_mWaterSpeed, navigation_pitch, navigation_roll, navigation_time, navigation_yaw, tailcone_depth, tailcone_latitude, tailcone_longitude, tailcone_propRpm, tailcone_time INFO 2024-11-13 19:35:11,479 process.py resample():251 [1] Resampling steps for 2024.317.01 INFO 2024-11-13 19:35:11,548 resample.py instruments_variables():234 [1] Reading variables from /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_align.nc mission netCDF file INFO 2024-11-13 19:35:11,576 resample.py instruments_variables():234 [1] Reading variables from /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_align.nc mission netCDF file INFO 2024-11-13 19:35:11,581 resample.py get_mission_start_end():808 [1] navigation: 2024-11-12T18:15:17.794031104 to 2024-11-13T15:48:37.698073600 (0 days 00:00:01.668384256 before mission_end) INFO 2024-11-13 19:35:11,586 resample.py get_mission_start_end():808 [1] hs2 : 2024-11-12T18:14:33.787045632 to 2024-11-13T15:48:37.508086528 (0 days 00:00:01.858371328 before mission_end) INFO 2024-11-13 19:35:11,592 resample.py get_mission_start_end():808 [1] ctd1 : 2024-11-12T18:14:42.706434816 to 2024-11-13T15:48:37.628078336 (0 days 00:00:01.738379520 before mission_end) INFO 2024-11-13 19:35:11,597 resample.py get_mission_start_end():808 [1] ctd2 : 2024-11-12T18:14:42.706434816 to 2024-11-13T15:48:37.678074880 (0 days 00:00:01.688382976 before mission_end) INFO 2024-11-13 19:35:11,599 resample.py get_mission_start_end():808 [1] lopc : 2024-11-12T18:14:51.866457856 to 2024-11-13T15:48:39.366457856 (0 days 00:00:00 before mission_end) INFO 2024-11-13 19:35:11,608 resample.py get_mission_start_end():808 [1] ecopuck : 2024-11-12T18:14:27.637467136 to 2024-11-13T15:48:37.678074880 (0 days 00:00:01.688382976 before mission_end) INFO 2024-11-13 19:35:11,615 resample.py get_mission_start_end():808 [1] tailcone : 2024-11-12T18:14:36.786840320 to 2024-11-13T15:48:37.578081792 (0 days 00:00:01.788376064 before mission_end) INFO 2024-11-13 19:35:11,617 resample.py instruments_variables():234 [1] Reading variables from /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_align.nc mission netCDF file INFO 2024-11-13 19:35:11,618 resample.py resample_coordinates():248 [1] Resampling coordinates depth, latitude and longitude with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,122 resample.py resample_variable():703 [1] Resampling navigation_roll with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,275 resample.py resample_variable():703 [1] Resampling navigation_pitch with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,425 resample.py resample_variable():703 [1] Resampling navigation_yaw with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,574 resample.py resample_variable():703 [1] Resampling navigation_mWaterSpeed with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,701 resample.py resample_variable():703 [1] Resampling hs2_bbp420 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,828 resample.py resample_variable():703 [1] Resampling hs2_bbp700 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:12,955 resample.py resample_variable():703 [1] Resampling hs2_fl700 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,082 resample.py resample_variable():703 [1] Resampling ctd1_temperature with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,204 resample.py resample_variable():703 [1] Resampling ctd1_conductivity with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,325 resample.py resample_variable():703 [1] Resampling ctd1_salinity with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,446 resample.py resample_variable():703 [1] Resampling ctd1_temperature_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,568 resample.py resample_variable():703 [1] Resampling ctd1_conductivity_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,689 resample.py resample_variable():703 [1] Resampling ctd1_salinity_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,809 resample.py resample_variable():703 [1] Resampling ctd1_dissolvedO2 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:13,930 resample.py resample_variable():703 [1] Resampling ctd1_oxygen_mll with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,051 resample.py resample_variable():703 [1] Resampling ctd1_oxygen_umolkg with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,172 resample.py resample_variable():703 [1] Resampling ctd1_flow1 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,297 resample.py resample_variable():703 [1] Resampling ctd2_temperature with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,421 resample.py resample_variable():703 [1] Resampling ctd2_conductivity with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,548 resample.py resample_variable():703 [1] Resampling ctd2_salinity with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,672 resample.py resample_variable():703 [1] Resampling ctd2_temperature_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,795 resample.py resample_variable():703 [1] Resampling ctd2_conductivity_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:14,920 resample.py resample_variable():703 [1] Resampling ctd2_salinity_onboard with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,043 resample.py resample_variable():703 [1] Resampling ctd2_flow2 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,166 resample.py resample_variable():703 [1] Resampling ctd2_par with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,195 resample.py resample_variable():703 [1] Resampling lopc_countListSum with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,217 resample.py resample_variable():703 [1] Resampling lopc_transCount with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,234 resample.py resample_variable():703 [1] Resampling lopc_nonTransCount with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,251 resample.py resample_variable():703 [1] Resampling lopc_LCcount with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,268 resample.py resample_variable():703 [1] Resampling lopc_flowSpeed with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,566 resample.py resample_variable():703 [1] Resampling ecopuck_bbp700 with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:15,913 resample.py resample_variable():703 [1] Resampling ecopuck_cdom with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:16,257 resample.py resample_variable():703 [1] Resampling ecopuck_chl with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:16,437 resample.py resample_variable():703 [1] Resampling tailcone_propRpm with frequency 1S following 3 point median filter INFO 2024-11-13 19:35:17,715 resample.py resample_mission():942 [1] Saved resampled mission to /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_1S.nc INFO 2024-11-13 19:35:21,910 create_products.py _get_best_ctd():376 [1] ctdToUse = ctd1 INFO 2024-11-13 19:35:58,184 create_products.py gulper_odv():514 [1] Wrote 20 Gulper data lines to /data/auv_data/dorado/missionodvs/2024.317.01/dorado_2024.317.01_1S_Gulper.txt INFO 2024-11-13 19:35:58,184 process.py archive():307 [1] Archiving steps for 2024.317.01 INFO 2024-11-13 19:35:58,188 archive.py copy_to_AUVTCD():54 [1] Archiving /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01 files to /Volumes/AUVCTD/surveys/2024/netcdf INFO 2024-11-13 19:35:58,379 archive.py copy_to_AUVTCD():73 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_1S.nc /Volumes/AUVCTD/surveys/2024/netcdf done. INFO 2024-11-13 19:35:58,943 archive.py copy_to_AUVTCD():73 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_cal.nc /Volumes/AUVCTD/surveys/2024/netcdf done. INFO 2024-11-13 19:35:59,497 archive.py copy_to_AUVTCD():73 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_align.nc /Volumes/AUVCTD/surveys/2024/netcdf done. INFO 2024-11-13 19:35:59,676 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/ctdDriver.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:35:59,850 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/ctdDriver2.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:35:59,909 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/gps.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:00,050 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/hydroscatlog.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:00,620 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/navigation.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:00,811 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/parosci.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:00,969 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/FLBBCD2K.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:01,112 archive.py copy_to_AUVTCD():89 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/tailCone.nc /Volumes/AUVCTD/missionnetcdfs/2024/2024317/2024.317.01 done. INFO 2024-11-13 19:36:01,112 archive.py copy_to_AUVTCD():94 [1] Archiving product files INFO 2024-11-13 19:36:01,165 archive.py copy_to_AUVTCD():103 [1] rsync /data/auv_data/dorado/missionodvs/2024.317.01/* /Volumes/AUVCTD/surveys/2024/odv done. INFO 2024-11-13 19:36:01,166 archive.py copy_to_AUVTCD():116 [1] rsync /data/auv_data/dorado/missionnetcdfs/2024.317.01/dorado_2024.317.01_processing.log /Volumes/AUVCTD/surveys/2024/netcdf