2021-04-22T16:01:07.120Z,1619107267.120 [CommandLine](IMPORTANT): got command restart logs 2021-04-22T16:01:07.543Z,1619107267.543 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:03:33.383Z,1619107413.383 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263527 2021-04-22T16:04:27.614Z,1619107467.614 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:04:41.742Z,1619107481.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:04:41.743Z,1619107481.743 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:04:41.743Z,1619107481.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:04:41.744Z,1619107481.744 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:04:42.146Z,1619107482.146 [Default:CheckIn:D] Stopped 2021-04-22T16:04:42.146Z,1619107482.146 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.234202 min 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn:E] Stopped 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn] Stopped 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:04:42.562Z,1619107482.562 [Default:CheckIn](INFO): Running loop #3 2021-04-22T16:04:42.563Z,1619107482.563 [Default:CheckIn] Running Loop=3 2021-04-22T16:04:42.563Z,1619107482.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:04:42.563Z,1619107482.563 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:04:44.561Z,1619107484.561 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160444.00,A,3648.34581,N,12150.03185,W,21.013,66.69,220421,,,D*79 2021-04-22T16:04:44.564Z,1619107484.564 [NAL9602](INFO): GPS fix at 20210422T160444: (36.805763, -121.833864) 2021-04-22T16:04:44.595Z,1619107484.595 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:04:44.595Z,1619107484.595 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:05:02.754Z,1619107502.754 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:05:05.279Z,1619107505.279 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=2998, MT Status=1, MTMSN=210 2021-04-22T16:05:05.326Z,1619107505.326 [NAL9602](INFO): Sent 39 bytes from file Logs/20210421T012532/Courier0150.lzma 2021-04-22T16:05:05.327Z,1619107505.327 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:05:05.750Z,1619107505.750 [NAL9602](INFO): Received command:Stop 2021-04-22T16:05:05.779Z,1619107505.779 [CommandLine](IMPORTANT): got command stop 2021-04-22T16:05:23.082Z,1619107523.082 [NAL9602](INFO): SBD MO Status=1, MOMSN=2999, MT Status=0, MTMSN=0 2021-04-22T16:05:23.134Z,1619107523.134 [NAL9602](INFO): Sent 265 bytes from file Logs/20210422T160107/Courier0000.lzma 2021-04-22T16:05:23.134Z,1619107523.134 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:05:30.361Z,1619107530.361 [NAL9602](INFO): SBD MO Status=1, MOMSN=3000, MT Status=0, MTMSN=0 2021-04-22T16:05:30.412Z,1619107530.412 [NAL9602](INFO): Sent 163 bytes from file Logs/20210421T012532/Express0151.lzma 2021-04-22T16:05:30.412Z,1619107530.412 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:05:37.894Z,1619107537.894 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:05:39.293Z,1619107539.293 [NAL9602](INFO): SBD MO Status=1, MOMSN=3001, MT Status=0, MTMSN=0 2021-04-22T16:05:39.350Z,1619107539.350 [NAL9602](INFO): Sent 332 bytes from file Logs/20210422T160107/Express0001.lzma 2021-04-22T16:05:39.350Z,1619107539.350 [NAL9602](INFO): Packets left to send: 1 2021-04-22T16:05:56.306Z,1619107556.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=3002, MT Status=2, MTMSN=0 2021-04-22T16:05:56.306Z,1619107556.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T16:06:13.051Z,1619107573.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:06:15.320Z,1619107575.320 [NAL9602](INFO): SBD MO Status=1, MOMSN=3002, MT Status=0, MTMSN=0 2021-04-22T16:06:15.374Z,1619107575.374 [NAL9602](INFO): Sent 68 bytes from file Logs/20210422T160107/Express0001.lzma 2021-04-22T16:06:15.374Z,1619107575.374 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:06:22.189Z,1619107582.189 [NAL9602](INFO): SBD MO Status=0, MOMSN=3003, MT Status=0, MTMSN=0 2021-04-22T16:06:48.202Z,1619107608.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:06:52.951Z,1619107612.951 [NAL9602](INFO): SBD MO Status=1, MOMSN=3004, MT Status=0, MTMSN=0 2021-04-22T16:06:53.006Z,1619107613.006 [NAL9602](INFO): Sent 39 bytes from file Logs/20210422T160107/Courier0003.lzma 2021-04-22T16:06:53.006Z,1619107613.006 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:07:09.970Z,1619107629.970 [NAL9602](INFO): SBD MO Status=2, MOMSN=3005, MT Status=2, MTMSN=0 2021-04-22T16:07:09.971Z,1619107629.971 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T16:07:23.342Z,1619107643.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:07:27.017Z,1619107647.017 [NAL9602](INFO): SBD MO Status=1, MOMSN=3005, MT Status=0, MTMSN=0 2021-04-22T16:07:27.074Z,1619107647.074 [NAL9602](INFO): Sent 168 bytes from file Logs/20210422T160107/Express0004.lzma 2021-04-22T16:07:27.074Z,1619107647.074 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:07:35.101Z,1619107655.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=3006, MT Status=0, MTMSN=0 2021-04-22T16:07:35.203Z,1619107655.203 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:07:35.204Z,1619107655.204 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:07:35.204Z,1619107655.204 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:07:45.209Z,1619107665.209 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2021-04-22T16:07:45.210Z,1619107665.210 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2021-04-22T16:07:45.212Z,1619107665.212 [BPC1](INFO): Received data from all battery sticks. 2021-04-22T16:07:55.818Z,1619107675.818 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003169 2021-04-22T16:08:05.803Z,1619107685.803 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:10:06.641Z,1619107806.641 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T16:10:06.641Z,1619107806.641 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+1.0,1489.0,000 2021-04-22T16:12:35.779Z,1619107955.779 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:12:35.779Z,1619107955.779 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:12:35.779Z,1619107955.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:12:35.780Z,1619107955.780 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:12:36.195Z,1619107956.195 [Default:CheckIn:D] Stopped 2021-04-22T16:12:36.195Z,1619107956.195 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:12:36.575Z,1619107956.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.134727 min 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn:E] Stopped 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn] Stopped 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn](INFO): Running loop #4 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn] Running Loop=4 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:12:36.576Z,1619107956.576 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:12:38.590Z,1619107958.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161238.00,A,3648.56973,N,12149.59850,W,4.218,104.39,220421,,,D*78 2021-04-22T16:12:38.592Z,1619107958.592 [NAL9602](INFO): GPS fix at 20210422T161238: (36.809495, -121.826642) 2021-04-22T16:12:38.603Z,1619107958.603 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:12:38.603Z,1619107958.603 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:12:45.670Z,1619107965.670 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20210422T160107/Courier0006.lzma 2021-04-22T16:12:46.671Z,1619107966.671 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0006.lzma.bak 2021-04-22T16:12:46.672Z,1619107966.672 [DataOverHttps](INFO): SBD MOMSN=15598442 2021-04-22T16:12:51.517Z,1619107971.517 [NAL9602](INFO): SBD MO Status=0, MOMSN=3007, MT Status=0, MTMSN=0 2021-04-22T16:12:51.517Z,1619107971.517 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:13:03.578Z,1619107983.578 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210422T160107/Express0007.lzma 2021-04-22T16:13:04.580Z,1619107984.580 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0007.lzma.bak 2021-04-22T16:13:04.580Z,1619107984.580 [DataOverHttps](INFO): SBD MOMSN=15598445 2021-04-22T16:13:20.593Z,1619108000.593 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20210422T160107/Express0008.lzma 2021-04-22T16:13:21.596Z,1619108001.596 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0008.lzma.bak 2021-04-22T16:13:21.596Z,1619108001.596 [DataOverHttps](INFO): SBD MOMSN=15598448 2021-04-22T16:13:22.223Z,1619108002.223 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:13:23.053Z,1619108003.053 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:13:23.053Z,1619108003.053 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:13:23.053Z,1619108003.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:14:50.373Z,1619108090.373 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:14:50.374Z,1619108090.374 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.999 2021-04-22T16:16:15.106Z,1619108175.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:18:01.282Z,1619108281.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:18:03.290Z,1619108283.290 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259361 2021-04-22T16:18:23.719Z,1619108303.719 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:18:23.720Z,1619108303.720 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:18:23.720Z,1619108303.720 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:18:23.720Z,1619108303.720 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:18:24.128Z,1619108304.128 [Default:CheckIn:D] Stopped 2021-04-22T16:18:24.128Z,1619108304.128 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:18:24.520Z,1619108304.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.933897 min 2021-04-22T16:18:24.520Z,1619108304.520 [Default:CheckIn:E] Stopped 2021-04-22T16:18:24.520Z,1619108304.520 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:18:24.520Z,1619108304.520 [Default:CheckIn] Stopped 2021-04-22T16:18:24.520Z,1619108304.520 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:18:24.521Z,1619108304.521 [Default:CheckIn](INFO): Running loop #5 2021-04-22T16:18:24.521Z,1619108304.521 [Default:CheckIn] Running Loop=5 2021-04-22T16:18:24.521Z,1619108304.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:18:24.521Z,1619108304.521 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:18:26.533Z,1619108306.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161826.00,A,3648.50017,N,12149.04282,W,5.501,98.03,220421,,,D*4E 2021-04-22T16:18:26.535Z,1619108306.535 [NAL9602](INFO): GPS fix at 20210422T161826: (36.808336, -121.817380) 2021-04-22T16:18:26.546Z,1619108306.546 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:18:26.546Z,1619108306.546 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:18:36.422Z,1619108316.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:18:45.601Z,1619108325.601 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:18:45.601Z,1619108325.601 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2021-04-22T16:18:52.053Z,1619108332.053 [NAL9602](INFO): SBD MO Status=1, MOMSN=3008, MT Status=0, MTMSN=0 2021-04-22T16:18:52.110Z,1619108332.110 [NAL9602](INFO): Sent 72 bytes from file Logs/20210422T160107/Courier0011.lzma 2021-04-22T16:18:52.110Z,1619108332.110 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:19:07.034Z,1619108347.034 [NAL9602](INFO): SBD MO Status=1, MOMSN=3009, MT Status=0, MTMSN=0 2021-04-22T16:19:07.082Z,1619108347.082 [NAL9602](INFO): Sent 141 bytes from file Logs/20210422T160107/Express0013.lzma 2021-04-22T16:19:07.082Z,1619108347.082 [NAL9602](INFO): Packets left to send: 0 2021-04-22T16:19:11.562Z,1619108351.562 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:19:20.373Z,1619108360.373 [NAL9602](INFO): SBD MO Status=0, MOMSN=3010, MT Status=0, MTMSN=0 2021-04-22T16:19:20.479Z,1619108360.479 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:19:20.479Z,1619108360.479 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:19:20.479Z,1619108360.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:19:46.714Z,1619108386.714 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-22T16:19:51.071Z,1619108391.071 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:20:20.938Z,1619108420.938 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003142 2021-04-22T16:20:45.727Z,1619108445.727 [DVL_micro](ERROR): only read 1 of 4 data items 2021-04-22T16:20:45.728Z,1619108445.728 [DVL_micro](ERROR): Failed to parse: :RD,+99999.99,++9999.99 2021-04-22T16:24:21.181Z,1619108661.181 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:24:21.210Z,1619108661.210 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:24:21.210Z,1619108661.210 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:24:21.210Z,1619108661.210 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:24:21.587Z,1619108661.587 [Default:CheckIn:D] Stopped 2021-04-22T16:24:21.587Z,1619108661.587 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:24:21.981Z,1619108661.981 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.891549 min 2021-04-22T16:24:21.981Z,1619108661.981 [Default:CheckIn:E] Stopped 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn] Stopped 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn](INFO): Running loop #6 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn] Running Loop=6 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:24:21.982Z,1619108661.982 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:24:23.985Z,1619108663.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162423.00,A,3648.41248,N,12148.46321,W,4.510,96.02,220421,,,D*4D 2021-04-22T16:24:23.987Z,1619108663.987 [NAL9602](INFO): GPS fix at 20210422T162423: (36.806875, -121.807720) 2021-04-22T16:24:24.018Z,1619108664.018 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:24:24.018Z,1619108664.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:24:32.837Z,1619108672.837 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0015.lzma 2021-04-22T16:24:35.848Z,1619108675.848 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0015.lzma.bak 2021-04-22T16:24:35.848Z,1619108675.848 [DataOverHttps](INFO): SBD MOMSN=15598482 2021-04-22T16:24:38.532Z,1619108678.532 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:24:38.532Z,1619108678.532 [DVL_micro](ERROR): Failed to parse: :BI,-00084,-00060,-00542,+ 2021-04-22T16:24:40.973Z,1619108680.973 [NAL9602](INFO): SBD MO Status=0, MOMSN=3011, MT Status=0, MTMSN=0 2021-04-22T16:24:40.973Z,1619108680.973 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:24:53.780Z,1619108693.780 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210422T160107/Express0016.lzma 2021-04-22T16:24:54.780Z,1619108694.780 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0016.lzma.bak 2021-04-22T16:24:54.780Z,1619108694.780 [DataOverHttps](INFO): SBD MOMSN=15598485 2021-04-22T16:24:55.917Z,1619108695.917 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:24:55.917Z,1619108695.917 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:24:55.918Z,1619108695.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:25:11.676Z,1619108711.676 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:28:30.899Z,1619108910.899 [DVL_micro](ERROR): Failed to parse: 00000000011.1,0000.0,1489.0,000 2021-04-22T16:29:42.810Z,1619108982.810 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:29:42.810Z,1619108982.810 [DVL_micro](ERROR): Failed to parse: :BI,+00093,-01513,-00880I 2021-04-22T16:29:56.549Z,1619108996.549 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:29:56.549Z,1619108996.549 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:29:56.549Z,1619108996.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:29:56.575Z,1619108996.575 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:29:56.947Z,1619108996.947 [Default:CheckIn:D] Stopped 2021-04-22T16:29:56.947Z,1619108996.947 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:29:57.358Z,1619108997.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.480888 min 2021-04-22T16:29:57.358Z,1619108997.358 [Default:CheckIn:E] Stopped 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn] Stopped 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn](INFO): Running loop #7 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn] Running Loop=7 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:29:57.359Z,1619108997.359 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:29:59.374Z,1619108999.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162959.00,A,3648.36922,N,12147.96150,W,5.015,84.32,220421,,,D*4D 2021-04-22T16:29:59.376Z,1619108999.376 [NAL9602](INFO): GPS fix at 20210422T162959: (36.806154, -121.799358) 2021-04-22T16:29:59.387Z,1619108999.387 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:29:59.387Z,1619108999.387 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:30:11.469Z,1619109011.469 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0018.lzma 2021-04-22T16:30:12.472Z,1619109012.472 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0018.lzma.bak 2021-04-22T16:30:12.472Z,1619109012.472 [DataOverHttps](INFO): SBD MOMSN=15598608 2021-04-22T16:30:13.187Z,1619109013.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=3012, MT Status=0, MTMSN=0 2021-04-22T16:30:13.188Z,1619109013.188 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:30:38.590Z,1619109038.590 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210422T160107/Express0019.lzma 2021-04-22T16:30:39.592Z,1619109039.592 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0019.lzma.bak 2021-04-22T16:30:39.592Z,1619109039.592 [DataOverHttps](INFO): SBD MOMSN=15598612 2021-04-22T16:30:40.710Z,1619109040.710 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:30:40.710Z,1619109040.710 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:30:40.710Z,1619109040.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:30:43.948Z,1619109043.948 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:35:41.354Z,1619109341.354 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:35:41.354Z,1619109341.354 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:35:41.354Z,1619109341.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:35:41.355Z,1619109341.355 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:35:41.742Z,1619109341.742 [Default:CheckIn:D] Stopped 2021-04-22T16:35:41.742Z,1619109341.742 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:35:42.142Z,1619109342.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.227462 min 2021-04-22T16:35:42.142Z,1619109342.142 [Default:CheckIn:E] Stopped 2021-04-22T16:35:42.142Z,1619109342.142 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:35:42.142Z,1619109342.142 [Default:CheckIn] Stopped 2021-04-22T16:35:42.142Z,1619109342.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:35:42.143Z,1619109342.143 [Default:CheckIn](INFO): Running loop #8 2021-04-22T16:35:42.143Z,1619109342.143 [Default:CheckIn] Running Loop=8 2021-04-22T16:35:42.143Z,1619109342.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:35:42.143Z,1619109342.143 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:35:44.157Z,1619109344.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163543.00,A,3648.32448,N,12147.63091,W,3.771,127.76,220421,,,D*73 2021-04-22T16:35:44.167Z,1619109344.167 [NAL9602](INFO): GPS fix at 20210422T163543: (36.805408, -121.793848) 2021-04-22T16:35:44.179Z,1619109344.179 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:35:44.179Z,1619109344.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:35:51.201Z,1619109351.201 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0021.lzma 2021-04-22T16:35:52.204Z,1619109352.204 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0021.lzma.bak 2021-04-22T16:35:52.204Z,1619109352.204 [DataOverHttps](INFO): SBD MOMSN=15598647 2021-04-22T16:36:00.731Z,1619109360.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=3013, MT Status=0, MTMSN=0 2021-04-22T16:36:00.731Z,1619109360.731 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:36:11.118Z,1619109371.118 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210422T160107/Express0022.lzma 2021-04-22T16:36:12.120Z,1619109372.120 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0022.lzma.bak 2021-04-22T16:36:12.120Z,1619109372.120 [DataOverHttps](INFO): SBD MOMSN=15598650 2021-04-22T16:36:13.345Z,1619109373.345 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:36:13.350Z,1619109373.350 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:36:13.350Z,1619109373.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:36:31.157Z,1619109391.157 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:37:56.421Z,1619109476.421 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:37:56.421Z,1619109476.421 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.999 2021-04-22T16:41:01.097Z,1619109661.097 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T16:41:01.118Z,1619109661.118 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+ 2021-04-22T16:41:14.039Z,1619109674.039 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:41:14.039Z,1619109674.039 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:41:14.039Z,1619109674.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:41:14.039Z,1619109674.039 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:41:14.437Z,1619109674.437 [Default:CheckIn:D] Stopped 2021-04-22T16:41:14.437Z,1619109674.437 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:41:14.835Z,1619109674.835 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.772388 min 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn:E] Stopped 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn] Stopped 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn](INFO): Running loop #9 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn] Running Loop=9 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:41:14.836Z,1619109674.836 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:41:16.853Z,1619109676.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164116.00,A,3648.44993,N,12147.28857,W,0.156,52.19,220421,,,D*4D 2021-04-22T16:41:16.856Z,1619109676.856 [NAL9602](INFO): GPS fix at 20210422T164116: (36.807499, -121.788143) 2021-04-22T16:41:16.882Z,1619109676.882 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:41:16.882Z,1619109676.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:41:24.273Z,1619109684.273 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210422T160107/Courier0024.lzma 2021-04-22T16:41:25.276Z,1619109685.276 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0024.lzma.bak 2021-04-22T16:41:25.276Z,1619109685.276 [DataOverHttps](INFO): SBD MOMSN=15598660 2021-04-22T16:41:36.695Z,1619109696.695 [NAL9602](INFO): SBD MO Status=2, MOMSN=3014, MT Status=2, MTMSN=0 2021-04-22T16:41:36.695Z,1619109696.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T16:41:41.166Z,1619109701.166 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210422T160107/Express0025.lzma 2021-04-22T16:41:42.168Z,1619109702.168 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0025.lzma.bak 2021-04-22T16:41:42.168Z,1619109702.168 [DataOverHttps](INFO): SBD MOMSN=15598663 2021-04-22T16:41:43.529Z,1619109703.529 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:41:43.529Z,1619109703.529 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:41:43.530Z,1619109703.530 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:42:13.006Z,1619109733.006 [NAL9602](INFO): SBD MO Status=0, MOMSN=3014, MT Status=0, MTMSN=0 2021-04-22T16:42:13.006Z,1619109733.006 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:42:43.713Z,1619109763.713 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:46:44.191Z,1619110004.191 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:46:44.191Z,1619110004.191 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:46:44.191Z,1619110004.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:46:44.191Z,1619110004.191 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:46:44.576Z,1619110004.576 [Default:CheckIn:D] Stopped 2021-04-22T16:46:44.576Z,1619110004.576 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.274691 min 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn:E] Stopped 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn] Stopped 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:46:44.987Z,1619110004.987 [Default:CheckIn](INFO): Running loop #10 2021-04-22T16:46:44.988Z,1619110004.988 [Default:CheckIn] Running Loop=10 2021-04-22T16:46:44.988Z,1619110004.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:46:44.988Z,1619110004.988 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:46:46.991Z,1619110006.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164646.00,A,3648.26365,N,12147.13382,W,3.965,187.85,220421,,,D*74 2021-04-22T16:46:46.993Z,1619110006.993 [NAL9602](INFO): GPS fix at 20210422T164646: (36.804394, -121.785564) 2021-04-22T16:46:47.021Z,1619110007.021 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:46:47.021Z,1619110007.021 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:46:54.197Z,1619110014.197 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0027.lzma 2021-04-22T16:46:55.200Z,1619110015.200 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0027.lzma.bak 2021-04-22T16:46:55.200Z,1619110015.200 [DataOverHttps](INFO): SBD MOMSN=15598742 2021-04-22T16:47:10.021Z,1619110030.021 [NAL9602](INFO): SBD MO Status=0, MOMSN=3015, MT Status=0, MTMSN=0 2021-04-22T16:47:10.021Z,1619110030.021 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:47:11.101Z,1619110031.101 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210422T160107/Express0028.lzma 2021-04-22T16:47:12.104Z,1619110032.104 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0028.lzma.bak 2021-04-22T16:47:12.104Z,1619110032.104 [DataOverHttps](INFO): SBD MOMSN=15598745 2021-04-22T16:47:13.269Z,1619110033.269 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:47:13.269Z,1619110033.269 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:47:13.269Z,1619110033.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:47:40.731Z,1619110060.731 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:52:13.938Z,1619110333.938 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:52:13.938Z,1619110333.938 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:52:13.938Z,1619110333.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:52:13.939Z,1619110333.939 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:52:14.311Z,1619110334.311 [Default:CheckIn:D] Stopped 2021-04-22T16:52:14.311Z,1619110334.311 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.770288 min 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn:E] Stopped 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn] Stopped 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn](INFO): Running loop #11 2021-04-22T16:52:14.737Z,1619110334.737 [Default:CheckIn] Running Loop=11 2021-04-22T16:52:14.738Z,1619110334.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:52:14.742Z,1619110334.742 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:52:16.727Z,1619110336.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165216.00,A,3648.13243,N,12147.20895,W,0.019,278.71,220421,,,D*73 2021-04-22T16:52:16.730Z,1619110336.730 [NAL9602](INFO): GPS fix at 20210422T165216: (36.802207, -121.786816) 2021-04-22T16:52:16.761Z,1619110336.761 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:52:16.761Z,1619110336.761 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:52:23.841Z,1619110343.841 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0030.lzma 2021-04-22T16:52:24.844Z,1619110344.844 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0030.lzma.bak 2021-04-22T16:52:24.844Z,1619110344.844 [DataOverHttps](INFO): SBD MOMSN=15598749 2021-04-22T16:52:37.947Z,1619110357.947 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T16:52:37.947Z,1619110357.947 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+11.8,0000.0,1489:WI,-00108,-00029,-00308,+00000,A 2021-04-22T16:52:40.778Z,1619110360.778 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210422T160107/Express0031.lzma 2021-04-22T16:52:41.780Z,1619110361.780 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0031.lzma.bak 2021-04-22T16:52:41.780Z,1619110361.780 [DataOverHttps](INFO): SBD MOMSN=15598752 2021-04-22T16:52:43.211Z,1619110363.211 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:52:43.211Z,1619110363.211 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:52:43.211Z,1619110363.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:52:47.637Z,1619110367.637 [NAL9602](INFO): SBD MO Status=2, MOMSN=3016, MT Status=2, MTMSN=0 2021-04-22T16:52:47.637Z,1619110367.637 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T16:53:40.154Z,1619110420.154 [NAL9602](INFO): SBD MO Status=0, MOMSN=3016, MT Status=0, MTMSN=0 2021-04-22T16:53:40.154Z,1619110420.154 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:54:10.849Z,1619110450.849 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T16:57:43.833Z,1619110663.833 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T16:57:43.833Z,1619110663.833 [Default:CheckIn:C.Wait] Stopped 2021-04-22T16:57:43.833Z,1619110663.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T16:57:43.833Z,1619110663.833 [Default:CheckIn:D] Running Loop=1 2021-04-22T16:57:44.210Z,1619110664.210 [Default:CheckIn:D] Stopped 2021-04-22T16:57:44.210Z,1619110664.210 [Default:CheckIn:E] Running Loop=1 2021-04-22T16:57:44.627Z,1619110664.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.268599 min 2021-04-22T16:57:44.627Z,1619110664.627 [Default:CheckIn:E] Stopped 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn] Stopped 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn](INFO): Running loop #12 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn] Running Loop=12 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T16:57:44.628Z,1619110664.628 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T16:57:46.619Z,1619110666.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165746.00,A,3648.13239,N,12147.20974,W,0.000,278.71,220421,,,D*78 2021-04-22T16:57:46.622Z,1619110666.622 [NAL9602](INFO): GPS fix at 20210422T165746: (36.802206, -121.786829) 2021-04-22T16:57:46.633Z,1619110666.633 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T16:57:46.633Z,1619110666.633 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T16:57:54.297Z,1619110674.297 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0033.lzma 2021-04-22T16:57:55.300Z,1619110675.300 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0033.lzma.bak 2021-04-22T16:57:55.300Z,1619110675.300 [DataOverHttps](INFO): SBD MOMSN=15598756 2021-04-22T16:58:03.185Z,1619110683.185 [NAL9602](INFO): SBD MO Status=0, MOMSN=3017, MT Status=0, MTMSN=0 2021-04-22T16:58:03.185Z,1619110683.185 [NAL9602](INFO): No messages in MT queue 2021-04-22T16:58:14.986Z,1619110694.986 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210422T160107/Express0034.lzma 2021-04-22T16:58:15.984Z,1619110695.984 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0034.lzma.bak 2021-04-22T16:58:15.984Z,1619110695.984 [DataOverHttps](INFO): SBD MOMSN=15598759 2021-04-22T16:58:17.415Z,1619110697.415 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T16:58:17.415Z,1619110697.415 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T16:58:17.415Z,1619110697.415 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T16:58:33.965Z,1619110713.965 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:00:02.851Z,1619110802.851 [DVL_micro](ERROR): Failed to parse: 46,+00100,-00176,+00000,I 2021-04-22T17:03:18.120Z,1619110998.120 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:03:18.120Z,1619110998.120 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:03:18.120Z,1619110998.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:03:18.121Z,1619110998.121 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:03:18.506Z,1619110998.506 [Default:CheckIn:D] Stopped 2021-04-22T17:03:18.506Z,1619110998.506 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.840202 min 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn:E] Stopped 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn] Stopped 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn](INFO): Running loop #13 2021-04-22T17:03:18.907Z,1619110998.907 [Default:CheckIn] Running Loop=13 2021-04-22T17:03:18.908Z,1619110998.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:03:18.908Z,1619110998.908 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:03:20.916Z,1619111000.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170320.00,A,3648.13259,N,12147.20913,W,0.000,278.71,220421,,,D*7F 2021-04-22T17:03:20.919Z,1619111000.919 [NAL9602](INFO): GPS fix at 20210422T170320: (36.802210, -121.786819) 2021-04-22T17:03:20.962Z,1619111000.962 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:03:20.962Z,1619111000.962 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:03:27.937Z,1619111007.937 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210422T160107/Courier0036.lzma 2021-04-22T17:03:28.940Z,1619111008.940 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0036.lzma.bak 2021-04-22T17:03:28.940Z,1619111008.940 [DataOverHttps](INFO): SBD MOMSN=15598763 2021-04-22T17:03:41.525Z,1619111021.525 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:03:41.525Z,1619111021.525 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.2,0000.,000 2021-04-22T17:03:44.750Z,1619111024.750 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210422T160107/Express0037.lzma 2021-04-22T17:03:45.752Z,1619111025.752 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0037.lzma.bak 2021-04-22T17:03:45.752Z,1619111025.752 [DataOverHttps](INFO): SBD MOMSN=15598766 2021-04-22T17:03:47.182Z,1619111027.182 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:03:47.182Z,1619111027.182 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:03:47.182Z,1619111027.182 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:03:49.214Z,1619111029.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=3018, MT Status=0, MTMSN=0 2021-04-22T17:03:49.214Z,1619111029.214 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:04:19.900Z,1619111059.900 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:04:32.845Z,1619111072.845 [DVL_micro](ERROR): only read 0 of 4 data items 2021-04-22T17:04:32.845Z,1619111072.845 [DVL_micro](ERROR): Failed to parse: :RD,,+9999.99,+9999.99,+9999.99 2021-04-22T17:08:32.475Z,1619111312.475 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T17:08:32.476Z,1619111312.476 [DVL_micro](ERROR): Failed to parse: :BI,-00017,+00001,-00176,+ 2021-04-22T17:08:47.860Z,1619111327.860 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:08:47.860Z,1619111327.860 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:08:47.860Z,1619111327.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:08:47.860Z,1619111327.860 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:08:48.250Z,1619111328.250 [Default:CheckIn:D] Stopped 2021-04-22T17:08:48.250Z,1619111328.250 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:08:48.646Z,1619111328.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.335929 min 2021-04-22T17:08:48.646Z,1619111328.646 [Default:CheckIn:E] Stopped 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn] Stopped 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn](INFO): Running loop #14 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn] Running Loop=14 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:08:48.647Z,1619111328.647 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:08:50.661Z,1619111330.661 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2021-04-22T17:08:50.664Z,1619111330.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170850.00,A,3648.13667,N,12147.20680,W,0.039,278.71,220421,,,D*75 2021-04-22T17:08:50.666Z,1619111330.666 [NAL9602](INFO): GPS fix at 20210422T170850: (36.802278, -121.786780) 2021-04-22T17:08:50.677Z,1619111330.677 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:08:50.677Z,1619111330.677 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:08:53.134Z,1619111333.134 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T17:08:53.135Z,1619111333.135 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999..99 2021-04-22T17:08:57.837Z,1619111337.837 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0039.lzma 2021-04-22T17:08:58.840Z,1619111338.840 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0039.lzma.bak 2021-04-22T17:08:58.840Z,1619111338.840 [DataOverHttps](INFO): SBD MOMSN=15598770 2021-04-22T17:09:07.653Z,1619111347.653 [NAL9602](INFO): SBD MO Status=0, MOMSN=3019, MT Status=0, MTMSN=0 2021-04-22T17:09:07.653Z,1619111347.653 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:09:14.837Z,1619111354.838 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210422T160107/Express0040.lzma 2021-04-22T17:09:15.840Z,1619111355.840 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0040.lzma.bak 2021-04-22T17:09:15.840Z,1619111355.840 [DataOverHttps](INFO): SBD MOMSN=15598773 2021-04-22T17:09:16.954Z,1619111356.954 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:09:16.954Z,1619111356.954 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:09:16.954Z,1619111356.954 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:09:38.335Z,1619111378.335 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:10:07.816Z,1619111407.816 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T17:10:07.817Z,1619111407.817 [DVL_micro](ERROR): Failed to parse: :BI,-00001,+00138,-00199,+0:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2021-04-22T17:14:17.383Z,1619111657.383 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:14:17.383Z,1619111657.383 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:14:17.383Z,1619111657.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:14:17.384Z,1619111657.384 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:14:17.803Z,1619111657.803 [Default:CheckIn:D] Stopped 2021-04-22T17:14:17.803Z,1619111657.803 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:14:18.186Z,1619111658.186 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.828483 min 2021-04-22T17:14:18.186Z,1619111658.186 [Default:CheckIn:E] Stopped 2021-04-22T17:14:18.186Z,1619111658.186 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:14:18.186Z,1619111658.186 [Default:CheckIn] Stopped 2021-04-22T17:14:18.187Z,1619111658.187 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:14:18.187Z,1619111658.187 [Default:CheckIn](INFO): Running loop #15 2021-04-22T17:14:18.187Z,1619111658.187 [Default:CheckIn] Running Loop=15 2021-04-22T17:14:18.187Z,1619111658.187 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:14:18.187Z,1619111658.187 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:14:20.193Z,1619111660.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171419.00,A,3648.13637,N,12147.22233,W,2.741,282.52,220421,,,D*70 2021-04-22T17:14:20.196Z,1619111660.196 [NAL9602](INFO): GPS fix at 20210422T171419: (36.802273, -121.787039) 2021-04-22T17:14:20.239Z,1619111660.239 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:14:20.239Z,1619111660.239 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:14:28.065Z,1619111668.065 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0042.lzma 2021-04-22T17:14:29.068Z,1619111669.068 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0042.lzma.bak 2021-04-22T17:14:29.068Z,1619111669.068 [DataOverHttps](INFO): SBD MOMSN=15598777 2021-04-22T17:14:45.102Z,1619111685.102 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210422T160107/Express0043.lzma 2021-04-22T17:14:46.104Z,1619111686.104 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0043.lzma.bak 2021-04-22T17:14:46.104Z,1619111686.104 [DataOverHttps](INFO): SBD MOMSN=15598780 2021-04-22T17:14:47.484Z,1619111687.484 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:14:47.484Z,1619111687.484 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:14:47.484Z,1619111687.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:15:22.625Z,1619111722.625 [NAL9602](INFO): SBD MO Status=0, MOMSN=3020, MT Status=0, MTMSN=0 2021-04-22T17:15:22.625Z,1619111722.625 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:15:25.050Z,1619111725.050 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2021-04-22T17:15:25.051Z,1619111725.051 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2021-04-22T17:15:25.054Z,1619111725.054 [BPC1](INFO): Received data from all battery sticks. 2021-04-22T17:15:41.223Z,1619111741.223 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T17:15:41.223Z,1619111741.223 [DVL_micro](ERROR): Failed to parse: :BI,-00128,00085,+00000,I 2021-04-22T17:15:43.631Z,1619111743.631 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:15:43.632Z,1619111743.632 [DVL_micro](ERROR): Failed to parse: :TS,000000,35.0,+0.0,1489.0,000 2021-04-22T17:15:53.329Z,1619111753.329 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:19:48.092Z,1619111988.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:19:48.092Z,1619111988.092 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:19:48.092Z,1619111988.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:19:48.093Z,1619111988.093 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:19:48.502Z,1619111988.502 [Default:CheckIn:D] Stopped 2021-04-22T17:19:48.502Z,1619111988.502 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:19:48.909Z,1619111988.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.340129 min 2021-04-22T17:19:48.909Z,1619111988.909 [Default:CheckIn:E] Stopped 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn] Stopped 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn](INFO): Running loop #16 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn] Running Loop=16 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:19:48.910Z,1619111988.910 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:19:50.913Z,1619111990.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171950.00,A,3648.14047,N,12147.26222,W,0.156,159.75,220421,,,D*70 2021-04-22T17:19:50.916Z,1619111990.916 [NAL9602](INFO): GPS fix at 20210422T171950: (36.802341, -121.787704) 2021-04-22T17:19:50.927Z,1619111990.927 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:19:50.927Z,1619111990.927 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:19:57.861Z,1619111997.861 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20210422T160107/Courier0045.lzma 2021-04-22T17:19:58.864Z,1619111998.864 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0045.lzma.bak 2021-04-22T17:19:58.864Z,1619111998.864 [DataOverHttps](INFO): SBD MOMSN=15598785 2021-04-22T17:20:25.978Z,1619112025.978 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210422T160107/Express0046.lzma 2021-04-22T17:20:26.980Z,1619112026.980 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0046.lzma.bak 2021-04-22T17:20:26.980Z,1619112026.980 [DataOverHttps](INFO): SBD MOMSN=15598788 2021-04-22T17:20:28.275Z,1619112028.275 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:20:28.275Z,1619112028.275 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:20:28.275Z,1619112028.275 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:20:40.767Z,1619112040.767 [NAL9602](INFO): SBD MO Status=2, MOMSN=3021, MT Status=2, MTMSN=0 2021-04-22T17:20:40.768Z,1619112040.768 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:21:20.351Z,1619112080.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=3021, MT Status=2, MTMSN=0 2021-04-22T17:21:20.351Z,1619112080.351 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:22:07.627Z,1619112127.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=3021, MT Status=2, MTMSN=0 2021-04-22T17:22:07.627Z,1619112127.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:22:33.921Z,1619112153.921 [NAL9602](INFO): SBD MO Status=0, MOMSN=3021, MT Status=0, MTMSN=0 2021-04-22T17:22:33.921Z,1619112153.921 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:23:04.628Z,1619112184.628 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:25:28.917Z,1619112328.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:25:28.918Z,1619112328.918 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:25:28.918Z,1619112328.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:25:28.919Z,1619112328.919 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:25:29.343Z,1619112329.343 [Default:CheckIn:D] Stopped 2021-04-22T17:25:29.343Z,1619112329.343 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.020817 min 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn:E] Stopped 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn] Stopped 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:25:29.712Z,1619112329.712 [Default:CheckIn](INFO): Running loop #17 2021-04-22T17:25:29.713Z,1619112329.713 [Default:CheckIn] Running Loop=17 2021-04-22T17:25:29.713Z,1619112329.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:25:29.713Z,1619112329.713 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:25:31.730Z,1619112331.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172531.00,A,3648.14263,N,12147.26594,W,0.097,159.75,220421,,,D*7A 2021-04-22T17:25:31.732Z,1619112331.732 [NAL9602](INFO): GPS fix at 20210422T172531: (36.802377, -121.787766) 2021-04-22T17:25:31.743Z,1619112331.743 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:25:31.743Z,1619112331.743 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:25:38.753Z,1619112338.753 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0048.lzma 2021-04-22T17:25:39.756Z,1619112339.756 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0048.lzma.bak 2021-04-22T17:25:39.756Z,1619112339.756 [DataOverHttps](INFO): SBD MOMSN=15598792 2021-04-22T17:25:55.606Z,1619112355.606 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210422T160107/Express0049.lzma 2021-04-22T17:25:56.608Z,1619112356.608 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0049.lzma.bak 2021-04-22T17:25:56.608Z,1619112356.608 [DataOverHttps](INFO): SBD MOMSN=15598795 2021-04-22T17:25:58.017Z,1619112358.017 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:25:58.017Z,1619112358.017 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:25:58.017Z,1619112358.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:26:33.579Z,1619112393.579 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:26:33.580Z,1619112393.580 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.9,0000.,000 2021-04-22T17:26:48.954Z,1619112408.954 [DVL_micro](ERROR): Failed to parse: :WI,+00070,-00058,+00003,+00000,A 2021-04-22T17:29:28.107Z,1619112568.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=3022, MT Status=2, MTMSN=0 2021-04-22T17:29:28.108Z,1619112568.108 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:29:45.477Z,1619112585.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=3022, MT Status=2, MTMSN=0 2021-04-22T17:29:45.477Z,1619112585.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:30:00.834Z,1619112600.834 [NAL9602](INFO): SBD MO Status=2, MOMSN=3022, MT Status=2, MTMSN=0 2021-04-22T17:30:00.834Z,1619112600.834 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:30:34.009Z,1619112634.009 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-22T17:30:58.686Z,1619112658.686 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:30:58.686Z,1619112658.686 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:30:58.686Z,1619112658.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:30:58.686Z,1619112658.686 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:30:59.074Z,1619112659.074 [Default:CheckIn:D] Stopped 2021-04-22T17:30:59.074Z,1619112659.074 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:30:59.478Z,1619112659.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.516325 min 2021-04-22T17:30:59.478Z,1619112659.478 [Default:CheckIn:E] Stopped 2021-04-22T17:30:59.478Z,1619112659.478 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:30:59.478Z,1619112659.478 [Default:CheckIn] Stopped 2021-04-22T17:30:59.478Z,1619112659.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:30:59.479Z,1619112659.479 [Default:CheckIn](INFO): Running loop #18 2021-04-22T17:30:59.479Z,1619112659.479 [Default:CheckIn] Running Loop=18 2021-04-22T17:30:59.479Z,1619112659.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:30:59.479Z,1619112659.479 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:31:01.483Z,1619112661.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173101.00,A,3648.14053,N,12147.25939,W,0.175,159.75,220421,,,D*78 2021-04-22T17:31:01.485Z,1619112661.485 [NAL9602](INFO): GPS fix at 20210422T173101: (36.802342, -121.787656) 2021-04-22T17:31:01.517Z,1619112661.517 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:31:01.517Z,1619112661.517 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:31:08.861Z,1619112668.861 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0051.lzma 2021-04-22T17:31:09.864Z,1619112669.864 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0051.lzma.bak 2021-04-22T17:31:09.864Z,1619112669.864 [DataOverHttps](INFO): SBD MOMSN=15598800 2021-04-22T17:31:25.758Z,1619112685.758 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20210422T160107/Express0052.lzma 2021-04-22T17:31:26.760Z,1619112686.760 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0052.lzma.bak 2021-04-22T17:31:26.760Z,1619112686.760 [DataOverHttps](INFO): SBD MOMSN=15598803 2021-04-22T17:31:28.162Z,1619112688.162 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:31:28.162Z,1619112688.162 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:31:28.163Z,1619112688.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:31:34.221Z,1619112694.221 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:32:00.081Z,1619112720.081 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:32:00.081Z,1619112720.081 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.099 2021-04-22T17:36:19.562Z,1619112979.562 [DVL_micro](ERROR): Failed to parse: :WI,+00097,-00053,+00054,+00000,A 2021-04-22T17:36:28.859Z,1619112988.859 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:36:28.859Z,1619112988.859 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:36:28.859Z,1619112988.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:36:28.859Z,1619112988.859 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:36:29.257Z,1619112989.257 [Default:CheckIn:D] Stopped 2021-04-22T17:36:29.257Z,1619112989.257 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:36:29.667Z,1619112989.667 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.019393 min 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn:E] Stopped 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn] Stopped 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn](INFO): Running loop #19 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn] Running Loop=19 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:36:29.668Z,1619112989.668 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:36:31.673Z,1619112991.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173631.00,A,3648.13789,N,12147.25476,W,0.136,159.75,220421,,,D*7A 2021-04-22T17:36:31.675Z,1619112991.675 [NAL9602](INFO): GPS fix at 20210422T173631: (36.802298, -121.787579) 2021-04-22T17:36:31.686Z,1619112991.686 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:36:31.687Z,1619112991.687 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:36:39.577Z,1619112999.577 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0054.lzma 2021-04-22T17:36:40.588Z,1619113000.588 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0054.lzma.bak 2021-04-22T17:36:40.588Z,1619113000.588 [DataOverHttps](INFO): SBD MOMSN=15598808 2021-04-22T17:36:56.638Z,1619113016.638 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210422T160107/Express0055.lzma 2021-04-22T17:36:57.640Z,1619113017.640 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0055.lzma.bak 2021-04-22T17:36:57.640Z,1619113017.640 [DataOverHttps](INFO): SBD MOMSN=15598812 2021-04-22T17:36:58.746Z,1619113018.746 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:36:58.746Z,1619113018.746 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:36:58.747Z,1619113018.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:37:02.372Z,1619113022.372 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-04-22T17:37:02.446Z,1619113022.446 [NAL9602](ERROR): received: +CSQ:0 OK22, 2, 0, 0, 0 OK 2021-04-22T17:37:12.482Z,1619113032.482 [NAL9602](INFO): SBD MO Status=0, MOMSN=3022, MT Status=0, MTMSN=0 2021-04-22T17:37:12.482Z,1619113032.482 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:37:43.180Z,1619113063.180 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:38:02.166Z,1619113082.166 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:38:02.166Z,1619113082.166 [DVL_micro](ERROR): Failed to parse: :TS,000000005.0,+13.3,0000.0,1489.0,000 2021-04-22T17:38:28.089Z,1619113108.089 [DVL_micro](ERROR): only read 3 of 4 data items 2021-04-22T17:38:28.089Z,1619113108.089 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2021-04-22T17:41:19.802Z,1619113279.802 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:41:19.802Z,1619113279.802 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+130,1489.0I,+00086,-00061,+00117,+00000,A 2021-04-22T17:41:59.383Z,1619113319.383 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:41:59.383Z,1619113319.383 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:41:59.384Z,1619113319.384 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:41:59.384Z,1619113319.384 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:41:59.806Z,1619113319.806 [Default:CheckIn:D] Stopped 2021-04-22T17:41:59.806Z,1619113319.806 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:42:00.205Z,1619113320.205 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.528524 min 2021-04-22T17:42:00.205Z,1619113320.205 [Default:CheckIn:E] Stopped 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn] Stopped 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn](INFO): Running loop #20 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn] Running Loop=20 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:42:00.206Z,1619113320.206 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:42:02.205Z,1619113322.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174201.00,A,3648.17236,N,12147.27535,W,2.274,297.94,220421,,,D*72 2021-04-22T17:42:02.208Z,1619113322.208 [NAL9602](INFO): GPS fix at 20210422T174201: (36.802873, -121.787922) 2021-04-22T17:42:02.219Z,1619113322.219 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:42:02.219Z,1619113322.219 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:42:03.029Z,1619113323.029 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:42:03.029Z,1619113323.029 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:42:03.029Z,1619113323.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:43:48.516Z,1619113428.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=3023, MT Status=2, MTMSN=0 2021-04-22T17:43:48.516Z,1619113428.516 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:44:32.969Z,1619113472.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=3023, MT Status=2, MTMSN=0 2021-04-22T17:44:32.969Z,1619113472.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:45:07.714Z,1619113507.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=3023, MT Status=2, MTMSN=0 2021-04-22T17:45:07.714Z,1619113507.714 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:46:00.642Z,1619113560.642 [NAL9602](INFO): SBD MO Status=2, MOMSN=3023, MT Status=2, MTMSN=0 2021-04-22T17:46:00.642Z,1619113560.642 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:46:36.643Z,1619113596.643 [NAL9602](INFO): SBD MO Status=2, MOMSN=3023, MT Status=2, MTMSN=0 2021-04-22T17:46:36.643Z,1619113596.643 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T17:47:03.759Z,1619113623.759 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:47:03.760Z,1619113623.760 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:47:03.760Z,1619113623.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:47:03.760Z,1619113623.760 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:47:04.130Z,1619113624.130 [Default:CheckIn:D] Stopped 2021-04-22T17:47:04.130Z,1619113624.130 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:47:04.555Z,1619113624.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.600602 min 2021-04-22T17:47:04.555Z,1619113624.555 [Default:CheckIn:E] Stopped 2021-04-22T17:47:04.555Z,1619113624.555 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn] Stopped 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn](INFO): Running loop #21 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn] Running Loop=21 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:47:04.556Z,1619113624.556 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:47:04.947Z,1619113624.947 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-22T17:47:06.146Z,1619113626.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174705.00,A,3648.16674,N,12147.28174,W,0.253,279.00,220421,,,D*74 2021-04-22T17:47:06.148Z,1619113626.148 [NAL9602](INFO): GPS fix at 20210422T174705: (36.802779, -121.788029) 2021-04-22T17:47:06.159Z,1619113626.159 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:47:06.159Z,1619113626.159 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:47:14.097Z,1619113634.097 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0057.lzma 2021-04-22T17:47:15.100Z,1619113635.100 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0057.lzma.bak 2021-04-22T17:47:15.100Z,1619113635.100 [DataOverHttps](INFO): SBD MOMSN=15598823 2021-04-22T17:47:30.894Z,1619113650.894 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0060.lzma 2021-04-22T17:47:31.896Z,1619113651.896 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0060.lzma.bak 2021-04-22T17:47:31.896Z,1619113651.896 [DataOverHttps](INFO): SBD MOMSN=15598826 2021-04-22T17:47:38.863Z,1619113658.863 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:47:47.722Z,1619113667.722 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210422T160107/Express0058.lzma 2021-04-22T17:47:48.724Z,1619113668.724 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0058.lzma.bak 2021-04-22T17:47:48.724Z,1619113668.724 [DataOverHttps](INFO): SBD MOMSN=15598829 2021-04-22T17:48:07.031Z,1619113687.031 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210422T160107/Express0061.lzma 2021-04-22T17:48:07.597Z,1619113687.597 [DVL_micro](ERROR): Failed to parse: 9.99,+9999.99,+99999.99 2021-04-22T17:48:08.038Z,1619113688.038 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0061.lzma.bak 2021-04-22T17:48:08.039Z,1619113688.039 [DataOverHttps](INFO): SBD MOMSN=15598835 2021-04-22T17:48:09.247Z,1619113689.247 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:48:09.247Z,1619113689.247 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:48:09.247Z,1619113689.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:49:40.177Z,1619113780.177 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:49:40.182Z,1619113780.182 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+13.6,0000.,000 2021-04-22T17:53:09.876Z,1619113989.876 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:53:09.876Z,1619113989.876 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:53:09.876Z,1619113989.876 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:53:09.876Z,1619113989.876 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:53:10.294Z,1619113990.294 [Default:CheckIn:D] Stopped 2021-04-22T17:53:10.294Z,1619113990.294 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:53:10.678Z,1619113990.678 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.703337 min 2021-04-22T17:53:10.678Z,1619113990.678 [Default:CheckIn:E] Stopped 2021-04-22T17:53:10.678Z,1619113990.678 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:53:10.678Z,1619113990.678 [Default:CheckIn] Stopped 2021-04-22T17:53:10.678Z,1619113990.678 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:53:10.679Z,1619113990.679 [Default:CheckIn](INFO): Running loop #22 2021-04-22T17:53:10.679Z,1619113990.679 [Default:CheckIn] Running Loop=22 2021-04-22T17:53:10.679Z,1619113990.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:53:10.679Z,1619113990.679 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:53:12.689Z,1619113992.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175312.00,A,3648.16922,N,12147.28325,W,0.292,279.00,220421,,,A*75 2021-04-22T17:53:12.692Z,1619113992.692 [NAL9602](INFO): GPS fix at 20210422T175312: (36.802820, -121.788054) 2021-04-22T17:53:12.703Z,1619113992.703 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:53:12.703Z,1619113992.703 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:53:20.481Z,1619114000.481 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210422T160107/Courier0063.lzma 2021-04-22T17:53:21.484Z,1619114001.484 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0063.lzma.bak 2021-04-22T17:53:21.484Z,1619114001.484 [DataOverHttps](INFO): SBD MOMSN=15598846 2021-04-22T17:53:37.358Z,1619114017.358 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210422T160107/Express0064.lzma 2021-04-22T17:53:38.360Z,1619114018.360 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0064.lzma.bak 2021-04-22T17:53:38.360Z,1619114018.360 [DataOverHttps](INFO): SBD MOMSN=15598849 2021-04-22T17:53:39.767Z,1619114019.767 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:53:39.768Z,1619114019.768 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:53:39.768Z,1619114019.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:53:47.046Z,1619114027.046 [NAL9602](INFO): SBD MO Status=0, MOMSN=3023, MT Status=0, MTMSN=0 2021-04-22T17:53:47.046Z,1619114027.046 [NAL9602](INFO): No messages in MT queue 2021-04-22T17:54:17.785Z,1619114057.785 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T17:54:25.429Z,1619114065.429 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T17:54:25.430Z,1619114065.430 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+13.8,0000.0,148 2021-04-22T17:56:21.853Z,1619114181.853 [CBIT](IMPORTANT): Beginning ground fault scan 2021-04-22T17:56:25.065Z,1619114185.065 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-04-22T17:56:25.065Z,1619114185.065 [DropWeight] Hardware Fault, FailCount= 1 2021-04-22T17:56:25.065Z,1619114185.065 [DropWeight](ERROR): Hardware Fault 2021-04-22T17:56:25.083Z,1619114185.083 [CommandLine](FAULT): Scheduling is paused 2021-04-22T17:56:25.084Z,1619114185.084 [CBIT](INFO): Critical error at 20210422T175625 2021-04-22T17:56:25.114Z,1619114185.114 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-04-22T17:56:25.115Z,1619114185.115 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-04-22T17:56:25.493Z,1619114185.493 [CBIT](INFO): Critical error at 20210422T175625 2021-04-22T17:56:32.774Z,1619114192.774 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.029259 CHAN A1 (24V): 0.035041 CHAN A2 (12V): -0.008235 CHAN A3 (5V): -0.019877 CHAN B0 (3.3V): -0.011788 CHAN B1 (3.15aV): -0.008025 CHAN B2 (3.15bV): -0.004214 CHAN B3 (GND): -0.011280 OPEN: 0.004408 Full Scale Calc: 4.765 mA, -1.589 mA 2021-04-22T17:58:40.433Z,1619114320.433 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T17:58:40.433Z,1619114320.433 [Default:CheckIn:C.Wait] Stopped 2021-04-22T17:58:40.433Z,1619114320.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T17:58:40.434Z,1619114320.434 [Default:CheckIn:D] Running Loop=1 2021-04-22T17:58:40.843Z,1619114320.843 [Default:CheckIn:D] Stopped 2021-04-22T17:58:40.843Z,1619114320.843 [Default:CheckIn:E] Running Loop=1 2021-04-22T17:58:41.258Z,1619114321.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.212476 min 2021-04-22T17:58:41.258Z,1619114321.258 [Default:CheckIn:E] Stopped 2021-04-22T17:58:41.258Z,1619114321.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T17:58:41.258Z,1619114321.258 [Default:CheckIn] Stopped 2021-04-22T17:58:41.258Z,1619114321.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T17:58:41.259Z,1619114321.259 [Default:CheckIn](INFO): Running loop #23 2021-04-22T17:58:41.259Z,1619114321.259 [Default:CheckIn] Running Loop=23 2021-04-22T17:58:41.259Z,1619114321.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T17:58:41.259Z,1619114321.259 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T17:58:43.248Z,1619114323.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175843.00,A,3648.16627,N,12147.27822,W,0.330,0.00,220421,,,A*76 2021-04-22T17:58:43.251Z,1619114323.251 [NAL9602](INFO): GPS fix at 20210422T175843: (36.802771, -121.787970) 2021-04-22T17:58:43.262Z,1619114323.262 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T17:58:43.262Z,1619114323.262 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T17:58:51.178Z,1619114331.178 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20210422T160107/Courier0066.lzma 2021-04-22T17:58:52.180Z,1619114332.180 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0066.lzma.bak 2021-04-22T17:58:52.180Z,1619114332.180 [DataOverHttps](INFO): SBD MOMSN=15598889 2021-04-22T17:59:08.042Z,1619114348.042 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20210422T160107/Express0067.lzma 2021-04-22T17:59:09.044Z,1619114349.044 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0067.lzma.bak 2021-04-22T17:59:09.045Z,1619114349.045 [DataOverHttps](INFO): SBD MOMSN=15598894 2021-04-22T17:59:10.335Z,1619114350.335 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T17:59:10.335Z,1619114350.335 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T17:59:10.335Z,1619114350.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T17:59:25.677Z,1619114365.677 [DVL_micro](ERROR): Failed to parse: 0000000000,35.0,+14.0,0000.0,1489.0,000 2021-04-22T17:59:36.581Z,1619114376.581 [NAL9602](INFO): SBD MO Status=2, MOMSN=3024, MT Status=2, MTMSN=0 2021-04-22T17:59:36.581Z,1619114376.581 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T18:00:11.343Z,1619114411.343 [NAL9602](INFO): SBD MO Status=2, MOMSN=3024, MT Status=2, MTMSN=0 2021-04-22T18:00:11.343Z,1619114411.343 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-22T18:00:56.197Z,1619114456.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=3024, MT Status=0, MTMSN=0 2021-04-22T18:00:56.197Z,1619114456.197 [NAL9602](INFO): No messages in MT queue 2021-04-22T18:01:26.902Z,1619114486.902 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T18:03:21.717Z,1619114601.717 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T18:03:21.717Z,1619114601.717 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+14.2,0000.0,1489.0,000 2021-04-22T18:04:11.023Z,1619114651.023 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T18:04:11.023Z,1619114651.023 [Default:CheckIn:C.Wait] Stopped 2021-04-22T18:04:11.023Z,1619114651.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T18:04:11.023Z,1619114651.023 [Default:CheckIn:D] Running Loop=1 2021-04-22T18:04:11.458Z,1619114651.458 [Default:CheckIn:D] Stopped 2021-04-22T18:04:11.458Z,1619114651.458 [Default:CheckIn:E] Running Loop=1 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.722738 min 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn:E] Stopped 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn] Stopped 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn](INFO): Running loop #24 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn] Running Loop=24 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T18:04:11.816Z,1619114651.816 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T18:04:13.821Z,1619114653.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180413.00,A,3648.16511,N,12147.28197,W,0.330,107.48,220421,,,A*71 2021-04-22T18:04:13.823Z,1619114653.823 [NAL9602](INFO): GPS fix at 20210422T180413: (36.802752, -121.788033) 2021-04-22T18:04:13.871Z,1619114653.871 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T18:04:13.871Z,1619114653.871 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T18:04:22.737Z,1619114662.737 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210422T160107/Courier0069.lzma 2021-04-22T18:04:23.740Z,1619114663.740 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0069.lzma.bak 2021-04-22T18:04:23.740Z,1619114663.740 [DataOverHttps](INFO): SBD MOMSN=15598901 2021-04-22T18:04:35.245Z,1619114675.245 [NAL9602](INFO): SBD MO Status=0, MOMSN=3025, MT Status=0, MTMSN=0 2021-04-22T18:04:35.245Z,1619114675.245 [NAL9602](INFO): No messages in MT queue 2021-04-22T18:04:39.715Z,1619114679.715 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210422T160107/Express0070.lzma 2021-04-22T18:04:40.716Z,1619114680.716 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0070.lzma.bak 2021-04-22T18:04:40.716Z,1619114680.716 [DataOverHttps](INFO): SBD MOMSN=15598904 2021-04-22T18:04:42.127Z,1619114682.127 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T18:04:42.127Z,1619114682.127 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T18:04:42.127Z,1619114682.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T18:04:56.250Z,1619114696.250 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T18:04:56.250Z,1619114696.250 [DVL_micro](ERROR): Failed to parse: :TS,000000,35.0,00.0,14 2021-04-22T18:05:05.951Z,1619114705.951 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-22T18:09:42.717Z,1619114982.717 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-22T18:09:42.717Z,1619114982.717 [Default:CheckIn:C.Wait] Stopped 2021-04-22T18:09:42.718Z,1619114982.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T18:09:42.718Z,1619114982.718 [Default:CheckIn:D] Running Loop=1 2021-04-22T18:09:43.121Z,1619114983.121 [Default:CheckIn:D] Stopped 2021-04-22T18:09:43.121Z,1619114983.121 [Default:CheckIn:E] Running Loop=1 2021-04-22T18:09:43.525Z,1619114983.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.250456 min 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn:E] Stopped 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn] Stopped 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn](INFO): Running loop #25 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn] Running Loop=25 2021-04-22T18:09:43.526Z,1619114983.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-22T18:09:43.527Z,1619114983.527 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-22T18:09:45.533Z,1619114985.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180945.00,A,3648.17206,N,12147.28013,W,1.166,24.92,220421,,,A*45 2021-04-22T18:09:45.544Z,1619114985.544 [NAL9602](INFO): GPS fix at 20210422T180945: (36.802868, -121.788002) 2021-04-22T18:09:45.555Z,1619114985.555 [Default:CheckIn:Read_GPS] Stopped 2021-04-22T18:09:45.555Z,1619114985.555 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-22T18:09:53.505Z,1619114993.505 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210422T160107/Courier0072.lzma 2021-04-22T18:09:54.508Z,1619114994.508 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Courier0072.lzma.bak 2021-04-22T18:09:54.508Z,1619114994.508 [DataOverHttps](INFO): SBD MOMSN=15598908 2021-04-22T18:10:13.450Z,1619115013.450 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210422T160107/Express0073.lzma 2021-04-22T18:10:14.448Z,1619115014.448 [DataOverHttps](INFO): Moved sent file to Logs/20210422T160107/Express0073.lzma.bak 2021-04-22T18:10:14.448Z,1619115014.448 [DataOverHttps](INFO): SBD MOMSN=15598911 2021-04-22T18:10:15.915Z,1619115015.915 [Default:CheckIn:Read_Iridium] Stopped 2021-04-22T18:10:15.915Z,1619115015.915 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-22T18:10:15.915Z,1619115015.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-22T18:11:25.072Z,1619115085.072 [CBIT](INFO): Clearing failed state for component DropWeight 2021-04-22T18:11:25.072Z,1619115085.072 [DropWeight] No Fault, FailCount= 1 2021-04-22T18:12:38.985Z,1619115158.985 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-22T18:12:38.985Z,1619115158.985 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+14.9,0000.0,1489.0,000 2021-04-22T18:13:48.123Z,1619115228.123 [CommandLine](IMPORTANT): got command quit 2021-04-22T18:13:49.130Z,1619115229.130 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:49.130Z,1619115229.130 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.318Z,1619115229.318 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-04-22T18:13:49.318Z,1619115229.318 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.319Z,1619115229.319 [CommandLine](INFO): Join timeout helper Thread ID is 3841 2021-04-22T18:13:49.319Z,1619115229.319 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-04-22T18:13:49.319Z,1619115229.319 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.320Z,1619115229.320 [NavChartDb](INFO): Join timeout helper Thread ID is 3842 2021-04-22T18:13:49.378Z,1619115229.378 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:49.378Z,1619115229.378 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.398Z,1619115229.398 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-04-22T18:13:49.398Z,1619115229.398 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.398Z,1619115229.398 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3843 2021-04-22T18:13:49.498Z,1619115229.498 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:49.498Z,1619115229.498 [WetLabsBB2FL](INFO): Powering down 2021-04-22T18:13:49.499Z,1619115229.499 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.518Z,1619115229.518 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-04-22T18:13:49.518Z,1619115229.518 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:49.518Z,1619115229.518 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3844 2021-04-22T18:13:50.350Z,1619115230.350 [CTD_Seabird](INFO): Powering down 2021-04-22T18:13:50.362Z,1619115230.362 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:50.362Z,1619115230.362 [CTD_Seabird](INFO): Powering down 2021-04-22T18:13:50.374Z,1619115230.374 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:50.382Z,1619115230.382 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-04-22T18:13:50.382Z,1619115230.382 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:50.382Z,1619115230.382 [Radio_Surface](INFO): Join timeout helper Thread ID is 3845 2021-04-22T18:13:50.418Z,1619115230.418 [Radio_Surface](INFO): Powering down 2021-04-22T18:13:50.419Z,1619115230.419 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:50.419Z,1619115230.419 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:50.435Z,1619115230.435 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-04-22T18:13:50.435Z,1619115230.435 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:50.435Z,1619115230.435 [Onboard](INFO): Join timeout helper Thread ID is 3846 2021-04-22T18:13:51.302Z,1619115231.302 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:51.302Z,1619115231.302 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:51.307Z,1619115231.307 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-04-22T18:13:51.307Z,1619115231.307 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:51.307Z,1619115231.307 [DataOverHttps](INFO): Join timeout helper Thread ID is 3847 2021-04-22T18:13:54.208Z,1619115234.208 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:54.212Z,1619115234.212 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.227Z,1619115234.227 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2021-04-22T18:13:54.227Z,1619115234.227 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.227Z,1619115234.227 [BackseatComponent](INFO): Join timeout helper Thread ID is 3848 2021-04-22T18:13:54.242Z,1619115234.242 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:54.242Z,1619115234.242 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.250Z,1619115234.250 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-04-22T18:13:54.250Z,1619115234.250 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.251Z,1619115234.251 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:54.251Z,1619115234.251 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.251Z,1619115234.251 [logger](INFO): Join timeout helper Thread ID is 3849 2021-04-22T18:13:54.252Z,1619115234.252 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-04-22T18:13:54.253Z,1619115234.253 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.253Z,1619115234.253 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-04-22T18:13:54.253Z,1619115234.253 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.253Z,1619115234.253 [controlThread](INFO): Join timeout helper Thread ID is 3850 2021-04-22T18:13:54.506Z,1619115234.506 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-22T18:13:54.506Z,1619115234.506 [controlThread](DEBUG): Uninitializing ControlThread 2021-04-22T18:13:54.507Z,1619115234.507 [AHRS_M2](INFO): Powering down 2021-04-22T18:13:54.650Z,1619115234.650 [DVL_micro](INFO): Powering down 2021-04-22T18:13:54.651Z,1619115234.651 [NAL9602](INFO): Powering down 2021-04-22T18:13:54.652Z,1619115234.652 [DAT](INFO): Powering down 2021-04-22T18:13:54.772Z,1619115234.772 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-04-22T18:13:54.773Z,1619115234.773 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-04-22T18:13:54.773Z,1619115234.773 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-04-22T18:13:54.774Z,1619115234.774 [MissionManager](INFO): Uninitializing Mission Default 2021-04-22T18:13:54.774Z,1619115234.774 [Default] Stopped 2021-04-22T18:13:54.774Z,1619115234.774 [Default](DEBUG): Aggregate::uninitialize Default 2021-04-22T18:13:54.774Z,1619115234.774 [Default:B.GoToSurface] Stopped 2021-04-22T18:13:54.774Z,1619115234.774 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-22T18:13:54.774Z,1619115234.774 [Default:CheckIn] Stopped 2021-04-22T18:13:54.775Z,1619115234.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-22T18:13:54.775Z,1619115234.775 [Default:CheckIn:C.Wait] Stopped 2021-04-22T18:13:54.775Z,1619115234.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-22T18:13:54.777Z,1619115234.777 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-04-22T18:13:54.778Z,1619115234.778 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-04-22T18:13:54.778Z,1619115234.778 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-04-22T18:13:54.778Z,1619115234.778 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-04-22T18:13:54.779Z,1619115234.779 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-04-22T18:13:54.779Z,1619115234.779 [BuoyancyServo](INFO): Powering down 2021-04-22T18:13:54.790Z,1619115234.790 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-04-22T18:13:54.790Z,1619115234.790 [ElevatorServo](INFO): Powering down 2021-04-22T18:13:54.791Z,1619115234.791 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-04-22T18:13:54.791Z,1619115234.791 [MassServo](INFO): Powering down 2021-04-22T18:13:54.792Z,1619115234.792 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-22T18:13:54.792Z,1619115234.792 [RudderServo](INFO): Powering down 2021-04-22T18:13:54.792Z,1619115234.792 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-04-22T18:13:54.792Z,1619115234.792 [ThrusterServo](INFO): Powering down 2021-04-22T18:13:54.793Z,1619115234.793 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-04-22T18:13:54.793Z,1619115234.793 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-04-22T18:13:54.794Z,1619115234.794 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-04-22T18:13:54.794Z,1619115234.794 [CBIT](DEBUG): Powering off loads. 2021-04-22T18:13:54.806Z,1619115234.806 [CBIT](DEBUG): Disabling WDT. 2021-04-22T18:13:54.818Z,1619115234.818 [CBIT](DEBUG): Opening all GF detection circuits. 2021-04-22T18:13:54.819Z,1619115234.819 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.874Z,1619115234.874 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.876Z,1619115234.876 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.884Z,1619115234.884 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.928Z,1619115234.928 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:54.989Z,1619115234.989 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:55.067Z,1619115235.067 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:55.090Z,1619115235.090 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-22T18:13:55.171Z,1619115235.171 [logger ThreadHandler](INFO): Thread cancelled.