2024-08-14T16:37:18.838Z,1723653438.838 [CommandExec](IMPORTANT): got command restart logs 2024-08-14T16:40:33.427Z,1723653633.427 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T16:40:33.427Z,1723653633.427 [Default:CheckIn:C.Wait] Stopped 2024-08-14T16:40:33.427Z,1723653633.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T16:40:33.427Z,1723653633.427 [Default:CheckIn:D] Running Loop=1 2024-08-14T16:40:33.831Z,1723653633.831 [Default:CheckIn:D] Stopped 2024-08-14T16:40:33.831Z,1723653633.831 [Default:CheckIn:E] Running Loop=1 2024-08-14T16:40:34.227Z,1723653634.227 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.218250 min 2024-08-14T16:40:34.227Z,1723653634.227 [Default:CheckIn:E] Stopped 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn] Stopped 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn](INFO): Running loop #3 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn] Running Loop=3 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T16:40:34.228Z,1723653634.228 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T16:40:36.239Z,1723653636.239 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164035.00,A,3648.11606,N,12150.37383,W,20.274,53.51,140824,,,D*74 2024-08-14T16:40:36.241Z,1723653636.241 [NAL9602](INFO): GPS fix at 20240814T164035: (36.801934, -121.839564) 2024-08-14T16:40:36.252Z,1723653636.252 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T16:40:36.252Z,1723653636.252 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T16:41:08.629Z,1723653668.629 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:41:39.753Z,1723653699.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:41:50.189Z,1723653710.189 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240812T183435/Courier0198.lzma 2024-08-14T16:41:53.198Z,1723653713.198 [DataOverHttps](INFO): Moved sent file to Logs/20240812T183435/Courier0198.lzma.bak 2024-08-14T16:41:53.199Z,1723653713.199 [DataOverHttps](INFO): SBD MOMSN=21500091 2024-08-14T16:42:09.121Z,1723653729.121 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240814T163718/Courier0000.lzma 2024-08-14T16:42:10.122Z,1723653730.122 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0000.lzma.bak 2024-08-14T16:42:10.122Z,1723653730.122 [DataOverHttps](INFO): SBD MOMSN=21500093 2024-08-14T16:42:26.579Z,1723653746.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=54989, MT Status=2, MTMSN=0 2024-08-14T16:42:26.579Z,1723653746.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T16:42:28.664Z,1723653748.664 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20240812T183435/Express0199.lzma 2024-08-14T16:42:29.667Z,1723653749.667 [DataOverHttps](INFO): Moved sent file to Logs/20240812T183435/Express0199.lzma.bak 2024-08-14T16:42:29.667Z,1723653749.667 [DataOverHttps](INFO): SBD MOMSN=21500097 2024-08-14T16:42:45.473Z,1723653765.473 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20240814T163718/Express0001.lzma 2024-08-14T16:42:49.486Z,1723653769.486 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0001.lzma.bak 2024-08-14T16:42:49.486Z,1723653769.486 [DataOverHttps](INFO): SBD MOMSN=21500100 2024-08-14T16:43:00.975Z,1723653780.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=54989, MT Status=2, MTMSN=0 2024-08-14T16:43:00.976Z,1723653780.976 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T16:43:08.080Z,1723653788.080 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240814T163718/Express0004.lzma 2024-08-14T16:43:09.082Z,1723653789.082 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0004.lzma.bak 2024-08-14T16:43:09.082Z,1723653789.082 [DataOverHttps](INFO): SBD MOMSN=21500104 2024-08-14T16:43:10.268Z,1723653790.268 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T16:43:10.284Z,1723653790.284 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T16:43:10.284Z,1723653790.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T16:43:45.798Z,1723653825.798 [NAL9602](INFO): SBD MO Status=0, MOMSN=54989, MT Status=0, MTMSN=0 2024-08-14T16:43:45.798Z,1723653825.798 [NAL9602](INFO): No messages in MT queue 2024-08-14T16:44:10.626Z,1723653850.626 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:44:16.505Z,1723653856.505 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T16:45:13.705Z,1723653913.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:45:44.845Z,1723653944.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:48:11.013Z,1723654091.013 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T16:48:11.013Z,1723654091.013 [Default:CheckIn:C.Wait] Stopped 2024-08-14T16:48:11.013Z,1723654091.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T16:48:11.013Z,1723654091.013 [Default:CheckIn:D] Running Loop=1 2024-08-14T16:48:11.423Z,1723654091.423 [Default:CheckIn:D] Stopped 2024-08-14T16:48:11.423Z,1723654091.423 [Default:CheckIn:E] Running Loop=1 2024-08-14T16:48:11.830Z,1723654091.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.844786 min 2024-08-14T16:48:11.830Z,1723654091.830 [Default:CheckIn:E] Stopped 2024-08-14T16:48:11.830Z,1723654091.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T16:48:11.830Z,1723654091.830 [Default:CheckIn] Stopped 2024-08-14T16:48:11.831Z,1723654091.831 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T16:48:11.831Z,1723654091.831 [Default:CheckIn](INFO): Running loop #4 2024-08-14T16:48:11.831Z,1723654091.831 [Default:CheckIn] Running Loop=4 2024-08-14T16:48:11.831Z,1723654091.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T16:48:11.831Z,1723654091.831 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T16:48:13.831Z,1723654093.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164812.00,A,3648.49637,N,12149.46090,W,0.797,295.72,140824,,,D*7A 2024-08-14T16:48:13.834Z,1723654093.834 [NAL9602](INFO): GPS fix at 20240814T164812: (36.808273, -121.824348) 2024-08-14T16:48:13.865Z,1723654093.865 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T16:48:13.865Z,1723654093.865 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T16:48:24.020Z,1723654104.020 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240814T163718/Courier0006.lzma 2024-08-14T16:48:25.022Z,1723654105.022 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0006.lzma.bak 2024-08-14T16:48:25.022Z,1723654105.022 [DataOverHttps](INFO): SBD MOMSN=21500107 2024-08-14T16:48:38.527Z,1723654118.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=54990, MT Status=2, MTMSN=0 2024-08-14T16:48:38.527Z,1723654118.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T16:48:49.048Z,1723654129.048 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240814T163718/Express0007.lzma 2024-08-14T16:48:50.050Z,1723654130.050 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0007.lzma.bak 2024-08-14T16:48:50.050Z,1723654130.050 [DataOverHttps](INFO): SBD MOMSN=21500110 2024-08-14T16:48:51.487Z,1723654131.487 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T16:48:51.487Z,1723654131.487 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T16:48:51.487Z,1723654131.487 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T16:49:04.411Z,1723654144.411 [NAL9602](INFO): SBD MO Status=2, MOMSN=54990, MT Status=2, MTMSN=0 2024-08-14T16:49:04.411Z,1723654144.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T16:49:21.387Z,1723654161.387 [NAL9602](INFO): SBD MO Status=2, MOMSN=54990, MT Status=2, MTMSN=0 2024-08-14T16:49:21.387Z,1723654161.387 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T16:49:31.479Z,1723654171.479 [NAL9602](INFO): SBD MO Status=0, MOMSN=54990, MT Status=0, MTMSN=0 2024-08-14T16:49:31.479Z,1723654171.479 [NAL9602](INFO): No messages in MT queue 2024-08-14T16:49:51.509Z,1723654191.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:50:02.185Z,1723654202.185 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T16:50:22.641Z,1723654222.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:50:53.765Z,1723654253.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:51:24.889Z,1723654284.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:53:52.212Z,1723654432.212 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T16:53:52.212Z,1723654432.212 [Default:CheckIn:C.Wait] Stopped 2024-08-14T16:53:52.212Z,1723654432.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T16:53:52.213Z,1723654432.213 [Default:CheckIn:D] Running Loop=1 2024-08-14T16:53:52.624Z,1723654432.624 [Default:CheckIn:D] Stopped 2024-08-14T16:53:52.624Z,1723654432.624 [Default:CheckIn:E] Running Loop=1 2024-08-14T16:53:53.030Z,1723654433.030 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.531464 min 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn:E] Stopped 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn] Stopped 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn](INFO): Running loop #5 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn] Running Loop=5 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T16:53:53.031Z,1723654433.031 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T16:53:55.035Z,1723654435.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165354.00,A,3648.58611,N,12149.47057,W,3.790,20.82,140824,,,D*4B 2024-08-14T16:53:55.038Z,1723654435.038 [NAL9602](INFO): GPS fix at 20240814T165354: (36.809768, -121.824510) 2024-08-14T16:53:55.068Z,1723654435.068 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T16:53:55.069Z,1723654435.069 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T16:54:02.436Z,1723654442.436 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0009.lzma 2024-08-14T16:54:04.443Z,1723654444.443 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0009.lzma.bak 2024-08-14T16:54:04.443Z,1723654444.443 [DataOverHttps](INFO): SBD MOMSN=21500316 2024-08-14T16:54:37.451Z,1723654477.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=54991, MT Status=0, MTMSN=0 2024-08-14T16:54:37.451Z,1723654477.451 [NAL9602](INFO): No messages in MT queue 2024-08-14T16:54:56.941Z,1723654496.941 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240814T163718/Express0010.lzma 2024-08-14T16:54:57.942Z,1723654497.942 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0010.lzma.bak 2024-08-14T16:54:57.943Z,1723654497.943 [DataOverHttps](INFO): SBD MOMSN=21500319 2024-08-14T16:54:59.341Z,1723654499.341 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T16:54:59.341Z,1723654499.341 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T16:54:59.341Z,1723654499.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T16:55:08.206Z,1723654508.206 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T16:56:32.349Z,1723654592.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:57:03.485Z,1723654623.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:57:32.019Z,1723654652.019 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:57:34.609Z,1723654654.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:57:43.737Z,1723654663.737 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:57:55.855Z,1723654675.855 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:57:58.721Z,1723654678.721 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.779068 2024-08-14T16:58:07.572Z,1723654687.572 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:58:18.886Z,1723654698.886 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:58:30.600Z,1723654710.600 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:58:35.869Z,1723654715.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:58:42.719Z,1723654722.719 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:58:55.257Z,1723654735.257 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T16:59:06.993Z,1723654746.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:59:38.121Z,1723654778.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T16:59:59.908Z,1723654799.908 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T16:59:59.908Z,1723654799.908 [Default:CheckIn:C.Wait] Stopped 2024-08-14T16:59:59.908Z,1723654799.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T16:59:59.908Z,1723654799.908 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:00:00.303Z,1723654800.303 [Default:CheckIn:D] Stopped 2024-08-14T17:00:00.303Z,1723654800.303 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.659456 min 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn:E] Stopped 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn] Stopped 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn](INFO): Running loop #6 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn] Running Loop=6 2024-08-14T17:00:00.708Z,1723654800.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:00:00.709Z,1723654800.709 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:00:02.724Z,1723654802.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170002.00,A,3648.63304,N,12148.86864,W,4.724,81.55,140824,,,D*4B 2024-08-14T17:00:02.726Z,1723654802.726 [NAL9602](INFO): GPS fix at 20240814T170002: (36.810551, -121.814477) 2024-08-14T17:00:02.736Z,1723654802.736 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:00:02.736Z,1723654802.736 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:00:09.245Z,1723654809.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T17:00:40.369Z,1723654840.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T17:00:45.996Z,1723654845.996 [NAL9602](INFO): SBD MO Status=2, MOMSN=54992, MT Status=2, MTMSN=0 2024-08-14T17:00:45.996Z,1723654845.996 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:01:09.467Z,1723654869.467 [NAL9602](INFO): SBD MO Status=2, MOMSN=54992, MT Status=2, MTMSN=0 2024-08-14T17:01:09.467Z,1723654869.467 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:01:11.517Z,1723654871.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T17:01:32.540Z,1723654892.540 [NAL9602](INFO): SBD MO Status=2, MOMSN=54992, MT Status=2, MTMSN=0 2024-08-14T17:01:32.541Z,1723654892.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:01:34.153Z,1723654894.153 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:01:37.789Z,1723654897.789 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-08-14T17:01:42.641Z,1723654902.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T17:01:47.483Z,1723654907.483 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:02:03.297Z,1723654923.297 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:02:07.747Z,1723654927.747 [NAL9602](INFO): SBD MO Status=1, MOMSN=54992, MT Status=0, MTMSN=0 2024-08-14T17:02:07.809Z,1723654927.809 [NAL9602](INFO): Sent 73 bytes from file Logs/20240814T163718/Courier0012.lzma 2024-08-14T17:02:07.809Z,1723654927.809 [NAL9602](INFO): Packets left to send: 0 2024-08-14T17:02:13.773Z,1723654933.773 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T17:02:17.079Z,1723654937.079 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:02:19.123Z,1723654939.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=54993, MT Status=0, MTMSN=0 2024-08-14T17:02:19.173Z,1723654939.173 [NAL9602](INFO): Sent 146 bytes from file Logs/20240814T163718/Express0013.lzma 2024-08-14T17:02:19.173Z,1723654939.173 [NAL9602](INFO): Packets left to send: 0 2024-08-14T17:02:30.412Z,1723654950.412 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:02:31.166Z,1723654951.166 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003285 2024-08-14T17:02:32.482Z,1723654952.482 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:02:32.482Z,1723654952.482 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:02:32.482Z,1723654952.482 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:02:36.491Z,1723654956.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=54994, MT Status=2, MTMSN=0 2024-08-14T17:02:36.491Z,1723654956.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:02:43.744Z,1723654963.744 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:02:57.486Z,1723654977.486 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:03:07.185Z,1723654987.185 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:03:11.626Z,1723654991.626 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:03:25.363Z,1723655005.363 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:03:37.963Z,1723655017.963 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-14T17:03:38.701Z,1723655018.701 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T17:03:44.350Z,1723655024.350 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5. 2024-08-14T17:03:44.365Z,1723655024.365 [BPC1](INFO): Received data from all battery sticks. 2024-08-14T17:07:33.097Z,1723655253.097 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:07:33.097Z,1723655253.097 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:07:33.097Z,1723655253.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:07:33.098Z,1723655253.098 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:07:33.495Z,1723655253.495 [Default:CheckIn:D] Stopped 2024-08-14T17:07:33.495Z,1723655253.495 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:07:33.899Z,1723655253.899 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.212655 min 2024-08-14T17:07:33.899Z,1723655253.899 [Default:CheckIn:E] Stopped 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn] Stopped 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn](INFO): Running loop #7 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn] Running Loop=7 2024-08-14T17:07:33.900Z,1723655253.900 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:07:33.901Z,1723655253.901 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:07:35.907Z,1723655255.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170735.00,A,3648.46267,N,12148.12087,W,4.510,107.79,140824,,,D*77 2024-08-14T17:07:35.909Z,1723655255.909 [NAL9602](INFO): GPS fix at 20240814T170735: (36.807711, -121.802014) 2024-08-14T17:07:35.920Z,1723655255.920 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:07:35.921Z,1723655255.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:07:44.021Z,1723655264.021 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240814T163718/Courier0015.lzma 2024-08-14T17:07:45.022Z,1723655265.022 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0015.lzma.bak 2024-08-14T17:07:45.022Z,1723655265.022 [DataOverHttps](INFO): SBD MOMSN=21500371 2024-08-14T17:08:00.912Z,1723655280.912 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240814T163718/Express0016.lzma 2024-08-14T17:08:01.914Z,1723655281.914 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0016.lzma.bak 2024-08-14T17:08:01.914Z,1723655281.914 [DataOverHttps](INFO): SBD MOMSN=21500374 2024-08-14T17:08:03.393Z,1723655283.393 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:08:03.393Z,1723655283.393 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:08:03.393Z,1723655283.393 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:08:08.231Z,1723655288.231 [NAL9602](INFO): SBD MO Status=2, MOMSN=54994, MT Status=2, MTMSN=0 2024-08-14T17:08:08.231Z,1723655288.231 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:09:04.391Z,1723655344.391 [NAL9602](INFO): SBD MO Status=2, MOMSN=54994, MT Status=2, MTMSN=0 2024-08-14T17:09:04.391Z,1723655344.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:10:11.047Z,1723655411.047 [NAL9602](INFO): SBD MO Status=2, MOMSN=54994, MT Status=2, MTMSN=0 2024-08-14T17:10:11.047Z,1723655411.047 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:10:32.054Z,1723655432.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=54994, MT Status=0, MTMSN=0 2024-08-14T17:10:32.054Z,1723655432.054 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:11:02.763Z,1723655462.763 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:13:03.969Z,1723655583.969 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:13:03.969Z,1723655583.969 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:13:03.969Z,1723655583.969 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:13:03.969Z,1723655583.969 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:13:04.380Z,1723655584.380 [Default:CheckIn:D] Stopped 2024-08-14T17:13:04.380Z,1723655584.380 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:13:04.796Z,1723655584.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.727405 min 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn:E] Stopped 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn] Stopped 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn](INFO): Running loop #8 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn] Running Loop=8 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:13:04.797Z,1723655584.797 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:13:06.791Z,1723655586.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171306.00,A,3648.32620,N,12147.70398,W,4.898,111.08,140824,,,D*7C 2024-08-14T17:13:06.794Z,1723655586.794 [NAL9602](INFO): GPS fix at 20240814T171306: (36.805437, -121.795066) 2024-08-14T17:13:06.804Z,1723655586.804 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:13:06.804Z,1723655586.804 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:13:14.232Z,1723655594.232 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0018.lzma 2024-08-14T17:13:15.234Z,1723655595.234 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0018.lzma.bak 2024-08-14T17:13:15.234Z,1723655595.234 [DataOverHttps](INFO): SBD MOMSN=21500378 2024-08-14T17:13:25.370Z,1723655605.370 [NAL9602](INFO): SBD MO Status=0, MOMSN=54995, MT Status=0, MTMSN=0 2024-08-14T17:13:25.370Z,1723655605.370 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:13:31.045Z,1723655611.045 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240814T163718/Express0019.lzma 2024-08-14T17:13:32.047Z,1723655612.047 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0019.lzma.bak 2024-08-14T17:13:32.047Z,1723655612.047 [DataOverHttps](INFO): SBD MOMSN=21500381 2024-08-14T17:13:33.467Z,1723655613.467 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:13:33.467Z,1723655613.467 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:13:33.467Z,1723655613.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:13:56.083Z,1723655636.083 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:18:34.079Z,1723655914.079 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:18:34.079Z,1723655914.079 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:18:34.079Z,1723655914.079 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:18:34.079Z,1723655914.079 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:18:34.475Z,1723655914.475 [Default:CheckIn:D] Stopped 2024-08-14T17:18:34.475Z,1723655914.475 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:18:34.879Z,1723655914.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.228992 min 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn:E] Stopped 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn] Stopped 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn](INFO): Running loop #9 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn] Running Loop=9 2024-08-14T17:18:34.880Z,1723655914.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:18:34.881Z,1723655914.881 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:18:36.892Z,1723655916.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171836.00,A,3648.44110,N,12147.31380,W,1.050,68.99,140824,,,D*43 2024-08-14T17:18:36.894Z,1723655916.894 [NAL9602](INFO): GPS fix at 20240814T171836: (36.807352, -121.788563) 2024-08-14T17:18:36.905Z,1723655916.905 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:18:36.905Z,1723655916.905 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:18:44.604Z,1723655924.604 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240814T163718/Courier0021.lzma 2024-08-14T17:18:45.606Z,1723655925.606 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0021.lzma.bak 2024-08-14T17:18:45.606Z,1723655925.606 [DataOverHttps](INFO): SBD MOMSN=21500418 2024-08-14T17:19:04.328Z,1723655944.328 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240814T163718/Express0022.lzma 2024-08-14T17:19:05.330Z,1723655945.330 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0022.lzma.bak 2024-08-14T17:19:05.330Z,1723655945.330 [DataOverHttps](INFO): SBD MOMSN=21500421 2024-08-14T17:19:06.449Z,1723655946.449 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:19:06.449Z,1723655946.449 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:19:06.449Z,1723655946.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:19:16.129Z,1723655956.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=54996, MT Status=0, MTMSN=0 2024-08-14T17:19:16.130Z,1723655956.130 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:19:46.829Z,1723655986.829 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:24:07.015Z,1723656247.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:24:07.015Z,1723656247.015 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:24:07.015Z,1723656247.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:24:07.016Z,1723656247.016 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:24:07.419Z,1723656247.419 [Default:CheckIn:D] Stopped 2024-08-14T17:24:07.419Z,1723656247.419 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.778056 min 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn:E] Stopped 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn] Stopped 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn](INFO): Running loop #10 2024-08-14T17:24:07.841Z,1723656247.841 [Default:CheckIn] Running Loop=10 2024-08-14T17:24:07.842Z,1723656247.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:24:07.842Z,1723656247.842 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:24:09.835Z,1723656249.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172409.00,A,3648.28397,N,12147.13680,W,4.004,187.33,140824,,,D*76 2024-08-14T17:24:09.838Z,1723656249.838 [NAL9602](INFO): GPS fix at 20240814T172409: (36.804733, -121.785613) 2024-08-14T17:24:09.869Z,1723656249.869 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:24:09.869Z,1723656249.869 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:24:16.784Z,1723656256.784 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240814T163718/Courier0024.lzma 2024-08-14T17:24:17.786Z,1723656257.786 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0024.lzma.bak 2024-08-14T17:24:17.786Z,1723656257.786 [DataOverHttps](INFO): SBD MOMSN=21500425 2024-08-14T17:24:36.541Z,1723656276.541 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240814T163718/Express0025.lzma 2024-08-14T17:24:37.542Z,1723656277.542 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0025.lzma.bak 2024-08-14T17:24:37.542Z,1723656277.542 [DataOverHttps](INFO): SBD MOMSN=21500430 2024-08-14T17:24:39.012Z,1723656279.012 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:24:39.013Z,1723656279.013 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:24:39.013Z,1723656279.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:24:57.577Z,1723656297.577 [NAL9602](INFO): SBD MO Status=0, MOMSN=54997, MT Status=0, MTMSN=0 2024-08-14T17:24:57.577Z,1723656297.577 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:25:28.277Z,1723656328.277 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:29:39.587Z,1723656579.587 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:29:39.587Z,1723656579.587 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:29:39.587Z,1723656579.587 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:29:39.587Z,1723656579.587 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:29:39.991Z,1723656579.991 [Default:CheckIn:D] Stopped 2024-08-14T17:29:39.991Z,1723656579.991 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:29:40.396Z,1723656580.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.320923 min 2024-08-14T17:29:40.396Z,1723656580.396 [Default:CheckIn:E] Stopped 2024-08-14T17:29:40.396Z,1723656580.396 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:29:40.396Z,1723656580.396 [Default:CheckIn] Stopped 2024-08-14T17:29:40.396Z,1723656580.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:29:40.397Z,1723656580.397 [Default:CheckIn](INFO): Running loop #11 2024-08-14T17:29:40.397Z,1723656580.397 [Default:CheckIn] Running Loop=11 2024-08-14T17:29:40.397Z,1723656580.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:29:40.397Z,1723656580.397 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:29:42.407Z,1723656582.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172941.00,A,3648.13066,N,12147.20970,W,0.039,265.72,140824,,,D*72 2024-08-14T17:29:42.417Z,1723656582.417 [NAL9602](INFO): GPS fix at 20240814T172941: (36.802178, -121.786828) 2024-08-14T17:29:42.428Z,1723656582.428 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:29:42.428Z,1723656582.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:29:49.297Z,1723656589.297 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0027.lzma 2024-08-14T17:29:50.298Z,1723656590.298 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0027.lzma.bak 2024-08-14T17:29:50.298Z,1723656590.298 [DataOverHttps](INFO): SBD MOMSN=21500484 2024-08-14T17:29:58.167Z,1723656598.167 [NAL9602](INFO): SBD MO Status=0, MOMSN=54998, MT Status=0, MTMSN=0 2024-08-14T17:29:58.167Z,1723656598.167 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:30:06.296Z,1723656606.296 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240814T163718/Express0028.lzma 2024-08-14T17:30:07.298Z,1723656607.298 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0028.lzma.bak 2024-08-14T17:30:07.298Z,1723656607.298 [DataOverHttps](INFO): SBD MOMSN=21500487 2024-08-14T17:30:08.689Z,1723656608.689 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:30:08.690Z,1723656608.690 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:30:08.690Z,1723656608.690 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:30:28.873Z,1723656628.873 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:35:09.287Z,1723656909.287 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:35:09.287Z,1723656909.287 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:35:09.287Z,1723656909.287 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:35:09.287Z,1723656909.287 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:35:09.683Z,1723656909.683 [Default:CheckIn:D] Stopped 2024-08-14T17:35:09.684Z,1723656909.684 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:35:10.087Z,1723656910.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.815796 min 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn:E] Stopped 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn] Stopped 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn](INFO): Running loop #12 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn] Running Loop=12 2024-08-14T17:35:10.088Z,1723656910.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:35:10.089Z,1723656910.089 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:35:12.096Z,1723656912.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173511.00,A,3648.13109,N,12147.20977,W,0.019,265.72,140824,,,D*77 2024-08-14T17:35:12.098Z,1723656912.098 [NAL9602](INFO): GPS fix at 20240814T173511: (36.802185, -121.786829) 2024-08-14T17:35:12.109Z,1723656912.109 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:35:12.109Z,1723656912.109 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:35:19.332Z,1723656919.332 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0030.lzma 2024-08-14T17:35:20.335Z,1723656920.335 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0030.lzma.bak 2024-08-14T17:35:20.335Z,1723656920.335 [DataOverHttps](INFO): SBD MOMSN=21500534 2024-08-14T17:35:36.248Z,1723656936.248 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240814T163718/Express0031.lzma 2024-08-14T17:35:37.284Z,1723656937.284 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0031.lzma.bak 2024-08-14T17:35:37.284Z,1723656937.284 [DataOverHttps](INFO): SBD MOMSN=21500569 2024-08-14T17:35:38.369Z,1723656938.369 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:35:38.369Z,1723656938.369 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:35:38.369Z,1723656938.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:37:15.749Z,1723657035.749 [NAL9602](INFO): SBD MO Status=2, MOMSN=54999, MT Status=2, MTMSN=0 2024-08-14T17:37:15.749Z,1723657035.749 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:38:11.499Z,1723657091.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=54999, MT Status=0, MTMSN=0 2024-08-14T17:38:11.499Z,1723657091.499 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:38:42.199Z,1723657122.199 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:40:38.971Z,1723657238.971 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:40:38.971Z,1723657238.971 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:40:38.971Z,1723657238.971 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:40:38.971Z,1723657238.971 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:40:39.375Z,1723657239.375 [Default:CheckIn:D] Stopped 2024-08-14T17:40:39.375Z,1723657239.375 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:40:39.776Z,1723657239.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.310653 min 2024-08-14T17:40:39.776Z,1723657239.776 [Default:CheckIn:E] Stopped 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn] Stopped 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn](INFO): Running loop #13 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn] Running Loop=13 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:40:39.777Z,1723657239.777 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:40:41.791Z,1723657241.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174041.00,A,3648.13724,N,12147.20532,W,0.019,265.72,140824,,,D*74 2024-08-14T17:40:41.803Z,1723657241.803 [NAL9602](INFO): GPS fix at 20240814T174041: (36.802287, -121.786755) 2024-08-14T17:40:41.813Z,1723657241.813 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:40:41.813Z,1723657241.813 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:40:50.612Z,1723657250.612 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0033.lzma 2024-08-14T17:40:51.614Z,1723657251.614 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0033.lzma.bak 2024-08-14T17:40:51.615Z,1723657251.615 [DataOverHttps](INFO): SBD MOMSN=21500573 2024-08-14T17:41:09.617Z,1723657269.617 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240814T163718/Express0034.lzma 2024-08-14T17:41:10.618Z,1723657270.618 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0034.lzma.bak 2024-08-14T17:41:10.618Z,1723657270.618 [DataOverHttps](INFO): SBD MOMSN=21500576 2024-08-14T17:41:11.737Z,1723657271.737 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:41:11.737Z,1723657271.737 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:41:11.737Z,1723657271.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:41:21.424Z,1723657281.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=55000, MT Status=2, MTMSN=0 2024-08-14T17:41:21.424Z,1723657281.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:42:12.735Z,1723657332.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=55000, MT Status=2, MTMSN=0 2024-08-14T17:42:12.735Z,1723657332.735 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:43:25.094Z,1723657405.094 [NAL9602](INFO): SBD MO Status=2, MOMSN=55000, MT Status=2, MTMSN=0 2024-08-14T17:43:25.095Z,1723657405.095 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:44:04.283Z,1723657444.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=55000, MT Status=2, MTMSN=0 2024-08-14T17:44:04.283Z,1723657444.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:44:49.987Z,1723657489.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=55000, MT Status=0, MTMSN=0 2024-08-14T17:44:49.987Z,1723657489.987 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:45:20.696Z,1723657520.696 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:46:12.419Z,1723657572.419 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:46:12.419Z,1723657572.419 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:46:12.419Z,1723657572.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:46:12.419Z,1723657572.419 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:46:12.828Z,1723657572.828 [Default:CheckIn:D] Stopped 2024-08-14T17:46:12.828Z,1723657572.828 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.868197 min 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn:E] Stopped 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn] Stopped 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn](INFO): Running loop #14 2024-08-14T17:46:13.236Z,1723657573.236 [Default:CheckIn] Running Loop=14 2024-08-14T17:46:13.237Z,1723657573.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:46:13.237Z,1723657573.237 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:46:15.243Z,1723657575.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174614.00,A,3648.13559,N,12147.21020,W,0.078,174.20,140824,,,D*7E 2024-08-14T17:46:15.253Z,1723657575.253 [NAL9602](INFO): GPS fix at 20240814T174614: (36.802260, -121.786837) 2024-08-14T17:46:15.264Z,1723657575.264 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:46:15.264Z,1723657575.264 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:46:25.075Z,1723657585.075 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0036.lzma 2024-08-14T17:46:26.074Z,1723657586.074 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0036.lzma.bak 2024-08-14T17:46:26.075Z,1723657586.075 [DataOverHttps](INFO): SBD MOMSN=21500623 2024-08-14T17:46:42.036Z,1723657602.036 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240814T163718/Express0037.lzma 2024-08-14T17:46:43.038Z,1723657603.038 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0037.lzma.bak 2024-08-14T17:46:43.039Z,1723657603.039 [DataOverHttps](INFO): SBD MOMSN=21500626 2024-08-14T17:46:44.448Z,1723657604.448 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:46:44.448Z,1723657604.448 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:46:44.448Z,1723657604.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:47:32.174Z,1723657652.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=55001, MT Status=2, MTMSN=0 2024-08-14T17:47:32.174Z,1723657652.174 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:48:21.907Z,1723657701.907 [NAL9602](INFO): SBD MO Status=2, MOMSN=55001, MT Status=2, MTMSN=0 2024-08-14T17:48:21.907Z,1723657701.907 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:48:44.548Z,1723657724.548 [NAL9602](INFO): SBD MO Status=2, MOMSN=55001, MT Status=2, MTMSN=0 2024-08-14T17:48:44.548Z,1723657724.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:49:44.379Z,1723657784.379 [NAL9602](INFO): SBD MO Status=2, MOMSN=55001, MT Status=2, MTMSN=0 2024-08-14T17:49:44.379Z,1723657784.379 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:50:34.123Z,1723657834.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=55001, MT Status=2, MTMSN=0 2024-08-14T17:50:34.123Z,1723657834.123 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:50:52.341Z,1723657852.341 [NAL9602](INFO): SBD MO Status=0, MOMSN=55001, MT Status=0, MTMSN=0 2024-08-14T17:50:52.341Z,1723657852.341 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:51:23.058Z,1723657883.058 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:51:44.883Z,1723657904.883 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:51:44.884Z,1723657904.884 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:51:44.884Z,1723657904.884 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:51:44.884Z,1723657904.884 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:51:45.293Z,1723657905.293 [Default:CheckIn:D] Stopped 2024-08-14T17:51:45.293Z,1723657905.293 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:51:45.704Z,1723657905.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.409285 min 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn:E] Stopped 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn] Stopped 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn](INFO): Running loop #15 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn] Running Loop=15 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:51:45.705Z,1723657905.705 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:51:47.698Z,1723657907.698 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175146.00,A,3648.18007,N,12147.27603,W,0.000,237.78,140824,,,D*7D 2024-08-14T17:51:47.701Z,1723657907.701 [NAL9602](INFO): GPS fix at 20240814T175146: (36.803001, -121.787934) 2024-08-14T17:51:47.711Z,1723657907.711 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:51:47.711Z,1723657907.711 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:51:54.792Z,1723657914.792 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0039.lzma 2024-08-14T17:51:55.794Z,1723657915.794 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0039.lzma.bak 2024-08-14T17:51:55.794Z,1723657915.794 [DataOverHttps](INFO): SBD MOMSN=21500731 2024-08-14T17:52:11.535Z,1723657931.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=55002, MT Status=2, MTMSN=0 2024-08-14T17:52:11.535Z,1723657931.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T17:52:11.609Z,1723657931.609 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240814T163718/Express0040.lzma 2024-08-14T17:52:12.610Z,1723657932.610 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0040.lzma.bak 2024-08-14T17:52:12.611Z,1723657932.611 [DataOverHttps](INFO): SBD MOMSN=21500734 2024-08-14T17:52:13.989Z,1723657933.989 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:52:13.989Z,1723657933.989 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:52:13.989Z,1723657933.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:52:39.431Z,1723657959.431 [NAL9602](INFO): SBD MO Status=0, MOMSN=55002, MT Status=0, MTMSN=0 2024-08-14T17:52:39.431Z,1723657959.431 [NAL9602](INFO): No messages in MT queue 2024-08-14T17:53:10.153Z,1723657990.153 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T17:57:14.763Z,1723658234.763 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T17:57:14.764Z,1723658234.764 [Default:CheckIn:C.Wait] Stopped 2024-08-14T17:57:14.764Z,1723658234.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T17:57:14.764Z,1723658234.764 [Default:CheckIn:D] Running Loop=1 2024-08-14T17:57:15.173Z,1723658235.173 [Default:CheckIn:D] Stopped 2024-08-14T17:57:15.173Z,1723658235.173 [Default:CheckIn:E] Running Loop=1 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.907284 min 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn:E] Stopped 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn] Stopped 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn](INFO): Running loop #16 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn] Running Loop=16 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T17:57:15.574Z,1723658235.574 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T17:57:17.588Z,1723658237.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175716.00,A,3648.17623,N,12147.27925,W,0.039,237.78,140824,,,D*70 2024-08-14T17:57:17.590Z,1723658237.590 [NAL9602](INFO): GPS fix at 20240814T175716: (36.802937, -121.787987) 2024-08-14T17:57:17.600Z,1723658237.600 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T17:57:17.601Z,1723658237.601 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T17:57:23.336Z,1723658243.336 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240814T163718/Courier0042.lzma 2024-08-14T17:57:24.338Z,1723658244.338 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0042.lzma.bak 2024-08-14T17:57:24.338Z,1723658244.338 [DataOverHttps](INFO): SBD MOMSN=21500782 2024-08-14T17:57:40.176Z,1723658260.176 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240814T163718/Express0043.lzma 2024-08-14T17:57:41.178Z,1723658261.178 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0043.lzma.bak 2024-08-14T17:57:41.179Z,1723658261.179 [DataOverHttps](INFO): SBD MOMSN=21500964 2024-08-14T17:57:42.638Z,1723658262.638 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T17:57:42.639Z,1723658262.639 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T17:57:42.639Z,1723658262.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T17:59:41.427Z,1723658381.427 [NAL9602](INFO): SBD MO Status=0, MOMSN=55003, MT Status=0, MTMSN=0 2024-08-14T17:59:41.427Z,1723658381.427 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:00:12.138Z,1723658412.138 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:02:43.354Z,1723658563.354 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:02:43.354Z,1723658563.354 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:02:43.354Z,1723658563.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:02:43.355Z,1723658563.355 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:02:43.769Z,1723658563.769 [Default:CheckIn:D] Stopped 2024-08-14T18:02:43.769Z,1723658563.769 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:02:44.180Z,1723658564.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.383887 min 2024-08-14T18:02:44.180Z,1723658564.180 [Default:CheckIn:E] Stopped 2024-08-14T18:02:44.180Z,1723658564.180 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:02:44.180Z,1723658564.180 [Default:CheckIn] Stopped 2024-08-14T18:02:44.180Z,1723658564.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:02:44.181Z,1723658564.181 [Default:CheckIn](INFO): Running loop #17 2024-08-14T18:02:44.181Z,1723658564.181 [Default:CheckIn] Running Loop=17 2024-08-14T18:02:44.181Z,1723658564.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:02:44.181Z,1723658564.181 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:02:46.179Z,1723658566.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180245.00,A,3648.17686,N,12147.27849,W,0.097,237.78,140824,,,A*7C 2024-08-14T18:02:46.182Z,1723658566.182 [NAL9602](INFO): GPS fix at 20240814T180245: (36.802948, -121.787975) 2024-08-14T18:02:46.213Z,1723658566.213 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:02:46.213Z,1723658566.213 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:02:58.032Z,1723658578.032 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0045.lzma 2024-08-14T18:02:59.034Z,1723658579.034 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0045.lzma.bak 2024-08-14T18:02:59.035Z,1723658579.035 [DataOverHttps](INFO): SBD MOMSN=21501025 2024-08-14T18:03:15.016Z,1723658595.016 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240814T163718/Express0046.lzma 2024-08-14T18:03:16.019Z,1723658596.019 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0046.lzma.bak 2024-08-14T18:03:16.019Z,1723658596.019 [DataOverHttps](INFO): SBD MOMSN=21501028 2024-08-14T18:03:17.397Z,1723658597.397 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:03:17.398Z,1723658597.398 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:03:17.398Z,1723658597.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:03:23.851Z,1723658603.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=55004, MT Status=2, MTMSN=0 2024-08-14T18:03:23.851Z,1723658603.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:03:41.219Z,1723658621.219 [NAL9602](INFO): SBD MO Status=2, MOMSN=55004, MT Status=2, MTMSN=0 2024-08-14T18:03:41.219Z,1723658621.219 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:03:57.783Z,1723658637.783 [NAL9602](INFO): SBD MO Status=2, MOMSN=55004, MT Status=2, MTMSN=0 2024-08-14T18:03:57.783Z,1723658637.783 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:04:20.003Z,1723658660.003 [NAL9602](INFO): SBD MO Status=2, MOMSN=55004, MT Status=2, MTMSN=0 2024-08-14T18:04:20.003Z,1723658660.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:04:34.212Z,1723658674.212 [NAL9602](INFO): SBD MO Status=2, MOMSN=55004, MT Status=2, MTMSN=0 2024-08-14T18:04:34.212Z,1723658674.212 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:05:10.618Z,1723658710.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=55004, MT Status=0, MTMSN=0 2024-08-14T18:05:10.619Z,1723658710.619 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:05:32.840Z,1723658732.840 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-08-14T18:05:41.325Z,1723658741.325 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:08:17.875Z,1723658897.875 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:08:17.875Z,1723658897.875 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:08:17.875Z,1723658897.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:08:17.875Z,1723658897.875 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:08:18.198Z,1723658898.198 [Default:CheckIn:D] Stopped 2024-08-14T18:08:18.198Z,1723658898.198 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:08:18.593Z,1723658898.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.957699 min 2024-08-14T18:08:18.593Z,1723658898.593 [Default:CheckIn:E] Stopped 2024-08-14T18:08:18.593Z,1723658898.593 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:08:18.593Z,1723658898.593 [Default:CheckIn] Stopped 2024-08-14T18:08:18.593Z,1723658898.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:08:18.594Z,1723658898.594 [Default:CheckIn](INFO): Running loop #18 2024-08-14T18:08:18.594Z,1723658898.594 [Default:CheckIn] Running Loop=18 2024-08-14T18:08:18.594Z,1723658898.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:08:18.594Z,1723658898.594 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:08:20.583Z,1723658900.583 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180819.00,A,3648.18500,N,12147.27903,W,0.078,237.78,140824,,,A*73 2024-08-14T18:08:20.585Z,1723658900.585 [NAL9602](INFO): GPS fix at 20240814T180819: (36.803083, -121.787984) 2024-08-14T18:08:20.628Z,1723658900.628 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:08:20.628Z,1723658900.628 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:08:30.716Z,1723658910.716 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0048.lzma 2024-08-14T18:08:31.718Z,1723658911.718 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0048.lzma.bak 2024-08-14T18:08:31.718Z,1723658911.718 [DataOverHttps](INFO): SBD MOMSN=21501059 2024-08-14T18:08:44.455Z,1723658924.455 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:08:47.721Z,1723658927.721 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240814T163718/Express0049.lzma 2024-08-14T18:08:48.723Z,1723658928.723 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0049.lzma.bak 2024-08-14T18:08:48.723Z,1723658928.723 [DataOverHttps](INFO): SBD MOMSN=21501062 2024-08-14T18:08:50.191Z,1723658930.191 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:08:50.191Z,1723658930.191 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:08:50.191Z,1723658930.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:08:57.847Z,1723658937.847 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:09:01.492Z,1723658941.492 [NAL9602](INFO): SBD MO Status=0, MOMSN=55005, MT Status=0, MTMSN=0 2024-08-14T18:09:01.493Z,1723658941.493 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:09:11.583Z,1723658951.583 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:09:24.916Z,1723658964.916 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:09:32.190Z,1723658972.190 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:09:38.247Z,1723658978.247 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:09:51.579Z,1723658991.579 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:10:04.929Z,1723659004.929 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:10:18.243Z,1723659018.243 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:10:31.603Z,1723659031.603 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:10:45.339Z,1723659045.339 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:10:55.632Z,1723659055.632 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-14T18:10:59.511Z,1723659059.511 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-14T18:11:01.944Z,1723659061.944 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2024-08-14T18:11:01.946Z,1723659061.946 [BPC1](INFO): Received data from all battery sticks. 2024-08-14T18:13:50.823Z,1723659230.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:13:50.823Z,1723659230.823 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:13:50.823Z,1723659230.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:13:50.824Z,1723659230.824 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:13:51.239Z,1723659231.239 [Default:CheckIn:D] Stopped 2024-08-14T18:13:51.239Z,1723659231.239 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:13:51.627Z,1723659231.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.508390 min 2024-08-14T18:13:51.627Z,1723659231.627 [Default:CheckIn:E] Stopped 2024-08-14T18:13:51.627Z,1723659231.627 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:13:51.627Z,1723659231.627 [Default:CheckIn] Stopped 2024-08-14T18:13:51.628Z,1723659231.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:13:51.628Z,1723659231.628 [Default:CheckIn](INFO): Running loop #19 2024-08-14T18:13:51.628Z,1723659231.628 [Default:CheckIn] Running Loop=19 2024-08-14T18:13:51.628Z,1723659231.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:13:51.628Z,1723659231.628 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:13:53.643Z,1723659233.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181352.00,A,3648.19480,N,12147.27594,W,0.039,237.78,140824,,,A*79 2024-08-14T18:13:53.645Z,1723659233.645 [NAL9602](INFO): GPS fix at 20240814T181352: (36.803247, -121.787932) 2024-08-14T18:13:53.656Z,1723659233.656 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:13:53.656Z,1723659233.656 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:14:00.524Z,1723659240.524 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240814T163718/Courier0051.lzma 2024-08-14T18:14:01.528Z,1723659241.528 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0051.lzma.bak 2024-08-14T18:14:01.529Z,1723659241.529 [DataOverHttps](INFO): SBD MOMSN=21501093 2024-08-14T18:14:17.453Z,1723659257.453 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240814T163718/Express0052.lzma 2024-08-14T18:14:18.455Z,1723659258.455 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0052.lzma.bak 2024-08-14T18:14:18.455Z,1723659258.455 [DataOverHttps](INFO): SBD MOMSN=21501096 2024-08-14T18:14:19.920Z,1723659259.920 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:14:19.920Z,1723659259.920 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:14:19.921Z,1723659259.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:14:38.947Z,1723659278.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=55006, MT Status=2, MTMSN=0 2024-08-14T18:14:38.947Z,1723659278.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:14:48.235Z,1723659288.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=55006, MT Status=0, MTMSN=0 2024-08-14T18:14:48.235Z,1723659288.235 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:15:18.989Z,1723659318.989 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:19:20.629Z,1723659560.629 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:19:20.629Z,1723659560.629 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:19:20.629Z,1723659560.629 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:19:20.629Z,1723659560.629 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:19:21.040Z,1723659561.040 [Default:CheckIn:D] Stopped 2024-08-14T18:19:21.040Z,1723659561.040 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.005070 min 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn:E] Stopped 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn] Stopped 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn](INFO): Running loop #20 2024-08-14T18:19:21.427Z,1723659561.427 [Default:CheckIn] Running Loop=20 2024-08-14T18:19:21.428Z,1723659561.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:19:21.428Z,1723659561.428 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:19:23.439Z,1723659563.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181922.00,A,3648.19010,N,12147.27461,W,0.117,0.00,140824,,,A*76 2024-08-14T18:19:23.441Z,1723659563.441 [NAL9602](INFO): GPS fix at 20240814T181922: (36.803168, -121.787910) 2024-08-14T18:19:23.452Z,1723659563.452 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:19:23.452Z,1723659563.452 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:19:30.320Z,1723659570.320 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0054.lzma 2024-08-14T18:19:31.322Z,1723659571.322 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0054.lzma.bak 2024-08-14T18:19:31.322Z,1723659571.322 [DataOverHttps](INFO): SBD MOMSN=21501101 2024-08-14T18:19:47.184Z,1723659587.184 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240814T163718/Express0055.lzma 2024-08-14T18:19:48.186Z,1723659588.186 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0055.lzma.bak 2024-08-14T18:19:48.187Z,1723659588.187 [DataOverHttps](INFO): SBD MOMSN=21501104 2024-08-14T18:19:49.303Z,1723659589.303 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:19:49.303Z,1723659589.303 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:19:49.303Z,1723659589.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:19:50.107Z,1723659590.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=55007, MT Status=0, MTMSN=0 2024-08-14T18:19:50.107Z,1723659590.107 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:20:20.831Z,1723659620.831 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:24:50.015Z,1723659890.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:24:50.015Z,1723659890.015 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:24:50.015Z,1723659890.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:24:50.016Z,1723659890.016 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:24:50.433Z,1723659890.433 [Default:CheckIn:D] Stopped 2024-08-14T18:24:50.433Z,1723659890.433 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:24:50.856Z,1723659890.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.494946 min 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn:E] Stopped 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn] Stopped 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn](INFO): Running loop #21 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn] Running Loop=21 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:24:50.857Z,1723659890.857 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:24:52.837Z,1723659892.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182452.00,A,3648.18658,N,12147.27886,W,0.117,0.00,140824,,,A*71 2024-08-14T18:24:52.839Z,1723659892.839 [NAL9602](INFO): GPS fix at 20240814T182452: (36.803110, -121.787981) 2024-08-14T18:24:52.858Z,1723659892.858 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:24:52.858Z,1723659892.858 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:25:01.468Z,1723659901.468 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240814T163718/Courier0057.lzma 2024-08-14T18:25:02.470Z,1723659902.470 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0057.lzma.bak 2024-08-14T18:25:02.471Z,1723659902.471 [DataOverHttps](INFO): SBD MOMSN=21501108 2024-08-14T18:25:18.317Z,1723659918.317 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240814T163718/Express0058.lzma 2024-08-14T18:25:19.318Z,1723659919.318 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0058.lzma.bak 2024-08-14T18:25:19.319Z,1723659919.319 [DataOverHttps](INFO): SBD MOMSN=21501111 2024-08-14T18:25:20.772Z,1723659920.772 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:25:20.772Z,1723659920.772 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:25:20.772Z,1723659920.772 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:28:27.851Z,1723660107.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=55008, MT Status=2, MTMSN=0 2024-08-14T18:28:27.851Z,1723660107.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:28:39.158Z,1723660119.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=55008, MT Status=0, MTMSN=0 2024-08-14T18:28:39.158Z,1723660119.158 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:29:09.865Z,1723660149.865 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:30:21.393Z,1723660221.393 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:30:21.393Z,1723660221.393 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:30:21.393Z,1723660221.393 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:30:21.393Z,1723660221.393 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:30:21.799Z,1723660221.799 [Default:CheckIn:D] Stopped 2024-08-14T18:30:21.800Z,1723660221.800 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:30:22.200Z,1723660222.200 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.017725 min 2024-08-14T18:30:22.200Z,1723660222.200 [Default:CheckIn:E] Stopped 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn] Stopped 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn](INFO): Running loop #22 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn] Running Loop=22 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:30:22.201Z,1723660222.201 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:30:24.209Z,1723660224.209 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183023.00,A,3648.17885,N,12147.27699,W,0.214,0.00,140824,,,A*73 2024-08-14T18:30:24.211Z,1723660224.211 [NAL9602](INFO): GPS fix at 20240814T183023: (36.802981, -121.787950) 2024-08-14T18:30:24.234Z,1723660224.234 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:30:24.234Z,1723660224.234 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:30:31.636Z,1723660231.636 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0060.lzma 2024-08-14T18:30:32.638Z,1723660232.638 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0060.lzma.bak 2024-08-14T18:30:32.639Z,1723660232.639 [DataOverHttps](INFO): SBD MOMSN=21501115 2024-08-14T18:30:34.298Z,1723660234.298 [NAL9602](INFO): SBD MO Status=0, MOMSN=55009, MT Status=0, MTMSN=0 2024-08-14T18:30:34.298Z,1723660234.298 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:30:49.601Z,1723660249.601 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240814T163718/Express0061.lzma 2024-08-14T18:30:50.604Z,1723660250.604 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0061.lzma.bak 2024-08-14T18:30:50.604Z,1723660250.604 [DataOverHttps](INFO): SBD MOMSN=21501119 2024-08-14T18:30:52.101Z,1723660252.101 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:30:52.101Z,1723660252.101 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:30:52.102Z,1723660252.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:31:05.020Z,1723660265.020 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:35:52.707Z,1723660552.707 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:35:52.707Z,1723660552.707 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:35:52.707Z,1723660552.707 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:35:52.708Z,1723660552.708 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:35:53.112Z,1723660553.112 [Default:CheckIn:D] Stopped 2024-08-14T18:35:53.112Z,1723660553.112 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.539600 min 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn:E] Stopped 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn] Stopped 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn](INFO): Running loop #23 2024-08-14T18:35:53.517Z,1723660553.517 [Default:CheckIn] Running Loop=23 2024-08-14T18:35:53.518Z,1723660553.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:35:53.518Z,1723660553.518 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:35:55.522Z,1723660555.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183554.00,A,3648.17662,N,12147.27873,W,0.117,213.23,140824,,,A*7A 2024-08-14T18:35:55.524Z,1723660555.524 [NAL9602](INFO): GPS fix at 20240814T183554: (36.802944, -121.787979) 2024-08-14T18:35:55.555Z,1723660555.555 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:35:55.555Z,1723660555.555 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:36:02.561Z,1723660562.561 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0063.lzma 2024-08-14T18:36:03.562Z,1723660563.562 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0063.lzma.bak 2024-08-14T18:36:03.563Z,1723660563.563 [DataOverHttps](INFO): SBD MOMSN=21501124 2024-08-14T18:36:16.535Z,1723660576.535 [NAL9602](INFO): SBD MO Status=0, MOMSN=55010, MT Status=0, MTMSN=0 2024-08-14T18:36:16.535Z,1723660576.535 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:36:20.549Z,1723660580.549 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240814T163718/Express0064.lzma 2024-08-14T18:36:21.551Z,1723660581.551 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0064.lzma.bak 2024-08-14T18:36:21.551Z,1723660581.551 [DataOverHttps](INFO): SBD MOMSN=21501132 2024-08-14T18:36:23.045Z,1723660583.045 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:36:23.045Z,1723660583.045 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:36:23.045Z,1723660583.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:36:47.258Z,1723660607.258 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:41:23.628Z,1723660883.628 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:41:23.628Z,1723660883.628 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:41:23.628Z,1723660883.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:41:23.628Z,1723660883.628 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:41:24.025Z,1723660884.025 [Default:CheckIn:D] Stopped 2024-08-14T18:41:24.025Z,1723660884.025 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:41:24.434Z,1723660884.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.054818 min 2024-08-14T18:41:24.434Z,1723660884.434 [Default:CheckIn:E] Stopped 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn] Stopped 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn](INFO): Running loop #24 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn] Running Loop=24 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:41:24.435Z,1723660884.435 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:41:26.434Z,1723660886.434 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184125.00,A,3648.17414,N,12147.28063,W,0.952,204.73,140824,,,D*75 2024-08-14T18:41:26.437Z,1723660886.437 [NAL9602](INFO): GPS fix at 20240814T184125: (36.802902, -121.788010) 2024-08-14T18:41:26.447Z,1723660886.447 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:41:26.447Z,1723660886.447 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:41:33.616Z,1723660893.616 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0066.lzma 2024-08-14T18:41:34.618Z,1723660894.618 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0066.lzma.bak 2024-08-14T18:41:34.618Z,1723660894.618 [DataOverHttps](INFO): SBD MOMSN=21501415 2024-08-14T18:41:45.424Z,1723660905.424 [NAL9602](INFO): SBD MO Status=0, MOMSN=55011, MT Status=0, MTMSN=0 2024-08-14T18:41:45.424Z,1723660905.424 [NAL9602](INFO): No messages in MT queue 2024-08-14T18:41:50.337Z,1723660910.337 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240814T163718/Express0067.lzma 2024-08-14T18:41:51.338Z,1723660911.338 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0067.lzma.bak 2024-08-14T18:41:51.339Z,1723660911.339 [DataOverHttps](INFO): SBD MOMSN=21501418 2024-08-14T18:41:52.718Z,1723660912.718 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:41:52.718Z,1723660912.718 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:41:52.719Z,1723660912.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:42:16.124Z,1723660936.124 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:46:53.381Z,1723661213.381 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:46:53.381Z,1723661213.381 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:46:53.381Z,1723661213.381 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:46:53.381Z,1723661213.381 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:46:53.704Z,1723661213.704 [Default:CheckIn:D] Stopped 2024-08-14T18:46:53.704Z,1723661213.704 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:46:54.149Z,1723661214.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.549463 min 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn:E] Stopped 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn] Stopped 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn](INFO): Running loop #25 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn] Running Loop=25 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:46:54.150Z,1723661214.150 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:46:56.125Z,1723661216.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184655.00,A,3648.16735,N,12147.28360,W,0.564,207.56,140824,,,D*79 2024-08-14T18:46:56.127Z,1723661216.127 [NAL9602](INFO): GPS fix at 20240814T184655: (36.802789, -121.788060) 2024-08-14T18:46:56.163Z,1723661216.163 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:46:56.164Z,1723661216.164 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:47:04.188Z,1723661224.188 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240814T163718/Courier0069.lzma 2024-08-14T18:47:05.190Z,1723661225.190 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0069.lzma.bak 2024-08-14T18:47:05.191Z,1723661225.191 [DataOverHttps](INFO): SBD MOMSN=21501422 2024-08-14T18:47:21.090Z,1723661241.090 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240814T163718/Express0070.lzma 2024-08-14T18:47:22.090Z,1723661242.090 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0070.lzma.bak 2024-08-14T18:47:22.091Z,1723661242.091 [DataOverHttps](INFO): SBD MOMSN=21501425 2024-08-14T18:47:23.205Z,1723661243.205 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:47:23.205Z,1723661243.205 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:47:23.205Z,1723661243.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:47:47.431Z,1723661267.431 [NAL9602](INFO): SBD MO Status=2, MOMSN=55012, MT Status=2, MTMSN=0 2024-08-14T18:47:47.431Z,1723661267.431 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-14T18:50:26.301Z,1723661426.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T18:50:57.425Z,1723661457.425 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T18:51:28.549Z,1723661488.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T18:51:58.320Z,1723661518.320 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-14T18:51:59.673Z,1723661519.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-14T18:52:23.805Z,1723661543.805 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-14T18:52:23.805Z,1723661543.805 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:52:23.805Z,1723661543.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:52:23.805Z,1723661543.805 [Default:CheckIn:D] Running Loop=1 2024-08-14T18:52:24.237Z,1723661544.237 [Default:CheckIn:D] Stopped 2024-08-14T18:52:24.237Z,1723661544.237 [Default:CheckIn:E] Running Loop=1 2024-08-14T18:52:24.600Z,1723661544.600 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.058350 min 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn:E] Stopped 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn] Stopped 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn](INFO): Running loop #26 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn] Running Loop=26 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-14T18:52:24.601Z,1723661544.601 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-14T18:52:29.032Z,1723661549.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185228.00,A,3648.15668,N,12147.25679,W,10.400,224.66,140824,,,A*49 2024-08-14T18:52:29.035Z,1723661549.035 [NAL9602](INFO): GPS fix at 20240814T185228: (36.802611, -121.787613) 2024-08-14T18:52:29.065Z,1723661549.065 [Default:CheckIn:Read_GPS] Stopped 2024-08-14T18:52:29.065Z,1723661549.065 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-14T18:52:36.800Z,1723661556.800 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240814T163718/Courier0072.lzma 2024-08-14T18:52:37.802Z,1723661557.802 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Courier0072.lzma.bak 2024-08-14T18:52:37.802Z,1723661557.802 [DataOverHttps](INFO): SBD MOMSN=21501471 2024-08-14T18:52:53.621Z,1723661573.621 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20240814T163718/Express0073.lzma 2024-08-14T18:52:54.623Z,1723661574.623 [DataOverHttps](INFO): Moved sent file to Logs/20240814T163718/Express0073.lzma.bak 2024-08-14T18:52:54.623Z,1723661574.623 [DataOverHttps](INFO): SBD MOMSN=21501474 2024-08-14T18:52:55.724Z,1723661575.724 [Default:CheckIn:Read_Iridium] Stopped 2024-08-14T18:52:55.724Z,1723661575.724 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-14T18:52:55.724Z,1723661575.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-14T18:53:01.349Z,1723661581.349 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-14T18:53:28.513Z,1723661608.513 [CommandExec](IMPORTANT): got command quit 2024-08-14T18:53:29.516Z,1723661609.516 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-08-14T18:53:29.517Z,1723661609.517 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:29.517Z,1723661609.517 [CommandExec](INFO): Uninitializing the command executive. 2024-08-14T18:53:29.517Z,1723661609.517 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-14T18:53:29.517Z,1723661609.517 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:29.708Z,1723661609.708 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-14T18:53:29.708Z,1723661609.708 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-14T18:53:29.709Z,1723661609.709 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:29.709Z,1723661609.709 [NavChartDb](INFO): Join timeout helper Thread ID is 2201 2024-08-14T18:53:29.937Z,1723661609.937 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:29.937Z,1723661609.937 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:29.956Z,1723661609.956 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-08-14T18:53:29.957Z,1723661609.957 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:29.957Z,1723661609.957 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2202 2024-08-14T18:53:30.165Z,1723661610.165 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:30.165Z,1723661610.165 [WetLabsBB2FL](INFO): Powering down 2024-08-14T18:53:30.166Z,1723661610.166 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:30.169Z,1723661610.169 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-14T18:53:30.169Z,1723661610.169 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:30.169Z,1723661610.169 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2203 2024-08-14T18:53:30.857Z,1723661610.857 [CTD_Seabird](INFO): Powering down 2024-08-14T18:53:30.869Z,1723661610.869 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:30.869Z,1723661610.869 [CTD_Seabird](INFO): Powering down 2024-08-14T18:53:30.881Z,1723661610.881 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:30.897Z,1723661610.897 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-08-14T18:53:30.897Z,1723661610.897 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:30.897Z,1723661610.897 [DAT](INFO): Join timeout helper Thread ID is 2204 2024-08-14T18:53:31.037Z,1723661611.037 [DAT](INFO): Powering down 2024-08-14T18:53:31.109Z,1723661611.109 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:31.109Z,1723661611.109 [DAT](INFO): Powering down 2024-08-14T18:53:31.110Z,1723661611.110 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:31.116Z,1723661611.116 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-14T18:53:31.117Z,1723661611.117 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:31.117Z,1723661611.117 [Radio_Surface](INFO): Join timeout helper Thread ID is 2205 2024-08-14T18:53:31.389Z,1723661611.389 [Radio_Surface](INFO): Powering down 2024-08-14T18:53:31.389Z,1723661611.389 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:31.390Z,1723661611.390 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:31.402Z,1723661611.402 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-14T18:53:31.402Z,1723661611.402 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:31.402Z,1723661611.402 [Onboard](INFO): Join timeout helper Thread ID is 2206 2024-08-14T18:53:32.497Z,1723661612.497 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:32.497Z,1723661612.497 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.517Z,1723661612.517 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-14T18:53:32.517Z,1723661612.517 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.518Z,1723661612.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 2207 2024-08-14T18:53:32.713Z,1723661612.713 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:32.716Z,1723661612.716 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.729Z,1723661612.729 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-14T18:53:32.729Z,1723661612.729 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.730Z,1723661612.730 [logger](INFO): Join timeout helper Thread ID is 2208 2024-08-14T18:53:32.765Z,1723661612.765 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:32.765Z,1723661612.765 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.769Z,1723661612.769 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-14T18:53:32.769Z,1723661612.769 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.770Z,1723661612.770 [CommandLine](INFO): Join timeout helper Thread ID is 2209 2024-08-14T18:53:32.857Z,1723661612.857 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:32.857Z,1723661612.857 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.858Z,1723661612.858 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-14T18:53:32.858Z,1723661612.858 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.859Z,1723661612.859 [CommandExec](INFO): Join timeout helper Thread ID is 2210 2024-08-14T18:53:32.865Z,1723661612.865 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-14T18:53:32.865Z,1723661612.865 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:32.866Z,1723661612.866 [controlThread](INFO): Join timeout helper Thread ID is 2211 2024-08-14T18:53:32.886Z,1723661612.886 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T18:53:32.886Z,1723661612.886 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-14T18:53:32.886Z,1723661612.886 [AHRS_M2](INFO): Powering down 2024-08-14T18:53:32.957Z,1723661612.957 [NAL9602](INFO): Powering down 2024-08-14T18:53:32.960Z,1723661612.960 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-14T18:53:32.961Z,1723661612.961 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-14T18:53:32.961Z,1723661612.961 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-14T18:53:32.962Z,1723661612.962 [MissionManager](INFO): Uninitializing Mission Default 2024-08-14T18:53:32.962Z,1723661612.962 [Default] Stopped 2024-08-14T18:53:32.962Z,1723661612.962 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-14T18:53:32.962Z,1723661612.962 [Default:B.GoToSurface] Stopped 2024-08-14T18:53:32.962Z,1723661612.962 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-14T18:53:32.962Z,1723661612.962 [Default:CheckIn] Stopped 2024-08-14T18:53:32.962Z,1723661612.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-14T18:53:32.962Z,1723661612.962 [Default:CheckIn:C.Wait] Stopped 2024-08-14T18:53:32.962Z,1723661612.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-14T18:53:32.965Z,1723661612.965 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-08-14T18:53:32.966Z,1723661612.966 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-08-14T18:53:32.966Z,1723661612.966 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-08-14T18:53:32.966Z,1723661612.966 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-08-14T18:53:32.966Z,1723661612.966 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-08-14T18:53:32.967Z,1723661612.967 [BuoyancyServo](INFO): Powering down 2024-08-14T18:53:32.981Z,1723661612.981 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-08-14T18:53:32.981Z,1723661612.981 [ElevatorServo](INFO): Powering down 2024-08-14T18:53:32.981Z,1723661612.981 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-08-14T18:53:32.982Z,1723661612.982 [MassServo](INFO): Powering down 2024-08-14T18:53:32.982Z,1723661612.982 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-14T18:53:32.982Z,1723661612.982 [RudderServo](INFO): Powering down 2024-08-14T18:53:32.983Z,1723661612.983 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-08-14T18:53:32.983Z,1723661612.983 [ThrusterHE](INFO): Powering down 2024-08-14T18:53:32.984Z,1723661612.984 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-08-14T18:53:32.985Z,1723661612.985 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-08-14T18:53:32.985Z,1723661612.985 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-08-14T18:53:32.985Z,1723661612.985 [CBIT](DEBUG): Powering off loads. 2024-08-14T18:53:32.996Z,1723661612.996 [CBIT](DEBUG): Disabling WDT. 2024-08-14T18:53:33.008Z,1723661613.008 [CBIT](DEBUG): Opening all GF detection circuits. 2024-08-14T18:53:33.009Z,1723661613.009 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.055Z,1723661613.055 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.061Z,1723661613.061 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.063Z,1723661613.063 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.069Z,1723661613.069 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.141Z,1723661613.141 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.146Z,1723661613.146 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.188Z,1723661613.188 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T18:53:33.300Z,1723661613.300 [logger ThreadHandler](INFO): Thread cancelled.