2022-07-13T21:26:56.183Z,1657747616.183 [CommandExec](IMPORTANT): got command restart logs 2022-07-13T21:26:57.495Z,1657747617.495 [DataOverHttps](INFO): Moved sent file to Logs/20220713T092033/Courier0085.lzma.bak 2022-07-13T21:26:57.495Z,1657747617.495 [DataOverHttps](INFO): SBD MOMSN=16984316 2022-07-13T21:27:02.796Z,1657747622.796 [NAL9602](INFO): SBD MO Status=0, MOMSN=8931, MT Status=0, MTMSN=0 2022-07-13T21:27:02.796Z,1657747622.796 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:27:18.957Z,1657747638.957 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220713T092033/Courier0088.lzma 2022-07-13T21:27:19.960Z,1657747639.960 [DataOverHttps](INFO): Moved sent file to Logs/20220713T092033/Courier0088.lzma.bak 2022-07-13T21:27:19.960Z,1657747639.960 [DataOverHttps](INFO): SBD MOMSN=16984321 2022-07-13T21:27:33.491Z,1657747653.491 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:27:37.534Z,1657747657.534 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20220713T092033/Express0086.lzma 2022-07-13T21:27:38.535Z,1657747658.535 [DataOverHttps](INFO): Moved sent file to Logs/20220713T092033/Express0086.lzma.bak 2022-07-13T21:27:38.535Z,1657747658.535 [DataOverHttps](INFO): SBD MOMSN=16984323 2022-07-13T21:28:07.081Z,1657747687.081 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20220713T092033/Express0089.lzma 2022-07-13T21:28:08.080Z,1657747688.080 [DataOverHttps](INFO): Moved sent file to Logs/20220713T092033/Express0089.lzma.bak 2022-07-13T21:28:08.080Z,1657747688.080 [DataOverHttps](INFO): SBD MOMSN=16984329 2022-07-13T21:28:11.659Z,1657747691.659 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:28:11.659Z,1657747691.659 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:28:11.659Z,1657747691.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:29:40.090Z,1657747780.090 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T21:29:40.090Z,1657747780.090 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+15.5,0000.,000 2022-07-13T21:29:53.026Z,1657747793.026 [DVL_micro](ERROR): only read 1 of 4 data items 2022-07-13T21:29:53.026Z,1657747793.026 [DVL_micro](ERROR): Failed to parse: :BI,+00052+00083,+00000,I 2022-07-13T21:33:12.252Z,1657747992.252 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T21:33:12.252Z,1657747992.252 [Default:CheckIn:C.Wait] Stopped 2022-07-13T21:33:12.252Z,1657747992.252 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T21:33:12.253Z,1657747992.253 [Default:CheckIn:D] Running Loop=1 2022-07-13T21:33:12.705Z,1657747992.705 [Default:CheckIn:D] Stopped 2022-07-13T21:33:12.705Z,1657747992.705 [Default:CheckIn:E] Running Loop=1 2022-07-13T21:33:13.074Z,1657747993.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.684753 min 2022-07-13T21:33:13.074Z,1657747993.074 [Default:CheckIn:E] Stopped 2022-07-13T21:33:13.074Z,1657747993.074 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T21:33:13.074Z,1657747993.074 [Default:CheckIn] Stopped 2022-07-13T21:33:13.075Z,1657747993.075 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T21:33:13.075Z,1657747993.075 [Default:CheckIn](INFO): Running loop #10 2022-07-13T21:33:13.075Z,1657747993.075 [Default:CheckIn] Running Loop=10 2022-07-13T21:33:13.075Z,1657747993.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T21:33:13.075Z,1657747993.075 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T21:33:15.072Z,1657747995.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213314.00,A,3648.02224,N,12148.16941,W,25.853,69.36,130722,,,D*73 2022-07-13T21:33:15.075Z,1657747995.075 [NAL9602](INFO): GPS fix at 20220713T213314: (36.800371, -121.802824) 2022-07-13T21:33:15.086Z,1657747995.086 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T21:33:15.086Z,1657747995.086 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T21:33:30.415Z,1657748010.415 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T21:33:30.416Z,1657748010.416 [DVL_micro](ERROR): Failed to parse: :TS,000000,35.0,+0.0,1489.WI,+00405,-00360,+00365,+00BI,+00405,-00360,+00365,+ 2022-07-13T21:33:32.381Z,1657748012.381 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20220713T212656/Courier0003.lzma 2022-07-13T21:33:33.383Z,1657748013.383 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0003.lzma.bak 2022-07-13T21:33:33.383Z,1657748013.383 [DataOverHttps](INFO): SBD MOMSN=16984367 2022-07-13T21:33:40.925Z,1657748020.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=8932, MT Status=0, MTMSN=0 2022-07-13T21:33:40.925Z,1657748020.925 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:33:54.258Z,1657748034.258 [DataOverHttps](INFO): Sending 453 bytes from file Logs/20220713T212656/Express0004.lzma 2022-07-13T21:33:55.259Z,1657748035.259 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0004.lzma.bak 2022-07-13T21:33:55.259Z,1657748035.259 [DataOverHttps](INFO): SBD MOMSN=16984371 2022-07-13T21:33:58.412Z,1657748038.412 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:33:58.412Z,1657748038.412 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:33:58.412Z,1657748038.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:34:08.184Z,1657748048.184 [CBIT](INFO): Clearing failed state for component BPC1 2022-07-13T21:34:08.184Z,1657748048.184 [BPC1] No Fault, FailCount= 9 2022-07-13T21:34:11.353Z,1657748051.353 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:35:14.762Z,1657748114.762 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:35:31.728Z,1657748131.728 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:35:48.303Z,1657748148.303 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:36:04.879Z,1657748164.879 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:36:21.068Z,1657748181.068 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:36:37.632Z,1657748197.632 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:36:46.524Z,1657748206.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:37:00.257Z,1657748220.257 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:37:13.993Z,1657748233.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:37:27.741Z,1657748247.741 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:37:41.882Z,1657748261.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:37:55.612Z,1657748275.612 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:38:09.811Z,1657748289.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:38:23.488Z,1657748303.488 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:38:37.622Z,1657748317.622 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:38:51.356Z,1657748331.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:38:59.051Z,1657748339.051 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T21:38:59.052Z,1657748339.052 [Default:CheckIn:C.Wait] Stopped 2022-07-13T21:38:59.052Z,1657748339.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T21:38:59.052Z,1657748339.052 [Default:CheckIn:D] Running Loop=1 2022-07-13T21:38:59.475Z,1657748339.475 [Default:CheckIn:D] Stopped 2022-07-13T21:38:59.475Z,1657748339.475 [Default:CheckIn:E] Running Loop=1 2022-07-13T21:38:59.915Z,1657748339.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.464246 min 2022-07-13T21:38:59.915Z,1657748339.915 [Default:CheckIn:E] Stopped 2022-07-13T21:38:59.915Z,1657748339.915 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T21:38:59.915Z,1657748339.915 [Default:CheckIn] Stopped 2022-07-13T21:38:59.915Z,1657748339.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T21:38:59.916Z,1657748339.916 [Default:CheckIn](INFO): Running loop #11 2022-07-13T21:38:59.916Z,1657748339.916 [Default:CheckIn] Running Loop=11 2022-07-13T21:38:59.916Z,1657748339.916 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T21:38:59.916Z,1657748339.916 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T21:39:01.873Z,1657748341.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213900.00,A,3648.32549,N,12147.12179,W,5.073,190.17,130722,,,D*77 2022-07-13T21:39:01.875Z,1657748341.875 [NAL9602](INFO): GPS fix at 20220713T213900: (36.805425, -121.785363) 2022-07-13T21:39:01.918Z,1657748341.918 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T21:39:01.918Z,1657748341.918 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T21:39:03.711Z,1657748343.711 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220713T212656/Courier0006.lzma 2022-07-13T21:39:03.847Z,1657748343.847 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0006.lzma.bak 2022-07-13T21:39:03.847Z,1657748343.847 [DataOverHttps](INFO): SBD MOMSN=16984413 2022-07-13T21:39:05.231Z,1657748345.231 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:39:21.469Z,1657748361.469 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20220713T212656/Express0007.lzma 2022-07-13T21:39:22.471Z,1657748362.471 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0007.lzma.bak 2022-07-13T21:39:22.471Z,1657748362.471 [DataOverHttps](INFO): SBD MOMSN=16984416 2022-07-13T21:39:25.056Z,1657748365.056 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:39:25.056Z,1657748365.056 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:39:25.056Z,1657748365.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:39:40.810Z,1657748380.810 [DVL_micro](ERROR): Failed to parse: 9.99,+9999.99,+99999.99 2022-07-13T21:40:10.280Z,1657748410.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=8933, MT Status=0, MTMSN=0 2022-07-13T21:40:10.280Z,1657748410.280 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:40:40.982Z,1657748440.982 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:44:04.687Z,1657748644.687 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:44:21.251Z,1657748661.251 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:44:25.717Z,1657748665.717 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T21:44:25.717Z,1657748665.717 [Default:CheckIn:C.Wait] Stopped 2022-07-13T21:44:25.717Z,1657748665.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T21:44:25.718Z,1657748665.718 [Default:CheckIn:D] Running Loop=1 2022-07-13T21:44:26.143Z,1657748666.143 [Default:CheckIn:D] Stopped 2022-07-13T21:44:26.143Z,1657748666.143 [Default:CheckIn:E] Running Loop=1 2022-07-13T21:44:26.516Z,1657748666.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.908708 min 2022-07-13T21:44:26.516Z,1657748666.516 [Default:CheckIn:E] Stopped 2022-07-13T21:44:26.516Z,1657748666.516 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T21:44:26.516Z,1657748666.516 [Default:CheckIn] Stopped 2022-07-13T21:44:26.517Z,1657748666.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T21:44:26.517Z,1657748666.517 [Default:CheckIn](INFO): Running loop #12 2022-07-13T21:44:26.517Z,1657748666.517 [Default:CheckIn] Running Loop=12 2022-07-13T21:44:26.517Z,1657748666.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T21:44:26.517Z,1657748666.517 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T21:44:28.533Z,1657748668.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214427.00,A,3648.16714,N,12147.21921,W,0.019,212.66,130722,,,D*77 2022-07-13T21:44:28.535Z,1657748668.535 [NAL9602](INFO): GPS fix at 20220713T214427: (36.802786, -121.786987) 2022-07-13T21:44:28.546Z,1657748668.546 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T21:44:28.546Z,1657748668.546 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T21:44:36.017Z,1657748676.017 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0009.lzma 2022-07-13T21:44:37.019Z,1657748677.019 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0009.lzma.bak 2022-07-13T21:44:37.019Z,1657748677.019 [DataOverHttps](INFO): SBD MOMSN=16984427 2022-07-13T21:44:38.223Z,1657748678.223 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:44:52.768Z,1657748692.768 [NAL9602](INFO): SBD MO Status=0, MOMSN=8934, MT Status=0, MTMSN=0 2022-07-13T21:44:52.769Z,1657748692.769 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:44:54.437Z,1657748694.437 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220713T212656/Express0010.lzma 2022-07-13T21:44:55.189Z,1657748695.189 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:44:55.439Z,1657748695.439 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0010.lzma.bak 2022-07-13T21:44:55.439Z,1657748695.439 [DataOverHttps](INFO): SBD MOMSN=16984436 2022-07-13T21:44:58.042Z,1657748698.042 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:44:58.043Z,1657748698.043 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:44:58.043Z,1657748698.043 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:45:12.156Z,1657748712.156 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:45:23.475Z,1657748723.475 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:45:28.722Z,1657748728.722 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:45:45.697Z,1657748745.697 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:46:02.704Z,1657748762.704 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:46:19.267Z,1657748779.267 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:46:45.196Z,1657748805.196 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:46:59.338Z,1657748819.338 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:47:13.072Z,1657748833.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:47:27.212Z,1657748847.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:47:40.948Z,1657748860.948 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:47:55.088Z,1657748875.088 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:48:08.823Z,1657748888.823 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:48:22.966Z,1657748902.966 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:48:36.740Z,1657748916.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:48:50.476Z,1657748930.476 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:49:04.218Z,1657748944.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:49:58.603Z,1657748998.603 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T21:49:58.603Z,1657748998.603 [Default:CheckIn:C.Wait] Stopped 2022-07-13T21:49:58.603Z,1657748998.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T21:49:58.603Z,1657748998.603 [Default:CheckIn:D] Running Loop=1 2022-07-13T21:49:58.989Z,1657748998.989 [Default:CheckIn:D] Stopped 2022-07-13T21:49:58.989Z,1657748998.989 [Default:CheckIn:E] Running Loop=1 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.456144 min 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn:E] Stopped 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn] Stopped 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn](INFO): Running loop #13 2022-07-13T21:49:59.392Z,1657748999.392 [Default:CheckIn] Running Loop=13 2022-07-13T21:49:59.393Z,1657748999.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T21:49:59.393Z,1657748999.393 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T21:50:01.396Z,1657749001.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215000.00,A,3648.17273,N,12147.21843,W,0.019,269.37,130722,,,A*7A 2022-07-13T21:50:01.398Z,1657749001.398 [NAL9602](INFO): GPS fix at 20220713T215000: (36.802879, -121.786974) 2022-07-13T21:50:01.435Z,1657749001.435 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T21:50:01.435Z,1657749001.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T21:50:13.889Z,1657749013.889 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220713T212656/Courier0012.lzma 2022-07-13T21:50:14.891Z,1657749014.891 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0012.lzma.bak 2022-07-13T21:50:14.892Z,1657749014.892 [DataOverHttps](INFO): SBD MOMSN=16984462 2022-07-13T21:50:15.990Z,1657749015.990 [NAL9602](INFO): SBD MO Status=0, MOMSN=8935, MT Status=0, MTMSN=0 2022-07-13T21:50:15.990Z,1657749015.990 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:50:26.077Z,1657749026.077 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+17.4,0000.0,1489.0,000 2022-07-13T21:50:31.312Z,1657749031.312 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2022-07-13T21:50:33.709Z,1657749033.709 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220713T212656/Express0013.lzma 2022-07-13T21:50:34.712Z,1657749034.712 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0013.lzma.bak 2022-07-13T21:50:34.712Z,1657749034.712 [DataOverHttps](INFO): SBD MOMSN=16984465 2022-07-13T21:50:37.544Z,1657749037.544 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:50:37.544Z,1657749037.544 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:50:37.544Z,1657749037.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:50:46.663Z,1657749046.663 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:52:28.531Z,1657749148.531 [DVL_micro](ERROR): Failed to parse: 25,-0039,+00000,A 2022-07-13T21:53:56.553Z,1657749236.553 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:54:13.521Z,1657749253.521 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:54:30.488Z,1657749270.488 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:54:47.053Z,1657749287.053 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:55:03.617Z,1657749303.617 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:55:20.180Z,1657749320.180 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:55:37.148Z,1657749337.148 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:55:37.975Z,1657749337.975 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T21:55:37.975Z,1657749337.975 [Default:CheckIn:C.Wait] Stopped 2022-07-13T21:55:37.975Z,1657749337.975 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T21:55:37.975Z,1657749337.975 [Default:CheckIn:D] Running Loop=1 2022-07-13T21:55:38.393Z,1657749338.393 [Default:CheckIn:D] Stopped 2022-07-13T21:55:38.393Z,1657749338.393 [Default:CheckIn:E] Running Loop=1 2022-07-13T21:55:38.793Z,1657749338.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.112882 min 2022-07-13T21:55:38.793Z,1657749338.793 [Default:CheckIn:E] Stopped 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn] Stopped 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn](INFO): Running loop #14 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn] Running Loop=14 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T21:55:38.794Z,1657749338.794 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T21:55:40.816Z,1657749340.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215540.00,A,3648.17135,N,12147.28193,W,0.058,219.62,130722,,,A*75 2022-07-13T21:55:40.818Z,1657749340.818 [NAL9602](INFO): GPS fix at 20220713T215540: (36.802856, -121.788032) 2022-07-13T21:55:40.835Z,1657749340.835 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T21:55:40.835Z,1657749340.835 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T21:55:53.321Z,1657749353.321 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0015.lzma 2022-07-13T21:55:54.189Z,1657749354.189 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:55:54.324Z,1657749354.324 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0015.lzma.bak 2022-07-13T21:55:54.324Z,1657749354.324 [DataOverHttps](INFO): SBD MOMSN=16984503 2022-07-13T21:56:10.828Z,1657749370.828 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T21:56:16.794Z,1657749376.794 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220713T212656/Express0016.lzma 2022-07-13T21:56:17.795Z,1657749377.795 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0016.lzma.bak 2022-07-13T21:56:17.796Z,1657749377.796 [DataOverHttps](INFO): SBD MOMSN=16984506 2022-07-13T21:56:20.567Z,1657749380.567 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T21:56:20.567Z,1657749380.567 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T21:56:20.567Z,1657749380.567 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T21:56:40.724Z,1657749400.724 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:56:57.693Z,1657749417.693 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:57:14.268Z,1657749434.268 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:57:30.820Z,1657749450.820 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:57:41.333Z,1657749461.333 [NAL9602](INFO): SBD MO Status=0, MOMSN=8936, MT Status=0, MTMSN=0 2022-07-13T21:57:41.333Z,1657749461.333 [NAL9602](INFO): No messages in MT queue 2022-07-13T21:57:47.417Z,1657749467.417 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:58:04.416Z,1657749484.416 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:58:12.116Z,1657749492.116 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T21:58:21.385Z,1657749501.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:58:26.249Z,1657749506.249 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T21:58:26.249Z,1657749506.249 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2022-07-13T21:58:38.353Z,1657749518.353 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T21:58:54.923Z,1657749534.923 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:00:48.857Z,1657749648.857 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:00:48.857Z,1657749648.857 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2022-07-13T22:01:21.205Z,1657749681.205 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:01:21.205Z,1657749681.205 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:01:21.205Z,1657749681.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:01:21.205Z,1657749681.205 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:01:21.587Z,1657749681.587 [Default:CheckIn:D] Stopped 2022-07-13T22:01:21.587Z,1657749681.587 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.832772 min 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn:E] Stopped 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn] Stopped 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:01:22.003Z,1657749682.003 [Default:CheckIn](INFO): Running loop #15 2022-07-13T22:01:22.004Z,1657749682.004 [Default:CheckIn] Running Loop=15 2022-07-13T22:01:22.004Z,1657749682.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:01:22.004Z,1657749682.004 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:01:22.259Z,1657749682.259 [DataOverHttps](IMPORTANT): SBD MTMSN=20220713T220121 2022-07-13T22:01:24.007Z,1657749684.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220123.00,A,3648.16780,N,12147.28054,W,0.486,198.93,130722,,,A*72 2022-07-13T22:01:24.009Z,1657749684.009 [NAL9602](INFO): GPS fix at 20220713T220123: (36.802797, -121.788009) 2022-07-13T22:01:24.020Z,1657749684.020 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:01:24.020Z,1657749684.020 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:01:30.557Z,1657749690.557 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0018.lzma 2022-07-13T22:01:30.560Z,1657749690.560 [DataOverHttps](INFO): Received command: strobe off 2022-07-13T22:01:30.663Z,1657749690.663 [CommandExec](IMPORTANT): got command strobe off 2022-07-13T22:01:30.663Z,1657749690.663 [CommandExec](IMPORTANT): Deactivating strobe 2022-07-13T22:01:31.812Z,1657749691.812 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0018.lzma.bak 2022-07-13T22:01:31.813Z,1657749691.813 [DataOverHttps](INFO): SBD MOMSN=16984541 2022-07-13T22:01:52.057Z,1657749712.057 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220713T212656/Courier0021.lzma 2022-07-13T22:01:53.060Z,1657749713.060 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0021.lzma.bak 2022-07-13T22:01:53.060Z,1657749713.060 [DataOverHttps](INFO): SBD MOMSN=16984555 2022-07-13T22:02:14.998Z,1657749734.998 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20220713T212656/Express0019.lzma 2022-07-13T22:02:15.000Z,1657749736.000 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0019.lzma.bak 2022-07-13T22:02:15.000Z,1657749736.000 [DataOverHttps](INFO): SBD MOMSN=16984557 2022-07-13T22:02:34.593Z,1657749754.593 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20220713T212656/Express0022.lzma 2022-07-13T22:02:35.596Z,1657749755.596 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0022.lzma.bak 2022-07-13T22:02:35.596Z,1657749755.596 [DataOverHttps](INFO): SBD MOMSN=16984561 2022-07-13T22:02:38.180Z,1657749758.180 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:02:38.180Z,1657749758.180 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:02:38.180Z,1657749758.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:03:34.741Z,1657749814.741 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:03:34.741Z,1657749814.741 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+99999.99,+9999.99 2022-07-13T22:03:36.330Z,1657749816.330 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:03:53.298Z,1657749833.298 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:04:10.264Z,1657749850.264 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:04:26.837Z,1657749866.837 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:04:43.392Z,1657749883.392 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:04:59.956Z,1657749899.956 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:05:08.859Z,1657749908.859 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 6. 2022-07-13T22:05:08.862Z,1657749908.862 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Missing stick IDs are: 1, 2, 25, 28, 29, 42. 2022-07-13T22:05:08.862Z,1657749908.862 [BPC1] Data Fault, FailCount= 10 2022-07-13T22:05:08.862Z,1657749908.862 [BPC1](ERROR): Data Fault 2022-07-13T22:05:08.962Z,1657749908.962 [CBIT](ERROR): Data Fault in component: BPC1 2022-07-13T22:05:08.963Z,1657749908.963 [CBIT](CRITICAL): Data Fault in component: BPC1 2022-07-13T22:05:09.281Z,1657749909.281 [CommandExec](FAULT): Scheduling is paused 2022-07-13T22:05:09.282Z,1657749909.282 [CBIT](INFO): Critical error at 20220713T220508 2022-07-13T22:05:29.142Z,1657749929.142 [DVL_micro](ERROR): DVL uart error: serial timeout 2022-07-13T22:05:29.142Z,1657749929.142 [DVL_micro] Communications Fault, FailCount= 1 2022-07-13T22:05:29.142Z,1657749929.142 [DVL_micro](ERROR): Communications Fault 2022-07-13T22:05:29.142Z,1657749929.142 [DVL_micro](ERROR): Failed to parse: 2022-07-13T22:05:29.198Z,1657749929.198 [CBIT](ERROR): Communications Fault in component: DVL_micro 2022-07-13T22:05:29.518Z,1657749929.518 [DVL_micro](INFO): Powering down 2022-07-13T22:05:30.279Z,1657749930.279 [CBIT](INFO): Clearing failed state for component DVL_micro 2022-07-13T22:05:30.279Z,1657749930.279 [DVL_micro] No Fault, FailCount= 1 2022-07-13T22:06:26.473Z,1657749986.473 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-07-13T22:06:57.171Z,1657750017.171 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:07:38.786Z,1657750058.786 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:07:38.786Z,1657750058.786 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:07:38.786Z,1657750058.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:07:38.786Z,1657750058.786 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:07:39.203Z,1657750059.203 [Default:CheckIn:D] Stopped 2022-07-13T22:07:39.203Z,1657750059.203 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:07:39.598Z,1657750059.598 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.126383 min 2022-07-13T22:07:39.598Z,1657750059.598 [Default:CheckIn:E] Stopped 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn] Stopped 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn](INFO): Running loop #16 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn] Running Loop=16 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:07:39.599Z,1657750059.599 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:07:41.610Z,1657750061.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220740.00,A,3648.16817,N,12147.28263,W,0.175,227.60,130722,,,D*71 2022-07-13T22:07:41.612Z,1657750061.612 [NAL9602](INFO): GPS fix at 20220713T220740: (36.802803, -121.788044) 2022-07-13T22:07:41.622Z,1657750061.622 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:07:41.623Z,1657750061.623 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:07:49.634Z,1657750069.634 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20220713T212656/Courier0024.lzma 2022-07-13T22:07:50.636Z,1657750070.636 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0024.lzma.bak 2022-07-13T22:07:50.637Z,1657750070.637 [DataOverHttps](INFO): SBD MOMSN=16984595 2022-07-13T22:08:12.364Z,1657750092.364 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-07-13T22:08:12.446Z,1657750092.446 [NAL9602](ERROR): received: +CSQ:0 OK36, 0, 0, 0, 0 OK 2022-07-13T22:08:12.802Z,1657750092.802 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20220713T212656/Express0025.lzma 2022-07-13T22:08:13.804Z,1657750093.804 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0025.lzma.bak 2022-07-13T22:08:13.804Z,1657750093.804 [DataOverHttps](INFO): SBD MOMSN=16984599 2022-07-13T22:08:16.446Z,1657750096.446 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:08:16.446Z,1657750096.446 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:08:16.446Z,1657750096.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:08:52.353Z,1657750132.353 [NAL9602](INFO): SBD MO Status=2, MOMSN=8937, MT Status=2, MTMSN=0 2022-07-13T22:08:52.353Z,1657750132.353 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-07-13T22:09:38.418Z,1657750178.418 [NAL9602](INFO): SBD MO Status=2, MOMSN=8937, MT Status=2, MTMSN=0 2022-07-13T22:09:38.419Z,1657750178.419 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-07-13T22:10:03.501Z,1657750203.501 [NAL9602](INFO): SBD MO Status=0, MOMSN=8937, MT Status=0, MTMSN=0 2022-07-13T22:10:03.501Z,1657750203.501 [NAL9602](INFO): No messages in MT queue 2022-07-13T22:10:34.207Z,1657750234.207 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:13:17.079Z,1657750397.079 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:13:17.079Z,1657750397.079 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:13:17.080Z,1657750397.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:13:17.080Z,1657750397.080 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:13:17.489Z,1657750397.489 [Default:CheckIn:D] Stopped 2022-07-13T22:13:17.489Z,1657750397.489 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.764478 min 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn:E] Stopped 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn] Stopped 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn](INFO): Running loop #17 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn] Running Loop=17 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:13:17.903Z,1657750397.903 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:13:19.901Z,1657750399.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221319.00,A,3648.15677,N,12147.28193,W,1.186,6.13,130722,,,D*77 2022-07-13T22:13:19.904Z,1657750399.904 [NAL9602](INFO): GPS fix at 20220713T221319: (36.802613, -121.788032) 2022-07-13T22:13:19.934Z,1657750399.934 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:13:19.935Z,1657750399.935 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:13:31.322Z,1657750411.322 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0027.lzma 2022-07-13T22:13:32.324Z,1657750412.324 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0027.lzma.bak 2022-07-13T22:13:32.324Z,1657750412.324 [DataOverHttps](INFO): SBD MOMSN=16984613 2022-07-13T22:13:44.554Z,1657750424.554 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2022-07-13T22:13:54.566Z,1657750434.566 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220713T212656/Express0028.lzma 2022-07-13T22:13:55.569Z,1657750435.569 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0028.lzma.bak 2022-07-13T22:13:55.569Z,1657750435.569 [DataOverHttps](INFO): SBD MOMSN=16984616 2022-07-13T22:14:00.017Z,1657750440.017 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:14:00.017Z,1657750440.017 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:14:00.017Z,1657750440.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:15:09.923Z,1657750509.923 [CBIT](INFO): Clearing failed state for component BPC1 2022-07-13T22:15:09.923Z,1657750509.923 [BPC1] No Fault, FailCount= 10 2022-07-13T22:16:16.582Z,1657750576.582 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:16:33.548Z,1657750593.548 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:16:50.112Z,1657750610.112 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:17:06.676Z,1657750626.676 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:17:22.836Z,1657750642.836 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:17:39.400Z,1657750659.400 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:17:48.715Z,1657750668.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:17:48.746Z,1657750668.746 [DVL_micro](ERROR): Failed to parse: :WI,+01271,+00457,+00911,+00000,A 2022-07-13T22:18:02.490Z,1657750682.490 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:18:16.628Z,1657750696.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:18:22.695Z,1657750702.695 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-07-13T22:18:30.363Z,1657750710.363 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:18:44.504Z,1657750724.504 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:18:53.394Z,1657750733.394 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:18:58.240Z,1657750738.240 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:19:00.682Z,1657750740.682 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:19:00.682Z,1657750740.682 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:19:00.682Z,1657750740.682 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:19:00.682Z,1657750740.682 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:19:01.083Z,1657750741.083 [Default:CheckIn:D] Stopped 2022-07-13T22:19:01.083Z,1657750741.083 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:19:01.492Z,1657750741.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.491040 min 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn:E] Stopped 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn] Stopped 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn](INFO): Running loop #18 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn] Running Loop=18 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:19:01.493Z,1657750741.493 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:19:03.501Z,1657750743.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221902.00,A,3648.13509,N,12147.29083,W,1.147,6.13,130722,,,A*72 2022-07-13T22:19:03.504Z,1657750743.504 [NAL9602](INFO): GPS fix at 20220713T221902: (36.802251, -121.788180) 2022-07-13T22:19:03.547Z,1657750743.547 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:19:03.547Z,1657750743.547 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:19:07.690Z,1657750747.690 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0030.lzma 2022-07-13T22:19:07.940Z,1657750747.940 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0030.lzma.bak 2022-07-13T22:19:07.940Z,1657750747.940 [DataOverHttps](INFO): SBD MOMSN=16984635 2022-07-13T22:19:12.453Z,1657750752.453 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:19:26.226Z,1657750766.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:19:30.671Z,1657750770.671 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20220713T212656/Express0031.lzma 2022-07-13T22:19:31.668Z,1657750771.668 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0031.lzma.bak 2022-07-13T22:19:31.668Z,1657750771.668 [DataOverHttps](INFO): SBD MOMSN=16984638 2022-07-13T22:19:33.932Z,1657750773.932 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-07-13T22:19:34.007Z,1657750774.007 [NAL9602](ERROR): received: +CSQ:0 OK37, 0, 0, 0, 0 OK 2022-07-13T22:19:37.199Z,1657750777.199 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:19:37.199Z,1657750777.199 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:19:37.199Z,1657750777.199 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:19:39.994Z,1657750779.994 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:19:54.254Z,1657750794.254 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:20:08.336Z,1657750808.336 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:21:24.683Z,1657750884.683 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-07-13T22:21:24.684Z,1657750884.684 [DropWeight] Hardware Fault, FailCount= 1 2022-07-13T22:21:24.684Z,1657750884.684 [DropWeight](ERROR): Hardware Fault 2022-07-13T22:21:24.757Z,1657750884.757 [CBIT](INFO): Critical error at 20220713T222124 2022-07-13T22:21:24.763Z,1657750884.763 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-07-13T22:21:24.764Z,1657750884.764 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-07-13T22:21:25.139Z,1657750885.139 [CBIT](INFO): Critical error at 20220713T222124 2022-07-13T22:21:35.218Z,1657750895.218 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T22:21:35.219Z,1657750895.219 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+19.2,000.0,000 2022-07-13T22:22:02.812Z,1657750922.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:24:05.499Z,1657751045.499 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-07-13T22:24:20.127Z,1657751060.127 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:24:36.195Z,1657751076.195 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:24:37.825Z,1657751077.825 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:24:37.825Z,1657751077.825 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:24:37.825Z,1657751077.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:24:37.825Z,1657751077.825 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:24:38.238Z,1657751078.238 [Default:CheckIn:D] Stopped 2022-07-13T22:24:38.239Z,1657751078.239 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:24:38.630Z,1657751078.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.110303 min 2022-07-13T22:24:38.630Z,1657751078.630 [Default:CheckIn:E] Stopped 2022-07-13T22:24:38.630Z,1657751078.630 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:24:38.630Z,1657751078.630 [Default:CheckIn] Stopped 2022-07-13T22:24:38.631Z,1657751078.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:24:38.631Z,1657751078.631 [Default:CheckIn](INFO): Running loop #19 2022-07-13T22:24:38.631Z,1657751078.631 [Default:CheckIn] Running Loop=19 2022-07-13T22:24:38.631Z,1657751078.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:24:38.631Z,1657751078.631 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:24:40.666Z,1657751080.666 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222439.00,A,3648.17918,N,12147.27606,W,0.680,0.00,130722,,,A*70 2022-07-13T22:24:40.669Z,1657751080.669 [NAL9602](INFO): GPS fix at 20220713T222439: (36.802986, -121.787934) 2022-07-13T22:24:40.680Z,1657751080.680 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:24:40.680Z,1657751080.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:24:46.376Z,1657751086.376 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:24:48.629Z,1657751088.629 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20220713T212656/Courier0033.lzma 2022-07-13T22:24:49.632Z,1657751089.632 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0033.lzma.bak 2022-07-13T22:24:49.632Z,1657751089.632 [DataOverHttps](INFO): SBD MOMSN=16984648 2022-07-13T22:25:06.494Z,1657751106.494 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:25:07.006Z,1657751107.006 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20220713T212656/Express0034.lzma 2022-07-13T22:25:08.008Z,1657751108.008 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0034.lzma.bak 2022-07-13T22:25:08.008Z,1657751108.008 [DataOverHttps](INFO): SBD MOMSN=16984653 2022-07-13T22:25:10.565Z,1657751110.565 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:25:10.565Z,1657751110.565 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:25:10.565Z,1657751110.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:25:11.344Z,1657751111.344 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-07-13T22:25:11.418Z,1657751111.418 [NAL9602](ERROR): received: +CSQ:0 OK37, 0, 0, 0, 0 OK 2022-07-13T22:25:25.481Z,1657751125.481 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:25:44.064Z,1657751144.064 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:26:02.098Z,1657751162.098 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:26:20.684Z,1657751180.684 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:26:31.253Z,1657751191.253 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:26:39.267Z,1657751199.267 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:26:57.052Z,1657751217.052 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:26:59.960Z,1657751219.960 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:27:16.039Z,1657751236.039 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:27:22.913Z,1657751242.913 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T22:27:22.913Z,1657751242.913 [DVL_micro](ERROR): Failed to parse: :TS,000000000000019.2,0000.0,1489.0,000 2022-07-13T22:27:47.248Z,1657751267.248 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:27:52.400Z,1657751272.400 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:28:11.388Z,1657751291.388 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:28:29.567Z,1657751309.567 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:28:48.173Z,1657751328.173 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:29:07.149Z,1657751347.149 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:29:25.327Z,1657751365.327 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:29:30.237Z,1657751370.237 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:29:42.298Z,1657751382.298 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-07-13T22:29:43.104Z,1657751383.104 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:29:58.922Z,1657751398.922 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:30:01.284Z,1657751401.284 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:30:05.366Z,1657751405.366 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T22:30:05.366Z,1657751405.366 [DVL_micro](ERROR): Failed to parse: :TS,000000035.0,+19.3,0000.0,1489.0,000 2022-07-13T22:30:11.001Z,1657751411.001 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:30:11.001Z,1657751411.001 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:30:11.001Z,1657751411.001 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:30:11.002Z,1657751411.002 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:30:11.395Z,1657751411.395 [Default:CheckIn:D] Stopped 2022-07-13T22:30:11.395Z,1657751411.395 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:30:11.799Z,1657751411.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.662915 min 2022-07-13T22:30:11.799Z,1657751411.799 [Default:CheckIn:E] Stopped 2022-07-13T22:30:11.799Z,1657751411.799 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn] Stopped 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn](INFO): Running loop #20 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn] Running Loop=20 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:30:11.800Z,1657751411.800 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:30:13.819Z,1657751413.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223013.00,A,3648.16388,N,12147.28710,W,1.069,211.91,130722,,,A*7C 2022-07-13T22:30:13.821Z,1657751413.821 [NAL9602](INFO): GPS fix at 20220713T223013: (36.802731, -121.788118) 2022-07-13T22:30:13.832Z,1657751413.832 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:30:13.832Z,1657751413.832 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:30:21.636Z,1657751421.636 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0036.lzma 2022-07-13T22:30:22.638Z,1657751422.638 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0036.lzma.bak 2022-07-13T22:30:22.638Z,1657751422.638 [DataOverHttps](INFO): SBD MOMSN=16984672 2022-07-13T22:30:38.920Z,1657751438.920 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20220713T212656/Express0037.lzma 2022-07-13T22:30:39.923Z,1657751439.923 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0037.lzma.bak 2022-07-13T22:30:39.923Z,1657751439.923 [DataOverHttps](INFO): SBD MOMSN=16984675 2022-07-13T22:30:42.521Z,1657751442.521 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:30:42.522Z,1657751442.522 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:30:42.522Z,1657751442.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:30:46.534Z,1657751446.534 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:32:48.156Z,1657751568.156 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:32:48.156Z,1657751568.156 [DVL_micro](ERROR): Failed to parse: :RD,+99999.99,+9999.99,+9999.99 2022-07-13T22:32:56.316Z,1657751576.316 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:34:04.515Z,1657751644.515 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:34:04.515Z,1657751644.515 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.999 2022-07-13T22:34:15.903Z,1657751655.903 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:34:32.407Z,1657751672.407 [DVL_micro](ERROR): Failed to parse: :000000000,35.0,+19.3,0000.0,1489.0,000 2022-07-13T22:34:54.773Z,1657751694.773 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:35:01.094Z,1657751701.094 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:35:18.067Z,1657751718.067 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:35:18.068Z,1657751718.068 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2022-07-13T22:35:20.078Z,1657751720.078 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:35:28.567Z,1657751728.567 [DVL_micro](ERROR): Failed to parse: :T00000000,35.0,+19.3,0000.0,1489.0,000 2022-07-13T22:35:29.481Z,1657751729.481 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:35:39.067Z,1657751739.067 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:35:43.180Z,1657751743.180 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:35:43.180Z,1657751743.180 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:35:43.180Z,1657751743.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:35:43.181Z,1657751743.181 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:35:43.605Z,1657751743.605 [Default:CheckIn:D] Stopped 2022-07-13T22:35:43.605Z,1657751743.605 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.199748 min 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn:E] Stopped 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn] Stopped 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn](INFO): Running loop #21 2022-07-13T22:35:44.133Z,1657751744.133 [Default:CheckIn] Running Loop=21 2022-07-13T22:35:44.134Z,1657751744.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:35:44.134Z,1657751744.134 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:35:45.947Z,1657751745.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223545.00,A,3648.15685,N,12147.28373,W,1.030,0.00,130722,,,A*76 2022-07-13T22:35:45.956Z,1657751745.956 [NAL9602](INFO): GPS fix at 20220713T223545: (36.802614, -121.788062) 2022-07-13T22:35:46.012Z,1657751746.012 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:35:46.012Z,1657751746.012 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:35:57.591Z,1657751757.591 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:35:59.824Z,1657751759.824 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220713T212656/Courier0039.lzma 2022-07-13T22:36:00.826Z,1657751760.826 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0039.lzma.bak 2022-07-13T22:36:00.826Z,1657751760.826 [DataOverHttps](INFO): SBD MOMSN=16984688 2022-07-13T22:36:15.813Z,1657751775.813 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:36:16.628Z,1657751776.628 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-07-13T22:36:16.701Z,1657751776.701 [NAL9602](ERROR): received: +CSQ:0 OK37, 0, 0, 0, 0 OK 2022-07-13T22:36:17.808Z,1657751777.808 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:36:17.808Z,1657751777.808 [AHRS_M2] Data Fault, FailCount= 1 2022-07-13T22:36:17.808Z,1657751777.808 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:36:17.921Z,1657751777.921 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:36:18.193Z,1657751778.193 [AHRS_M2](INFO): Powering down 2022-07-13T22:36:19.098Z,1657751779.098 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-07-13T22:36:19.098Z,1657751779.098 [AHRS_M2] No Fault, FailCount= 1 2022-07-13T22:36:19.405Z,1657751779.405 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:36:24.783Z,1657751784.783 [CBIT](INFO): Clearing failed state for component DropWeight 2022-07-13T22:36:24.783Z,1657751784.783 [DropWeight] No Fault, FailCount= 1 2022-07-13T22:36:26.715Z,1657751786.715 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:36:26.715Z,1657751786.715 [DVL_micro](ERROR): Failed to parse: :BI,-00045,-00054,+00125,+00000+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2022-07-13T22:36:30.392Z,1657751790.392 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220713T212656/Express0040.lzma 2022-07-13T22:36:31.394Z,1657751791.394 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0040.lzma.bak 2022-07-13T22:36:31.394Z,1657751791.394 [DataOverHttps](INFO): SBD MOMSN=16984697 2022-07-13T22:36:32.756Z,1657751792.756 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:36:32.756Z,1657751792.756 [AHRS_M2] Data Fault, FailCount= 2 2022-07-13T22:36:32.757Z,1657751792.757 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:36:32.853Z,1657751792.853 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:36:33.142Z,1657751793.142 [AHRS_M2](INFO): Powering down 2022-07-13T22:36:33.950Z,1657751793.950 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:36:34.083Z,1657751794.083 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-07-13T22:36:34.083Z,1657751794.083 [AHRS_M2] No Fault, FailCount= 2 2022-07-13T22:36:34.353Z,1657751794.353 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:36:35.991Z,1657751795.991 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:36:35.991Z,1657751795.991 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:36:35.991Z,1657751795.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:36:47.704Z,1657751807.704 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:36:47.704Z,1657751807.704 [AHRS_M2] Data Fault, FailCount= 3 2022-07-13T22:36:47.705Z,1657751807.705 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:36:47.825Z,1657751807.825 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:36:48.089Z,1657751808.089 [AHRS_M2](INFO): Powering down 2022-07-13T22:36:48.974Z,1657751808.974 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-07-13T22:36:48.974Z,1657751808.974 [AHRS_M2] No Fault, FailCount= 3 2022-07-13T22:36:49.302Z,1657751809.302 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:36:52.969Z,1657751812.969 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:37:02.652Z,1657751822.652 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:37:02.652Z,1657751822.652 [AHRS_M2] Data Fault, FailCount= 4 2022-07-13T22:37:02.652Z,1657751822.652 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:37:02.802Z,1657751822.802 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:37:03.037Z,1657751823.037 [AHRS_M2](INFO): Powering down 2022-07-13T22:37:03.946Z,1657751823.946 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-07-13T22:37:03.946Z,1657751823.946 [AHRS_M2] No Fault, FailCount= 4 2022-07-13T22:37:04.250Z,1657751824.250 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:37:07.521Z,1657751827.521 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:37:07.522Z,1657751827.522 [DVL_micro](ERROR): Failed to parse: :RD,+99999.99,+9999.99,+9999.99 2022-07-13T22:37:11.541Z,1657751831.541 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-13T22:37:17.600Z,1657751837.600 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:37:17.600Z,1657751837.600 [AHRS_M2] Data Fault, FailCount= 5 2022-07-13T22:37:17.600Z,1657751837.600 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:37:17.710Z,1657751837.710 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:37:17.710Z,1657751837.710 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2022-07-13T22:37:17.986Z,1657751837.986 [AHRS_M2](INFO): Powering down 2022-07-13T22:37:18.130Z,1657751838.130 [CBIT](INFO): Critical error at 20220713T223717 2022-07-13T22:37:42.345Z,1657751862.345 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-07-13T22:37:48.286Z,1657751868.286 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:38:06.466Z,1657751886.466 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:38:25.465Z,1657751905.465 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:38:26.231Z,1657751906.231 [CommandExec](IMPORTANT): got command failComponent hardware AHRS_M2 2022-07-13T22:38:26.231Z,1657751906.231 [AHRS_M2] Hardware Fault, FailCount= 5 2022-07-13T22:38:26.231Z,1657751906.231 [AHRS_M2](ERROR): Hardware Fault 2022-07-13T22:38:26.231Z,1657751906.231 [CommandExec](IMPORTANT): AHRS_M2 failureMode is Hardware Fault 2022-07-13T22:38:28.692Z,1657751908.692 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-07-13T22:38:28.692Z,1657751908.692 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0000.0,1489.0,000 2022-07-13T22:38:29.957Z,1657751909.957 [CommandExec](IMPORTANT): got command failComponent 2022-07-13T22:38:29.957Z,1657751909.957 [CommandExec](IMPORTANT): Failed components: 2022-07-13T22:38:29.957Z,1657751909.957 [CommandExec](IMPORTANT): AHRS_M2: Hardware Fault 2022-07-13T22:38:38.414Z,1657751918.414 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-07-13T22:38:38.414Z,1657751918.414 [AHRS_M2] No Fault, FailCount= 5 2022-07-13T22:38:38.414Z,1657751918.414 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-07-13T22:38:38.785Z,1657751918.785 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:38:43.653Z,1657751923.653 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:38:52.137Z,1657751932.137 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:38:52.137Z,1657751932.137 [AHRS_M2] Data Fault, FailCount= 6 2022-07-13T22:38:52.137Z,1657751932.137 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:38:52.242Z,1657751932.242 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:38:52.242Z,1657751932.242 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2022-07-13T22:38:52.521Z,1657751932.521 [AHRS_M2](INFO): Powering down 2022-07-13T22:38:52.644Z,1657751932.644 [CBIT](INFO): Critical error at 20220713T223852 2022-07-13T22:39:01.684Z,1657751941.684 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-07-13T22:39:01.684Z,1657751941.684 [AHRS_M2] No Fault, FailCount= 6 2022-07-13T22:39:01.688Z,1657751941.688 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-07-13T22:39:01.815Z,1657751941.815 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:39:01.848Z,1657751941.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:39:05.205Z,1657751945.205 [CommandExec](IMPORTANT): got command failComponent 2022-07-13T22:39:05.205Z,1657751945.205 [CommandExec](IMPORTANT): Failed components: 2022-07-13T22:39:05.205Z,1657751945.205 [CommandExec](IMPORTANT): No failed Components. 2022-07-13T22:39:08.415Z,1657751948.415 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:39:15.165Z,1657751955.165 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:39:15.165Z,1657751955.165 [AHRS_M2] Data Fault, FailCount= 7 2022-07-13T22:39:15.165Z,1657751955.165 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:39:15.257Z,1657751955.257 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:39:15.257Z,1657751955.257 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2022-07-13T22:39:15.550Z,1657751955.550 [AHRS_M2](INFO): Powering down 2022-07-13T22:39:15.676Z,1657751955.676 [CBIT](INFO): Critical error at 20220713T223915 2022-07-13T22:39:20.814Z,1657751960.814 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:39:39.790Z,1657751979.790 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:39:41.233Z,1657751981.233 [CommandExec](IMPORTANT): got command failComponent 2022-07-13T22:39:41.233Z,1657751981.233 [CommandExec](IMPORTANT): Failed components: 2022-07-13T22:39:41.234Z,1657751981.234 [CommandExec](IMPORTANT): AHRS_M2: Data Fault 2022-07-13T22:39:49.621Z,1657751989.621 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-07-13T22:39:49.621Z,1657751989.621 [AHRS_M2] No Fault, FailCount= 7 2022-07-13T22:39:49.622Z,1657751989.622 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-07-13T22:39:49.891Z,1657751989.891 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:39:50.303Z,1657751990.303 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:39:50.304Z,1657751990.304 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+ 2022-07-13T22:39:52.736Z,1657751992.736 [DVL_micro](ERROR): Failed to parse: 0000000000,35.0,+19.3,0000.0,1489.0,000 2022-07-13T22:39:58.797Z,1657751998.797 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2022-07-13T22:40:03.245Z,1657752003.245 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:40:03.245Z,1657752003.245 [AHRS_M2] Data Fault, FailCount= 8 2022-07-13T22:40:03.245Z,1657752003.245 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:40:03.355Z,1657752003.355 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:40:03.355Z,1657752003.355 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2022-07-13T22:40:03.625Z,1657752003.625 [AHRS_M2](INFO): Powering down 2022-07-13T22:40:03.744Z,1657752003.744 [CBIT](INFO): Critical error at 20220713T224003 2022-07-13T22:40:05.770Z,1657752005.770 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:40:11.619Z,1657752011.619 [CommandExec](IMPORTANT): got command configSet AHRS_M2.verbosity 3 count 2022-07-13T22:40:21.466Z,1657752021.466 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2022-07-13T22:40:21.466Z,1657752021.466 [AHRS_M2] No Fault, FailCount= 8 2022-07-13T22:40:21.466Z,1657752021.466 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2022-07-13T22:40:21.806Z,1657752021.806 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-13T22:40:25.061Z,1657752025.061 [AHRS_M2](INFO): Data-stream active. 2022-07-13T22:40:25.600Z,1657752025.600 [AHRS_M2](INFO): Tx (29): 0xa 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x89 0xab 0xc1 0x90 0x2c 0x93 0x43 0xac 0x1f 0xc6 0xf9 0x3b 0x3 2022-07-13T22:40:25.643Z,1657752025.643 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x89 0xab 0xc1 0x90 0x2c 0x93 0x43 0xac 0x1f 0xc6 0xf9 0x3b 2022-07-13T22:40:25.888Z,1657752025.888 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x77 0xfa 0xc1 0x90 0x10 0x96 0xa 0x43 0xac 0x1d 0x95 0x37 0xf2 0x3 2022-07-13T22:40:25.897Z,1657752025.897 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x77 0xfa 0xc1 0x90 0x16 0xa 0x43 0xac 0x1d 0x95 0x37 0xf2 2022-07-13T22:40:26.259Z,1657752026.259 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x7b 0x22 0xc1 0x90 0xe 0x68 0x43 0xac 0x1c 0x83 0x41 0xb9 0x3 2022-07-13T22:40:26.261Z,1657752026.261 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x7b 0x22 0xc1 0x90 0xe 0x68 0x43 0xac 0x1c 0x83 0x41 0xb9 2022-07-13T22:40:26.663Z,1657752026.663 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x54 0x17 0xc1 0x8f 0xe1 0x9e 0x43 0xac 0x1b 0x60 0xf8 0x6b 0x3 2022-07-13T22:40:26.664Z,1657752026.664 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x54 0x17 0xc1 0x8f 0xe1 0x9e 0x43 0xac 0x1b 0x60 0xf8 0x6b 2022-07-13T22:40:27.065Z,1657752027.065 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf9 0xe9 0xc1 0x8f 0x88 0xd7 0x43 0xac 0x1c 0xa6 0x9c 0xcd 0x3 2022-07-13T22:40:27.067Z,1657752027.067 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf9 0xe9 0xc1 0x8f 0x88 0xd7 0x43 0xac 0x1c 0xa6 0x9c 0xcd 2022-07-13T22:40:27.469Z,1657752027.469 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf1 0x7 0xc1 0x8f 0x6f 0x92 0x43 0xac 0x1d 0xfa 0x3b 0x68 0x3 2022-07-13T22:40:27.471Z,1657752027.471 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf1 0x7 0xc1 0x8f 0x6f 0x92 0x43 0xac 0x1d 0xfa 0x3b 0x68 2022-07-13T22:40:27.874Z,1657752027.874 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf5 0xa5 0xc1 0x8f 0x53 0xb4 0x43 0xac 0x1f 0xd5 0x1a 0xc5 0x3 2022-07-13T22:40:27.875Z,1657752027.875 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf5 0xa5 0xc1 0x8f 0x53 0xb4 0x43 0xac 0x1f 0xd5 0x1a 0xc5 2022-07-13T22:40:28.278Z,1657752028.278 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xff 0x6b 0xc1 0x8f 0x40 0x4a 0x43 0xac 0x20 0xcb 0xdf 0xd6 0x3 2022-07-13T22:40:28.279Z,1657752028.279 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xff 0x6b 0xc1 0x8f 0x40 0x4a 0x43 0xac 0x20 0xcb 0xdf 0xd6 2022-07-13T22:40:28.682Z,1657752028.682 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf9 0x91 0xc1 0x8f 0x3c 0x94 0x43 0xac 0x22 0x1f 0xc 0x36 0x3 2022-07-13T22:40:28.683Z,1657752028.683 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf9 0x91 0xc1 0x8f 0x3c 0x94 0x43 0xac 0x22 0x1f 0xc 0x36 2022-07-13T22:40:29.085Z,1657752029.085 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xd6 0x39 0xc1 0x8f 0x34 0x10 0x96 0x43 0xac 0x22 0xd8 0x90 0x81 0x3 2022-07-13T22:40:29.087Z,1657752029.087 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xd6 0x39 0xc1 0x8f 0x34 0x16 0x43 0xac 0x22 0xd8 0x90 0x81 2022-07-13T22:40:29.489Z,1657752029.489 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xd5 0x96 0xc1 0x8f 0x2d 0x2d 0x43 0xac 0x23 0x51 0x20 0xb3 0x3 2022-07-13T22:40:29.490Z,1657752029.490 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xd5 0x96 0xc1 0x8f 0x2d 0x2d 0x43 0xac 0x23 0x51 0x20 0xb3 2022-07-13T22:40:29.895Z,1657752029.895 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xeb 0xa1 0xc1 0x8f 0x3e 0x6a 0x43 0xac 0x24 0x18 0x13 0xd7 0x3 2022-07-13T22:40:29.896Z,1657752029.896 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xeb 0xa1 0xc1 0x8f 0x3e 0x6a 0x43 0xac 0x24 0x18 0x13 0xd7 2022-07-13T22:40:30.297Z,1657752030.297 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x10 0x96 0xed 0xc1 0x8f 0x3e 0x87 0x43 0xac 0x24 0x93 0xa8 0x4c 0x3 2022-07-13T22:40:30.298Z,1657752030.298 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x16 0xed 0xc1 0x8f 0x3e 0x87 0x43 0xac 0x24 0x93 0xa8 0x4c 2022-07-13T22:40:30.722Z,1657752030.722 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x10 0x81 0xc4 0xc1 0x8f 0x39 0xf2 0x43 0xac 0x25 0x36 0x65 0xee 0x3 2022-07-13T22:40:30.724Z,1657752030.724 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x1 0xc4 0xc1 0x8f 0x39 0xf2 0x43 0xac 0x25 0x36 0x65 0xee 2022-07-13T22:40:31.108Z,1657752031.108 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf3 0xbf 0xc1 0x8f 0x37 0xff 0x43 0xac 0x25 0x79 0xef 0xa 0x3 2022-07-13T22:40:31.109Z,1657752031.109 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf3 0xbf 0xc1 0x8f 0x37 0xff 0x43 0xac 0x25 0x79 0xef 0xa 2022-07-13T22:40:31.510Z,1657752031.510 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xe7 0x1c 0xc1 0x8f 0x28 0x21 0x43 0xac 0x25 0x3d 0xcc 0x2e 0x3 2022-07-13T22:40:31.511Z,1657752031.511 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xe7 0x1c 0xc1 0x8f 0x28 0x21 0x43 0xac 0x25 0x3d 0xcc 0x2e 2022-07-13T22:40:31.913Z,1657752031.913 [AHRS_M2](INFO): Tx (20): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xf5 0x90 0x24 0xde 0x3 2022-07-13T22:40:31.915Z,1657752031.915 [AHRS_M2](INFO): Tx post-dle (16): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xf5 0x90 0x24 0xde 2022-07-13T22:40:32.318Z,1657752032.318 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x10 0x81 0xe0 0xc1 0x8f 0x2d 0x7d 0x43 0xac 0x25 0xf0 0xbc 0x7b 0x3 2022-07-13T22:40:32.320Z,1657752032.320 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x1 0xe0 0xc1 0x8f 0x2d 0x7d 0x43 0xac 0x25 0xf0 0xbc 0x7b 2022-07-13T22:40:32.723Z,1657752032.723 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x7 0x63 0xc1 0x8f 0x2e 0x12 0x43 0xac 0x26 0x10 0x95 0x17 0x2f 0x3 2022-07-13T22:40:32.725Z,1657752032.725 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x7 0x63 0xc1 0x8f 0x2e 0x12 0x43 0xac 0x26 0x15 0x17 0x2f 2022-07-13T22:40:33.125Z,1657752033.125 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x10 0x81 0xca 0xc1 0x8f 0x27 0x86 0x43 0xac 0x26 0x8 0x9a 0xf0 0x3 2022-07-13T22:40:33.127Z,1657752033.127 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x1 0xca 0xc1 0x8f 0x27 0x86 0x43 0xac 0x26 0x8 0x9a 0xf0 2022-07-13T22:40:33.530Z,1657752033.530 [AHRS_M2](INFO): Tx (29): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4e 0xee 0xd7 0xc1 0x8f 0x2b 0x10 0x96 0x43 0xac 0x26 0xf 0xeb 0x68 0x3 2022-07-13T22:40:33.531Z,1657752033.531 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4e 0xee 0xd7 0xc1 0x8f 0x2b 0x16 0x43 0xac 0x26 0xf 0xeb 0x68 2022-07-13T22:40:33.934Z,1657752033.934 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x0 0x1f 0xc1 0x8f 0x35 0xe0 0x43 0xac 0x26 0x57 0xdf 0x6b 0x3 2022-07-13T22:40:33.935Z,1657752033.935 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x0 0x1f 0xc1 0x8f 0x35 0xe0 0x43 0xac 0x26 0x57 0xdf 0x6b 2022-07-13T22:40:34.338Z,1657752034.338 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x13 0x3d 0xc1 0x8f 0x3a 0x8c 0x43 0xac 0x26 0x62 0xaa 0xab 0x3 2022-07-13T22:40:34.339Z,1657752034.339 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x13 0x3d 0xc1 0x8f 0x3a 0x8c 0x43 0xac 0x26 0x62 0xaa 0xab 2022-07-13T22:40:34.548Z,1657752034.548 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:40:34.744Z,1657752034.744 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x11 0x30 0xc1 0x8f 0x31 0x22 0x43 0xac 0x26 0xa0 0xf5 0x72 0x3 2022-07-13T22:40:34.753Z,1657752034.753 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x11 0x30 0xc1 0x8f 0x31 0x22 0x43 0xac 0x26 0xa0 0xf5 0x72 2022-07-13T22:40:35.145Z,1657752035.145 [AHRS_M2](INFO): Tx (28): 0x1 0x17 0x60 0x5 0x9 0x10 0x81 0x82 0x4b 0x80 0xd 0x10 0x83 0xc1 0x4f 0x22 0xae 0xc1 0x8f 0x27 0x68 0x43 0xac 0x26 0xa9 0x27 0x97 0x3 2022-07-13T22:40:35.147Z,1657752035.147 [AHRS_M2](INFO): Tx post-dle (24): 0x17 0x60 0x5 0x9 0x1 0x82 0x4b 0x80 0xd 0x3 0xc1 0x4f 0x22 0xae 0xc1 0x8f 0x27 0x68 0x43 0xac 0x26 0xa9 0x27 0x97 2022-07-13T22:40:35.164Z,1657752035.164 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-07-13T22:40:35.164Z,1657752035.164 [AHRS_M2] Data Fault, FailCount= 9 2022-07-13T22:40:35.164Z,1657752035.164 [AHRS_M2](ERROR): Data Fault 2022-07-13T22:40:35.274Z,1657752035.274 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-07-13T22:40:35.280Z,1657752035.280 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2022-07-13T22:40:35.542Z,1657752035.542 [AHRS_M2](INFO): Powering down 2022-07-13T22:40:35.713Z,1657752035.713 [CBIT](INFO): Critical error at 20220713T224035 2022-07-13T22:40:35.958Z,1657752035.958 [DVL_micro](ERROR): only read 3 of 4 data items 2022-07-13T22:40:35.959Z,1657752035.959 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+99999.99 2022-07-13T22:40:48.604Z,1657752048.604 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2022-07-13T22:40:48.640Z,1657752048.640 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-07-13T22:41:19.190Z,1657752079.190 [NAL9602](INFO): Not Powering down - fast GPS 2022-07-13T22:41:36.564Z,1657752096.564 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-07-13T22:41:36.564Z,1657752096.564 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:41:36.564Z,1657752096.564 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:41:36.565Z,1657752096.565 [Default:CheckIn:D] Running Loop=1 2022-07-13T22:41:36.973Z,1657752096.973 [Default:CheckIn:D] Stopped 2022-07-13T22:41:36.973Z,1657752096.973 [Default:CheckIn:E] Running Loop=1 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.089209 min 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn:E] Stopped 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn] Stopped 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn](INFO): Running loop #22 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn] Running Loop=22 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-07-13T22:41:37.375Z,1657752097.375 [Default:CheckIn:Read_GPS] Running Loop=1 2022-07-13T22:41:39.380Z,1657752099.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224138.00,A,3648.15135,N,12147.28895,W,0.914,0.00,130722,,,A*7E 2022-07-13T22:41:39.382Z,1657752099.382 [NAL9602](INFO): GPS fix at 20220713T224138: (36.802523, -121.788149) 2022-07-13T22:41:39.422Z,1657752099.422 [Default:CheckIn:Read_GPS] Stopped 2022-07-13T22:41:39.423Z,1657752099.423 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-07-13T22:41:43.465Z,1657752103.465 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20220713T212656/Courier0042.lzma 2022-07-13T22:41:43.766Z,1657752103.766 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Courier0042.lzma.bak 2022-07-13T22:41:43.766Z,1657752103.766 [DataOverHttps](INFO): SBD MOMSN=16984709 2022-07-13T22:42:00.917Z,1657752120.917 [DataOverHttps](INFO): Sending 523 bytes from file Logs/20220713T212656/Express0043.lzma 2022-07-13T22:42:01.918Z,1657752121.918 [DataOverHttps](INFO): Moved sent file to Logs/20220713T212656/Express0043.lzma.bak 2022-07-13T22:42:01.918Z,1657752121.918 [DataOverHttps](INFO): SBD MOMSN=16984718 2022-07-13T22:42:04.556Z,1657752124.556 [Default:CheckIn:Read_Iridium] Stopped 2022-07-13T22:42:04.556Z,1657752124.556 [Default:CheckIn:C.Wait] Running Loop=1 2022-07-13T22:42:04.556Z,1657752124.556 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-07-13T22:42:10.233Z,1657752130.233 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2022-07-13T22:42:10.316Z,1657752130.316 [NAL9602](ERROR): received: +CSQ:0 OK37, 0, 0, 0, 0 OK 2022-07-13T22:42:34.873Z,1657752154.873 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2022-07-13T22:42:34.873Z,1657752154.873 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-07-13T22:42:34.873Z,1657752154.873 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-07-13T22:42:34.886Z,1657752154.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-07-13T22:42:35.293Z,1657752155.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-07-13T22:42:35.293Z,1657752155.293 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0 2022-07-13T22:43:11.225Z,1657752191.225 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 189 2022-07-13T22:43:11.227Z,1657752191.227 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2022-07-13T22:43:50.729Z,1657752230.729 [CommandExec](IMPORTANT): got command failComponent 2022-07-13T22:43:50.729Z,1657752230.729 [CommandExec](IMPORTANT): Failed components: 2022-07-13T22:43:50.730Z,1657752230.730 [CommandExec](IMPORTANT): AHRS_M2: Data Fault 2022-07-13T22:44:19.381Z,1657752259.381 [CommandExec](IMPORTANT): got command restart application 2022-07-13T22:44:20.388Z,1657752260.388 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:20.388Z,1657752260.388 [CommandExec](INFO): Uninitializing the command executive. 2022-07-13T22:44:20.389Z,1657752260.389 [CommandExec](INFO): Uninitializing the command scheduler. 2022-07-13T22:44:20.389Z,1657752260.389 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.477Z,1657752260.477 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-07-13T22:44:20.477Z,1657752260.477 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-07-13T22:44:20.477Z,1657752260.477 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.478Z,1657752260.478 [NavChartDb](INFO): Join timeout helper Thread ID is 7200 2022-07-13T22:44:20.488Z,1657752260.488 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:20.489Z,1657752260.489 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.496Z,1657752260.496 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-07-13T22:44:20.496Z,1657752260.496 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.497Z,1657752260.497 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7201 2022-07-13T22:44:20.825Z,1657752260.825 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:20.825Z,1657752260.825 [WetLabsBB2FL](INFO): Powering down 2022-07-13T22:44:20.826Z,1657752260.826 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.845Z,1657752260.845 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-07-13T22:44:20.845Z,1657752260.845 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:20.845Z,1657752260.845 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7202 2022-07-13T22:44:21.457Z,1657752261.457 [CTD_Seabird](INFO): Powering down 2022-07-13T22:44:21.468Z,1657752261.468 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:21.468Z,1657752261.468 [CTD_Seabird](INFO): Powering down 2022-07-13T22:44:21.480Z,1657752261.480 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.484Z,1657752261.484 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-07-13T22:44:21.484Z,1657752261.484 [DAT ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.485Z,1657752261.485 [DAT](INFO): Join timeout helper Thread ID is 7203 2022-07-13T22:44:21.485Z,1657752261.485 [DAT](INFO): Powering down 2022-07-13T22:44:21.557Z,1657752261.557 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:21.557Z,1657752261.557 [DAT](INFO): Powering down 2022-07-13T22:44:21.558Z,1657752261.558 [DAT ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.565Z,1657752261.565 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-07-13T22:44:21.565Z,1657752261.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.565Z,1657752261.565 [Radio_Surface](INFO): Join timeout helper Thread ID is 7204 2022-07-13T22:44:21.744Z,1657752261.744 [Radio_Surface](INFO): Powering down 2022-07-13T22:44:21.745Z,1657752261.745 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:21.746Z,1657752261.746 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.749Z,1657752261.749 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-07-13T22:44:21.749Z,1657752261.749 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:21.750Z,1657752261.750 [Onboard](INFO): Join timeout helper Thread ID is 7205 2022-07-13T22:44:22.361Z,1657752262.361 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-07-13T22:44:26.060Z,1657752266.060 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.060Z,1657752266.060 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.077Z,1657752266.077 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-07-13T22:44:26.077Z,1657752266.077 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.077Z,1657752266.077 [DataOverHttps](INFO): Join timeout helper Thread ID is 7206 2022-07-13T22:44:26.376Z,1657752266.376 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.377Z,1657752266.377 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.397Z,1657752266.397 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-07-13T22:44:26.397Z,1657752266.397 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.397Z,1657752266.397 [BackseatComponent](INFO): Join timeout helper Thread ID is 7207 2022-07-13T22:44:26.532Z,1657752266.532 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.533Z,1657752266.533 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.545Z,1657752266.545 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-07-13T22:44:26.545Z,1657752266.545 [logger ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.545Z,1657752266.545 [logger](INFO): Join timeout helper Thread ID is 7208 2022-07-13T22:44:26.568Z,1657752266.568 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.569Z,1657752266.569 [logger ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.585Z,1657752266.585 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-07-13T22:44:26.585Z,1657752266.585 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.585Z,1657752266.585 [CommandLine](INFO): Join timeout helper Thread ID is 7209 2022-07-13T22:44:26.640Z,1657752266.640 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.641Z,1657752266.641 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.645Z,1657752266.645 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-07-13T22:44:26.645Z,1657752266.645 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.645Z,1657752266.645 [CommandExec](INFO): Join timeout helper Thread ID is 7210 2022-07-13T22:44:26.647Z,1657752266.647 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-07-13T22:44:26.647Z,1657752266.647 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.647Z,1657752266.647 [controlThread](INFO): Join timeout helper Thread ID is 7211 2022-07-13T22:44:26.776Z,1657752266.776 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-13T22:44:26.777Z,1657752266.777 [controlThread](DEBUG): Uninitializing ControlThread 2022-07-13T22:44:26.777Z,1657752266.777 [AHRS_M2](INFO): Powering down 2022-07-13T22:44:26.848Z,1657752266.848 [DVL_micro](INFO): Powering down 2022-07-13T22:44:26.849Z,1657752266.849 [NAL9602](INFO): Powering down 2022-07-13T22:44:26.852Z,1657752266.852 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-07-13T22:44:26.853Z,1657752266.853 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-07-13T22:44:26.854Z,1657752266.854 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-07-13T22:44:26.854Z,1657752266.854 [MissionManager](INFO): Uninitializing Mission Default 2022-07-13T22:44:26.854Z,1657752266.854 [Default] Stopped 2022-07-13T22:44:26.854Z,1657752266.854 [Default](DEBUG): Aggregate::uninitialize Default 2022-07-13T22:44:26.855Z,1657752266.855 [Default:B.GoToSurface] Stopped 2022-07-13T22:44:26.855Z,1657752266.855 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-07-13T22:44:26.855Z,1657752266.855 [Default:CheckIn] Stopped 2022-07-13T22:44:26.855Z,1657752266.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-07-13T22:44:26.855Z,1657752266.855 [Default:CheckIn:C.Wait] Stopped 2022-07-13T22:44:26.855Z,1657752266.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-07-13T22:44:26.858Z,1657752266.858 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-07-13T22:44:26.858Z,1657752266.858 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-07-13T22:44:26.858Z,1657752266.858 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-07-13T22:44:26.859Z,1657752266.859 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-07-13T22:44:26.859Z,1657752266.859 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-07-13T22:44:26.859Z,1657752266.859 [BuoyancyServo](INFO): Powering down 2022-07-13T22:44:26.872Z,1657752266.872 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-07-13T22:44:26.873Z,1657752266.873 [ElevatorServo](INFO): Powering down 2022-07-13T22:44:26.873Z,1657752266.873 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-07-13T22:44:26.874Z,1657752266.874 [MassServo](INFO): Powering down 2022-07-13T22:44:26.874Z,1657752266.874 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-07-13T22:44:26.874Z,1657752266.874 [RudderServo](INFO): Powering down 2022-07-13T22:44:26.875Z,1657752266.875 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-07-13T22:44:26.875Z,1657752266.875 [ThrusterHE](INFO): Powering down 2022-07-13T22:44:26.876Z,1657752266.876 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-07-13T22:44:26.877Z,1657752266.877 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-07-13T22:44:26.877Z,1657752266.877 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-07-13T22:44:26.877Z,1657752266.877 [CBIT](DEBUG): Powering off loads. 2022-07-13T22:44:26.888Z,1657752266.888 [CBIT](DEBUG): Disabling WDT. 2022-07-13T22:44:26.900Z,1657752266.900 [CBIT](DEBUG): Opening all GF detection circuits. 2022-07-13T22:44:26.901Z,1657752266.901 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.941Z,1657752266.941 [DAT ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.946Z,1657752266.946 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.948Z,1657752266.948 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.955Z,1657752266.955 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:26.998Z,1657752266.998 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:27.053Z,1657752267.053 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:27.057Z,1657752267.057 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:27.079Z,1657752267.079 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-13T22:44:27.150Z,1657752267.150 [logger ThreadHandler](INFO): Thread cancelled.