2022-01-30T16:43:21.596Z,1643561001.596 [CommandExec](IMPORTANT): got command restart logs 2022-01-30T16:43:27.988Z,1643561007.988 [CommandExec](IMPORTANT): got command schedule clear 2022-01-30T16:43:27.988Z,1643561007.988 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2022-01-30T16:43:27.989Z,1643561007.989 [CommandExec](IMPORTANT): got command schedule resume 2022-01-30T16:43:27.989Z,1643561007.989 [CommandExec](IMPORTANT): Scheduling is resumed 2022-01-30T16:43:38.986Z,1643561018.986 [NAL9602](INFO): SBD MO Status=1, MOMSN=35332, MT Status=0, MTMSN=0 2022-01-30T16:43:39.033Z,1643561019.033 [NAL9602](INFO): Sent 52 bytes from file Logs/20220129T185242/Courier0036.lzma 2022-01-30T16:43:39.033Z,1643561019.033 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:43:49.231Z,1643561029.231 [NAL9602](INFO): SBD MO Status=1, MOMSN=35333, MT Status=0, MTMSN=0 2022-01-30T16:43:49.282Z,1643561029.282 [NAL9602](INFO): Sent 160 bytes from file Logs/20220129T185242/Express0034.lzma 2022-01-30T16:43:49.282Z,1643561029.282 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:43:59.894Z,1643561039.894 [NAL9602](INFO): SBD MO Status=1, MOMSN=35334, MT Status=0, MTMSN=0 2022-01-30T16:43:59.944Z,1643561039.944 [NAL9602](INFO): Sent 107 bytes from file Logs/20220129T185242/Express0037.lzma 2022-01-30T16:43:59.944Z,1643561039.944 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:44:03.191Z,1643561043.191 [NAL9602](INFO): SBD MO Status=0, MOMSN=35335, MT Status=0, MTMSN=0 2022-01-30T16:44:21.450Z,1643561061.450 [NAL9602](INFO): SBD MO Status=1, MOMSN=35336, MT Status=0, MTMSN=0 2022-01-30T16:44:21.508Z,1643561061.508 [NAL9602](INFO): Sent 170 bytes from file Logs/20220130T164321/Courier0000.lzma 2022-01-30T16:44:21.508Z,1643561061.508 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:44:29.354Z,1643561069.354 [NAL9602](INFO): SBD MO Status=1, MOMSN=35337, MT Status=0, MTMSN=0 2022-01-30T16:44:29.402Z,1643561069.402 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0001.lzma 2022-01-30T16:44:29.402Z,1643561069.402 [NAL9602](INFO): Packets left to send: 2 2022-01-30T16:44:38.882Z,1643561078.882 [NAL9602](INFO): SBD MO Status=1, MOMSN=35338, MT Status=0, MTMSN=0 2022-01-30T16:44:38.932Z,1643561078.932 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0001.lzma 2022-01-30T16:44:38.932Z,1643561078.932 [NAL9602](INFO): Packets left to send: 1 2022-01-30T16:44:48.767Z,1643561088.767 [NAL9602](INFO): SBD MO Status=1, MOMSN=35339, MT Status=0, MTMSN=0 2022-01-30T16:44:48.818Z,1643561088.818 [NAL9602](INFO): Sent 2 bytes from file Logs/20220130T164321/Express0001.lzma 2022-01-30T16:44:48.818Z,1643561088.818 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:44:54.458Z,1643561094.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=35340, MT Status=0, MTMSN=0 2022-01-30T16:44:54.551Z,1643561094.551 [Default:CheckIn:Read_Iridium] Stopped 2022-01-30T16:44:54.552Z,1643561094.552 [Default:CheckIn:C.Wait] Running Loop=1 2022-01-30T16:44:54.552Z,1643561094.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-01-30T16:45:25.145Z,1643561125.145 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-30T16:46:15.239Z,1643561175.239 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2022-01-30T16:47:58.752Z,1643561278.752 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-30T16:48:15.635Z,1643561295.635 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T16:48:15.637Z,1643561295.637 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T16:49:55.066Z,1643561395.066 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-01-30T16:49:55.066Z,1643561395.066 [Default:CheckIn:C.Wait] Stopped 2022-01-30T16:49:55.066Z,1643561395.066 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-01-30T16:49:55.067Z,1643561395.067 [Default:CheckIn:D] Running Loop=1 2022-01-30T16:49:55.432Z,1643561395.432 [Default:CheckIn:D] Stopped 2022-01-30T16:49:55.432Z,1643561395.432 [Default:CheckIn:E] Running Loop=1 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.331018 min 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn:E] Stopped 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn] Stopped 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn](INFO): Running loop #3 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn] Running Loop=3 2022-01-30T16:49:55.847Z,1643561395.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-01-30T16:49:55.848Z,1643561395.848 [Default:CheckIn:Read_GPS] Running Loop=1 2022-01-30T16:49:57.847Z,1643561397.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164959.00,A,4109.54249,N,07053.18055,W,1.749,159.48,300122,,,A*78 2022-01-30T16:49:57.849Z,1643561397.849 [NAL9602](INFO): GPS fix at 20220130T164959: (41.159042, -70.886342) 2022-01-30T16:49:57.861Z,1643561397.861 [Default:CheckIn:Read_GPS] Stopped 2022-01-30T16:49:57.861Z,1643561397.861 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-01-30T16:50:14.476Z,1643561414.476 [NAL9602](INFO): SBD MO Status=1, MOMSN=35341, MT Status=0, MTMSN=0 2022-01-30T16:50:14.524Z,1643561414.524 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Courier0003.lzma 2022-01-30T16:50:14.524Z,1643561414.524 [NAL9602](INFO): Packets left to send: 1 2022-01-30T16:50:26.252Z,1643561426.252 [NAL9602](INFO): SBD MO Status=1, MOMSN=35342, MT Status=0, MTMSN=0 2022-01-30T16:50:26.316Z,1643561426.316 [NAL9602](INFO): Sent 107 bytes from file Logs/20220130T164321/Courier0003.lzma 2022-01-30T16:50:26.316Z,1643561426.316 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:50:51.914Z,1643561451.914 [NAL9602](INFO): SBD MO Status=1, MOMSN=35343, MT Status=0, MTMSN=0 2022-01-30T16:50:51.964Z,1643561451.964 [NAL9602](INFO): Sent 161 bytes from file Logs/20220130T164321/Express0004.lzma 2022-01-30T16:50:51.964Z,1643561451.964 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:51:01.684Z,1643561461.684 [NAL9602](INFO): SBD MO Status=0, MOMSN=35344, MT Status=0, MTMSN=0 2022-01-30T16:51:01.769Z,1643561461.769 [Default:CheckIn:Read_Iridium] Stopped 2022-01-30T16:51:01.770Z,1643561461.770 [Default:CheckIn:C.Wait] Running Loop=1 2022-01-30T16:51:01.770Z,1643561461.770 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-01-30T16:51:32.393Z,1643561492.393 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-30T16:52:22.912Z,1643561542.912 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T16:56:02.296Z,1643561762.296 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-01-30T16:56:02.296Z,1643561762.296 [Default:CheckIn:C.Wait] Stopped 2022-01-30T16:56:02.296Z,1643561762.296 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-01-30T16:56:02.296Z,1643561762.296 [Default:CheckIn:D] Running Loop=1 2022-01-30T16:56:02.721Z,1643561762.721 [Default:CheckIn:D] Stopped 2022-01-30T16:56:02.721Z,1643561762.721 [Default:CheckIn:E] Running Loop=1 2022-01-30T16:56:03.104Z,1643561763.104 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.452494 min 2022-01-30T16:56:03.104Z,1643561763.104 [Default:CheckIn:E] Stopped 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn] Stopped 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn](INFO): Running loop #4 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn] Running Loop=4 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-01-30T16:56:03.105Z,1643561763.105 [Default:CheckIn:Read_GPS] Running Loop=1 2022-01-30T16:56:05.104Z,1643561765.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165606.00,A,4109.45444,N,07053.10269,W,1.322,154.48,300122,,,A*76 2022-01-30T16:56:05.106Z,1643561765.106 [NAL9602](INFO): GPS fix at 20220130T165606: (41.157574, -70.885045) 2022-01-30T16:56:05.118Z,1643561765.118 [Default:CheckIn:Read_GPS] Stopped 2022-01-30T16:56:05.118Z,1643561765.118 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-01-30T16:56:44.354Z,1643561804.354 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35345, MT Status=1, MTMSN=1392 2022-01-30T16:56:44.412Z,1643561804.412 [NAL9602](INFO): Sent 72 bytes from file Logs/20220130T164321/Courier0006.lzma 2022-01-30T16:56:44.412Z,1643561804.412 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:56:45.076Z,1643561805.076 [NAL9602](INFO): Received command: sched asap "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 240 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree" 161ip 1 3 2022-01-30T16:56:45.096Z,1643561805.096 [CommandExec](IMPORTANT): got command schedule asap "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 240 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree" 161ip 1 3.000000 2022-01-30T16:56:45.097Z,1643561805.097 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=161ip 2022-01-30T16:56:45.098Z,1643561805.098 [CommandExec](IMPORTANT): Scheduled #9 (#1 of 3 with id='161ip'): "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 240 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree", ASAP 2022-01-30T16:57:01.889Z,1643561821.889 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35346, MT Status=1, MTMSN=1393 2022-01-30T16:57:01.942Z,1643561821.942 [NAL9602](INFO): Sent 130 bytes from file Logs/20220130T164321/Express0007.lzma 2022-01-30T16:57:01.942Z,1643561821.942 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:57:02.640Z,1643561822.640 [NAL9602](INFO): Received command: sched asap "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m" 161ip 2 3 2022-01-30T16:57:02.657Z,1643561822.657 [CommandExec](IMPORTANT): got command schedule asap "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m" 161ip 2 3.000000 2022-01-30T16:57:02.657Z,1643561822.657 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=161ip 2022-01-30T16:57:02.659Z,1643561822.659 [CommandExec](IMPORTANT): Scheduled #10 (#2 of 3 with id='161ip'): "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m", ASAP 2022-01-30T16:57:10.482Z,1643561830.482 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=35347, MT Status=1, MTMSN=1394 2022-01-30T16:57:11.084Z,1643561831.084 [NAL9602](INFO): Received command: sched asap "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 10 min;run" 161ip 3 3 2022-01-30T16:57:11.135Z,1643561831.135 [CommandExec](IMPORTANT): got command schedule asap "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 10 min;run" 161ip 3 3.000000 2022-01-30T16:57:11.136Z,1643561831.136 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=161ip 2022-01-30T16:57:11.137Z,1643561831.137 [CommandExec](IMPORTANT): Scheduled #11 (#3 of 3 with id='161ip'): "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 10 min;run", ASAP 2022-01-30T16:57:11.205Z,1643561831.205 [CommandExec](IMPORTANT): got command load ./Missions/Science/sci2.xml 2022-01-30T16:57:11.206Z,1643561831.206 [MissionManager](INFO): Loading Mission from file: ./Missions/Science/sci2.xml 2022-01-30T16:57:11.390Z,1643561831.390 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h 2022-01-30T16:57:11.392Z,1643561831.392 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min 2022-01-30T16:57:11.394Z,1643561831.394 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count 2022-01-30T16:57:11.397Z,1643561831.397 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg 2022-01-30T16:57:11.399Z,1643561831.399 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg 2022-01-30T16:57:11.401Z,1643561831.401 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg 2022-01-30T16:57:11.403Z,1643561831.403 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg 2022-01-30T16:57:11.405Z,1643561831.405 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg 2022-01-30T16:57:11.407Z,1643561831.407 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg 2022-01-30T16:57:11.409Z,1643561831.409 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg 2022-01-30T16:57:11.411Z,1643561831.411 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg 2022-01-30T16:57:11.413Z,1643561831.413 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg 2022-01-30T16:57:11.415Z,1643561831.415 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg 2022-01-30T16:57:11.418Z,1643561831.418 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg 2022-01-30T16:57:11.420Z,1643561831.420 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg 2022-01-30T16:57:11.422Z,1643561831.422 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg 2022-01-30T16:57:11.424Z,1643561831.424 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg 2022-01-30T16:57:11.426Z,1643561831.426 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s 2022-01-30T16:57:11.428Z,1643561831.428 [MissionManager](INFO): DefineArg sci2.CaptureRadius = nan m 2022-01-30T16:57:11.430Z,1643561831.430 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m 2022-01-30T16:57:11.461Z,1643561831.461 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m 2022-01-30T16:57:11.463Z,1643561831.463 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m 2022-01-30T16:57:11.481Z,1643561831.481 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg 2022-01-30T16:57:11.483Z,1643561831.483 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg 2022-01-30T16:57:11.501Z,1643561831.501 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 154.000000 cc 2022-01-30T16:57:11.503Z,1643561831.503 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.600000 cm 2022-01-30T16:57:11.506Z,1643561831.506 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m 2022-01-30T16:57:11.516Z,1643561831.516 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m 2022-01-30T16:57:11.518Z,1643561831.518 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km 2022-01-30T16:57:11.519Z,1643561831.519 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml 2022-01-30T16:57:11.878Z,1643561831.878 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool 2022-01-30T16:57:11.879Z,1643561831.879 [MissionManager](INFO): DefineArg sci2:Science.TimeWindowPeakReport = nan min 2022-01-30T16:57:11.881Z,1643561831.881 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool 2022-01-30T16:57:11.883Z,1643561831.883 [MissionManager](INFO): DefineArg sci2:Science.HighestSaltPeakReportActive = 0 bool 2022-01-30T16:57:11.885Z,1643561831.885 [MissionManager](INFO): DefineArg sci2:Science.HighestOilPeakReportActive = 0 bool 2022-01-30T16:57:11.887Z,1643561831.887 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool 2022-01-30T16:57:11.888Z,1643561831.888 [MissionManager](INFO): DefineArg sci2:Science.DetectTrough = 0 bool 2022-01-30T16:57:11.890Z,1643561831.890 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count 2022-01-30T16:57:11.892Z,1643561831.892 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count 2022-01-30T16:57:11.894Z,1643561831.894 [MissionManager](INFO): DefineArg sci2:Science.OffPeakFractionHorizontal = 80.000000 % 2022-01-30T16:57:11.896Z,1643561831.896 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool 2022-01-30T16:57:11.898Z,1643561831.898 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool 2022-01-30T16:57:11.900Z,1643561831.900 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectFDOMActive = 0 bool 2022-01-30T16:57:11.902Z,1643561831.902 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectSalinityActive = 0 bool 2022-01-30T16:57:11.904Z,1643561831.904 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2022-01-30T16:57:11.906Z,1643561831.906 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 0 bool 2022-01-30T16:57:11.908Z,1643561831.908 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0 bool 2022-01-30T16:57:11.910Z,1643561831.910 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1 bool 2022-01-30T16:57:11.913Z,1643561831.913 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 0 bool 2022-01-30T16:57:11.915Z,1643561831.915 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1 bool 2022-01-30T16:57:11.917Z,1643561831.917 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsUBAT = 0 bool 2022-01-30T16:57:11.919Z,1643561831.919 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count 2022-01-30T16:57:12.001Z,1643561832.001 [MissionManager](INFO): DefineArg sci2:Science.MedianFilterLen = 5.000000 count 2022-01-30T16:57:12.003Z,1643561832.003 [MissionManager](INFO): DefineArg sci2:Science.PeakShallowBound = nan m 2022-01-30T16:57:12.042Z,1643561832.042 [MissionManager](INFO): DefineArg sci2:Science.PeakDeepBound = nan m 2022-01-30T16:57:12.045Z,1643561832.045 [MissionManager](INFO): DefineArg sci2:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2022-01-30T16:57:12.047Z,1643561832.047 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l 2022-01-30T16:57:12.062Z,1643561832.062 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m 2022-01-30T16:57:12.070Z,1643561832.070 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC 2022-01-30T16:57:12.073Z,1643561832.073 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg 2022-01-30T16:57:12.075Z,1643561832.075 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg 2022-01-30T16:57:12.106Z,1643561832.106 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l 2022-01-30T16:57:12.112Z,1643561832.112 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m 2022-01-30T16:57:12.114Z,1643561832.114 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg 2022-01-30T16:57:12.137Z,1643561832.137 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg 2022-01-30T16:57:12.139Z,1643561832.139 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3 2022-01-30T16:57:12.161Z,1643561832.161 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m 2022-01-30T16:57:12.163Z,1643561832.163 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg 2022-01-30T16:57:12.174Z,1643561832.174 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg 2022-01-30T16:57:12.185Z,1643561832.185 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOM = 0.000000 ppb 2022-01-30T16:57:12.187Z,1643561832.187 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMDepth = 0.000000 m 2022-01-30T16:57:12.202Z,1643561832.202 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMTemperature = nan degC 2022-01-30T16:57:12.209Z,1643561832.209 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLatitude = nan arcdeg 2022-01-30T16:57:12.211Z,1643561832.211 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLongitude = nan arcdeg 2022-01-30T16:57:12.221Z,1643561832.221 [MissionManager](INFO): DefineOutput sci2:Science.PatchFDOMDistance = nan m 2022-01-30T16:57:12.223Z,1643561832.223 [MissionManager](INFO): DefineOutput sci2:Science.PeakSalt = 0.000000 psu 2022-01-30T16:57:12.230Z,1643561832.230 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltDepth = 0.000000 m 2022-01-30T16:57:12.237Z,1643561832.237 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltTemperature = 0.000000 degC 2022-01-30T16:57:12.239Z,1643561832.239 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltLatitude = nan arcdeg 2022-01-30T16:57:12.249Z,1643561832.249 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltLongitude = nan arcdeg 2022-01-30T16:57:12.251Z,1643561832.251 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 ug/l 2022-01-30T16:57:12.258Z,1643561832.258 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m 2022-01-30T16:57:12.264Z,1643561832.264 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg 2022-01-30T16:57:12.267Z,1643561832.267 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg 2022-01-30T16:57:12.277Z,1643561832.277 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m 2022-01-30T16:57:12.279Z,1643561832.279 [MissionManager](INFO): DefineOutput sci2:Science.PatchSalt = 0.000000 psu 2022-01-30T16:57:12.286Z,1643561832.286 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltDepth = 0.000000 m 2022-01-30T16:57:12.292Z,1643561832.292 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltLatitude = nan arcdeg 2022-01-30T16:57:12.295Z,1643561832.295 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltLongitude = nan arcdeg 2022-01-30T16:57:12.305Z,1643561832.305 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltDistance = nan m 2022-01-30T16:57:12.307Z,1643561832.307 [MissionManager](INFO): DefineOutput sci2:Science.PatchOil = 0.000000 psu 2022-01-30T16:57:12.314Z,1643561832.314 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilDepth = 0.000000 m 2022-01-30T16:57:12.320Z,1643561832.320 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilLatitude = nan arcdeg 2022-01-30T16:57:12.323Z,1643561832.323 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilLongitude = nan arcdeg 2022-01-30T16:57:12.329Z,1643561832.329 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilDistance = nan m 2022-01-30T16:57:12.368Z,1643561832.368 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-30T16:57:12.395Z,1643561832.395 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-30T16:57:12.423Z,1643561832.423 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-30T16:57:12.459Z,1643561832.459 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-30T16:57:12.513Z,1643561832.513 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-30T16:57:12.566Z,1643561832.566 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-30T16:57:12.585Z,1643561832.585 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-30T16:57:12.616Z,1643561832.616 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-30T16:57:12.654Z,1643561832.654 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool 2022-01-30T16:57:12.655Z,1643561832.655 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2022-01-30T16:57:12.769Z,1643561832.769 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h 2022-01-30T16:57:12.771Z,1643561832.771 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min 2022-01-30T16:57:12.773Z,1643561832.773 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg 2022-01-30T16:57:12.775Z,1643561832.775 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s 2022-01-30T16:57:12.778Z,1643561832.778 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s 2022-01-30T16:57:12.780Z,1643561832.780 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min 2022-01-30T16:57:12.782Z,1643561832.782 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min 2022-01-30T16:57:12.788Z,1643561832.788 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-01-30T16:57:12.836Z,1643561832.836 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2022-01-30T16:57:12.861Z,1643561832.861 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m 2022-01-30T16:57:12.863Z,1643561832.863 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m 2022-01-30T16:57:12.866Z,1643561832.866 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m 2022-01-30T16:57:12.867Z,1643561832.867 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-01-30T16:57:12.872Z,1643561832.872 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-01-30T16:57:12.877Z,1643561832.877 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2022-01-30T16:57:12.960Z,1643561832.960 [sci2:I.Pitch](DEBUG): Construct. 2022-01-30T16:57:12.980Z,1643561832.980 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy. 2022-01-30T16:57:12.983Z,1643561832.983 [sci2:K.SetSpeed](DEBUG): Construct. 2022-01-30T16:57:12.985Z,1643561832.985 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-01-30T16:57:13.005Z,1643561833.005 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-01-30T16:57:13.025Z,1643561833.025 [sci2:N.YoYo](DEBUG): Construct YoYo. 2022-01-30T16:57:13.049Z,1643561833.049 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.055Z,1643561833.055 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.061Z,1643561833.061 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.066Z,1643561833.066 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.095Z,1643561833.095 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.100Z,1643561833.100 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.105Z,1643561833.105 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-30T16:57:13.140Z,1643561833.140 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoints, with science turned on. Maximum duration of mission 2 How often to surface for commumications 60 Number of times the vehicle should try to cycle through waypoints.. 1 Latitude of waypoint 1. If nan, waypoint is skipped. NaN Longitude of waypoint 1. If nan, waypoint is skipped. NaN Latitude of waypoint 2. If nan, waypoint is skipped. NaN Longitude of waypoint 2. If nan, waypoint is skipped. NaN Latitude of waypoint 3. If nan, waypoint is skipped. NaN Longitude of waypoint 3. If nan, waypoint is skipped. NaN Latitude of waypoint 4. If nan, waypoint is skipped. NaN Longitude of waypoint 4. If nan, waypoint is skipped. NaN Latitude of waypoint 5. If nan, waypoint is skipped. NaN Longitude of waypoint 5. If nan, waypoint is skipped. NaN Latitude of waypoint 6. If nan, waypoint is skipped. NaN Longitude of waypoint 6. If nan, waypoint is skipped. NaN Latitude of waypoint 7. If nan, waypoint is skipped. NaN Longitude of waypoint 7. If nan, waypoint is skipped. NaN Speed while performing the YoYo behavior. 1 Speed while performing the YoYo behavior. NaN Minimum depth while performing the YoYo behavior. 2 Maximum depth while performing the YoYo behavior. 200 Minimum altitude while performing the YoYo behavior (for bottom-terminated YoYos). 9 Vehicle up pitch while performing the YoYo behavior. 20 Vehicle down pitch while performing the YoYo behavior. -20 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc for active buoyancy Static setting for mass during the mission. Set to NaN mm for active mass position Minimum height above the sea floor for the entire mission. 5 Maximum depth for the entire mission. 205 Minimum offshore distance for the entire mission. 2 Most missions will run the science sensors. If you don't place this aggregate above NeedComms, science instruments get turned off on the last upcast and while floating on the surface. Another depth envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for depth-terminated YoYos. Another altitude envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for bottom-terminated YoYos. 2022-01-30T16:57:13.140Z,1643561833.140 [CommandExec](IMPORTANT): Loaded ./Missions/Science/sci2.xml 2022-01-30T16:57:16.419Z,1643561836.419 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2022-01-30T16:57:19.137Z,1643561839.137 [CommandExec](IMPORTANT): got command set sci2.MissionTimeout 1260 minute 2022-01-30T16:57:19.138Z,1643561839.138 [CommandExec](IMPORTANT): got command set sci2.NeedCommsTime 240 minute 2022-01-30T16:57:19.138Z,1643561839.138 [CommandExec](IMPORTANT): got command set sci2.Lat1 41.3764 degree 2022-01-30T16:57:19.139Z,1643561839.139 [CommandExec](IMPORTANT): got command set sci2.Lon1 -71.0111 degree 2022-01-30T16:57:19.140Z,1643561839.140 [CommandExec](IMPORTANT): got command set sci2.Lat2 41.430037 degree 2022-01-30T16:57:19.141Z,1643561839.141 [CommandExec](IMPORTANT): got command set sci2.Lon2 -70.998901 degree 2022-01-30T16:57:27.185Z,1643561847.185 [CommandExec](IMPORTANT): got command set sci2.Lat3 41.470377 degree 2022-01-30T16:57:27.186Z,1643561847.186 [CommandExec](IMPORTANT): got command set sci2.Lon3 -70.920415 degree 2022-01-30T16:57:27.187Z,1643561847.187 [CommandExec](IMPORTANT): got command set sci2.Lat4 41.501148 degree 2022-01-30T16:57:27.187Z,1643561847.187 [CommandExec](IMPORTANT): got command set sci2.Lon4 -70.811485 degree 2022-01-30T16:57:27.188Z,1643561847.188 [CommandExec](IMPORTANT): got command set sci2.Speed .8 meter_per_second 2022-01-30T16:57:27.189Z,1643561847.189 [CommandExec](IMPORTANT): got command set sci2.YoYoMinDepth 4 meter 2022-01-30T16:57:27.189Z,1643561847.189 [CommandExec](IMPORTANT): got command set sci2.YoYoMaxDepth 40 meter 2022-01-30T16:57:27.190Z,1643561847.190 [CommandExec](IMPORTANT): got command set sci2.YoYoMinAltitude 8 meter 2022-01-30T16:57:31.423Z,1643561851.423 [CommandExec](IMPORTANT): got command set sci2.MinAltitude 6 meter 2022-01-30T16:57:31.423Z,1643561851.423 [CommandExec](IMPORTANT): got command set sci2.MaxDepth 45 meter 2022-01-30T16:57:31.424Z,1643561851.424 [CommandExec](IMPORTANT): got command set sci2:NeedComms.CommsTimeout 10 minute 2022-01-30T16:57:31.425Z,1643561851.425 [CommandExec](IMPORTANT): got command run 2022-01-30T16:57:31.427Z,1643561851.427 [CommandExec](IMPORTANT): Running 2022-01-30T16:57:31.520Z,1643561851.520 [Default] Stopped 2022-01-30T16:57:31.520Z,1643561851.520 [Default](DEBUG): Aggregate::uninitialize Default 2022-01-30T16:57:31.521Z,1643561851.521 [Default:B.GoToSurface] Stopped 2022-01-30T16:57:31.521Z,1643561851.521 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-30T16:57:31.521Z,1643561851.521 [Default:CheckIn] Stopped 2022-01-30T16:57:31.521Z,1643561851.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-01-30T16:57:31.521Z,1643561851.521 [Default:CheckIn:Read_Iridium] Stopped 2022-01-30T16:57:31.521Z,1643561851.521 [MissionManager](IMPORTANT): Started mission sci2 2022-01-30T16:57:31.521Z,1643561851.521 [sci2] Running Loop=1 2022-01-30T16:57:31.521Z,1643561851.521 [sci2](DEBUG): Aggregate::initialize sci2 2022-01-30T16:57:31.521Z,1643561851.521 [sci2:Science] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:A] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:B] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:C] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:D] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:E] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:F] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:Read_Oil] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:PeakDetectChl] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2022-01-30T16:57:31.522Z,1643561851.522 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-30T16:57:31.528Z,1643561851.528 [sci2:Science:HighestChlPeakReport] Running Loop=1 2022-01-30T16:57:31.528Z,1643561851.528 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport 2022-01-30T16:57:31.528Z,1643561851.528 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-30T16:57:31.528Z,1643561851.528 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-30T16:57:31.529Z,1643561851.529 [sci2:Science:PeakDetectNO3] Running Loop=1 2022-01-30T16:57:31.529Z,1643561851.529 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3 2022-01-30T16:57:31.529Z,1643561851.529 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2022-01-30T16:57:31.529Z,1643561851.529 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-30T16:57:31.530Z,1643561851.530 [sci2:Science:PeakDetectOil] Running Loop=1 2022-01-30T16:57:31.530Z,1643561851.530 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil 2022-01-30T16:57:31.530Z,1643561851.530 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2022-01-30T16:57:31.530Z,1643561851.530 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-30T16:57:31.531Z,1643561851.531 [sci2:Science:HighestOilPeakReport] Running Loop=1 2022-01-30T16:57:31.531Z,1643561851.531 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestOilPeakReport 2022-01-30T16:57:31.536Z,1643561851.536 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-30T16:57:31.536Z,1643561851.536 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-30T16:57:31.536Z,1643561851.536 [sci2:Science:PeakDetectFDOM] Running Loop=1 2022-01-30T16:57:31.537Z,1643561851.537 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM 2022-01-30T16:57:31.537Z,1643561851.537 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2022-01-30T16:57:31.537Z,1643561851.537 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-30T16:57:31.538Z,1643561851.538 [sci2:Science:PeakDetectSalinity] Running Loop=1 2022-01-30T16:57:31.538Z,1643561851.538 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sci2:Science:PeakDetectSalinity 2022-01-30T16:57:31.538Z,1643561851.538 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2022-01-30T16:57:31.538Z,1643561851.538 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-30T16:57:31.539Z,1643561851.539 [sci2:Science:HighestSaltPeakReport] Running Loop=1 2022-01-30T16:57:31.539Z,1643561851.539 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestSaltPeakReport 2022-01-30T16:57:31.544Z,1643561851.544 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-30T16:57:31.544Z,1643561851.544 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-30T16:57:31.544Z,1643561851.544 [sci2:Science:OceanCurrent] Running Loop=1 2022-01-30T16:57:31.544Z,1643561851.544 [sci2:Science:OceanCurrent](DEBUG): Aggregate::initialize sci2:Science:OceanCurrent 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:Science:OceanCurrent:A.] Running Loop=1 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes] Running Loop=1 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-01-30T16:57:31.545Z,1643561851.545 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:I.Pitch] Running Loop=1 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:I.Pitch](DEBUG): Initialize. 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:J.Buoyancy] Running Loop=1 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:K.SetSpeed] Running Loop=1 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:K.SetSpeed](DEBUG): Initialize. 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:L.DepthEnvelope] Running Loop=1 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-01-30T16:57:31.546Z,1643561851.546 [sci2:M.AltitudeEnvelope] Running Loop=1 2022-01-30T16:57:31.547Z,1643561851.547 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-01-30T16:57:31.547Z,1643561851.547 [sci2:N.YoYo] Running Loop=1 2022-01-30T16:57:31.547Z,1643561851.547 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent. 2022-01-30T16:57:31.547Z,1643561851.547 [sci2:C] Running Loop=1 2022-01-30T16:57:31.552Z,1643561851.552 [sci2:N.YoYo] Running Loop=1 2022-01-30T16:57:31.552Z,1643561851.552 [sci2:M.AltitudeEnvelope] Running Loop=1 2022-01-30T16:57:31.553Z,1643561851.553 [sci2:L.DepthEnvelope] Running Loop=1 2022-01-30T16:57:31.553Z,1643561851.553 [sci2:K.SetSpeed] Running Loop=1 2022-01-30T16:57:31.554Z,1643561851.554 [sci2:J.Buoyancy] Running Loop=1 2022-01-30T16:57:31.554Z,1643561851.554 [sci2:I.Pitch] Running Loop=1 2022-01-30T16:57:31.554Z,1643561851.554 [sci2:StandardEnvelopes] Running Loop=1 2022-01-30T16:57:31.554Z,1643561851.554 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-01-30T16:57:31.554Z,1643561851.554 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-01-30T16:57:31.555Z,1643561851.555 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-01-30T16:57:31.555Z,1643561851.555 [sci2:C] Stopped 2022-01-30T16:57:31.555Z,1643561851.555 [sci2:E] Running Loop=1 2022-01-30T16:57:31.555Z,1643561851.555 [sci2:Science] Running Loop=1 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:HighestSaltPeakReport] Stopped 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestSaltPeakReport 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:PeakDetectSalinity] Stopped 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectSalinity 2022-01-30T16:57:31.561Z,1643561851.561 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectFDOM] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:HighestOilPeakReport] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestOilPeakReport 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectOil] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2022-01-30T16:57:31.562Z,1643561851.562 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:PeakDetectNO3] Stopped 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:HighestChlPeakReport] Stopped 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:PeakDetectChl] Running Loop=1 2022-01-30T16:57:31.563Z,1643561851.563 [sci2:Science:OceanCurrent:A.] Running Loop=1 2022-01-30T16:57:31.569Z,1643561851.569 [sci2:Science:Read_Oil] Running Loop=1 2022-01-30T16:57:31.569Z,1643561851.569 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water 2022-01-30T16:57:31.569Z,1643561851.569 [sci2:Science:F] Stopped 2022-01-30T16:57:31.569Z,1643561851.569 [sci2:Science:E] Running Loop=1 2022-01-30T16:57:31.570Z,1643561851.570 [sci2:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2022-01-30T16:57:31.570Z,1643561851.570 [sci2:Science:D] Stopped 2022-01-30T16:57:31.570Z,1643561851.570 [sci2:Science:C] Running Loop=1 2022-01-30T16:57:31.570Z,1643561851.570 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2022-01-30T16:57:31.571Z,1643561851.571 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2022-01-30T16:57:31.571Z,1643561851.571 [sci2:Science:B] Running Loop=1 2022-01-30T16:57:31.571Z,1643561851.571 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2022-01-30T16:57:31.576Z,1643561851.576 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2022-01-30T16:57:31.576Z,1643561851.576 [sci2:Science:A] Stopped 2022-01-30T16:57:31.741Z,1643561851.741 [WetLabsSeaOWL_UV_A](INFO): Powering up 2022-01-30T16:57:31.889Z,1643561851.889 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-01-30T16:57:31.890Z,1643561851.890 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-01-30T16:57:31.898Z,1643561851.898 [sci2:E] Stopped 2022-01-30T16:57:31.899Z,1643561851.899 [sci2:F] Running Loop=1 2022-01-30T16:57:32.297Z,1643561852.297 [sci2:F] Stopped 2022-01-30T16:57:32.298Z,1643561852.298 [sci2:G] Running Loop=1 2022-01-30T16:57:32.747Z,1643561852.747 [sci2:G] Stopped 2022-01-30T16:57:32.747Z,1643561852.747 [sci2:StartingMission] Running Loop=1 2022-01-30T16:57:32.747Z,1643561852.747 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission 2022-01-30T16:57:33.104Z,1643561853.104 [sci2:NeedComms] Running Loop=1 2022-01-30T16:57:33.105Z,1643561853.105 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-30T16:57:33.105Z,1643561853.105 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-30T16:57:33.105Z,1643561853.105 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-30T16:57:33.105Z,1643561853.105 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-30T16:57:33.105Z,1643561853.105 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-30T16:57:33.106Z,1643561853.106 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-30T16:57:33.106Z,1643561853.106 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-30T16:57:33.106Z,1643561853.106 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-30T16:57:33.107Z,1643561853.107 [sci2:NeedComms:A] Running Loop=1 2022-01-30T16:57:33.108Z,1643561853.108 [sci2:NeedComms:A](INFO): last time_fix was: 1643561766.000000 second since 1970/01/01T00:00:00Z 2022-01-30T16:57:33.108Z,1643561853.108 [sci2:NeedComms:A] Stopped 2022-01-30T16:57:33.495Z,1643561853.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=35348, MT Status=0, MTMSN=0 2022-01-30T16:57:33.787Z,1643561853.787 [sci2:NeedComms:C] Running Loop=1 2022-01-30T16:57:33.924Z,1643561853.924 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-01-30T16:57:34.801Z,1643561854.801 [CTD_Seabird](ERROR): Failed to parse device response: 2022-01-30T16:57:35.111Z,1643561855.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165736.00,A,4109.43252,N,07053.08578,W,0.991,139.56,300122,,,A*7A 2022-01-30T16:57:35.113Z,1643561855.113 [NAL9602](INFO): GPS fix at 20220130T165736: (41.157209, -70.884763) 2022-01-30T16:57:35.149Z,1643561855.149 [sci2:NeedComms:C] Stopped 2022-01-30T16:57:35.149Z,1643561855.149 [sci2:NeedComms:D] Running Loop=1 2022-01-30T16:57:35.552Z,1643561855.552 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-01-30T16:57:50.967Z,1643561870.967 [NAL9602](INFO): SBD MO Status=1, MOMSN=35349, MT Status=0, MTMSN=0 2022-01-30T16:57:51.016Z,1643561871.016 [NAL9602](INFO): Sent 52 bytes from file Logs/20220130T164321/Courier0009.lzma 2022-01-30T16:57:51.016Z,1643561871.016 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:58:00.734Z,1643561880.734 [NAL9602](INFO): SBD MO Status=1, MOMSN=35350, MT Status=0, MTMSN=0 2022-01-30T16:58:00.796Z,1643561880.796 [NAL9602](INFO): Sent 78 bytes from file Logs/20220130T164321/Courier0012.lzma 2022-01-30T16:58:00.796Z,1643561880.796 [NAL9602](INFO): Packets left to send: 0 2022-01-30T16:58:13.063Z,1643561893.063 [NAL9602](INFO): SBD MO Status=1, MOMSN=35351, MT Status=0, MTMSN=0 2022-01-30T16:58:13.112Z,1643561893.112 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0010.lzma 2022-01-30T16:58:13.112Z,1643561893.112 [NAL9602](INFO): Packets left to send: 4 2022-01-30T16:58:30.227Z,1643561910.227 [NAL9602](INFO): SBD MO Status=2, MOMSN=35352, MT Status=2, MTMSN=0 2022-01-30T16:58:30.227Z,1643561910.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-30T16:58:38.561Z,1643561918.561 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-30T16:58:39.066Z,1643561919.066 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:40.070Z,1643561920.070 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:41.074Z,1643561921.074 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:42.078Z,1643561922.078 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:43.082Z,1643561923.082 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:44.086Z,1643561924.086 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:45.089Z,1643561925.089 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:46.094Z,1643561926.094 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:47.098Z,1643561927.098 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:48.102Z,1643561928.102 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:49.106Z,1643561929.106 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T16:58:49.478Z,1643561929.478 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.073329 CHAN A1 (24V): 0.127536 CHAN A2 (12V): 0.044567 CHAN A3 (5V): 0.012630 CHAN B0 (3.3V): 0.009572 CHAN B1 (3.15aV): 0.009091 CHAN B2 (3.15bV): 0.009348 CHAN B3 (GND): -0.006669 OPEN: 0.006354 Full Scale: +/- 1 mA 2022-01-30T16:58:50.636Z,1643561930.636 [NAL9602](INFO): SBD MO Status=1, MOMSN=35352, MT Status=0, MTMSN=0 2022-01-30T16:58:50.690Z,1643561930.690 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0010.lzma 2022-01-30T16:58:50.690Z,1643561930.690 [NAL9602](INFO): Packets left to send: 3 2022-01-30T16:59:02.954Z,1643561942.954 [NAL9602](INFO): SBD MO Status=1, MOMSN=35353, MT Status=0, MTMSN=0 2022-01-30T16:59:03.010Z,1643561943.010 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0010.lzma 2022-01-30T16:59:03.010Z,1643561943.010 [NAL9602](INFO): Packets left to send: 2 2022-01-30T16:59:18.474Z,1643561958.474 [NAL9602](INFO): SBD MO Status=2, MOMSN=35354, MT Status=2, MTMSN=0 2022-01-30T16:59:18.475Z,1643561958.475 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-30T16:59:45.286Z,1643561985.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=35354, MT Status=2, MTMSN=0 2022-01-30T16:59:45.287Z,1643561985.287 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-30T17:00:04.491Z,1643562004.491 [NAL9602](INFO): SBD MO Status=1, MOMSN=35354, MT Status=0, MTMSN=0 2022-01-30T17:00:04.544Z,1643562004.544 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0010.lzma 2022-01-30T17:00:04.544Z,1643562004.544 [NAL9602](INFO): Packets left to send: 1 2022-01-30T17:00:15.614Z,1643562015.614 [NAL9602](INFO): SBD MO Status=1, MOMSN=35355, MT Status=0, MTMSN=0 2022-01-30T17:00:15.676Z,1643562015.676 [NAL9602](INFO): Sent 45 bytes from file Logs/20220130T164321/Express0010.lzma 2022-01-30T17:00:15.676Z,1643562015.676 [NAL9602](INFO): Packets left to send: 0 2022-01-30T17:00:35.194Z,1643562035.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=35356, MT Status=0, MTMSN=0 2022-01-30T17:00:35.252Z,1643562035.252 [NAL9602](INFO): Sent 62 bytes from file Logs/20220130T164321/Express0013.lzma 2022-01-30T17:00:35.252Z,1643562035.252 [NAL9602](INFO): Packets left to send: 0 2022-01-30T17:00:50.985Z,1643562050.985 [NAL9602](INFO): SBD MO Status=0, MOMSN=35357, MT Status=0, MTMSN=0 2022-01-30T17:00:51.086Z,1643562051.086 [sci2:NeedComms:D] Stopped 2022-01-30T17:00:51.086Z,1643562051.086 [sci2:NeedComms:E] Running Loop=1 2022-01-30T17:00:51.405Z,1643562051.405 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-01-30T17:00:53.405Z,1643562053.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170054.00,A,4109.38784,N,07053.05780,W,1.322,139.35,300122,,,A*71 2022-01-30T17:00:53.407Z,1643562053.407 [NAL9602](INFO): GPS fix at 20220130T170054: (41.156464, -70.884297) 2022-01-30T17:00:53.443Z,1643562053.443 [sci2:NeedComms:E] Stopped 2022-01-30T17:00:53.443Z,1643562053.443 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-30T17:00:53.443Z,1643562053.443 [sci2:NeedComms] Stopped 2022-01-30T17:00:53.448Z,1643562053.448 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-30T17:00:53.448Z,1643562053.448 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-30T17:00:53.448Z,1643562053.448 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-30T17:00:53.851Z,1643562053.851 [sci2:StartingMission](INFO): Completed sci2:StartingMission 2022-01-30T17:00:53.851Z,1643562053.851 [sci2:StartingMission] Stopped 2022-01-30T17:00:53.851Z,1643562053.851 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap] Running Loop=1 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap:Wpt1] Running Loop=1 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1 2022-01-30T17:00:53.852Z,1643562053.852 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent. 2022-01-30T17:01:16.899Z,1643562076.899 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm (1 active estimators). 2022-01-30T17:01:25.337Z,1643562085.337 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-30T17:02:57.042Z,1643562177.042 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T17:04:01.284Z,1643562241.284 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-30T17:11:28.939Z,1643562688.939 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T17:15:03.047Z,1643562903.047 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-30T17:31:54.704Z,1643563914.704 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T17:32:24.972Z,1643563944.972 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T17:43:14.997Z,1643564594.997 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T17:47:06.924Z,1643564826.924 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T17:51:40.838Z,1643565100.838 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T17:51:50.670Z,1643565110.670 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T17:52:10.752Z,1643565130.752 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T17:53:00.842Z,1643565180.842 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T17:55:38.813Z,1643565338.813 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T17:55:38.815Z,1643565338.815 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T17:56:04.365Z,1643565364.365 [CTD_Seabird](ERROR): Failed to parse device response: 5.77,90 2022-01-30T18:11:56.931Z,1643566316.931 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T18:12:06.708Z,1643566326.708 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-30T18:12:06.708Z,1643566326.708 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-30T18:12:06.708Z,1643566326.708 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-30T18:12:06.779Z,1643566326.779 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-30T18:12:07.096Z,1643566327.096 [RDI_Pathfinder](INFO): Powering down 2022-01-30T18:12:07.819Z,1643566327.819 [Power24vConverter](INFO): Powering down. 2022-01-30T18:12:07.953Z,1643566327.953 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-30T18:12:07.953Z,1643566327.953 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-30T18:12:08.625Z,1643566328.625 [Power24vConverter](INFO): Powering up. 2022-01-30T18:18:37.068Z,1643566717.068 [ThrusterHE](FAULT): getSpeed error serial timeout 2022-01-30T18:18:37.068Z,1643566717.068 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2022-01-30T18:18:37.068Z,1643566717.068 [ThrusterHE] Communications Fault, FailCount= 1 2022-01-30T18:18:37.068Z,1643566717.068 [ThrusterHE](ERROR): Communications Fault 2022-01-30T18:18:37.072Z,1643566717.072 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2022-01-30T18:18:37.316Z,1643566717.316 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-01-30T18:18:37.316Z,1643566717.316 [ThrusterHE](INFO): Powering down 2022-01-30T18:18:38.114Z,1643566718.114 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-01-30T18:18:38.230Z,1643566718.230 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-01-30T18:18:38.238Z,1643566718.238 [CBIT](INFO): Clearing failed state for component ThrusterHE 2022-01-30T18:18:38.239Z,1643566718.239 [ThrusterHE] No Fault, FailCount= 1 2022-01-30T18:18:43.821Z,1643566723.821 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-01-30T18:24:42.514Z,1643567082.514 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T18:41:42.637Z,1643568102.637 [CTD_Seabird](ERROR): Failed to parse device response: 4,3.11381,4716.07 2022-01-30T18:44:18.577Z,1643568258.577 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T18:58:46.785Z,1643569126.785 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2022-01-30T18:58:49.812Z,1643569129.812 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-30T18:58:49.898Z,1643569129.898 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:50.902Z,1643569130.902 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:51.906Z,1643569131.906 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:52.910Z,1643569132.910 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:53.917Z,1643569133.917 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:54.918Z,1643569134.918 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:55.922Z,1643569135.922 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:56.926Z,1643569136.926 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:57.930Z,1643569137.930 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:58.934Z,1643569138.934 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:58:59.938Z,1643569139.938 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:59:00.941Z,1643569140.941 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T18:59:01.011Z,1643569141.011 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.068467 CHAN A1 (24V): 0.122850 CHAN A2 (12V): 0.041173 CHAN A3 (5V): 0.010365 CHAN B0 (3.3V): 0.007539 CHAN B1 (3.15aV): 0.007269 CHAN B2 (3.15bV): 0.007229 CHAN B3 (GND): -0.006792 OPEN: 0.007943 Full Scale: +/- 1 mA 2022-01-30T19:00:07.210Z,1643569207.210 [CTD_Seabird](ERROR): Failed to parse device response: 72,4704.49 2022-01-30T19:02:59.288Z,1643569379.288 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T19:02:59.290Z,1643569379.290 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T19:14:21.745Z,1643570061.745 [CTD_Seabird](ERROR): Failed to parse device response: 12.72,4.701148 2022-01-30T19:18:42.263Z,1643570322.263 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-30T19:24:10.292Z,1643570650.292 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T19:24:20.409Z,1643570660.409 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-30T19:25:10.497Z,1643570710.497 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T19:25:20.266Z,1643570720.266 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T19:28:06.284Z,1643570886.284 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T19:43:36.279Z,1643571816.279 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T19:43:46.356Z,1643571826.356 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T20:00:06.477Z,1643572806.477 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T20:03:52.314Z,1643573032.314 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T20:04:02.415Z,1643573042.415 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T20:04:22.227Z,1643573062.227 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-30T20:04:34.345Z,1643573074.345 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-01-30T20:10:07.682Z,1643573407.682 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-30T20:10:22.202Z,1643573422.202 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T20:10:22.204Z,1643573422.204 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T20:24:30.123Z,1643574270.123 [CTD_Seabird](ERROR): Failed to parse device response: 6,3.13134,4705.23 2022-01-30T20:45:39.373Z,1643575539.373 [CTD_Seabird](ERROR): Failed to parse device response: 13,4720.40 2022-01-30T20:59:01.260Z,1643576341.260 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-30T20:59:01.718Z,1643576341.718 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:02.721Z,1643576342.721 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:03.726Z,1643576343.726 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:04.729Z,1643576344.729 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:05.734Z,1643576345.734 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:06.738Z,1643576346.738 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:07.742Z,1643576347.742 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:08.746Z,1643576348.746 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:09.750Z,1643576349.750 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:10.753Z,1643576350.753 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:11.762Z,1643576351.762 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T20:59:12.155Z,1643576352.155 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.071976 CHAN A1 (24V): 0.120909 CHAN A2 (12V): 0.037410 CHAN A3 (5V): 0.008005 CHAN B0 (3.3V): 0.005729 CHAN B1 (3.15aV): 0.004960 CHAN B2 (3.15bV): 0.004537 CHAN B3 (GND): -0.006525 OPEN: 0.006824 Full Scale: +/- 1 mA 2022-01-30T21:03:30.203Z,1643576610.203 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T21:03:30.238Z,1643576610.238 [sci2:NeedComms] Running Loop=1 2022-01-30T21:03:30.238Z,1643576610.238 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-30T21:03:30.238Z,1643576610.238 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-30T21:03:30.238Z,1643576610.238 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-30T21:03:30.239Z,1643576610.239 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-30T21:03:30.239Z,1643576610.239 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-30T21:03:30.239Z,1643576610.239 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-30T21:03:30.240Z,1643576610.240 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-30T21:03:30.240Z,1643576610.240 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-30T21:03:30.241Z,1643576610.241 [sci2:NeedComms:A] Running Loop=1 2022-01-30T21:03:30.242Z,1643576610.242 [sci2:NeedComms:A](INFO): last time_fix was: 1643562054.000000 second since 1970/01/01T00:00:00Z 2022-01-30T21:03:30.242Z,1643576610.242 [sci2:NeedComms:A] Stopped 2022-01-30T21:05:04.756Z,1643576704.756 [sci2:NeedComms:C] Running Loop=1 2022-01-30T21:05:07.212Z,1643576707.212 [CTD_Seabird](ERROR): Salinity reading out of range: 25.563904 psu 2022-01-30T21:05:23.274Z,1643576723.274 [CTD_Seabird](ERROR): Salinity reading out of range: 25.739744 psu 2022-01-30T21:05:24.158Z,1643576724.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=35358, MT Status=0, MTMSN=0 2022-01-30T21:05:24.159Z,1643576724.159 [NAL9602](INFO): No messages in MT queue 2022-01-30T21:06:00.434Z,1643576760.434 [CTD_Seabird](ERROR): Salinity reading out of range: 26.998188 psu 2022-01-30T21:08:27.987Z,1643576907.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210829.00,A,4112.74831,N,07055.70556,W,0.894,147.71,300122,,,A*76 2022-01-30T21:08:27.989Z,1643576907.989 [NAL9602](INFO): GPS fix at 20220130T210829: (41.212472, -70.928426) 2022-01-30T21:08:28.018Z,1643576908.018 [UniversalFixResidualReporter](INFO): Fix residual: 11.7 %DT, over the last 7770.6 m. Residual distance 905.3 m at bearing 56.0 degrees. Fix at (41.2125, -70.9284) with 7240.4 m made good. 2022-01-30T21:08:28.023Z,1643576908.023 [sci2:NeedComms:C] Stopped 2022-01-30T21:08:28.023Z,1643576908.023 [sci2:NeedComms:D] Running Loop=1 2022-01-30T21:08:46.242Z,1643576926.242 [NAL9602](INFO): SBD MO Status=1, MOMSN=35359, MT Status=0, MTMSN=0 2022-01-30T21:08:46.300Z,1643576926.300 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Courier0015.lzma 2022-01-30T21:08:46.300Z,1643576926.300 [NAL9602](INFO): Packets left to send: 1 2022-01-30T21:08:54.811Z,1643576934.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=35360, MT Status=0, MTMSN=0 2022-01-30T21:08:54.864Z,1643576934.864 [NAL9602](INFO): Sent 39 bytes from file Logs/20220130T164321/Courier0015.lzma 2022-01-30T21:08:54.864Z,1643576934.864 [NAL9602](INFO): Packets left to send: 0 2022-01-30T21:09:01.923Z,1643576941.923 [NAL9602](INFO): SBD MO Status=1, MOMSN=35361, MT Status=0, MTMSN=0 2022-01-30T21:09:01.972Z,1643576941.972 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:01.972Z,1643576941.972 [NAL9602](INFO): Packets left to send: 9 2022-01-30T21:09:08.622Z,1643576948.622 [NAL9602](INFO): SBD MO Status=1, MOMSN=35362, MT Status=0, MTMSN=0 2022-01-30T21:09:08.680Z,1643576948.680 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:08.680Z,1643576948.680 [NAL9602](INFO): Packets left to send: 8 2022-01-30T21:09:20.544Z,1643576960.544 [NAL9602](INFO): SBD MO Status=1, MOMSN=35363, MT Status=0, MTMSN=0 2022-01-30T21:09:20.594Z,1643576960.594 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:20.594Z,1643576960.594 [NAL9602](INFO): Packets left to send: 7 2022-01-30T21:09:28.771Z,1643576968.771 [NAL9602](INFO): SBD MO Status=1, MOMSN=35364, MT Status=0, MTMSN=0 2022-01-30T21:09:28.824Z,1643576968.824 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:28.824Z,1643576968.824 [NAL9602](INFO): Packets left to send: 6 2022-01-30T21:09:41.954Z,1643576981.954 [NAL9602](INFO): SBD MO Status=1, MOMSN=35365, MT Status=0, MTMSN=0 2022-01-30T21:09:42.001Z,1643576982.001 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:42.001Z,1643576982.001 [NAL9602](INFO): Packets left to send: 5 2022-01-30T21:09:56.711Z,1643576996.711 [NAL9602](INFO): SBD MO Status=1, MOMSN=35366, MT Status=0, MTMSN=0 2022-01-30T21:09:56.761Z,1643576996.761 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:09:56.761Z,1643576996.761 [NAL9602](INFO): Packets left to send: 4 2022-01-30T21:10:05.466Z,1643577005.466 [NAL9602](INFO): SBD MO Status=1, MOMSN=35367, MT Status=0, MTMSN=0 2022-01-30T21:10:05.520Z,1643577005.520 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:10:05.520Z,1643577005.520 [NAL9602](INFO): Packets left to send: 3 2022-01-30T21:10:18.161Z,1643577018.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=35368, MT Status=2, MTMSN=0 2022-01-30T21:10:18.161Z,1643577018.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-30T21:10:36.554Z,1643577036.554 [NAL9602](INFO): SBD MO Status=1, MOMSN=35368, MT Status=0, MTMSN=0 2022-01-30T21:10:36.608Z,1643577036.608 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:10:36.609Z,1643577036.609 [NAL9602](INFO): Packets left to send: 2 2022-01-30T21:10:45.176Z,1643577045.176 [NAL9602](INFO): SBD MO Status=1, MOMSN=35369, MT Status=0, MTMSN=0 2022-01-30T21:10:45.224Z,1643577045.224 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:10:45.224Z,1643577045.224 [NAL9602](INFO): Packets left to send: 1 2022-01-30T21:10:53.526Z,1643577053.526 [NAL9602](INFO): SBD MO Status=1, MOMSN=35370, MT Status=0, MTMSN=0 2022-01-30T21:10:53.573Z,1643577053.573 [NAL9602](INFO): Sent 84 bytes from file Logs/20220130T164321/Express0016.lzma 2022-01-30T21:10:53.573Z,1643577053.573 [NAL9602](INFO): Packets left to send: 0 2022-01-30T21:11:01.677Z,1643577061.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=35371, MT Status=0, MTMSN=0 2022-01-30T21:11:01.766Z,1643577061.766 [sci2:NeedComms:D] Stopped 2022-01-30T21:11:01.766Z,1643577061.766 [sci2:NeedComms:E] Running Loop=1 2022-01-30T21:11:04.126Z,1643577064.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211105.00,A,4112.74935,N,07055.70215,W,0.991,206.43,300122,,,A*76 2022-01-30T21:11:04.128Z,1643577064.128 [NAL9602](INFO): GPS fix at 20220130T211105: (41.212489, -70.928369) 2022-01-30T21:11:04.197Z,1643577064.197 [sci2:NeedComms:E] Stopped 2022-01-30T21:11:04.198Z,1643577064.198 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-30T21:11:04.198Z,1643577064.198 [sci2:NeedComms] Stopped 2022-01-30T21:11:04.198Z,1643577064.198 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-30T21:11:04.198Z,1643577064.198 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-30T21:11:04.198Z,1643577064.198 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-30T21:11:04.918Z,1643577064.918 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-01-30T21:11:04.918Z,1643577064.918 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-01-30T21:11:36.421Z,1643577096.421 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-30T21:12:24.526Z,1643577144.526 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm (1 active estimators). 2022-01-30T21:15:08.571Z,1643577308.571 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-30T21:17:44.115Z,1643577464.115 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T21:17:44.133Z,1643577464.133 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T21:23:36.388Z,1643577816.388 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T21:43:02.374Z,1643578982.374 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T21:43:52.133Z,1643579032.133 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T21:44:12.262Z,1643579052.262 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T22:01:12.363Z,1643580072.363 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T22:02:58.214Z,1643580178.214 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T22:03:28.126Z,1643580208.126 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-30T22:22:41.134Z,1643581361.134 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2022-01-30T22:23:28.425Z,1643581408.425 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T22:24:52.114Z,1643581492.114 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-30T22:25:08.216Z,1643581508.216 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T22:25:08.218Z,1643581508.218 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T22:41:50.165Z,1643582510.165 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T22:42:50.381Z,1643582570.381 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T22:46:43.269Z,1643582803.269 [CTD_Seabird](ERROR): Failed to parse device response: .42 2022-01-30T22:48:23.741Z,1643582903.741 [CTD_Seabird](ERROR): Failed to parse device response: .29 2022-01-30T22:59:12.321Z,1643583552.321 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-30T22:59:12.370Z,1643583552.370 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:13.374Z,1643583553.374 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:14.378Z,1643583554.378 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:15.383Z,1643583555.383 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:16.385Z,1643583556.385 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:17.390Z,1643583557.390 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:18.394Z,1643583558.394 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:19.398Z,1643583559.398 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:20.402Z,1643583560.402 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:21.406Z,1643583561.406 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:22.410Z,1643583562.410 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-30T22:59:23.179Z,1643583563.179 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.069869 CHAN A1 (24V): 0.123635 CHAN A2 (12V): 0.037924 CHAN A3 (5V): 0.007549 CHAN B0 (3.3V): 0.005293 CHAN B1 (3.15aV): 0.004495 CHAN B2 (3.15bV): 0.002062 CHAN B3 (GND): -0.014385 OPEN: 0.006974 Full Scale: +/- 1 mA 2022-01-30T23:01:37.088Z,1643583697.088 [ElevatorServo](ERROR): getPosition uart error serial timeout 2022-01-30T23:01:37.088Z,1643583697.088 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2022-01-30T23:01:37.088Z,1643583697.088 [ElevatorServo] Communications Fault, FailCount= 1 2022-01-30T23:01:37.088Z,1643583697.088 [ElevatorServo](ERROR): Communications Fault 2022-01-30T23:01:37.123Z,1643583697.123 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2022-01-30T23:01:37.193Z,1643583697.193 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-01-30T23:01:37.194Z,1643583697.194 [ElevatorServo](INFO): Powering down 2022-01-30T23:01:38.090Z,1643583698.090 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-01-30T23:01:38.209Z,1643583698.209 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-01-30T23:01:38.303Z,1643583698.303 [CBIT](INFO): Clearing failed state for component ElevatorServo 2022-01-30T23:01:38.303Z,1643583698.303 [ElevatorServo] No Fault, FailCount= 1 2022-01-30T23:01:56.146Z,1643583716.146 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-30T23:02:16.348Z,1643583736.348 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-30T23:19:00.376Z,1643584740.376 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-30T23:22:22.017Z,1643584942.017 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T23:32:33.628Z,1643585553.628 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-30T23:32:33.630Z,1643585553.630 [BPC1](INFO): Received data from all battery sticks. 2022-01-30T23:42:48.151Z,1643586168.151 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T23:42:58.228Z,1643586178.228 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-30T23:43:26.241Z,1643586206.241 [CTD_Seabird](ERROR): Failed to parse device response: 22.52,4.3599,3.698.64 2022-01-31T00:02:39.121Z,1643587359.121 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T00:04:50.477Z,1643587490.477 [CTD_Seabird](ERROR): Failed to parse device response: .21 2022-01-31T00:15:53.185Z,1643588153.185 [CTD_Seabird](ERROR): Failed to parse device response: ,4.0866,3.07241,4707.21 2022-01-31T00:22:25.286Z,1643588545.286 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T00:22:34.994Z,1643588554.994 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T00:22:55.180Z,1643588575.180 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T00:23:35.200Z,1643588615.200 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T00:23:35.200Z,1643588615.200 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T00:23:35.200Z,1643588615.200 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T00:23:35.419Z,1643588615.419 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T00:23:35.652Z,1643588615.652 [RDI_Pathfinder](INFO): Powering down 2022-01-31T00:23:36.527Z,1643588616.527 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T00:23:36.527Z,1643588616.527 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T00:39:54.907Z,1643589594.907 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T00:39:54.909Z,1643589594.909 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T00:40:30.877Z,1643589630.877 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T00:59:23.332Z,1643590763.332 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T00:59:24.033Z,1643590764.033 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:25.038Z,1643590765.038 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:26.041Z,1643590766.041 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:27.045Z,1643590767.045 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:28.050Z,1643590768.050 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:29.054Z,1643590769.054 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:30.057Z,1643590770.057 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:31.062Z,1643590771.062 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:32.066Z,1643590772.066 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:33.070Z,1643590773.070 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:34.073Z,1643590774.073 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T00:59:34.275Z,1643590774.275 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.064587 CHAN A1 (24V): 0.115528 CHAN A2 (12V): 0.036270 CHAN A3 (5V): 0.007157 CHAN B0 (3.3V): 0.004154 CHAN B1 (3.15aV): 0.003656 CHAN B2 (3.15bV): 0.004119 CHAN B3 (GND): -0.006530 OPEN: 0.006603 Full Scale: +/- 1 mA 2022-01-31T00:59:47.128Z,1643590787.128 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T01:00:17.036Z,1643590817.036 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T01:11:10.735Z,1643591470.735 [sci2:NeedComms] Running Loop=1 2022-01-31T01:11:10.735Z,1643591470.735 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T01:11:10.735Z,1643591470.735 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T01:11:10.735Z,1643591470.735 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T01:11:10.735Z,1643591470.735 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T01:11:10.753Z,1643591470.753 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T01:11:10.754Z,1643591470.754 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T01:11:10.755Z,1643591470.755 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T01:11:10.760Z,1643591470.760 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T01:11:10.761Z,1643591470.761 [sci2:NeedComms:A] Running Loop=1 2022-01-31T01:11:10.770Z,1643591470.770 [sci2:NeedComms:A](INFO): last time_fix was: 1643577065.000000 second since 1970/01/01T00:00:00Z 2022-01-31T01:11:10.770Z,1643591470.770 [sci2:NeedComms:A] Stopped 2022-01-31T01:12:00.181Z,1643591520.181 [CTD_Seabird](ERROR): Failed to parse device response: 26,4685.22 2022-01-31T01:13:03.858Z,1643591583.858 [sci2:NeedComms:C] Running Loop=1 2022-01-31T01:13:06.466Z,1643591586.466 [CTD_Seabird](ERROR): Salinity reading out of range: 19.295185 psu 2022-01-31T01:13:08.474Z,1643591588.474 [CTD_Seabird](ERROR): Salinity reading out of range: 20.487541 psu 2022-01-31T01:13:12.490Z,1643591592.490 [CTD_Seabird](ERROR): Salinity reading out of range: 25.703278 psu 2022-01-31T01:13:35.764Z,1643591615.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=35372, MT Status=0, MTMSN=0 2022-01-31T01:13:35.764Z,1643591615.764 [NAL9602](INFO): No messages in MT queue 2022-01-31T01:14:22.775Z,1643591662.775 [CTD_Seabird](ERROR): Salinity reading out of range: 23.341652 psu 2022-01-31T01:14:33.814Z,1643591673.814 [CTD_Seabird](ERROR): Salinity reading out of range: 27.088289 psu 2022-01-31T01:19:13.131Z,1643591953.131 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T01:20:04.050Z,1643592004.050 [sci2:NeedComms:C](INFO): Timed out from 2022-01-31T01:13:03.9Z 2022-01-31T01:20:04.050Z,1643592004.050 [sci2:NeedComms:C:A_Timeout] Running Loop=1 2022-01-31T01:20:04.050Z,1643592004.050 [sci2:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize sci2:NeedComms:C:A_Timeout 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:C:A_Timeout:A] Running Loop=1 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:C:A_Timeout:A] Stopped 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:C:A_Timeout](INFO): Completed sci2:NeedComms:C:A_Timeout 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:C] Stopped 2022-01-31T01:20:04.051Z,1643592004.051 [sci2:NeedComms:D] Running Loop=1 2022-01-31T01:20:28.058Z,1643592028.058 [NAL9602](INFO): SBD MO Status=2, MOMSN=35373, MT Status=2, MTMSN=0 2022-01-31T01:20:28.059Z,1643592028.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T01:20:57.652Z,1643592057.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=35373, MT Status=2, MTMSN=0 2022-01-31T01:20:57.652Z,1643592057.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T01:21:23.628Z,1643592083.628 [NAL9602](INFO): SBD MO Status=2, MOMSN=35373, MT Status=2, MTMSN=0 2022-01-31T01:21:23.628Z,1643592083.628 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T01:21:46.729Z,1643592106.729 [NAL9602](INFO): SBD MO Status=1, MOMSN=35373, MT Status=0, MTMSN=0 2022-01-31T01:21:46.794Z,1643592106.794 [NAL9602](INFO): Sent 97 bytes from file Logs/20220130T164321/Courier0018.lzma 2022-01-31T01:21:46.795Z,1643592106.795 [NAL9602](INFO): Packets left to send: 0 2022-01-31T01:21:54.947Z,1643592114.947 [NAL9602](INFO): SBD MO Status=1, MOMSN=35374, MT Status=0, MTMSN=0 2022-01-31T01:21:54.997Z,1643592114.997 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:21:54.997Z,1643592114.997 [NAL9602](INFO): Packets left to send: 7 2022-01-31T01:22:07.302Z,1643592127.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=35375, MT Status=2, MTMSN=0 2022-01-31T01:22:07.303Z,1643592127.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T01:22:28.123Z,1643592148.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=35375, MT Status=0, MTMSN=0 2022-01-31T01:22:28.172Z,1643592148.172 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:22:28.172Z,1643592148.172 [NAL9602](INFO): Packets left to send: 6 2022-01-31T01:22:36.886Z,1643592156.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=35376, MT Status=0, MTMSN=0 2022-01-31T01:22:36.940Z,1643592156.940 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:22:36.940Z,1643592156.940 [NAL9602](INFO): Packets left to send: 5 2022-01-31T01:22:46.319Z,1643592166.319 [NAL9602](INFO): SBD MO Status=1, MOMSN=35377, MT Status=0, MTMSN=0 2022-01-31T01:22:46.369Z,1643592166.369 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:22:46.369Z,1643592166.369 [NAL9602](INFO): Packets left to send: 4 2022-01-31T01:22:58.805Z,1643592178.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=35378, MT Status=0, MTMSN=0 2022-01-31T01:22:58.855Z,1643592178.855 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:22:58.856Z,1643592178.856 [NAL9602](INFO): Packets left to send: 3 2022-01-31T01:23:10.882Z,1643592190.882 [NAL9602](INFO): SBD MO Status=1, MOMSN=35379, MT Status=0, MTMSN=0 2022-01-31T01:23:10.932Z,1643592190.932 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:23:10.932Z,1643592190.932 [NAL9602](INFO): Packets left to send: 2 2022-01-31T01:23:24.902Z,1643592204.902 [NAL9602](INFO): SBD MO Status=1, MOMSN=35380, MT Status=0, MTMSN=0 2022-01-31T01:23:24.952Z,1643592204.952 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:23:24.952Z,1643592204.952 [NAL9602](INFO): Packets left to send: 1 2022-01-31T01:23:35.278Z,1643592215.278 [NAL9602](INFO): SBD MO Status=1, MOMSN=35381, MT Status=0, MTMSN=0 2022-01-31T01:23:35.332Z,1643592215.332 [NAL9602](INFO): Sent 150 bytes from file Logs/20220130T164321/Express0019.lzma 2022-01-31T01:23:35.332Z,1643592215.332 [NAL9602](INFO): Packets left to send: 0 2022-01-31T01:23:43.031Z,1643592223.031 [NAL9602](INFO): SBD MO Status=0, MOMSN=35382, MT Status=0, MTMSN=0 2022-01-31T01:23:43.142Z,1643592223.142 [sci2:NeedComms:D] Stopped 2022-01-31T01:23:43.143Z,1643592223.143 [sci2:NeedComms:E] Running Loop=1 2022-01-31T01:23:45.049Z,1643592225.049 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-01-31T01:23:45.050Z,1643592225.050 [NAL9602] Data Fault, FailCount= 1 2022-01-31T01:23:45.050Z,1643592225.050 [NAL9602](ERROR): Data Fault 2022-01-31T01:23:45.180Z,1643592225.180 [CBIT](ERROR): Data Fault in component: NAL9602 2022-01-31T01:23:45.463Z,1643592225.463 [NAL9602](INFO): Powering down 2022-01-31T01:23:46.512Z,1643592226.512 [CBIT](INFO): Clearing failed state for component NAL9602 2022-01-31T01:23:46.512Z,1643592226.512 [NAL9602] No Fault, FailCount= 1 2022-01-31T01:24:15.747Z,1643592255.747 [NAL9602](INFO): Powering up NAL9602 2022-01-31T01:24:26.657Z,1643592266.657 [NAL9602](INFO): NAL9602 initialized 2022-01-31T01:25:53.927Z,1643592353.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012554.00,A,4116.89904,N,07057.92843,W,0.680,0.00,310122,,,A*79 2022-01-31T01:25:53.929Z,1643592353.929 [NAL9602](INFO): GPS fix at 20220131T012554: (41.281651, -70.965474) 2022-01-31T01:25:53.941Z,1643592353.941 [UniversalFixResidualReporter](INFO): Fix residual: 8.3 %DT, over the last 8976.8 m. Residual distance 743.6 m at bearing 63.2 degrees. Fix at (41.2817, -70.9655) with 8292.5 m made good. 2022-01-31T01:25:53.947Z,1643592353.947 [sci2:NeedComms:E] Stopped 2022-01-31T01:25:53.947Z,1643592353.947 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T01:25:53.947Z,1643592353.947 [sci2:NeedComms] Stopped 2022-01-31T01:25:53.948Z,1643592353.948 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T01:25:53.948Z,1643592353.948 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T01:25:53.948Z,1643592353.948 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T01:25:54.798Z,1643592354.798 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-01-31T01:25:54.798Z,1643592354.798 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-01-31T01:26:26.239Z,1643592386.239 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T01:26:46.053Z,1643592406.053 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm (1 active estimators). 2022-01-31T01:30:57.377Z,1643592657.377 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-31T01:36:27.242Z,1643592987.242 [CTD_Seabird](ERROR): Failed to parse device response: 23.36,3.101.29 2022-01-31T01:39:09.034Z,1643593149.034 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T01:39:38.943Z,1643593178.943 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T01:39:49.051Z,1643593189.051 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T01:47:18.684Z,1643593638.684 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T01:47:18.686Z,1643593638.686 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T01:58:14.803Z,1643594294.803 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T01:58:24.909Z,1643594304.909 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T02:10:06.513Z,1643595006.513 [CTD_Seabird](ERROR): Failed to parse device response: .05 2022-01-31T02:10:20.569Z,1643595020.569 [CTD_Seabird](ERROR): Failed to parse device response: 14.06,8 2022-01-31T02:13:53.433Z,1643595233.433 [CTD_Seabird](ERROR): Failed to parse device response: .06 2022-01-31T02:18:00.994Z,1643595480.994 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T02:18:40.998Z,1643595520.998 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T02:28:55.081Z,1643596135.081 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T02:37:56.866Z,1643596676.866 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T02:38:06.945Z,1643596686.945 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T02:46:09.501Z,1643597169.501 [CTD_Seabird](ERROR): Failed to parse device response: 83,4703.80 2022-01-31T02:51:48.809Z,1643597508.809 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T02:54:40.405Z,1643597680.405 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T02:54:40.407Z,1643597680.407 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T02:56:52.908Z,1643597812.908 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2022-01-31T02:58:12.936Z,1643597892.936 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T02:58:12.936Z,1643597892.936 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T02:58:12.936Z,1643597892.936 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T02:58:13.003Z,1643597893.003 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T02:58:13.380Z,1643597893.380 [RDI_Pathfinder](INFO): Powering down 2022-01-31T02:58:14.117Z,1643597894.117 [Power24vConverter](INFO): Powering down. 2022-01-31T02:58:14.203Z,1643597894.203 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T02:58:14.203Z,1643597894.203 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T02:58:14.922Z,1643597894.922 [Power24vConverter](INFO): Powering up. 2022-01-31T02:59:34.636Z,1643597974.636 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T02:59:34.846Z,1643597974.846 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:35.850Z,1643597975.850 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:36.854Z,1643597976.854 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:37.862Z,1643597977.862 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:38.862Z,1643597978.862 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:39.865Z,1643597979.865 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:40.872Z,1643597980.872 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:41.873Z,1643597981.873 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:42.878Z,1643597982.878 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:43.881Z,1643597983.881 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:44.886Z,1643597984.886 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:45.889Z,1643597985.889 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T02:59:46.860Z,1643597986.860 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.074336 CHAN A1 (24V): 0.135475 CHAN A2 (12V): 0.039121 CHAN A3 (5V): 0.009517 CHAN B0 (3.3V): 0.003654 CHAN B1 (3.15aV): 0.005477 CHAN B2 (3.15bV): 0.005442 CHAN B3 (GND): -0.006421 OPEN: 0.006848 Full Scale: +/- 1 mA 2022-01-31T03:00:00.949Z,1643598000.949 [CTD_Seabird](ERROR): Failed to parse device response: 2.99591,4694.27 2022-01-31T03:06:48.630Z,1643598408.630 [CTD_Seabird](ERROR): Failed to parse device response: 3,3.03462,4684.52 2022-01-31T03:15:08.702Z,1643598908.702 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T03:15:48.721Z,1643598948.721 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T03:19:28.903Z,1643599168.903 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T03:44:26.858Z,1643600666.858 [CTD_Seabird](ERROR): Failed to parse device response: 5.42,3.9194,3.05020, 2022-01-31T03:54:50.728Z,1643601290.728 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T03:55:11.505Z,1643601311.505 [CTD_Seabird](ERROR): Failed to parse device response: 20.85,3.05327,4689.03 2022-01-31T04:01:12.598Z,1643601672.598 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude 2022-01-31T04:01:12.973Z,1643601672.973 [CTD_Seabird](ERROR): Failed to parse device response: 18.30,96 2022-01-31T04:02:03.525Z,1643601723.525 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T04:02:03.528Z,1643601723.528 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T04:13:26.709Z,1643602406.709 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T04:33:12.537Z,1643603592.537 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T04:33:32.679Z,1643603612.679 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T04:34:22.784Z,1643603662.784 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T04:34:22.784Z,1643603662.784 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T04:34:22.784Z,1643603662.784 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T04:34:22.860Z,1643603662.860 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T04:34:23.224Z,1643603663.224 [RDI_Pathfinder](INFO): Powering down 2022-01-31T04:34:24.047Z,1643603664.047 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T04:34:24.047Z,1643603664.047 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T04:37:52.506Z,1643603872.506 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T04:51:48.738Z,1643604708.738 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T04:52:08.542Z,1643604728.542 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T04:52:58.655Z,1643604778.655 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T04:59:47.132Z,1643605187.132 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T04:59:47.458Z,1643605187.458 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:48.465Z,1643605188.465 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:49.465Z,1643605189.465 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:50.469Z,1643605190.469 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:51.473Z,1643605191.473 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:52.478Z,1643605192.478 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:53.482Z,1643605193.482 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:54.486Z,1643605194.486 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:54.757Z,1643605194.757 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T04:59:55.536Z,1643605195.536 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:56.538Z,1643605196.538 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:57.536Z,1643605197.536 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T04:59:58.072Z,1643605198.072 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.070035 CHAN A1 (24V): 0.123280 CHAN A2 (12V): 0.039445 CHAN A3 (5V): 0.009870 CHAN B0 (3.3V): 0.006037 CHAN B1 (3.15aV): 0.005663 CHAN B2 (3.15bV): 0.005481 CHAN B3 (GND): -0.006659 OPEN: 0.006076 Full Scale: +/- 1 mA 2022-01-31T05:01:18.838Z,1643605278.838 [CTD_Seabird](ERROR): Failed to parse device response: .29 2022-01-31T05:03:19.325Z,1643605399.325 [CTD_Seabird](ERROR): Failed to parse device response: .63 2022-01-31T05:09:27.635Z,1643605767.635 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T05:09:27.637Z,1643605767.637 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T05:11:24.455Z,1643605884.455 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T05:19:23.285Z,1643606363.285 [CTD_Seabird](ERROR): Failed to parse device response: .17 2022-01-31T05:21:26.793Z,1643606486.793 [CTD_Seabird](ERROR): Failed to parse device response: .63 2022-01-31T05:25:54.258Z,1643606754.258 [sci2:NeedComms] Running Loop=1 2022-01-31T05:25:54.258Z,1643606754.258 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T05:25:54.258Z,1643606754.258 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T05:25:54.258Z,1643606754.258 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T05:25:54.258Z,1643606754.258 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T05:25:54.259Z,1643606754.259 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T05:25:54.259Z,1643606754.259 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T05:25:54.259Z,1643606754.259 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T05:25:54.260Z,1643606754.260 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T05:25:54.260Z,1643606754.260 [sci2:NeedComms:A] Running Loop=1 2022-01-31T05:25:54.262Z,1643606754.262 [sci2:NeedComms:A](INFO): last time_fix was: 1643592354.000000 second since 1970/01/01T00:00:00Z 2022-01-31T05:25:54.262Z,1643606754.262 [sci2:NeedComms:A] Stopped 2022-01-31T05:26:40.740Z,1643606800.740 [sci2:NeedComms:C] Running Loop=1 2022-01-31T05:26:44.114Z,1643606804.114 [CTD_Seabird](ERROR): Salinity reading out of range: 22.858261 psu 2022-01-31T05:26:55.654Z,1643606815.654 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=35383, MT Status=1, MTMSN=1395 2022-01-31T05:26:55.654Z,1643606815.654 [NAL9602](INFO): Data available in MT queue 2022-01-31T05:26:56.152Z,1643606816.152 [NAL9602](INFO): Received command: stop 2022-01-31T05:26:56.177Z,1643606816.177 [CommandExec](IMPORTANT): got command stop 2022-01-31T05:26:56.177Z,1643606816.177 [CommandExec](IMPORTANT): Scheduling is paused 2022-01-31T05:26:56.177Z,1643606816.177 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2022-01-31T05:26:56.191Z,1643606816.191 [MissionManager](INFO): MissionManager is completed. 2022-01-31T05:26:56.191Z,1643606816.191 [MissionManager](INFO): Uninitializing Mission sci2 2022-01-31T05:26:56.191Z,1643606816.191 [sci2] Stopped 2022-01-31T05:26:56.191Z,1643606816.191 [sci2](DEBUG): Aggregate::uninitialize sci2 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science] Stopped 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science:B] Stopped 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science:C] Stopped 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science:E] Stopped 2022-01-31T05:26:56.191Z,1643606816.191 [sci2:Science:Read_Oil] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:OceanCurrent] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2:Science:OceanCurrent 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:OceanCurrent:A.] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:PeakDetectChl] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:NeedComms] Stopped 2022-01-31T05:26:56.192Z,1643606816.192 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:NeedComms:C] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:I.Pitch] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:J.Buoyancy] Stopped 2022-01-31T05:26:56.193Z,1643606816.193 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:K.SetSpeed] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:K.SetSpeed](DEBUG): Uninitialize. 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:L.DepthEnvelope] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:L.DepthEnvelope](DEBUG): Uninitialize. 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:M.AltitudeEnvelope] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:N.YoYo] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent. 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap](DEBUG): Aggregate::uninitialize sci2:Lap 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap:Wpt1] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap:Wpt1:A.Waypoint] Stopped 2022-01-31T05:26:56.194Z,1643606816.194 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-01-31T05:26:56.524Z,1643606816.524 [WetLabsSeaOWL_UV_A](INFO): Powering down 2022-01-31T05:26:56.543Z,1643606816.543 [MissionManager](IMPORTANT): Started mission Default 2022-01-31T05:26:56.543Z,1643606816.543 [Default] Running Loop=1 2022-01-31T05:26:56.543Z,1643606816.543 [Default](DEBUG): Aggregate::initialize Default 2022-01-31T05:26:56.543Z,1643606816.543 [Default:B.GoToSurface] Running Loop=1 2022-01-31T05:26:56.548Z,1643606816.548 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T05:26:56.548Z,1643606816.548 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-01-31T05:26:56.548Z,1643606816.548 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-01-31T05:26:56.549Z,1643606816.549 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-01-31T05:26:56.549Z,1643606816.549 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T05:26:56.549Z,1643606816.549 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T05:26:56.550Z,1643606816.550 [Default:A.Wait] Running Loop=1 2022-01-31T05:26:56.550Z,1643606816.550 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-01-31T05:27:09.799Z,1643606829.799 [Default:A.Wait](INFO): Done Waiting. 2022-01-31T05:27:09.799Z,1643606829.799 [Default:A.Wait] Stopped 2022-01-31T05:27:09.799Z,1643606829.799 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-01-31T05:27:10.209Z,1643606830.209 [Default:CheckIn] Running Loop=1 2022-01-31T05:27:10.209Z,1643606830.209 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-01-31T05:27:10.210Z,1643606830.210 [Default:CheckIn:Read_GPS] Running Loop=1 2022-01-31T05:28:33.419Z,1643606913.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,052834.00,A,4119.76496,N,07059.56733,W,0.272,0.00,310122,,,A*78 2022-01-31T05:28:33.421Z,1643606913.421 [NAL9602](INFO): GPS fix at 20220131T052834: (41.329416, -70.992789) 2022-01-31T05:28:33.439Z,1643606913.439 [UniversalFixResidualReporter](INFO): Fix residual: 10.3 %DT, over the last 6543.3 m. Residual distance 671.6 m at bearing 55.0 degrees. Fix at (41.3294, -70.9928) with 5780.6 m made good. 2022-01-31T05:28:33.440Z,1643606913.440 [Default:CheckIn:Read_GPS] Stopped 2022-01-31T05:28:33.441Z,1643606913.441 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-01-31T05:29:00.971Z,1643606940.971 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35384, MT Status=1, MTMSN=1396 2022-01-31T05:29:01.024Z,1643606941.024 [NAL9602](INFO): Sent 98 bytes from file Logs/20220130T164321/Courier0021.lzma 2022-01-31T05:29:01.024Z,1643606941.024 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:29:01.696Z,1643606941.696 [NAL9602](INFO): Received command: sched asap "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 120 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree" 16s1g 1 3 2022-01-31T05:29:01.736Z,1643606941.736 [CommandExec](IMPORTANT): got command schedule asap "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 120 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree" 16s1g 1 3.000000 2022-01-31T05:29:01.737Z,1643606941.737 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=16s1g 2022-01-31T05:29:01.738Z,1643606941.738 [CommandExec](IMPORTANT): Scheduled #12 (#1 of 3 with id='16s1g'): "load Science/sci2.xml;set sci2.MissionTimeout 1260 min;set sci2.NeedCommsTime 120 min;set sci2.Lat1 41.3764 degree;set sci2.Lon1 -71.0111 degree;set sci2.Lat2 41.430037 degree;set sci2.Lon2 -70.998901 degree", ASAP 2022-01-31T05:29:18.548Z,1643606958.548 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35385, MT Status=1, MTMSN=1397 2022-01-31T05:29:18.600Z,1643606958.600 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:29:18.600Z,1643606958.600 [NAL9602](INFO): Packets left to send: 8 2022-01-31T05:29:19.292Z,1643606959.292 [NAL9602](INFO): Received command: sched asap "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m" 16s1g 2 3 2022-01-31T05:29:19.316Z,1643606959.316 [CommandExec](IMPORTANT): got command schedule asap "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m" 16s1g 2 3.000000 2022-01-31T05:29:19.317Z,1643606959.317 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=16s1g 2022-01-31T05:29:19.318Z,1643606959.318 [CommandExec](IMPORTANT): Scheduled #13 (#2 of 3 with id='16s1g'): "set sci2.Lat3 41.470377 degree;set sci2.Lon3 -70.920415 degree;set sci2.Lat4 41.501148 degree;set sci2.Lon4 -70.811485 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.YoYoMinAltitude 8 m", ASAP 2022-01-31T05:29:37.331Z,1643606977.331 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35386, MT Status=1, MTMSN=1398 2022-01-31T05:29:37.382Z,1643606977.382 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:29:37.382Z,1643606977.382 [NAL9602](INFO): Packets left to send: 7 2022-01-31T05:29:37.949Z,1643606977.949 [NAL9602](INFO): Received command: sched asap "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 30 min;run" 16s1g 3 3 2022-01-31T05:29:37.967Z,1643606977.967 [CommandExec](IMPORTANT): got command schedule asap "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 30 min;run" 16s1g 3 3.000000 2022-01-31T05:29:37.970Z,1643606977.970 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=16s1g 2022-01-31T05:29:37.971Z,1643606977.971 [CommandExec](IMPORTANT): Scheduled #14 (#3 of 3 with id='16s1g'): "set sci2.MinAltitude 6 m;set sci2.MaxDepth 45 m;set sci2:NeedComms.CommsTimeout 30 min;run", ASAP 2022-01-31T05:29:38.037Z,1643606978.037 [CommandExec](IMPORTANT): got command load ./Missions/Science/sci2.xml 2022-01-31T05:29:38.037Z,1643606978.037 [MissionManager](INFO): Loading Mission from file: ./Missions/Science/sci2.xml 2022-01-31T05:29:38.241Z,1643606978.241 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h 2022-01-31T05:29:38.243Z,1643606978.243 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min 2022-01-31T05:29:38.245Z,1643606978.245 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count 2022-01-31T05:29:38.247Z,1643606978.247 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg 2022-01-31T05:29:38.250Z,1643606978.250 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg 2022-01-31T05:29:38.252Z,1643606978.252 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg 2022-01-31T05:29:38.254Z,1643606978.254 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg 2022-01-31T05:29:38.256Z,1643606978.256 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg 2022-01-31T05:29:38.258Z,1643606978.258 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg 2022-01-31T05:29:38.261Z,1643606978.261 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg 2022-01-31T05:29:38.263Z,1643606978.263 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg 2022-01-31T05:29:38.290Z,1643606978.290 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg 2022-01-31T05:29:38.296Z,1643606978.296 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg 2022-01-31T05:29:38.298Z,1643606978.298 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg 2022-01-31T05:29:38.305Z,1643606978.305 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg 2022-01-31T05:29:38.316Z,1643606978.316 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg 2022-01-31T05:29:38.318Z,1643606978.318 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg 2022-01-31T05:29:38.331Z,1643606978.331 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s 2022-01-31T05:29:38.339Z,1643606978.339 [MissionManager](INFO): DefineArg sci2.CaptureRadius = nan m 2022-01-31T05:29:38.363Z,1643606978.363 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m 2022-01-31T05:29:38.376Z,1643606978.376 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m 2022-01-31T05:29:38.388Z,1643606978.388 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m 2022-01-31T05:29:38.391Z,1643606978.391 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg 2022-01-31T05:29:38.401Z,1643606978.401 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg 2022-01-31T05:29:38.403Z,1643606978.403 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 154.000000 cc 2022-01-31T05:29:38.410Z,1643606978.410 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.600000 cm 2022-01-31T05:29:38.417Z,1643606978.417 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m 2022-01-31T05:29:38.419Z,1643606978.419 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m 2022-01-31T05:29:38.425Z,1643606978.425 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km 2022-01-31T05:29:38.426Z,1643606978.426 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml 2022-01-31T05:29:38.799Z,1643606978.799 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool 2022-01-31T05:29:38.802Z,1643606978.802 [MissionManager](INFO): DefineArg sci2:Science.TimeWindowPeakReport = nan min 2022-01-31T05:29:38.803Z,1643606978.803 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool 2022-01-31T05:29:38.805Z,1643606978.805 [MissionManager](INFO): DefineArg sci2:Science.HighestSaltPeakReportActive = 0 bool 2022-01-31T05:29:38.807Z,1643606978.807 [MissionManager](INFO): DefineArg sci2:Science.HighestOilPeakReportActive = 0 bool 2022-01-31T05:29:38.809Z,1643606978.809 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool 2022-01-31T05:29:38.810Z,1643606978.810 [MissionManager](INFO): DefineArg sci2:Science.DetectTrough = 0 bool 2022-01-31T05:29:38.813Z,1643606978.813 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count 2022-01-31T05:29:38.815Z,1643606978.815 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count 2022-01-31T05:29:38.817Z,1643606978.817 [MissionManager](INFO): DefineArg sci2:Science.OffPeakFractionHorizontal = 80.000000 % 2022-01-31T05:29:38.819Z,1643606978.819 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool 2022-01-31T05:29:38.821Z,1643606978.821 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool 2022-01-31T05:29:38.822Z,1643606978.822 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectFDOMActive = 0 bool 2022-01-31T05:29:38.824Z,1643606978.824 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectSalinityActive = 0 bool 2022-01-31T05:29:38.826Z,1643606978.826 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2022-01-31T05:29:38.828Z,1643606978.828 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 0 bool 2022-01-31T05:29:38.831Z,1643606978.831 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0 bool 2022-01-31T05:29:38.833Z,1643606978.833 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1 bool 2022-01-31T05:29:38.835Z,1643606978.835 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 0 bool 2022-01-31T05:29:38.885Z,1643606978.885 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1 bool 2022-01-31T05:29:38.887Z,1643606978.887 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsUBAT = 0 bool 2022-01-31T05:29:38.902Z,1643606978.902 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count 2022-01-31T05:29:38.904Z,1643606978.904 [MissionManager](INFO): DefineArg sci2:Science.MedianFilterLen = 5.000000 count 2022-01-31T05:29:38.906Z,1643606978.906 [MissionManager](INFO): DefineArg sci2:Science.PeakShallowBound = nan m 2022-01-31T05:29:38.908Z,1643606978.908 [MissionManager](INFO): DefineArg sci2:Science.PeakDeepBound = nan m 2022-01-31T05:29:38.910Z,1643606978.910 [MissionManager](INFO): DefineArg sci2:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2022-01-31T05:29:38.945Z,1643606978.945 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l 2022-01-31T05:29:38.947Z,1643606978.947 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m 2022-01-31T05:29:38.953Z,1643606978.953 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC 2022-01-31T05:29:38.955Z,1643606978.955 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg 2022-01-31T05:29:38.974Z,1643606978.974 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg 2022-01-31T05:29:38.980Z,1643606978.980 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l 2022-01-31T05:29:38.983Z,1643606978.983 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m 2022-01-31T05:29:38.993Z,1643606978.993 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg 2022-01-31T05:29:38.995Z,1643606978.995 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg 2022-01-31T05:29:39.010Z,1643606979.010 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3 2022-01-31T05:29:39.020Z,1643606979.020 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m 2022-01-31T05:29:39.023Z,1643606979.023 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg 2022-01-31T05:29:39.031Z,1643606979.031 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg 2022-01-31T05:29:39.034Z,1643606979.034 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOM = 0.000000 ppb 2022-01-31T05:29:39.049Z,1643606979.049 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMDepth = 0.000000 m 2022-01-31T05:29:39.051Z,1643606979.051 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMTemperature = nan degC 2022-01-31T05:29:39.058Z,1643606979.058 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLatitude = nan arcdeg 2022-01-31T05:29:39.064Z,1643606979.064 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLongitude = nan arcdeg 2022-01-31T05:29:39.066Z,1643606979.066 [MissionManager](INFO): DefineOutput sci2:Science.PatchFDOMDistance = nan m 2022-01-31T05:29:39.069Z,1643606979.069 [MissionManager](INFO): DefineOutput sci2:Science.PeakSalt = 0.000000 psu 2022-01-31T05:29:39.071Z,1643606979.071 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltDepth = 0.000000 m 2022-01-31T05:29:39.086Z,1643606979.086 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltTemperature = 0.000000 degC 2022-01-31T05:29:39.088Z,1643606979.088 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltLatitude = nan arcdeg 2022-01-31T05:29:39.090Z,1643606979.090 [MissionManager](INFO): DefineOutput sci2:Science.PeakSaltLongitude = nan arcdeg 2022-01-31T05:29:39.097Z,1643606979.097 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 ug/l 2022-01-31T05:29:39.099Z,1643606979.099 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m 2022-01-31T05:29:39.109Z,1643606979.109 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg 2022-01-31T05:29:39.111Z,1643606979.111 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg 2022-01-31T05:29:39.118Z,1643606979.118 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m 2022-01-31T05:29:39.124Z,1643606979.124 [MissionManager](INFO): DefineOutput sci2:Science.PatchSalt = 0.000000 psu 2022-01-31T05:29:39.127Z,1643606979.127 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltDepth = 0.000000 m 2022-01-31T05:29:39.129Z,1643606979.129 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltLatitude = nan arcdeg 2022-01-31T05:29:39.140Z,1643606979.140 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltLongitude = nan arcdeg 2022-01-31T05:29:39.142Z,1643606979.142 [MissionManager](INFO): DefineOutput sci2:Science.PatchSaltDistance = nan m 2022-01-31T05:29:39.149Z,1643606979.149 [MissionManager](INFO): DefineOutput sci2:Science.PatchOil = 0.000000 psu 2022-01-31T05:29:39.151Z,1643606979.151 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilDepth = 0.000000 m 2022-01-31T05:29:39.166Z,1643606979.166 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilLatitude = nan arcdeg 2022-01-31T05:29:39.172Z,1643606979.172 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilLongitude = nan arcdeg 2022-01-31T05:29:39.177Z,1643606979.177 [MissionManager](INFO): DefineOutput sci2:Science.PatchOilDistance = nan m 2022-01-31T05:29:39.215Z,1643606979.215 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-31T05:29:39.242Z,1643606979.242 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-31T05:29:39.266Z,1643606979.266 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-31T05:29:39.290Z,1643606979.290 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-31T05:29:39.322Z,1643606979.322 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-31T05:29:39.418Z,1643606979.418 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-31T05:29:39.441Z,1643606979.441 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-01-31T05:29:39.465Z,1643606979.465 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-01-31T05:29:39.495Z,1643606979.495 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool 2022-01-31T05:29:39.495Z,1643606979.495 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2022-01-31T05:29:39.583Z,1643606979.583 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h 2022-01-31T05:29:39.589Z,1643606979.589 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min 2022-01-31T05:29:39.591Z,1643606979.591 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg 2022-01-31T05:29:39.597Z,1643606979.597 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s 2022-01-31T05:29:39.599Z,1643606979.599 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s 2022-01-31T05:29:39.610Z,1643606979.610 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min 2022-01-31T05:29:39.616Z,1643606979.616 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min 2022-01-31T05:29:39.626Z,1643606979.626 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-01-31T05:29:39.655Z,1643606979.655 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2022-01-31T05:29:39.690Z,1643606979.690 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m 2022-01-31T05:29:39.692Z,1643606979.692 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m 2022-01-31T05:29:39.694Z,1643606979.694 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m 2022-01-31T05:29:39.695Z,1643606979.695 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-01-31T05:29:39.701Z,1643606979.701 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-01-31T05:29:39.706Z,1643606979.706 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2022-01-31T05:29:39.721Z,1643606979.721 [sci2:I.Pitch](DEBUG): Construct. 2022-01-31T05:29:39.725Z,1643606979.725 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy. 2022-01-31T05:29:39.727Z,1643606979.727 [sci2:K.SetSpeed](DEBUG): Construct. 2022-01-31T05:29:39.786Z,1643606979.786 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-01-31T05:29:39.814Z,1643606979.814 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-01-31T05:29:39.832Z,1643606979.832 [sci2:N.YoYo](DEBUG): Construct YoYo. 2022-01-31T05:29:39.839Z,1643606979.839 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.846Z,1643606979.846 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.851Z,1643606979.851 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.867Z,1643606979.867 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.872Z,1643606979.872 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.877Z,1643606979.877 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.882Z,1643606979.882 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint. 2022-01-31T05:29:39.922Z,1643606979.922 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoints, with science turned on. Maximum duration of mission 2 How often to surface for commumications 60 Number of times the vehicle should try to cycle through waypoints.. 1 Latitude of waypoint 1. If nan, waypoint is skipped. NaN Longitude of waypoint 1. If nan, waypoint is skipped. NaN Latitude of waypoint 2. If nan, waypoint is skipped. NaN Longitude of waypoint 2. If nan, waypoint is skipped. NaN Latitude of waypoint 3. If nan, waypoint is skipped. NaN Longitude of waypoint 3. If nan, waypoint is skipped. NaN Latitude of waypoint 4. If nan, waypoint is skipped. NaN Longitude of waypoint 4. If nan, waypoint is skipped. NaN Latitude of waypoint 5. If nan, waypoint is skipped. NaN Longitude of waypoint 5. If nan, waypoint is skipped. NaN Latitude of waypoint 6. If nan, waypoint is skipped. NaN Longitude of waypoint 6. If nan, waypoint is skipped. NaN Latitude of waypoint 7. If nan, waypoint is skipped. NaN Longitude of waypoint 7. If nan, waypoint is skipped. NaN Speed while performing the YoYo behavior. 1 Speed while performing the YoYo behavior. NaN Minimum depth while performing the YoYo behavior. 2 Maximum depth while performing the YoYo behavior. 200 Minimum altitude while performing the YoYo behavior (for bottom-terminated YoYos). 9 Vehicle up pitch while performing the YoYo behavior. 20 Vehicle down pitch while performing the YoYo behavior. -20 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc for active buoyancy Static setting for mass during the mission. Set to NaN mm for active mass position Minimum height above the sea floor for the entire mission. 5 Maximum depth for the entire mission. 205 Minimum offshore distance for the entire mission. 2 Most missions will run the science sensors. If you don't place this aggregate above NeedComms, science instruments get turned off on the last upcast and while floating on the surface. Another depth envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for depth-terminated YoYos. Another altitude envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for bottom-terminated YoYos. 2022-01-31T05:29:39.922Z,1643606979.922 [CommandExec](IMPORTANT): Loaded ./Missions/Science/sci2.xml 2022-01-31T05:29:45.533Z,1643606985.533 [CommandExec](IMPORTANT): got command set sci2.MissionTimeout 1260 minute 2022-01-31T05:29:45.533Z,1643606985.533 [CommandExec](IMPORTANT): got command set sci2.NeedCommsTime 120 minute 2022-01-31T05:29:45.534Z,1643606985.534 [CommandExec](IMPORTANT): got command set sci2.Lat1 41.3764 degree 2022-01-31T05:29:45.535Z,1643606985.535 [CommandExec](IMPORTANT): got command set sci2.Lon1 -71.0111 degree 2022-01-31T05:29:45.535Z,1643606985.535 [CommandExec](IMPORTANT): got command set sci2.Lat2 41.430037 degree 2022-01-31T05:29:45.536Z,1643606985.536 [CommandExec](IMPORTANT): got command set sci2.Lon2 -70.998901 degree 2022-01-31T05:29:53.281Z,1643606993.281 [CommandExec](IMPORTANT): got command set sci2.Lat3 41.470377 degree 2022-01-31T05:29:53.282Z,1643606993.282 [CommandExec](IMPORTANT): got command set sci2.Lon3 -70.920415 degree 2022-01-31T05:29:53.283Z,1643606993.283 [CommandExec](IMPORTANT): got command set sci2.Lat4 41.501148 degree 2022-01-31T05:29:53.284Z,1643606993.284 [CommandExec](IMPORTANT): got command set sci2.Lon4 -70.811485 degree 2022-01-31T05:29:53.284Z,1643606993.284 [CommandExec](IMPORTANT): got command set sci2.Speed .8 meter_per_second 2022-01-31T05:29:53.285Z,1643606993.285 [CommandExec](IMPORTANT): got command set sci2.YoYoMinDepth 4 meter 2022-01-31T05:29:53.286Z,1643606993.286 [CommandExec](IMPORTANT): got command set sci2.YoYoMaxDepth 40 meter 2022-01-31T05:29:53.286Z,1643606993.286 [CommandExec](IMPORTANT): got command set sci2.YoYoMinAltitude 8 meter 2022-01-31T05:29:53.391Z,1643606993.391 [NAL9602](INFO): SBD MO Status=1, MOMSN=35387, MT Status=0, MTMSN=0 2022-01-31T05:29:53.440Z,1643606993.440 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:29:53.440Z,1643606993.440 [NAL9602](INFO): Packets left to send: 6 2022-01-31T05:29:57.518Z,1643606997.518 [CommandExec](IMPORTANT): got command set sci2.MinAltitude 6 meter 2022-01-31T05:29:57.519Z,1643606997.519 [CommandExec](IMPORTANT): got command set sci2.MaxDepth 45 meter 2022-01-31T05:29:57.520Z,1643606997.520 [CommandExec](IMPORTANT): got command set sci2:NeedComms.CommsTimeout 30 minute 2022-01-31T05:29:57.520Z,1643606997.520 [CommandExec](IMPORTANT): got command run 2022-01-31T05:29:57.522Z,1643606997.522 [CommandExec](IMPORTANT): Running 2022-01-31T05:29:57.679Z,1643606997.679 [Default] Stopped 2022-01-31T05:29:57.679Z,1643606997.679 [Default](DEBUG): Aggregate::uninitialize Default 2022-01-31T05:29:57.679Z,1643606997.679 [Default:B.GoToSurface] Stopped 2022-01-31T05:29:57.679Z,1643606997.679 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T05:29:57.680Z,1643606997.680 [Default:CheckIn] Stopped 2022-01-31T05:29:57.680Z,1643606997.680 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-01-31T05:29:57.680Z,1643606997.680 [Default:CheckIn:Read_Iridium] Stopped 2022-01-31T05:29:57.680Z,1643606997.680 [MissionManager](IMPORTANT): Started mission sci2 2022-01-31T05:29:57.680Z,1643606997.680 [sci2] Running Loop=1 2022-01-31T05:29:57.680Z,1643606997.680 [sci2](DEBUG): Aggregate::initialize sci2 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:A] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:B] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:C] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:D] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:E] Running Loop=1 2022-01-31T05:29:57.681Z,1643606997.681 [sci2:Science:F] Running Loop=1 2022-01-31T05:29:57.682Z,1643606997.682 [sci2:Science:Read_Oil] Running Loop=1 2022-01-31T05:29:57.682Z,1643606997.682 [sci2:Science:PeakDetectChl] Running Loop=1 2022-01-31T05:29:57.683Z,1643606997.683 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl 2022-01-31T05:29:57.683Z,1643606997.683 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2022-01-31T05:29:57.684Z,1643606997.684 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-31T05:29:57.687Z,1643606997.687 [sci2:Science:HighestChlPeakReport] Running Loop=1 2022-01-31T05:29:57.687Z,1643606997.687 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport 2022-01-31T05:29:57.688Z,1643606997.688 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-31T05:29:57.688Z,1643606997.688 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-31T05:29:57.688Z,1643606997.688 [sci2:Science:PeakDetectNO3] Running Loop=1 2022-01-31T05:29:57.689Z,1643606997.689 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3 2022-01-31T05:29:57.689Z,1643606997.689 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2022-01-31T05:29:57.689Z,1643606997.689 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-31T05:29:57.690Z,1643606997.690 [sci2:Science:PeakDetectOil] Running Loop=1 2022-01-31T05:29:57.690Z,1643606997.690 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil 2022-01-31T05:29:57.690Z,1643606997.690 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2022-01-31T05:29:57.690Z,1643606997.690 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-31T05:29:57.691Z,1643606997.691 [sci2:Science:HighestOilPeakReport] Running Loop=1 2022-01-31T05:29:57.691Z,1643606997.691 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestOilPeakReport 2022-01-31T05:29:57.691Z,1643606997.691 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-31T05:29:57.691Z,1643606997.691 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-31T05:29:57.692Z,1643606997.692 [sci2:Science:PeakDetectFDOM] Running Loop=1 2022-01-31T05:29:57.692Z,1643606997.692 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM 2022-01-31T05:29:57.692Z,1643606997.692 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2022-01-31T05:29:57.692Z,1643606997.692 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-31T05:29:57.693Z,1643606997.693 [sci2:Science:PeakDetectSalinity] Running Loop=1 2022-01-31T05:29:57.693Z,1643606997.693 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sci2:Science:PeakDetectSalinity 2022-01-31T05:29:57.694Z,1643606997.694 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2022-01-31T05:29:57.694Z,1643606997.694 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-01-31T05:29:57.695Z,1643606997.695 [sci2:Science:HighestSaltPeakReport] Running Loop=1 2022-01-31T05:29:57.695Z,1643606997.695 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestSaltPeakReport 2022-01-31T05:29:57.695Z,1643606997.695 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-01-31T05:29:57.695Z,1643606997.695 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:Science:OceanCurrent] Running Loop=1 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:Science:OceanCurrent](DEBUG): Aggregate::initialize sci2:Science:OceanCurrent 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:Science:OceanCurrent:A.] Running Loop=1 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:StandardEnvelopes] Running Loop=1 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-01-31T05:29:57.696Z,1643606997.696 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:I.Pitch] Running Loop=1 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:I.Pitch](DEBUG): Initialize. 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:J.Buoyancy] Running Loop=1 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:K.SetSpeed] Running Loop=1 2022-01-31T05:29:57.697Z,1643606997.697 [sci2:K.SetSpeed](DEBUG): Initialize. 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:L.DepthEnvelope] Running Loop=1 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:M.AltitudeEnvelope] Running Loop=1 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:N.YoYo] Running Loop=1 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent. 2022-01-31T05:29:57.698Z,1643606997.698 [sci2:C] Running Loop=1 2022-01-31T05:29:57.699Z,1643606997.699 [sci2:N.YoYo] Running Loop=1 2022-01-31T05:29:57.699Z,1643606997.699 [sci2:M.AltitudeEnvelope] Running Loop=1 2022-01-31T05:29:57.700Z,1643606997.700 [sci2:L.DepthEnvelope] Running Loop=1 2022-01-31T05:29:57.701Z,1643606997.701 [sci2:K.SetSpeed] Running Loop=1 2022-01-31T05:29:57.701Z,1643606997.701 [sci2:J.Buoyancy] Running Loop=1 2022-01-31T05:29:57.701Z,1643606997.701 [sci2:I.Pitch] Running Loop=1 2022-01-31T05:29:57.701Z,1643606997.701 [sci2:StandardEnvelopes] Running Loop=1 2022-01-31T05:29:57.701Z,1643606997.701 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-01-31T05:29:57.702Z,1643606997.702 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-01-31T05:29:57.702Z,1643606997.702 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-01-31T05:29:57.703Z,1643606997.703 [sci2:C] Stopped 2022-01-31T05:29:57.703Z,1643606997.703 [sci2:E] Running Loop=1 2022-01-31T05:29:57.703Z,1643606997.703 [sci2:Science] Running Loop=1 2022-01-31T05:29:57.704Z,1643606997.704 [sci2:Science:HighestSaltPeakReport] Stopped 2022-01-31T05:29:57.704Z,1643606997.704 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestSaltPeakReport 2022-01-31T05:29:57.704Z,1643606997.704 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-31T05:29:57.704Z,1643606997.704 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-31T05:29:57.704Z,1643606997.704 [sci2:Science:PeakDetectSalinity] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectSalinity 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectFDOM] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:HighestOilPeakReport] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestOilPeakReport 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectOil] Stopped 2022-01-31T05:29:57.705Z,1643606997.705 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectNO3] Stopped 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:HighestChlPeakReport] Stopped 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:PeakDetectChl] Running Loop=1 2022-01-31T05:29:57.706Z,1643606997.706 [sci2:Science:OceanCurrent:A.] Running Loop=1 2022-01-31T05:29:57.707Z,1643606997.707 [sci2:Science:Read_Oil] Running Loop=1 2022-01-31T05:29:57.724Z,1643606997.724 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water 2022-01-31T05:29:57.724Z,1643606997.724 [sci2:Science:F] Stopped 2022-01-31T05:29:57.724Z,1643606997.724 [sci2:Science:E] Running Loop=1 2022-01-31T05:29:57.725Z,1643606997.725 [sci2:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2022-01-31T05:29:57.725Z,1643606997.725 [sci2:Science:D] Stopped 2022-01-31T05:29:57.725Z,1643606997.725 [sci2:Science:C] Running Loop=1 2022-01-31T05:29:57.725Z,1643606997.725 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2022-01-31T05:29:57.726Z,1643606997.726 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2022-01-31T05:29:57.726Z,1643606997.726 [sci2:Science:B] Running Loop=1 2022-01-31T05:29:57.726Z,1643606997.726 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2022-01-31T05:29:57.727Z,1643606997.727 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2022-01-31T05:29:57.727Z,1643606997.727 [sci2:Science:A] Stopped 2022-01-31T05:29:58.009Z,1643606998.009 [WetLabsSeaOWL_UV_A](INFO): Powering up 2022-01-31T05:29:58.058Z,1643606998.058 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-01-31T05:29:58.066Z,1643606998.066 [sci2:E] Stopped 2022-01-31T05:29:58.066Z,1643606998.066 [sci2:F] Running Loop=1 2022-01-31T05:29:58.516Z,1643606998.516 [sci2:F] Stopped 2022-01-31T05:29:58.516Z,1643606998.516 [sci2:G] Running Loop=1 2022-01-31T05:29:58.873Z,1643606998.873 [sci2:G] Stopped 2022-01-31T05:29:58.873Z,1643606998.873 [sci2:StartingMission] Running Loop=1 2022-01-31T05:29:58.873Z,1643606998.873 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission 2022-01-31T05:29:59.295Z,1643606999.295 [sci2:NeedComms] Running Loop=1 2022-01-31T05:29:59.295Z,1643606999.295 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T05:29:59.295Z,1643606999.295 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T05:29:59.295Z,1643606999.295 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T05:29:59.295Z,1643606999.295 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T05:29:59.296Z,1643606999.296 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T05:29:59.297Z,1643606999.297 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T05:29:59.297Z,1643606999.297 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T05:29:59.297Z,1643606999.297 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T05:29:59.297Z,1643606999.297 [sci2:NeedComms:A] Running Loop=1 2022-01-31T05:29:59.299Z,1643606999.299 [sci2:NeedComms:A](INFO): last time_fix was: 1643606914.000000 second since 1970/01/01T00:00:00Z 2022-01-31T05:29:59.299Z,1643606999.299 [sci2:NeedComms:A] Stopped 2022-01-31T05:29:59.703Z,1643606999.703 [sci2:NeedComms:C] Running Loop=1 2022-01-31T05:30:00.093Z,1643607000.093 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-01-31T05:30:00.890Z,1643607000.890 [NAL9602](INFO): SBD MO Status=1, MOMSN=35388, MT Status=0, MTMSN=0 2022-01-31T05:30:00.901Z,1643607000.901 [CTD_Seabird](ERROR): Failed to parse device response: 2022-01-31T05:30:00.944Z,1643607000.944 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:30:00.945Z,1643607000.945 [NAL9602](INFO): Packets left to send: 5 2022-01-31T05:30:02.095Z,1643607002.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053002.00,A,4119.75366,N,07059.56558,W,1.108,104.77,310122,,,D*7F 2022-01-31T05:30:02.097Z,1643607002.097 [NAL9602](INFO): GPS fix at 20220131T053002: (41.329228, -70.992760) 2022-01-31T05:30:02.112Z,1643607002.112 [sci2:NeedComms:C] Stopped 2022-01-31T05:30:02.112Z,1643607002.112 [sci2:NeedComms:D] Running Loop=1 2022-01-31T05:30:02.569Z,1643607002.569 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-01-31T05:30:19.598Z,1643607019.598 [NAL9602](INFO): SBD MO Status=1, MOMSN=35389, MT Status=0, MTMSN=0 2022-01-31T05:30:19.664Z,1643607019.664 [NAL9602](INFO): Sent 52 bytes from file Logs/20220130T164321/Courier0024.lzma 2022-01-31T05:30:19.665Z,1643607019.665 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:30:26.602Z,1643607026.602 [NAL9602](INFO): SBD MO Status=1, MOMSN=35390, MT Status=0, MTMSN=0 2022-01-31T05:30:26.652Z,1643607026.652 [NAL9602](INFO): Sent 79 bytes from file Logs/20220130T164321/Courier0027.lzma 2022-01-31T05:30:26.652Z,1643607026.652 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:30:34.430Z,1643607034.430 [NAL9602](INFO): SBD MO Status=1, MOMSN=35391, MT Status=0, MTMSN=0 2022-01-31T05:30:34.488Z,1643607034.488 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:30:34.488Z,1643607034.488 [NAL9602](INFO): Packets left to send: 4 2022-01-31T05:30:43.918Z,1643607043.918 [NAL9602](INFO): SBD MO Status=1, MOMSN=35392, MT Status=0, MTMSN=0 2022-01-31T05:30:43.970Z,1643607043.970 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:30:43.970Z,1643607043.970 [NAL9602](INFO): Packets left to send: 3 2022-01-31T05:30:51.554Z,1643607051.554 [NAL9602](INFO): SBD MO Status=1, MOMSN=35393, MT Status=0, MTMSN=0 2022-01-31T05:30:51.608Z,1643607051.608 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:30:51.608Z,1643607051.608 [NAL9602](INFO): Packets left to send: 2 2022-01-31T05:31:06.675Z,1643607066.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=35394, MT Status=2, MTMSN=0 2022-01-31T05:31:06.675Z,1643607066.675 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T05:31:25.863Z,1643607085.863 [NAL9602](INFO): SBD MO Status=1, MOMSN=35394, MT Status=0, MTMSN=0 2022-01-31T05:31:25.920Z,1643607085.920 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:31:25.921Z,1643607085.921 [NAL9602](INFO): Packets left to send: 1 2022-01-31T05:31:35.931Z,1643607095.931 [NAL9602](INFO): SBD MO Status=1, MOMSN=35395, MT Status=0, MTMSN=0 2022-01-31T05:31:35.981Z,1643607095.981 [NAL9602](INFO): Sent 115 bytes from file Logs/20220130T164321/Express0022.lzma 2022-01-31T05:31:35.981Z,1643607095.981 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:31:42.648Z,1643607102.648 [NAL9602](INFO): SBD MO Status=1, MOMSN=35396, MT Status=0, MTMSN=0 2022-01-31T05:31:42.698Z,1643607102.698 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0025.lzma 2022-01-31T05:31:42.699Z,1643607102.699 [NAL9602](INFO): Packets left to send: 4 2022-01-31T05:31:51.326Z,1643607111.326 [NAL9602](INFO): SBD MO Status=1, MOMSN=35397, MT Status=0, MTMSN=0 2022-01-31T05:31:51.376Z,1643607111.376 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0025.lzma 2022-01-31T05:31:51.376Z,1643607111.376 [NAL9602](INFO): Packets left to send: 3 2022-01-31T05:31:59.170Z,1643607119.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=35398, MT Status=0, MTMSN=0 2022-01-31T05:31:59.228Z,1643607119.228 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0025.lzma 2022-01-31T05:31:59.228Z,1643607119.228 [NAL9602](INFO): Packets left to send: 2 2022-01-31T05:32:10.282Z,1643607130.282 [NAL9602](INFO): SBD MO Status=1, MOMSN=35399, MT Status=0, MTMSN=0 2022-01-31T05:32:10.337Z,1643607130.337 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0025.lzma 2022-01-31T05:32:10.338Z,1643607130.338 [NAL9602](INFO): Packets left to send: 1 2022-01-31T05:32:17.338Z,1643607137.338 [NAL9602](INFO): SBD MO Status=1, MOMSN=35400, MT Status=0, MTMSN=0 2022-01-31T05:32:17.388Z,1643607137.388 [NAL9602](INFO): Sent 5 bytes from file Logs/20220130T164321/Express0025.lzma 2022-01-31T05:32:17.388Z,1643607137.388 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:32:26.810Z,1643607146.810 [NAL9602](INFO): SBD MO Status=1, MOMSN=35401, MT Status=0, MTMSN=0 2022-01-31T05:32:26.860Z,1643607146.860 [NAL9602](INFO): Sent 62 bytes from file Logs/20220130T164321/Express0028.lzma 2022-01-31T05:32:26.860Z,1643607146.860 [NAL9602](INFO): Packets left to send: 0 2022-01-31T05:32:34.946Z,1643607154.946 [NAL9602](INFO): SBD MO Status=0, MOMSN=35402, MT Status=0, MTMSN=0 2022-01-31T05:32:35.042Z,1643607155.042 [sci2:NeedComms:D] Stopped 2022-01-31T05:32:35.042Z,1643607155.042 [sci2:NeedComms:E] Running Loop=1 2022-01-31T05:32:35.358Z,1643607155.358 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-01-31T05:32:37.378Z,1643607157.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053239.00,A,4119.73590,N,07059.56192,W,0.719,159.97,310122,,,D*7F 2022-01-31T05:32:37.381Z,1643607157.381 [NAL9602](INFO): GPS fix at 20220131T053239: (41.328932, -70.992699) 2022-01-31T05:32:37.405Z,1643607157.405 [sci2:NeedComms:E] Stopped 2022-01-31T05:32:37.406Z,1643607157.406 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T05:32:37.406Z,1643607157.406 [sci2:NeedComms] Stopped 2022-01-31T05:32:37.406Z,1643607157.406 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T05:32:37.406Z,1643607157.406 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T05:32:37.406Z,1643607157.406 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T05:32:37.812Z,1643607157.812 [sci2:StartingMission](INFO): Completed sci2:StartingMission 2022-01-31T05:32:37.812Z,1643607157.812 [sci2:StartingMission] Stopped 2022-01-31T05:32:37.812Z,1643607157.812 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission 2022-01-31T05:32:37.812Z,1643607157.812 [sci2:Lap] Running Loop=1 2022-01-31T05:32:37.812Z,1643607157.812 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap 2022-01-31T05:32:37.813Z,1643607157.813 [sci2:Lap:Wpt1] Running Loop=1 2022-01-31T05:32:37.813Z,1643607157.813 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1 2022-01-31T05:32:37.813Z,1643607157.813 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1 2022-01-31T05:32:37.813Z,1643607157.813 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent. 2022-01-31T05:33:10.090Z,1643607190.090 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T05:34:51.082Z,1643607291.082 [CTD_Seabird](ERROR): Salinity reading out of range: 25.095366 psu 2022-01-31T05:37:39.778Z,1643607459.778 [CTD_Seabird](ERROR): Salinity reading out of range: 27.204561 psu 2022-01-31T05:38:53.545Z,1643607533.545 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-31T05:49:46.409Z,1643608186.409 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T05:51:06.430Z,1643608266.430 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T06:09:42.651Z,1643609382.651 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T06:10:52.557Z,1643609452.557 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T06:11:57.326Z,1643609517.326 [CTD_Seabird](ERROR): Failed to parse device response: 6.34,2.3425,2.904.39 2022-01-31T06:16:36.002Z,1643609796.002 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-31T06:16:52.513Z,1643609812.513 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T06:16:52.515Z,1643609812.515 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T06:30:28.615Z,1643610628.615 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T06:49:16.595Z,1643611756.595 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T06:49:44.498Z,1643611784.498 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T06:49:44.498Z,1643611784.498 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T06:49:44.499Z,1643611784.499 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T06:49:44.583Z,1643611784.583 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T06:49:44.936Z,1643611784.936 [RDI_Pathfinder](INFO): Powering down 2022-01-31T06:49:45.682Z,1643611785.682 [Power24vConverter](INFO): Powering down. 2022-01-31T06:49:45.831Z,1643611785.831 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T06:49:45.831Z,1643611785.831 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T06:49:46.481Z,1643611786.481 [Power24vConverter](INFO): Powering up. 2022-01-31T06:54:22.833Z,1643612062.833 [CTD_Seabird](ERROR): Failed to parse device response: .01 2022-01-31T06:59:58.272Z,1643612398.272 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T06:59:59.174Z,1643612399.174 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:00.179Z,1643612400.179 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:01.181Z,1643612401.181 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:02.186Z,1643612402.186 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:03.189Z,1643612403.189 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:04.195Z,1643612404.195 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:05.198Z,1643612405.198 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:06.202Z,1643612406.202 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:07.205Z,1643612407.205 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:08.210Z,1643612408.210 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T07:00:09.129Z,1643612409.129 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.073836 CHAN A1 (24V): 0.142596 CHAN A2 (12V): 0.043122 CHAN A3 (5V): 0.009894 CHAN B0 (3.3V): 0.005852 CHAN B1 (3.15aV): 0.005631 CHAN B2 (3.15bV): 0.005902 CHAN B3 (GND): -0.007919 OPEN: 0.006392 Full Scale: +/- 1 mA 2022-01-31T07:04:40.162Z,1643612680.162 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T07:05:50.500Z,1643612750.500 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T07:05:50.500Z,1643612750.500 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T07:05:50.500Z,1643612750.500 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T07:05:50.623Z,1643612750.623 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T07:05:50.932Z,1643612750.932 [RDI_Pathfinder](INFO): Powering down 2022-01-31T07:05:51.732Z,1643612751.732 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T07:05:51.732Z,1643612751.732 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T07:24:00.950Z,1643613840.950 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-31T07:24:17.864Z,1643613857.864 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T07:24:17.866Z,1643613857.866 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T07:34:24.339Z,1643614464.339 [sci2:NeedComms] Running Loop=1 2022-01-31T07:34:24.339Z,1643614464.339 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T07:34:24.339Z,1643614464.339 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T07:34:24.339Z,1643614464.339 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T07:34:24.340Z,1643614464.340 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T07:34:24.340Z,1643614464.340 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T07:34:24.341Z,1643614464.341 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T07:34:24.341Z,1643614464.341 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T07:34:24.341Z,1643614464.341 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T07:34:24.342Z,1643614464.342 [sci2:NeedComms:A] Running Loop=1 2022-01-31T07:34:24.343Z,1643614464.343 [sci2:NeedComms:A](INFO): last time_fix was: 1643607159.000000 second since 1970/01/01T00:00:00Z 2022-01-31T07:34:24.343Z,1643614464.343 [sci2:NeedComms:A] Stopped 2022-01-31T07:35:51.580Z,1643614551.580 [sci2:NeedComms:C] Running Loop=1 2022-01-31T07:35:54.082Z,1643614554.082 [CTD_Seabird](ERROR): Salinity reading out of range: 27.098154 psu 2022-01-31T07:35:56.073Z,1643614556.073 [CTD_Seabird](ERROR): Salinity reading out of range: 27.197283 psu 2022-01-31T07:36:07.325Z,1643614567.325 [NAL9602](INFO): SBD MO Status=0, MOMSN=35403, MT Status=0, MTMSN=0 2022-01-31T07:36:07.325Z,1643614567.325 [NAL9602](INFO): No messages in MT queue 2022-01-31T07:37:20.450Z,1643614640.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,073722.00,A,4121.90225,N,07100.78408,W,5.151,355.18,310122,,,A*79 2022-01-31T07:37:20.452Z,1643614640.452 [NAL9602](INFO): GPS fix at 20220131T073722: (41.365037, -71.013068) 2022-01-31T07:37:20.504Z,1643614640.504 [UniversalFixResidualReporter](INFO): Fix residual: 11.9 %DT, over the last 4306.9 m. Residual distance 511.3 m at bearing 83.0 degrees. Fix at (41.3650, -71.0131) with 4360.0 m made good. 2022-01-31T07:37:20.514Z,1643614640.514 [sci2:NeedComms:C] Stopped 2022-01-31T07:37:20.514Z,1643614640.514 [sci2:NeedComms:D] Running Loop=1 2022-01-31T07:37:34.698Z,1643614654.698 [NAL9602](INFO): SBD MO Status=1, MOMSN=35404, MT Status=0, MTMSN=0 2022-01-31T07:37:34.752Z,1643614654.752 [NAL9602](INFO): Sent 98 bytes from file Logs/20220130T164321/Courier0030.lzma 2022-01-31T07:37:34.752Z,1643614654.752 [NAL9602](INFO): Packets left to send: 0 2022-01-31T07:37:50.359Z,1643614670.359 [NAL9602](INFO): SBD MO Status=2, MOMSN=35405, MT Status=2, MTMSN=0 2022-01-31T07:37:50.359Z,1643614670.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T07:38:01.265Z,1643614681.265 [NAL9602](INFO): SBD MO Status=1, MOMSN=35405, MT Status=0, MTMSN=0 2022-01-31T07:38:01.316Z,1643614681.316 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0031.lzma 2022-01-31T07:38:01.316Z,1643614681.316 [NAL9602](INFO): Packets left to send: 4 2022-01-31T07:38:17.255Z,1643614697.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=35406, MT Status=2, MTMSN=0 2022-01-31T07:38:17.255Z,1643614697.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T07:38:35.233Z,1643614715.233 [NAL9602](INFO): SBD MO Status=1, MOMSN=35406, MT Status=0, MTMSN=0 2022-01-31T07:38:35.284Z,1643614715.284 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0031.lzma 2022-01-31T07:38:35.284Z,1643614715.284 [NAL9602](INFO): Packets left to send: 3 2022-01-31T07:38:44.440Z,1643614724.440 [NAL9602](INFO): SBD MO Status=1, MOMSN=35407, MT Status=0, MTMSN=0 2022-01-31T07:38:44.489Z,1643614724.489 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0031.lzma 2022-01-31T07:38:44.489Z,1643614724.489 [NAL9602](INFO): Packets left to send: 2 2022-01-31T07:38:52.265Z,1643614732.265 [NAL9602](INFO): SBD MO Status=1, MOMSN=35408, MT Status=0, MTMSN=0 2022-01-31T07:38:52.312Z,1643614732.312 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0031.lzma 2022-01-31T07:38:52.312Z,1643614732.312 [NAL9602](INFO): Packets left to send: 1 2022-01-31T07:39:00.975Z,1643614740.975 [NAL9602](INFO): SBD MO Status=1, MOMSN=35409, MT Status=0, MTMSN=0 2022-01-31T07:39:01.028Z,1643614741.028 [NAL9602](INFO): Sent 88 bytes from file Logs/20220130T164321/Express0031.lzma 2022-01-31T07:39:01.028Z,1643614741.028 [NAL9602](INFO): Packets left to send: 0 2022-01-31T07:39:09.119Z,1643614749.119 [NAL9602](INFO): SBD MO Status=0, MOMSN=35410, MT Status=0, MTMSN=0 2022-01-31T07:39:09.202Z,1643614749.202 [sci2:NeedComms:D] Stopped 2022-01-31T07:39:09.202Z,1643614749.202 [sci2:NeedComms:E] Running Loop=1 2022-01-31T07:39:11.543Z,1643614751.543 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,073913.00,A,4121.80523,N,07100.71449,W,3.421,289.32,310122,,,A*75 2022-01-31T07:39:11.546Z,1643614751.546 [NAL9602](INFO): GPS fix at 20220131T073913: (41.363420, -71.011908) 2022-01-31T07:39:11.561Z,1643614751.561 [sci2:NeedComms:E] Stopped 2022-01-31T07:39:11.562Z,1643614751.562 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T07:39:11.562Z,1643614751.562 [sci2:NeedComms] Stopped 2022-01-31T07:39:11.562Z,1643614751.562 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T07:39:11.562Z,1643614751.562 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T07:39:11.562Z,1643614751.562 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T07:39:12.387Z,1643614752.387 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-01-31T07:39:43.859Z,1643614783.859 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T07:44:02.438Z,1643615042.438 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T07:44:12.141Z,1643615052.141 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T07:44:42.453Z,1643615082.453 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T07:58:04.796Z,1643615884.796 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-31T08:03:38.107Z,1643616218.107 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T08:04:08.401Z,1643616248.401 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T08:04:18.141Z,1643616258.141 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T08:09:22.434Z,1643616562.434 [CTD_Seabird](ERROR): Failed to parse device response: .81 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1:A.Waypoint](IMPORTANT): Reached Waypoint: 41.376400,-71.011101 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1:A.Waypoint] Stopped 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1](INFO): Completed sci2:Lap:Wpt1 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1] Stopped 2022-01-31T08:13:22.303Z,1643616802.303 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1 2022-01-31T08:13:22.304Z,1643616802.304 [sci2:Lap:Wpt2] Running Loop=1 2022-01-31T08:13:22.304Z,1643616802.304 [sci2:Lap:Wpt2](DEBUG): Aggregate::initialize sci2:Lap:Wpt2 2022-01-31T08:13:22.304Z,1643616802.304 [sci2:Lap:Wpt2:A.Waypoint] Running Loop=1 2022-01-31T08:13:22.304Z,1643616802.304 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Initialize WaypointComponent. 2022-01-31T08:23:38.391Z,1643617418.391 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 102 2022-01-31T08:23:44.102Z,1643617424.102 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T08:24:44.254Z,1643617484.254 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T08:30:35.588Z,1643617835.588 [CTD_Seabird](ERROR): Failed to parse device response: .19 2022-01-31T08:31:43.196Z,1643617903.196 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T08:31:43.198Z,1643617903.198 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T08:41:10.442Z,1643618470.442 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T08:43:10.080Z,1643618590.080 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T08:43:40.328Z,1643618620.328 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T08:44:00.136Z,1643618640.136 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T08:44:00.136Z,1643618640.136 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T08:44:00.136Z,1643618640.136 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T08:44:00.368Z,1643618640.368 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T08:44:00.580Z,1643618640.580 [RDI_Pathfinder](INFO): Powering down 2022-01-31T08:44:01.315Z,1643618641.315 [Power24vConverter](INFO): Powering down. 2022-01-31T08:44:01.403Z,1643618641.403 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T08:44:01.403Z,1643618641.403 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T08:44:02.125Z,1643618642.125 [Power24vConverter](INFO): Powering up. 2022-01-31T08:48:46.129Z,1643618926.129 [CTD_Seabird](ERROR): Failed to parse device response: 5.71,63 2022-01-31T08:50:06.133Z,1643619006.133 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T09:00:09.140Z,1643619609.140 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T09:00:09.926Z,1643619609.926 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:10.934Z,1643619610.934 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:11.934Z,1643619611.934 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:12.937Z,1643619612.937 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:13.942Z,1643619613.942 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:14.945Z,1643619614.945 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:15.951Z,1643619615.951 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:16.953Z,1643619616.953 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:17.960Z,1643619617.960 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:18.961Z,1643619618.961 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:19.966Z,1643619619.966 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T09:00:20.319Z,1643619620.319 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.084630 CHAN A1 (24V): 0.139874 CHAN A2 (12V): 0.042333 CHAN A3 (5V): 0.011885 CHAN B0 (3.3V): 0.007205 CHAN B1 (3.15aV): 0.006528 CHAN B2 (3.15bV): 0.006738 CHAN B3 (GND): -0.008157 OPEN: 0.008258 Full Scale: +/- 1 mA 2022-01-31T09:13:00.097Z,1643620380.097 [CTD_Seabird](ERROR): Failed to parse device response: 8.49,2.6102,2.932274 2022-01-31T09:18:41.999Z,1643620721.999 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T09:18:52.083Z,1643620732.083 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T09:37:27.962Z,1643621847.962 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T09:39:07.850Z,1643621947.850 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T09:39:08.135Z,1643621948.135 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T09:39:08.146Z,1643621948.146 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T09:39:45.321Z,1643621985.321 [sci2:NeedComms] Running Loop=1 2022-01-31T09:39:45.321Z,1643621985.321 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T09:39:45.321Z,1643621985.321 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T09:39:45.321Z,1643621985.321 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T09:39:45.321Z,1643621985.321 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T09:39:45.322Z,1643621985.322 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T09:39:45.322Z,1643621985.322 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T09:39:45.322Z,1643621985.322 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T09:39:45.323Z,1643621985.323 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T09:39:45.323Z,1643621985.323 [sci2:NeedComms:A] Running Loop=1 2022-01-31T09:39:45.325Z,1643621985.325 [sci2:NeedComms:A](INFO): last time_fix was: 1643614753.000000 second since 1970/01/01T00:00:00Z 2022-01-31T09:39:45.325Z,1643621985.325 [sci2:NeedComms:A] Stopped 2022-01-31T09:39:59.067Z,1643621999.067 [sci2:NeedComms:C] Running Loop=1 2022-01-31T09:40:15.199Z,1643622015.199 [NAL9602](INFO): SBD MO Status=0, MOMSN=35411, MT Status=0, MTMSN=0 2022-01-31T09:40:15.199Z,1643622015.199 [NAL9602](INFO): No messages in MT queue 2022-01-31T09:40:16.411Z,1643622016.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094018.00,A,4125.57795,N,07100.63597,W,0.583,7.72,310122,,,A*74 2022-01-31T09:40:16.413Z,1643622016.413 [NAL9602](INFO): GPS fix at 20220131T094018: (41.426299, -71.010599) 2022-01-31T09:40:16.426Z,1643622016.426 [UniversalFixResidualReporter](INFO): Fix residual: 15.1 %DT, over the last 6578.7 m. Residual distance 992.9 m at bearing 125.7 degrees. Fix at (41.4263, -71.0106) with 6992.6 m made good. 2022-01-31T09:40:16.431Z,1643622016.431 [sci2:NeedComms:C] Stopped 2022-01-31T09:40:16.432Z,1643622016.432 [sci2:NeedComms:D] Running Loop=1 2022-01-31T09:40:26.319Z,1643622026.319 [NAL9602](INFO): SBD MO Status=1, MOMSN=35412, MT Status=0, MTMSN=0 2022-01-31T09:40:26.371Z,1643622026.371 [NAL9602](INFO): Sent 101 bytes from file Logs/20220130T164321/Courier0033.lzma 2022-01-31T09:40:26.371Z,1643622026.371 [NAL9602](INFO): Packets left to send: 0 2022-01-31T09:40:37.562Z,1643622037.562 [NAL9602](INFO): SBD MO Status=2, MOMSN=35413, MT Status=2, MTMSN=0 2022-01-31T09:40:37.563Z,1643622037.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T09:40:55.743Z,1643622055.743 [NAL9602](INFO): SBD MO Status=1, MOMSN=35413, MT Status=0, MTMSN=0 2022-01-31T09:40:55.796Z,1643622055.796 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:40:55.796Z,1643622055.796 [NAL9602](INFO): Packets left to send: 5 2022-01-31T09:41:02.042Z,1643622062.042 [NAL9602](INFO): SBD MO Status=1, MOMSN=35414, MT Status=0, MTMSN=0 2022-01-31T09:41:02.092Z,1643622062.092 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:41:02.092Z,1643622062.092 [NAL9602](INFO): Packets left to send: 4 2022-01-31T09:41:10.782Z,1643622070.782 [NAL9602](INFO): SBD MO Status=1, MOMSN=35415, MT Status=0, MTMSN=0 2022-01-31T09:41:10.836Z,1643622070.836 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:41:10.836Z,1643622070.836 [NAL9602](INFO): Packets left to send: 3 2022-01-31T09:41:17.164Z,1643622077.164 [NAL9602](INFO): SBD MO Status=1, MOMSN=35416, MT Status=0, MTMSN=0 2022-01-31T09:41:17.212Z,1643622077.212 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:41:17.212Z,1643622077.212 [NAL9602](INFO): Packets left to send: 2 2022-01-31T09:41:26.023Z,1643622086.023 [NAL9602](INFO): SBD MO Status=1, MOMSN=35417, MT Status=0, MTMSN=0 2022-01-31T09:41:26.078Z,1643622086.078 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:41:26.078Z,1643622086.078 [NAL9602](INFO): Packets left to send: 1 2022-01-31T09:41:31.648Z,1643622091.648 [NAL9602](INFO): SBD MO Status=1, MOMSN=35418, MT Status=0, MTMSN=0 2022-01-31T09:41:31.701Z,1643622091.701 [NAL9602](INFO): Sent 25 bytes from file Logs/20220130T164321/Express0034.lzma 2022-01-31T09:41:31.701Z,1643622091.701 [NAL9602](INFO): Packets left to send: 0 2022-01-31T09:41:39.006Z,1643622099.006 [NAL9602](INFO): SBD MO Status=0, MOMSN=35419, MT Status=0, MTMSN=0 2022-01-31T09:41:39.099Z,1643622099.099 [sci2:NeedComms:D] Stopped 2022-01-31T09:41:39.101Z,1643622099.101 [sci2:NeedComms:E] Running Loop=1 2022-01-31T09:41:41.431Z,1643622101.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094143.00,A,4125.60208,N,07100.62639,W,1.263,23.40,310122,,,A*47 2022-01-31T09:41:41.433Z,1643622101.433 [NAL9602](INFO): GPS fix at 20220131T094143: (41.426701, -71.010440) 2022-01-31T09:41:41.449Z,1643622101.449 [sci2:NeedComms:E] Stopped 2022-01-31T09:41:41.450Z,1643622101.450 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T09:41:41.450Z,1643622101.450 [sci2:NeedComms] Stopped 2022-01-31T09:41:41.450Z,1643622101.450 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T09:41:41.450Z,1643622101.450 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T09:41:41.450Z,1643622101.450 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T09:41:42.246Z,1643622102.246 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-01-31T09:42:13.759Z,1643622133.759 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T09:49:37.800Z,1643622577.800 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T09:53:56.332Z,1643622836.332 [sci2:Lap:Wpt2:A.Waypoint](IMPORTANT): Reached Waypoint: 41.430038,-70.998901 2022-01-31T09:53:56.332Z,1643622836.332 [sci2:Lap:Wpt2:A.Waypoint] Stopped 2022-01-31T09:53:56.333Z,1643622836.333 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-01-31T09:53:56.333Z,1643622836.333 [sci2:Lap:Wpt2](INFO): Completed sci2:Lap:Wpt2 2022-01-31T09:53:56.333Z,1643622836.333 [sci2:Lap:Wpt2] Stopped 2022-01-31T09:53:56.333Z,1643622836.333 [sci2:Lap:Wpt2](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt2 2022-01-31T09:53:56.333Z,1643622836.333 [sci2:Lap:Wpt3] Running Loop=1 2022-01-31T09:53:56.334Z,1643622836.334 [sci2:Lap:Wpt3](DEBUG): Aggregate::initialize sci2:Lap:Wpt3 2022-01-31T09:53:56.334Z,1643622836.334 [sci2:Lap:Wpt3:A.Waypoint] Running Loop=1 2022-01-31T09:53:56.334Z,1643622836.334 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Initialize WaypointComponent. 2022-01-31T09:54:14.317Z,1643622854.317 [CTD_Seabird](ERROR): Failed to parse device response: 1 2022-01-31T09:59:23.997Z,1643623163.997 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T10:18:29.773Z,1643624309.773 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T10:18:49.959Z,1643624329.959 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T10:30:33.353Z,1643625033.353 [CTD_Seabird](ERROR): Failed to parse device response: 0.12,2.74172,4674.09 2022-01-31T10:37:25.814Z,1643625445.814 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T10:44:38.491Z,1643625878.491 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm (2 active estimators). 2022-01-31T10:46:29.600Z,1643625989.600 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T10:46:29.602Z,1643625989.602 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T10:49:17.713Z,1643626157.713 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T10:58:11.780Z,1643626691.780 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:00:20.404Z,1643626820.404 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T11:00:20.692Z,1643626820.692 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:21.694Z,1643626821.694 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:22.698Z,1643626822.698 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:23.702Z,1643626823.702 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:24.706Z,1643626824.706 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:25.710Z,1643626825.710 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:26.714Z,1643626826.714 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:27.718Z,1643626827.718 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:28.722Z,1643626828.722 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:29.726Z,1643626829.726 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:30.729Z,1643626830.729 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T11:00:31.297Z,1643626831.297 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.069636 CHAN A1 (24V): 0.136729 CHAN A2 (12V): 0.048781 CHAN A3 (5V): 0.014567 CHAN B0 (3.3V): 0.009752 CHAN B1 (3.15aV): 0.009068 CHAN B2 (3.15bV): 0.009376 CHAN B3 (GND): -0.008184 OPEN: 0.006700 Full Scale: +/- 1 mA 2022-01-31T11:16:37.937Z,1643627797.937 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T11:17:17.962Z,1643627837.962 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T11:17:27.708Z,1643627847.708 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T11:17:27.708Z,1643627847.708 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T11:17:27.708Z,1643627847.708 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T11:17:27.857Z,1643627847.857 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T11:17:28.112Z,1643627848.112 [RDI_Pathfinder](INFO): Powering down 2022-01-31T11:17:28.944Z,1643627848.944 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T11:17:28.944Z,1643627848.944 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T11:19:50.509Z,1643627990.509 [CTD_Seabird](ERROR): Failed to parse device response: 0.17,2.3222,2.905431 2022-01-31T11:29:23.553Z,1643628563.553 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T11:29:33.312Z,1643628573.312 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T11:29:53.454Z,1643628593.454 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T11:30:03.544Z,1643628603.544 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2022-01-31T11:30:23.357Z,1643628623.357 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:30:33.446Z,1643628633.446 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:35:17.486Z,1643628917.486 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:40:57.324Z,1643629257.324 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:40:57.734Z,1643629257.734 [CTD_Seabird](ERROR): Failed to parse device response: 0.19,2.2942,5 2022-01-31T11:41:41.709Z,1643629301.709 [sci2:NeedComms] Running Loop=1 2022-01-31T11:41:41.709Z,1643629301.709 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T11:41:41.709Z,1643629301.709 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T11:41:41.709Z,1643629301.709 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T11:41:41.710Z,1643629301.710 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T11:41:41.710Z,1643629301.710 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T11:41:41.710Z,1643629301.710 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T11:41:41.711Z,1643629301.711 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T11:41:41.711Z,1643629301.711 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T11:41:41.711Z,1643629301.711 [sci2:NeedComms:A] Running Loop=1 2022-01-31T11:41:41.713Z,1643629301.713 [sci2:NeedComms:A](INFO): last time_fix was: 1643622103.000000 second since 1970/01/01T00:00:00Z 2022-01-31T11:41:41.713Z,1643629301.713 [sci2:NeedComms:A] Stopped 2022-01-31T11:41:42.132Z,1643629302.132 [sci2:NeedComms:C] Running Loop=1 2022-01-31T11:41:44.113Z,1643629304.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114146.00,A,4127.93253,N,07056.48576,W,1.225,56.24,310122,,,D*4E 2022-01-31T11:41:44.115Z,1643629304.115 [NAL9602](INFO): GPS fix at 20220131T114146: (41.465542, -70.941429) 2022-01-31T11:41:44.128Z,1643629304.128 [UniversalFixResidualReporter](INFO): Fix residual: 6.5 %DT, over the last 7241.5 m. Residual distance 471.0 m at bearing 147.7 degrees. Fix at (41.4655, -70.9414) with 7192.9 m made good. 2022-01-31T11:41:44.133Z,1643629304.133 [sci2:NeedComms:C] Stopped 2022-01-31T11:41:44.134Z,1643629304.134 [sci2:NeedComms:D] Running Loop=1 2022-01-31T11:42:10.878Z,1643629330.878 [NAL9602](INFO): SBD MO Status=2, MOMSN=35420, MT Status=2, MTMSN=0 2022-01-31T11:42:10.879Z,1643629330.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T11:42:35.362Z,1643629355.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=35420, MT Status=0, MTMSN=0 2022-01-31T11:42:35.416Z,1643629355.416 [NAL9602](INFO): Sent 100 bytes from file Logs/20220130T164321/Courier0036.lzma 2022-01-31T11:42:35.416Z,1643629355.416 [NAL9602](INFO): Packets left to send: 0 2022-01-31T11:42:55.139Z,1643629375.139 [NAL9602](INFO): SBD MO Status=1, MOMSN=35421, MT Status=0, MTMSN=0 2022-01-31T11:42:55.188Z,1643629375.188 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0037.lzma 2022-01-31T11:42:55.188Z,1643629375.188 [NAL9602](INFO): Packets left to send: 2 2022-01-31T11:43:18.059Z,1643629398.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=35422, MT Status=2, MTMSN=0 2022-01-31T11:43:18.059Z,1643629398.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T11:43:38.163Z,1643629418.163 [NAL9602](INFO): SBD MO Status=1, MOMSN=35422, MT Status=0, MTMSN=0 2022-01-31T11:43:38.216Z,1643629418.216 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0037.lzma 2022-01-31T11:43:38.216Z,1643629418.216 [NAL9602](INFO): Packets left to send: 1 2022-01-31T11:43:49.394Z,1643629429.394 [NAL9602](INFO): SBD MO Status=1, MOMSN=35423, MT Status=0, MTMSN=0 2022-01-31T11:43:49.452Z,1643629429.452 [NAL9602](INFO): Sent 10 bytes from file Logs/20220130T164321/Express0037.lzma 2022-01-31T11:43:49.453Z,1643629429.453 [NAL9602](INFO): Packets left to send: 0 2022-01-31T11:43:57.907Z,1643629437.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=35424, MT Status=0, MTMSN=0 2022-01-31T11:43:58.001Z,1643629438.001 [sci2:NeedComms:D] Stopped 2022-01-31T11:43:58.002Z,1643629438.002 [sci2:NeedComms:E] Running Loop=1 2022-01-31T11:44:00.329Z,1643629440.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114402.00,A,4127.94518,N,07056.45944,W,0.816,67.61,310122,,,D*4C 2022-01-31T11:44:00.331Z,1643629440.331 [NAL9602](INFO): GPS fix at 20220131T114402: (41.465753, -70.940991) 2022-01-31T11:44:00.371Z,1643629440.371 [sci2:NeedComms:E] Stopped 2022-01-31T11:44:00.376Z,1643629440.376 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T11:44:00.376Z,1643629440.376 [sci2:NeedComms] Stopped 2022-01-31T11:44:00.376Z,1643629440.376 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T11:44:00.376Z,1643629440.376 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T11:44:00.376Z,1643629440.376 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T11:44:33.101Z,1643629473.101 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T11:50:19.345Z,1643629819.345 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T11:50:29.439Z,1643629829.439 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T11:50:39.551Z,1643629839.551 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T11:53:38.157Z,1643630018.157 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-31T11:53:54.674Z,1643630034.674 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T11:53:54.677Z,1643630034.677 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T11:58:01.514Z,1643630281.514 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T12:08:55.276Z,1643630935.276 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T12:09:55.422Z,1643630995.422 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T12:13:33.769Z,1643631213.769 [CTD_Seabird](ERROR): Failed to parse device response: 38,4665.90 2022-01-31T12:34:13.057Z,1643632453.057 [sci2:Lap:Wpt3:A.Waypoint](IMPORTANT): Reached Waypoint: 41.470379,-70.920418 2022-01-31T12:34:13.057Z,1643632453.057 [sci2:Lap:Wpt3:A.Waypoint] Stopped 2022-01-31T12:34:13.057Z,1643632453.057 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt3](INFO): Completed sci2:Lap:Wpt3 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt3] Stopped 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt3](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt3 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt4] Running Loop=1 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt4](DEBUG): Aggregate::initialize sci2:Lap:Wpt4 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt4:A.Waypoint] Running Loop=1 2022-01-31T12:34:13.058Z,1643632453.058 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Initialize WaypointComponent. 2022-01-31T12:45:47.547Z,1643633147.547 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T12:46:53.057Z,1643633213.057 [CTD_Seabird](ERROR): Failed to parse device response: ,2.2758,2.89990,4668.97 2022-01-31T12:48:47.345Z,1643633327.345 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-01-31T12:48:47.346Z,1643633327.346 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-01-31T12:48:47.346Z,1643633327.346 [RDI_Pathfinder](ERROR): Communications Fault 2022-01-31T12:48:47.471Z,1643633327.471 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-01-31T12:48:47.792Z,1643633327.792 [RDI_Pathfinder](INFO): Powering down 2022-01-31T12:48:48.529Z,1643633328.529 [Power24vConverter](INFO): Powering down. 2022-01-31T12:48:48.623Z,1643633328.623 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-01-31T12:48:48.623Z,1643633328.623 [RDI_Pathfinder] No Fault, FailCount= 1 2022-01-31T12:48:49.330Z,1643633329.330 [Power24vConverter](INFO): Powering up. 2022-01-31T13:00:31.605Z,1643634031.605 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T13:00:32.441Z,1643634032.441 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:33.446Z,1643634033.446 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:34.450Z,1643634034.450 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:35.453Z,1643634035.453 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:36.458Z,1643634036.458 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:37.461Z,1643634037.461 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:38.466Z,1643634038.466 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:39.469Z,1643634039.469 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:40.473Z,1643634040.473 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:41.478Z,1643634041.478 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:42.482Z,1643634042.482 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T13:00:42.630Z,1643634042.630 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.071408 CHAN A1 (24V): 0.152623 CHAN A2 (12V): 0.052961 CHAN A3 (5V): 0.014048 CHAN B0 (3.3V): 0.007847 CHAN B1 (3.15aV): 0.006566 CHAN B2 (3.15bV): 0.006813 CHAN B3 (GND): -0.009262 OPEN: 0.006169 Full Scale: +/- 1 mA 2022-01-31T13:01:19.590Z,1643634079.590 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T13:01:19.592Z,1643634079.592 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T13:04:22.373Z,1643634262.373 [CTD_Seabird](ERROR): Failed to parse device response: 10,4671.81 2022-01-31T13:23:09.014Z,1643635389.014 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:23:19.124Z,1643635399.124 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:23:29.226Z,1643635409.226 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:23:49.029Z,1643635429.029 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T13:24:18.996Z,1643635458.996 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T13:28:06.185Z,1643635686.185 [CTD_Seabird](ERROR): Failed to parse device response: .07 2022-01-31T13:42:55.203Z,1643636575.203 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:43:04.965Z,1643636584.965 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:43:15.030Z,1643636595.030 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T13:43:25.115Z,1643636605.115 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T13:44:01.890Z,1643636641.890 [sci2:NeedComms] Running Loop=1 2022-01-31T13:44:01.890Z,1643636641.890 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T13:44:01.890Z,1643636641.890 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T13:44:01.890Z,1643636641.890 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T13:44:01.891Z,1643636641.891 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T13:44:01.891Z,1643636641.891 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T13:44:01.892Z,1643636641.892 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T13:44:01.892Z,1643636641.892 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T13:44:01.892Z,1643636641.892 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T13:44:01.893Z,1643636641.893 [sci2:NeedComms:A] Running Loop=1 2022-01-31T13:44:01.894Z,1643636641.894 [sci2:NeedComms:A](INFO): last time_fix was: 1643629442.000000 second since 1970/01/01T00:00:00Z 2022-01-31T13:44:01.894Z,1643636641.894 [sci2:NeedComms:A] Stopped 2022-01-31T13:44:02.310Z,1643636642.310 [sci2:NeedComms:C] Running Loop=1 2022-01-31T13:44:04.293Z,1643636644.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134406.00,A,4128.62445,N,07054.18086,W,0.544,60.06,310122,,,D*44 2022-01-31T13:44:04.295Z,1643636644.295 [NAL9602](INFO): GPS fix at 20220131T134406: (41.477074, -70.903014) 2022-01-31T13:44:04.308Z,1643636644.308 [UniversalFixResidualReporter](INFO): Fix residual: 5.4 %DT, over the last 3595.3 m. Residual distance 193.9 m at bearing 151.9 degrees. Fix at (41.4771, -70.9030) with 3405.3 m made good. 2022-01-31T13:44:04.326Z,1643636644.326 [sci2:NeedComms:C] Stopped 2022-01-31T13:44:04.326Z,1643636644.326 [sci2:NeedComms:D] Running Loop=1 2022-01-31T13:44:05.143Z,1643636645.143 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T13:44:35.611Z,1643636675.611 [NAL9602](INFO): SBD MO Status=2, MOMSN=35425, MT Status=2, MTMSN=0 2022-01-31T13:44:35.611Z,1643636675.611 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T13:45:07.031Z,1643636707.031 [NAL9602](INFO): SBD MO Status=1, MOMSN=35425, MT Status=0, MTMSN=0 2022-01-31T13:45:07.080Z,1643636707.080 [NAL9602](INFO): Sent 100 bytes from file Logs/20220130T164321/Courier0039.lzma 2022-01-31T13:45:07.080Z,1643636707.080 [NAL9602](INFO): Packets left to send: 0 2022-01-31T13:45:23.063Z,1643636723.063 [NAL9602](INFO): SBD MO Status=1, MOMSN=35426, MT Status=0, MTMSN=0 2022-01-31T13:45:23.120Z,1643636723.120 [NAL9602](INFO): Sent 184 bytes from file Logs/20220130T164321/Express0040.lzma 2022-01-31T13:45:23.120Z,1643636723.120 [NAL9602](INFO): Packets left to send: 1 2022-01-31T13:45:31.827Z,1643636731.827 [NAL9602](INFO): SBD MO Status=1, MOMSN=35427, MT Status=0, MTMSN=0 2022-01-31T13:45:31.876Z,1643636731.876 [NAL9602](INFO): Sent 174 bytes from file Logs/20220130T164321/Express0040.lzma 2022-01-31T13:45:31.876Z,1643636731.876 [NAL9602](INFO): Packets left to send: 0 2022-01-31T13:45:39.198Z,1643636739.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=35428, MT Status=0, MTMSN=0 2022-01-31T13:45:39.314Z,1643636739.314 [sci2:NeedComms:D] Stopped 2022-01-31T13:45:39.314Z,1643636739.314 [sci2:NeedComms:E] Running Loop=1 2022-01-31T13:45:41.610Z,1643636741.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134543.00,A,4128.61633,N,07054.18830,W,0.525,220.25,310122,,,D*71 2022-01-31T13:45:41.613Z,1643636741.613 [NAL9602](INFO): GPS fix at 20220131T134543: (41.476939, -70.903138) 2022-01-31T13:45:41.631Z,1643636741.631 [sci2:NeedComms:E] Stopped 2022-01-31T13:45:41.632Z,1643636741.632 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2022-01-31T13:45:41.632Z,1643636741.632 [sci2:NeedComms] Stopped 2022-01-31T13:45:41.633Z,1643636741.633 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2022-01-31T13:45:41.633Z,1643636741.633 [sci2:NeedComms:B.GoToSurface] Stopped 2022-01-31T13:45:41.633Z,1643636741.633 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-01-31T13:46:13.957Z,1643636773.957 [NAL9602](INFO): Not Powering down - fast GPS 2022-01-31T13:51:10.932Z,1643637070.932 [CTD_Seabird](ERROR): Failed to parse device response: ,2.0673,2.87772,4668.26 2022-01-31T13:53:16.437Z,1643637196.437 [CTD_Seabird](ERROR): Failed to parse device response: 00665,2.87745,4670.00 2022-01-31T13:54:53.825Z,1643637293.825 [CTD_Seabird](ERROR): Failed to parse device response: 74,4670.99 2022-01-31T14:02:51.062Z,1643637771.062 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T14:07:53.263Z,1643638073.263 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T14:08:28.049Z,1643638108.049 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-01-31T14:08:43.761Z,1643638123.761 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T14:08:43.763Z,1643638123.763 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T14:21:09.187Z,1643638869.187 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-01-31T14:21:49.495Z,1643638909.495 [CTD_Seabird](ERROR): Salinity reading out of range: 20.069725 psu 2022-01-31T14:22:57.031Z,1643638977.031 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T14:23:07.133Z,1643638987.133 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T14:42:43.235Z,1643640163.235 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T14:58:58.910Z,1643641138.910 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T15:00:42.748Z,1643641242.748 [CBIT](IMPORTANT): Beginning ground fault scan 2022-01-31T15:00:43.090Z,1643641243.090 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:44.093Z,1643641244.093 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:45.098Z,1643641245.098 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:46.101Z,1643641246.101 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:47.105Z,1643641247.105 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:48.109Z,1643641248.109 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:49.113Z,1643641249.113 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:50.118Z,1643641250.118 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:51.121Z,1643641251.121 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:52.125Z,1643641252.125 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:53.130Z,1643641253.130 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2022-01-31T15:00:53.687Z,1643641253.687 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.086239 CHAN A1 (24V): 0.163843 CHAN A2 (12V): 0.054241 CHAN A3 (5V): 0.014540 CHAN B0 (3.3V): 0.007986 CHAN B1 (3.15aV): 0.008121 CHAN B2 (3.15bV): 0.007744 CHAN B3 (GND): -0.010954 OPEN: 0.006383 Full Scale: +/- 1 mA 2022-01-31T15:02:09.153Z,1643641329.153 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-01-31T15:02:28.945Z,1643641348.945 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T15:12:53.157Z,1643641973.157 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T15:16:08.667Z,1643642168.667 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-01-31T15:16:08.669Z,1643642168.669 [BPC1](INFO): Received data from all battery sticks. 2022-01-31T15:21:35.111Z,1643642495.111 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T15:22:03.409Z,1643642523.409 [CTD_Seabird](ERROR): Failed to parse device response: 56,4681.01 2022-01-31T15:22:24.873Z,1643642544.873 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T15:22:45.019Z,1643642565.019 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T15:22:55.106Z,1643642575.106 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-01-31T15:37:47.163Z,1643643467.163 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-01-31T15:41:51.172Z,1643643711.172 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-01-31T15:42:50.984Z,1643643770.984 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T15:43:01.081Z,1643643781.081 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-01-31T15:45:41.887Z,1643643941.887 [sci2:NeedComms] Running Loop=1 2022-01-31T15:45:41.887Z,1643643941.887 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2022-01-31T15:45:41.887Z,1643643941.887 [sci2:NeedComms:B.GoToSurface] Running Loop=1 2022-01-31T15:45:41.887Z,1643643941.887 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-01-31T15:45:41.888Z,1643643941.888 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-01-31T15:45:41.889Z,1643643941.889 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-01-31T15:45:41.889Z,1643643941.889 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-01-31T15:45:41.890Z,1643643941.890 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-01-31T15:45:41.890Z,1643643941.890 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-01-31T15:45:41.890Z,1643643941.890 [sci2:NeedComms:A] Running Loop=1 2022-01-31T15:45:41.891Z,1643643941.891 [sci2:NeedComms:A](INFO): last time_fix was: 1643636743.000000 second since 1970/01/01T00:00:00Z 2022-01-31T15:45:41.891Z,1643643941.891 [sci2:NeedComms:A] Stopped 2022-01-31T15:45:42.306Z,1643643942.306 [sci2:NeedComms:C] Running Loop=1 2022-01-31T15:45:44.291Z,1643643944.291 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154546.00,A,4128.86153,N,07053.13302,W,0.583,353.54,310122,,,D*73 2022-01-31T15:45:44.294Z,1643643944.294 [NAL9602](INFO): GPS fix at 20220131T154546: (41.481026, -70.885550) 2022-01-31T15:45:44.311Z,1643643944.311 [UniversalFixResidualReporter](INFO): Fix residual: 4.8 %DT, over the last 1844.8 m. Residual distance 87.9 m at bearing 154.1 degrees. Fix at (41.4810, -70.8856) with 1534.1 m made good. 2022-01-31T15:45:44.328Z,1643643944.328 [sci2:NeedComms:C] Stopped 2022-01-31T15:45:44.329Z,1643643944.329 [sci2:NeedComms:D] Running Loop=1 2022-01-31T15:46:02.629Z,1643643962.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=35429, MT Status=2, MTMSN=0 2022-01-31T15:46:02.629Z,1643643962.629 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T15:46:58.910Z,1643644018.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=35429, MT Status=2, MTMSN=0 2022-01-31T15:46:58.911Z,1643644018.911 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T15:47:12.358Z,1643644032.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=35429, MT Status=2, MTMSN=0 2022-01-31T15:47:12.359Z,1643644032.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-01-31T15:47:31.906Z,1643644051.906 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=35429, MT Status=1, MTMSN=1399 2022-01-31T15:47:31.968Z,1643644051.968 [NAL9602](INFO): Sent 100 bytes from file Logs/20220130T164321/Courier0042.lzma 2022-01-31T15:47:31.968Z,1643644051.968 [NAL9602](INFO): Packets left to send: 0 2022-01-31T15:47:32.404Z,1643644052.404 [NAL9602](INFO): Received command: restart logs