2026-03-10T21:14:28.210Z,1773177268.210 [CommandExec](IMPORTANT): got command restart logs
2026-03-10T21:16:04.933Z,1773177364.933 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251244
2026-03-10T21:16:15.349Z,1773177375.349 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003168
2026-03-10T21:18:38.667Z,1773177518.667 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:18:38.667Z,1773177518.667 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:18:38.667Z,1773177518.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:18:38.667Z,1773177518.667 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:18:39.073Z,1773177519.073 [Default:CheckIn:D] Stopped
2026-03-10T21:18:39.074Z,1773177519.074 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:18:39.486Z,1773177519.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  30.714056 min 
2026-03-10T21:18:39.486Z,1773177519.486 [Default:CheckIn:E] Stopped
2026-03-10T21:18:39.486Z,1773177519.486 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn] Stopped
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn](INFO): Running loop #6
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn] Running Loop=6
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:18:39.487Z,1773177519.487 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:18:41.487Z,1773177521.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211840.00,A,3648.38288,N,12147.12434,W,5.734,185.66,100326,,,D*79
2026-03-10T21:18:41.489Z,1773177521.489 [NAL9602](INFO): GPS fix at 20260310T211840: (36.806381, -121.785406)
2026-03-10T21:18:41.499Z,1773177521.499 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:18:41.499Z,1773177521.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:18:49.144Z,1773177529.144 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260310T052752/Courier0132.lzma
2026-03-10T21:18:50.147Z,1773177530.147 [DataOverHttps](INFO): Moved sent file to Logs/20260310T052752/Courier0132.lzma.bak
2026-03-10T21:18:50.147Z,1773177530.147 [DataOverHttps](INFO): SBD MOMSN=26708202
2026-03-10T21:18:50.834Z,1773177530.834 [NAL9602](INFO): SBD MO Status=0, MOMSN=12800, MT Status=0, MTMSN=0
2026-03-10T21:18:50.835Z,1773177530.835 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:19:21.286Z,1773177561.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:19:21.550Z,1773177561.550 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:19:37.021Z,1773177577.021 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20260310T211428/Courier0000.lzma
2026-03-10T21:19:38.022Z,1773177578.022 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0000.lzma.bak
2026-03-10T21:19:38.022Z,1773177578.022 [DataOverHttps](INFO): SBD MOMSN=26708211
2026-03-10T21:19:54.232Z,1773177594.232 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20260310T052752/Express0133.lzma
2026-03-10T21:19:55.235Z,1773177595.235 [DataOverHttps](INFO): Moved sent file to Logs/20260310T052752/Express0133.lzma.bak
2026-03-10T21:19:55.235Z,1773177595.235 [DataOverHttps](INFO): SBD MOMSN=26708224
2026-03-10T21:19:55.476Z,1773177595.476 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T21:20:10.977Z,1773177610.977 [DataOverHttps](INFO): Sending 494 bytes from file Logs/20260310T211428/Express0001.lzma
2026-03-10T21:20:11.978Z,1773177611.978 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0001.lzma.bak
2026-03-10T21:20:11.978Z,1773177611.978 [DataOverHttps](INFO): SBD MOMSN=26708230
2026-03-10T21:20:28.508Z,1773177628.508 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20260310T211428/Express0004.lzma
2026-03-10T21:20:29.510Z,1773177629.510 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0004.lzma.bak
2026-03-10T21:20:29.510Z,1773177629.510 [DataOverHttps](INFO): SBD MOMSN=26708244
2026-03-10T21:20:31.048Z,1773177631.048 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:20:31.048Z,1773177631.048 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:20:31.048Z,1773177631.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:21:01.637Z,1773177661.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:21:29.757Z,1773177689.757 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247276
2026-03-10T21:21:49.357Z,1773177709.357 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003167
2026-03-10T21:24:00.297Z,1773177840.297 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:01.949Z,1773177841.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:06.762Z,1773177846.762 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:08.779Z,1773177848.779 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:21.305Z,1773177861.305 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:24.131Z,1773177864.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:36.256Z,1773177876.256 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:39.891Z,1773177879.891 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:51.204Z,1773177891.204 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:24:54.841Z,1773177894.841 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:06.153Z,1773177906.153 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:10.191Z,1773177910.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:21.505Z,1773177921.505 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:26.356Z,1773177926.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:31.623Z,1773177931.623 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:25:31.623Z,1773177931.623 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:25:31.623Z,1773177931.623 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:25:31.623Z,1773177931.623 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:25:32.032Z,1773177932.032 [Default:CheckIn:D] Stopped
2026-03-10T21:25:32.032Z,1773177932.032 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:25:32.427Z,1773177932.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  37.596700 min 
2026-03-10T21:25:32.427Z,1773177932.427 [Default:CheckIn:E] Stopped
2026-03-10T21:25:32.428Z,1773177932.428 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:25:32.428Z,1773177932.428 [Default:CheckIn] Stopped
2026-03-10T21:25:32.428Z,1773177932.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:25:32.428Z,1773177932.428 [Default:CheckIn](INFO): Running loop #7
2026-03-10T21:25:32.428Z,1773177932.428 [Default:CheckIn] Running Loop=7
2026-03-10T21:25:32.445Z,1773177932.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:25:32.445Z,1773177932.445 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:25:34.435Z,1773177934.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212533.00,A,3648.15053,N,12147.22220,W,0.019,212.32,100326,,,D*79
2026-03-10T21:25:34.438Z,1773177934.438 [NAL9602](INFO): GPS fix at 20260310T212533: (36.802509, -121.787037)
2026-03-10T21:25:34.449Z,1773177934.449 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:25:34.449Z,1773177934.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:25:36.453Z,1773177936.453 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:41.301Z,1773177941.301 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:41.985Z,1773177941.985 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0006.lzma
2026-03-10T21:25:42.987Z,1773177942.987 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0006.lzma.bak
2026-03-10T21:25:42.988Z,1773177942.988 [DataOverHttps](INFO): SBD MOMSN=26708252
2026-03-10T21:25:52.207Z,1773177952.207 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:54.243Z,1773177954.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=12801, MT Status=0, MTMSN=0
2026-03-10T21:25:54.243Z,1773177954.243 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:25:56.652Z,1773177956.652 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:25:58.772Z,1773177958.772 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20260310T211428/Express0007.lzma
2026-03-10T21:25:59.774Z,1773177959.774 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0007.lzma.bak
2026-03-10T21:25:59.774Z,1773177959.774 [DataOverHttps](INFO): SBD MOMSN=26708255
2026-03-10T21:26:01.112Z,1773177961.112 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:26:01.112Z,1773177961.112 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:26:01.112Z,1773177961.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:26:07.964Z,1773177967.964 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-10T21:26:12.030Z,1773177972.030 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-10T21:26:22.919Z,1773177982.919 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2026-03-10T21:26:24.935Z,1773177984.935 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:26:26.961Z,1773177986.961 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5.
2026-03-10T21:26:26.964Z,1773177986.964 [BPC1](INFO): Received data from all battery sticks.
2026-03-10T21:27:41.853Z,1773178061.853 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:27:43.861Z,1773178063.861 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255890
2026-03-10T21:27:54.305Z,1773178074.305 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003165
2026-03-10T21:29:55.828Z,1773178195.828 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T21:31:01.694Z,1773178261.694 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:31:01.695Z,1773178261.695 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:31:01.695Z,1773178261.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:31:01.695Z,1773178261.695 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:31:02.093Z,1773178262.093 [Default:CheckIn:D] Stopped
2026-03-10T21:31:02.093Z,1773178262.093 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:31:02.491Z,1773178262.491 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  43.097705 min 
2026-03-10T21:31:02.491Z,1773178262.491 [Default:CheckIn:E] Stopped
2026-03-10T21:31:02.491Z,1773178262.491 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:31:02.491Z,1773178262.491 [Default:CheckIn] Stopped
2026-03-10T21:31:02.491Z,1773178262.491 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:31:02.492Z,1773178262.492 [Default:CheckIn](INFO): Running loop #8
2026-03-10T21:31:02.492Z,1773178262.492 [Default:CheckIn] Running Loop=8
2026-03-10T21:31:02.492Z,1773178262.492 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:31:02.492Z,1773178262.492 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:31:04.508Z,1773178264.508 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213103.00,A,3648.14948,N,12147.22048,W,0.039,212.32,100326,,,D*73
2026-03-10T21:31:04.510Z,1773178264.510 [NAL9602](INFO): GPS fix at 20260310T213103: (36.802491, -121.787008)
2026-03-10T21:31:04.520Z,1773178264.520 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:31:04.520Z,1773178264.520 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:31:12.109Z,1773178272.109 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20260310T211428/Courier0009.lzma
2026-03-10T21:31:13.111Z,1773178273.111 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0009.lzma.bak
2026-03-10T21:31:13.111Z,1773178273.111 [DataOverHttps](INFO): SBD MOMSN=26708275
2026-03-10T21:31:31.580Z,1773178291.580 [NAL9602](INFO): SBD MO Status=0, MOMSN=12802, MT Status=0, MTMSN=0
2026-03-10T21:31:31.580Z,1773178291.580 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:32:00.188Z,1773178320.188 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20260310T211428/Express0010.lzma
2026-03-10T21:32:01.190Z,1773178321.190 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0010.lzma.bak
2026-03-10T21:32:01.190Z,1773178321.190 [DataOverHttps](INFO): SBD MOMSN=26708346
2026-03-10T21:32:02.288Z,1773178322.288 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:32:02.708Z,1773178322.708 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:32:02.709Z,1773178322.709 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:32:02.709Z,1773178322.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:36:40.677Z,1773178600.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:36:43.689Z,1773178603.689 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251255
2026-03-10T21:36:53.337Z,1773178613.337 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003148
2026-03-10T21:37:03.268Z,1773178623.268 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:37:03.268Z,1773178623.268 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:37:03.268Z,1773178623.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:37:03.269Z,1773178623.269 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:37:03.668Z,1773178623.668 [Default:CheckIn:D] Stopped
2026-03-10T21:37:03.668Z,1773178623.668 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  49.123962 min 
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn:E] Stopped
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn] Stopped
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:37:04.083Z,1773178624.083 [Default:CheckIn](INFO): Running loop #9
2026-03-10T21:37:04.084Z,1773178624.084 [Default:CheckIn] Running Loop=9
2026-03-10T21:37:04.084Z,1773178624.084 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:37:04.084Z,1773178624.084 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:37:06.083Z,1773178626.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213705.00,A,3648.14959,N,12147.22015,W,0.019,212.32,100326,,,D*79
2026-03-10T21:37:06.086Z,1773178626.086 [NAL9602](INFO): GPS fix at 20260310T213705: (36.802493, -121.787002)
2026-03-10T21:37:06.121Z,1773178626.121 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:37:06.121Z,1773178626.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:37:13.786Z,1773178633.786 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0012.lzma
2026-03-10T21:37:14.786Z,1773178634.786 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0012.lzma.bak
2026-03-10T21:37:14.786Z,1773178634.786 [DataOverHttps](INFO): SBD MOMSN=26708415
2026-03-10T21:37:25.484Z,1773178645.484 [NAL9602](INFO): SBD MO Status=0, MOMSN=12803, MT Status=0, MTMSN=0
2026-03-10T21:37:25.484Z,1773178645.484 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:37:30.553Z,1773178650.553 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20260310T211428/Express0013.lzma
2026-03-10T21:37:31.554Z,1773178651.554 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0013.lzma.bak
2026-03-10T21:37:31.555Z,1773178651.555 [DataOverHttps](INFO): SBD MOMSN=26708423
2026-03-10T21:37:32.768Z,1773178652.768 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:37:32.768Z,1773178652.768 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:37:32.768Z,1773178652.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:37:56.177Z,1773178676.177 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:39:49.117Z,1773178789.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:39:51.126Z,1773178791.126 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240196
2026-03-10T21:39:56.200Z,1773178796.200 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T21:40:01.581Z,1773178801.581 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003141
2026-03-10T21:42:33.340Z,1773178953.340 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:42:33.340Z,1773178953.340 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:42:33.340Z,1773178953.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:42:33.344Z,1773178953.344 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:42:33.743Z,1773178953.743 [Default:CheckIn:D] Stopped
2026-03-10T21:42:33.743Z,1773178953.743 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:42:34.139Z,1773178954.139 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  54.625220 min 
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn:E] Stopped
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn] Stopped
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn](INFO): Running loop #10
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn] Running Loop=10
2026-03-10T21:42:34.140Z,1773178954.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:42:34.141Z,1773178954.141 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:42:36.156Z,1773178956.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214235.00,A,3648.14960,N,12147.22059,W,0.039,212.32,100326,,,D*78
2026-03-10T21:42:36.158Z,1773178956.158 [NAL9602](INFO): GPS fix at 20260310T214235: (36.802493, -121.787010)
2026-03-10T21:42:36.169Z,1773178956.169 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:42:36.169Z,1773178956.169 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:42:43.116Z,1773178963.116 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0015.lzma
2026-03-10T21:42:44.119Z,1773178964.119 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0015.lzma.bak
2026-03-10T21:42:44.119Z,1773178964.119 [DataOverHttps](INFO): SBD MOMSN=26708452
2026-03-10T21:42:59.912Z,1773178979.912 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20260310T211428/Express0016.lzma
2026-03-10T21:43:00.914Z,1773178980.914 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0016.lzma.bak
2026-03-10T21:43:00.914Z,1773178980.914 [DataOverHttps](INFO): SBD MOMSN=26708465
2026-03-10T21:43:02.427Z,1773178982.427 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:43:02.428Z,1773178982.428 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:43:02.428Z,1773178982.428 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:43:07.263Z,1773178987.263 [NAL9602](INFO): SBD MO Status=0, MOMSN=12804, MT Status=0, MTMSN=0
2026-03-10T21:43:07.263Z,1773178987.263 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:43:37.961Z,1773179017.961 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:46:29.609Z,1773179189.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:46:31.617Z,1773179191.617 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251339
2026-03-10T21:46:41.993Z,1773179201.993 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003149
2026-03-10T21:48:03.003Z,1773179283.003 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:48:03.003Z,1773179283.003 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:48:03.003Z,1773179283.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:48:03.003Z,1773179283.003 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:48:03.408Z,1773179283.408 [Default:CheckIn:D] Stopped
2026-03-10T21:48:03.408Z,1773179283.408 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:48:03.824Z,1773179283.824 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  60.119633 min 
2026-03-10T21:48:03.824Z,1773179283.824 [Default:CheckIn:E] Stopped
2026-03-10T21:48:03.824Z,1773179283.824 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:48:03.824Z,1773179283.824 [Default:CheckIn] Stopped
2026-03-10T21:48:03.824Z,1773179283.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:48:03.825Z,1773179283.825 [Default:CheckIn](INFO): Running loop #11
2026-03-10T21:48:03.825Z,1773179283.825 [Default:CheckIn] Running Loop=11
2026-03-10T21:48:03.825Z,1773179283.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:48:03.825Z,1773179283.825 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:48:05.815Z,1773179285.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214805.00,A,3648.15007,N,12147.21900,W,0.097,212.32,100326,,,D*7A
2026-03-10T21:48:05.817Z,1773179285.817 [NAL9602](INFO): GPS fix at 20260310T214805: (36.802501, -121.786983)
2026-03-10T21:48:05.827Z,1773179285.827 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:48:05.827Z,1773179285.827 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:48:13.412Z,1773179293.412 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0018.lzma
2026-03-10T21:48:14.414Z,1773179294.414 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0018.lzma.bak
2026-03-10T21:48:14.414Z,1773179294.414 [DataOverHttps](INFO): SBD MOMSN=26708865
2026-03-10T21:48:19.551Z,1773179299.551 [NAL9602](INFO): SBD MO Status=0, MOMSN=12805, MT Status=0, MTMSN=0
2026-03-10T21:48:19.551Z,1773179299.551 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:48:30.404Z,1773179310.404 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20260310T211428/Express0019.lzma
2026-03-10T21:48:31.406Z,1773179311.406 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0019.lzma.bak
2026-03-10T21:48:31.407Z,1773179311.407 [DataOverHttps](INFO): SBD MOMSN=26708917
2026-03-10T21:48:32.891Z,1773179312.891 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:48:32.891Z,1773179312.891 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:48:32.891Z,1773179312.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:48:50.253Z,1773179330.253 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:49:56.516Z,1773179396.516 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T21:51:26.541Z,1773179486.541 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239269
2026-03-10T21:52:12.017Z,1773179532.017 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003141
2026-03-10T21:53:33.477Z,1773179613.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:53:33.477Z,1773179613.477 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:53:33.477Z,1773179613.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:53:33.477Z,1773179613.477 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:53:33.875Z,1773179613.875 [Default:CheckIn:D] Stopped
2026-03-10T21:53:33.875Z,1773179613.875 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:53:34.275Z,1773179614.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  65.627413 min 
2026-03-10T21:53:34.275Z,1773179614.275 [Default:CheckIn:E] Stopped
2026-03-10T21:53:34.275Z,1773179614.275 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:53:34.275Z,1773179614.275 [Default:CheckIn] Stopped
2026-03-10T21:53:34.276Z,1773179614.276 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:53:34.276Z,1773179614.276 [Default:CheckIn](INFO): Running loop #12
2026-03-10T21:53:34.276Z,1773179614.276 [Default:CheckIn] Running Loop=12
2026-03-10T21:53:34.276Z,1773179614.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:53:34.276Z,1773179614.276 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:53:36.289Z,1773179616.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215335.00,A,3648.14884,N,12147.22044,W,0.000,212.32,100326,,,D*75
2026-03-10T21:53:36.291Z,1773179616.291 [NAL9602](INFO): GPS fix at 20260310T215335: (36.802481, -121.787007)
2026-03-10T21:53:36.301Z,1773179616.301 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:53:36.301Z,1773179616.301 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:53:43.564Z,1773179623.564 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0021.lzma
2026-03-10T21:53:44.566Z,1773179624.566 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0021.lzma.bak
2026-03-10T21:53:44.566Z,1773179624.566 [DataOverHttps](INFO): SBD MOMSN=26709246
2026-03-10T21:53:47.199Z,1773179627.199 [NAL9602](INFO): SBD MO Status=0, MOMSN=12806, MT Status=0, MTMSN=0
2026-03-10T21:53:47.199Z,1773179627.199 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:54:00.452Z,1773179640.452 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20260310T211428/Express0022.lzma
2026-03-10T21:54:01.454Z,1773179641.454 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0022.lzma.bak
2026-03-10T21:54:01.455Z,1773179641.455 [DataOverHttps](INFO): SBD MOMSN=26709253
2026-03-10T21:54:02.962Z,1773179642.962 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:54:02.962Z,1773179642.962 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:54:02.962Z,1773179642.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:54:17.898Z,1773179657.898 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T21:55:43.561Z,1773179743.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:55:45.569Z,1773179745.569 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.258976
2026-03-10T21:55:55.925Z,1773179755.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003151
2026-03-10T21:59:03.558Z,1773179943.558 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T21:59:03.558Z,1773179943.558 [Default:CheckIn:C.Wait] Stopped
2026-03-10T21:59:03.558Z,1773179943.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T21:59:03.559Z,1773179943.559 [Default:CheckIn:D] Running Loop=1
2026-03-10T21:59:03.949Z,1773179943.949 [Default:CheckIn:D] Stopped
2026-03-10T21:59:03.949Z,1773179943.949 [Default:CheckIn:E] Running Loop=1
2026-03-10T21:59:04.351Z,1773179944.351 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  71.128646 min 
2026-03-10T21:59:04.351Z,1773179944.351 [Default:CheckIn:E] Stopped
2026-03-10T21:59:04.351Z,1773179944.351 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn] Stopped
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn](INFO): Running loop #13
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn] Running Loop=13
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T21:59:04.352Z,1773179944.352 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T21:59:06.364Z,1773179946.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215905.00,A,3648.15199,N,12147.22206,W,0.078,307.42,100326,,,D*71
2026-03-10T21:59:06.367Z,1773179946.367 [NAL9602](INFO): GPS fix at 20260310T215905: (36.802533, -121.787034)
2026-03-10T21:59:06.377Z,1773179946.377 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T21:59:06.377Z,1773179946.377 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T21:59:16.165Z,1773179956.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T21:59:16.858Z,1773179956.858 [NAL9602](INFO): SBD MO Status=0, MOMSN=12807, MT Status=0, MTMSN=0
2026-03-10T21:59:16.858Z,1773179956.858 [NAL9602](INFO): No messages in MT queue
2026-03-10T21:59:18.173Z,1773179958.173 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239429
2026-03-10T21:59:26.377Z,1773179966.377 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003351
2026-03-10T21:59:28.252Z,1773179968.252 [NAL9602](INFO): SBD MO Status=1, MOMSN=12808, MT Status=0, MTMSN=0
2026-03-10T21:59:28.301Z,1773179968.301 [NAL9602](INFO): Sent 73 bytes from file Logs/20260310T211428/Courier0024.lzma
2026-03-10T21:59:28.301Z,1773179968.301 [NAL9602](INFO): Packets left to send: 0
2026-03-10T21:59:33.808Z,1773179973.808 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20260310T211428/Express0025.lzma
2026-03-10T21:59:34.811Z,1773179974.811 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0025.lzma.bak
2026-03-10T21:59:34.811Z,1773179974.811 [DataOverHttps](INFO): SBD MOMSN=26709262
2026-03-10T21:59:35.937Z,1773179975.937 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T21:59:35.937Z,1773179975.937 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T21:59:35.937Z,1773179975.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T21:59:56.541Z,1773179996.541 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T21:59:58.957Z,1773179998.957 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T22:03:25.092Z,1773180205.092 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2026-03-10T22:03:25.093Z,1773180205.093 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2026-03-10T22:03:25.093Z,1773180205.093 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/run_backseat_on_surface.tl
2026-03-10T22:03:25.095Z,1773180205.095 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/run_backseat_on_surface.tl
2026-03-10T22:03:25.338Z,1773180205.338 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/run_backseat_on_surface.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="run_backseat_on_surface">
    <DefineArg Name="ElapsedSinceMissionStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Assign>
        <Sequence />
        <Arg Name="BackseatDriver:EnableBackseat" />
        <True />
    </Assign>
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceMissionStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            run_backseat_on_surface mission has been running for 
            <Arg Name="ElapsedSinceMissionStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and run_backseat_on_surface mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2026-03-10T22:03:25.341Z,1773180205.341 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2026-03-10T22:03:25.342Z,1773180205.342 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2026-03-10T22:03:25.342Z,1773180205.342 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2026-03-10T22:03:25.343Z,1773180205.343 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2026-03-10T22:03:25.426Z,1773180205.426 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2026-03-10T22:03:25.437Z,1773180205.437 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2026-03-10T22:03:25.439Z,1773180205.439 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2026-03-10T22:03:25.451Z,1773180205.451 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2026-03-10T22:03:25.451Z,1773180205.451 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2026-03-10T22:03:25.457Z,1773180205.457 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2026-03-10T22:03:25.600Z,1773180205.600 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2026-03-10T22:03:25.604Z,1773180205.604 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleAll = 0 bool
2026-03-10T22:03:25.606Z,1773180205.606 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad1 = 0 bool
2026-03-10T22:03:25.608Z,1773180205.608 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad2 = 0 bool
2026-03-10T22:03:25.610Z,1773180205.610 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad3 = 0 bool
2026-03-10T22:03:25.612Z,1773180205.612 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.EnabledPowerOnly = 1 bool
2026-03-10T22:03:25.659Z,1773180205.659 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Construct Wait.
2026-03-10T22:03:25.669Z,1773180205.669 [run_backseat_on_surface:D.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T22:03:25.689Z,1773180205.689 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-10T22:03:25.691Z,1773180205.691 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-10T22:03:25.697Z,1773180205.697 [run_backseat_on_surface:G.Execute](DEBUG): Construct Execute.
2026-03-10T22:03:25.702Z,1773180205.702 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission run_backseat_on_surface {
  arguments {
    ElapsedSinceMissionStarted = 0 minute
  }

  # Power the science payload.

#  insert id="Science" Insert/Science.tl

  # Power the Backseat payload.

  insert Insert/BackseatDriver.tl

  assign in sequence BackseatDriver:EnableBackseat = true

  insert Insert/PowerOnly.tl

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P2H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceMissionStarted = elapsed ( Universal:mission_started )

    syslog important "run_backseat_on_surface mission has been running for "
         + ElapsedSinceMissionStarted~minute
  }

  syslog important "Restarting logs and run_backseat_on_surface mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2026-03-10T22:03:25.705Z,1773180205.705 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2026-03-10T22:03:25.817Z,1773180205.817 [Default] Stopped
2026-03-10T22:03:25.817Z,1773180205.817 [Default](DEBUG): Aggregate::uninitialize Default
2026-03-10T22:03:25.817Z,1773180205.817 [Default:B.GoToSurface] Stopped
2026-03-10T22:03:25.817Z,1773180205.817 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-10T22:03:25.817Z,1773180205.817 [Default:CheckIn] Stopped
2026-03-10T22:03:25.817Z,1773180205.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T22:03:25.818Z,1773180205.818 [Default:CheckIn:C.Wait] Stopped
2026-03-10T22:03:25.818Z,1773180205.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:03:25.818Z,1773180205.818 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface] Running Loop=1
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2026-03-10T22:03:25.818Z,1773180205.818 [run_backseat_on_surface:PowerOnly] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::initialize run_backseat_on_surface:PowerOnly
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:A] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:B] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:C] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:D] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:E.Wait] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:D.GoToSurface] Running Loop=1
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:D.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-10T22:03:25.819Z,1773180205.819 [run_backseat_on_surface:D.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-10T22:03:25.820Z,1773180205.820 [run_backseat_on_surface:D.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-10T22:03:25.820Z,1773180205.820 [run_backseat_on_surface:D.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-10T22:03:25.821Z,1773180205.821 [run_backseat_on_surface:D.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-10T22:03:25.821Z,1773180205.821 [run_backseat_on_surface:D.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-10T22:03:25.821Z,1773180205.821 [run_backseat_on_surface:D.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-10T22:03:25.821Z,1773180205.821 [run_backseat_on_surface:B] Running Loop=1
2026-03-10T22:03:25.822Z,1773180205.822 [run_backseat_on_surface:PowerOnly] Running Loop=1
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:PowerOnly:D] Stopped
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:PowerOnly:C] Stopped
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:PowerOnly:B] Stopped
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:PowerOnly:A] Stopped
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:B] Stopped
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2026-03-10T22:03:25.823Z,1773180205.823 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2026-03-10T22:03:26.045Z,1773180206.045 [BackseatComponent](INFO): Powering up
2026-03-10T22:03:26.045Z,1773180206.045 [BackseatComponent](INFO): Subscribing to LCM channels.
2026-03-10T22:03:26.225Z,1773180206.225 [run_backseat_on_surface:CheckIn] Running Loop=1
2026-03-10T22:03:26.226Z,1773180206.226 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2026-03-10T22:03:26.226Z,1773180206.226 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2026-03-10T22:03:26.635Z,1773180206.635 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-10T22:03:28.240Z,1773180208.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220327.00,A,3648.15114,N,12147.22094,W,0.039,307.42,100326,,,D*74
2026-03-10T22:03:28.242Z,1773180208.242 [NAL9602](INFO): GPS fix at 20260310T220327: (36.802519, -121.787016)
2026-03-10T22:03:28.252Z,1773180208.252 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2026-03-10T22:03:28.253Z,1773180208.253 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T22:03:28.664Z,1773180208.664 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-10T22:03:36.968Z,1773180216.968 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0027.lzma
2026-03-10T22:03:37.973Z,1773180217.973 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0027.lzma.bak
2026-03-10T22:03:37.973Z,1773180217.973 [DataOverHttps](INFO): SBD MOMSN=26709476
2026-03-10T22:03:41.202Z,1773180221.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=12809, MT Status=0, MTMSN=0
2026-03-10T22:03:41.202Z,1773180221.202 [NAL9602](INFO): No messages in MT queue
2026-03-10T22:04:11.875Z,1773180251.875 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T22:04:25.248Z,1773180265.248 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20260310T211428/Express0028.lzma
2026-03-10T22:04:26.242Z,1773180266.242 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0028.lzma.bak
2026-03-10T22:04:26.242Z,1773180266.242 [DataOverHttps](INFO): SBD MOMSN=26709480
2026-03-10T22:04:27.654Z,1773180267.654 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2026-03-10T22:04:27.655Z,1773180267.655 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2026-03-10T22:04:27.655Z,1773180267.655 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:04:33.945Z,1773180273.945 [BackseatComponent](IMPORTANT): conch: running backseat application.
2026-03-10T22:08:30.077Z,1773180510.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T22:08:36.105Z,1773180516.105 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251064
2026-03-10T22:09:14.208Z,1773180554.208 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-10T22:09:18.622Z,1773180558.622 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.036095
CHAN A1 (24V): 0.054618
CHAN A2 (12V): 0.023741
CHAN A3 (5V): 0.007203
CHAN B0 (3.3V): 0.004742
CHAN B1 (3.15aV): 0.004633
CHAN B2 (3.15bV): 0.004992
CHAN B3 (GND): -0.003461
OPEN: 0.006721
Full Scale: +/- 1 mA
2026-03-10T22:09:28.285Z,1773180568.285 [run_backseat_on_surface:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T22:09:28.285Z,1773180568.285 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2026-03-10T22:09:28.285Z,1773180568.285 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:09:28.285Z,1773180568.285 [run_backseat_on_surface:CheckIn:D] Running Loop=1
2026-03-10T22:09:28.662Z,1773180568.662 [run_backseat_on_surface:CheckIn:D] Stopped
2026-03-10T22:09:28.662Z,1773180568.662 [run_backseat_on_surface:CheckIn:E] Running Loop=1
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn:E](IMPORTANT): run_backseat_on_surface mission has been running for  6.047400 min 
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn:E] Stopped
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn](INFO): Completed run_backseat_on_surface:CheckIn
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn] Stopped
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2026-03-10T22:09:29.075Z,1773180569.075 [run_backseat_on_surface:CheckIn](INFO): Running loop #2
2026-03-10T22:09:29.076Z,1773180569.076 [run_backseat_on_surface:CheckIn] Running Loop=2
2026-03-10T22:09:29.076Z,1773180569.076 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2026-03-10T22:09:29.076Z,1773180569.076 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2026-03-10T22:09:31.072Z,1773180571.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220930.00,A,3648.15109,N,12147.22077,W,0.039,307.42,100326,,,A*7C
2026-03-10T22:09:31.082Z,1773180571.082 [NAL9602](INFO): GPS fix at 20260310T220930: (36.802518, -121.787013)
2026-03-10T22:09:31.114Z,1773180571.114 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2026-03-10T22:09:31.114Z,1773180571.114 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T22:09:40.413Z,1773180580.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T22:09:49.170Z,1773180589.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=12810, MT Status=0, MTMSN=0
2026-03-10T22:09:49.221Z,1773180589.221 [NAL9602](INFO): Sent 73 bytes from file Logs/20260310T211428/Courier0030.lzma
2026-03-10T22:09:49.222Z,1773180589.222 [NAL9602](INFO): Packets left to send: 0
2026-03-10T22:09:56.621Z,1773180596.621 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T22:09:57.274Z,1773180597.274 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003544
2026-03-10T22:10:01.064Z,1773180601.064 [NAL9602](INFO): SBD MO Status=1, MOMSN=12811, MT Status=0, MTMSN=0
2026-03-10T22:10:01.137Z,1773180601.137 [NAL9602](INFO): Sent 332 bytes from file Logs/20260310T211428/Express0031.lzma
2026-03-10T22:10:01.137Z,1773180601.137 [NAL9602](INFO): Packets left to send: 1
2026-03-10T22:10:09.241Z,1773180609.241 [DataOverHttps](INFO): Sending 431 bytes from file Logs/20260310T211428/Express0031.lzma
2026-03-10T22:10:10.311Z,1773180610.311 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0031.lzma.bak
2026-03-10T22:10:10.312Z,1773180610.312 [DataOverHttps](INFO): SBD MOMSN=26709507
2026-03-10T22:10:11.605Z,1773180611.605 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2026-03-10T22:10:11.606Z,1773180611.606 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2026-03-10T22:10:11.606Z,1773180611.606 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:10:31.785Z,1773180631.785 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T22:14:34.917Z,1773180874.917 [CommandExec](IMPORTANT): got command stop
2026-03-10T22:14:34.917Z,1773180874.917 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2026-03-10T22:14:35.084Z,1773180875.084 [MissionManager](INFO): MissionManager is completed.
2026-03-10T22:14:35.085Z,1773180875.085 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface] Stopped
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:BackseatDriver] Stopped
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:PowerOnly] Stopped
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::uninitialize run_backseat_on_surface:PowerOnly
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:PowerOnly:E.Wait] Stopped
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:14:35.085Z,1773180875.085 [run_backseat_on_surface:D.GoToSurface] Stopped
2026-03-10T22:14:35.086Z,1773180875.086 [run_backseat_on_surface:D.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-10T22:14:35.086Z,1773180875.086 [run_backseat_on_surface:CheckIn] Stopped
2026-03-10T22:14:35.086Z,1773180875.086 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2026-03-10T22:14:35.086Z,1773180875.086 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2026-03-10T22:14:35.086Z,1773180875.086 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:14:35.494Z,1773180875.494 [MissionManager](IMPORTANT): Started mission Default
2026-03-10T22:14:35.495Z,1773180875.495 [Default] Running Loop=1
2026-03-10T22:14:35.495Z,1773180875.495 [Default](DEBUG): Aggregate::initialize Default
2026-03-10T22:14:35.495Z,1773180875.495 [Default:B.GoToSurface] Running Loop=1
2026-03-10T22:14:35.495Z,1773180875.495 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-10T22:14:35.495Z,1773180875.495 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-10T22:14:35.495Z,1773180875.495 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-10T22:14:35.496Z,1773180875.496 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-10T22:14:35.497Z,1773180875.497 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-10T22:14:35.497Z,1773180875.497 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-10T22:14:35.498Z,1773180875.498 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-10T22:14:35.498Z,1773180875.498 [Default:A.Wait] Running Loop=1
2026-03-10T22:14:35.498Z,1773180875.498 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:14:36.102Z,1773180876.102 [BackseatComponent](INFO): Sent LCM shutdown request.
2026-03-10T22:14:36.102Z,1773180876.102 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2026-03-10T22:14:48.880Z,1773180888.880 [Default:A.Wait](INFO): Done Waiting.
2026-03-10T22:14:48.880Z,1773180888.880 [Default:A.Wait] Stopped
2026-03-10T22:14:48.880Z,1773180888.880 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:14:49.221Z,1773180889.221 [Default:CheckIn] Running Loop=1
2026-03-10T22:14:49.221Z,1773180889.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T22:14:49.221Z,1773180889.221 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T22:14:51.231Z,1773180891.231 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221450.00,A,3648.15193,N,12147.22115,W,0.019,307.42,100326,,,A*72
2026-03-10T22:14:51.242Z,1773180891.242 [NAL9602](INFO): GPS fix at 20260310T221450: (36.802532, -121.787019)
2026-03-10T22:14:51.252Z,1773180891.252 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T22:14:51.253Z,1773180891.253 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T22:14:54.261Z,1773180894.261 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2026-03-10T22:14:54.261Z,1773180894.261 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2026-03-10T22:14:54.262Z,1773180894.262 [LcmPublisher](INFO): Deactivating messaging.
2026-03-10T22:14:59.056Z,1773180899.056 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260310T211428/Courier0033.lzma
2026-03-10T22:15:00.058Z,1773180900.058 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0033.lzma.bak
2026-03-10T22:15:00.058Z,1773180900.058 [DataOverHttps](INFO): SBD MOMSN=26709663
2026-03-10T22:15:15.829Z,1773180915.829 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20260310T211428/Express0034.lzma
2026-03-10T22:15:16.830Z,1773180916.830 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0034.lzma.bak
2026-03-10T22:15:16.831Z,1773180916.831 [DataOverHttps](INFO): SBD MOMSN=26709667
2026-03-10T22:15:18.311Z,1773180918.311 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T22:15:18.312Z,1773180918.312 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T22:15:18.312Z,1773180918.312 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:15:52.234Z,1773180952.234 [NAL9602](INFO): SBD MO Status=0, MOMSN=12812, MT Status=0, MTMSN=0
2026-03-10T22:15:52.234Z,1773180952.234 [NAL9602](INFO): No messages in MT queue
2026-03-10T22:16:22.936Z,1773180982.936 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T22:17:34.337Z,1773181054.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-10T22:17:37.349Z,1773181057.349 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239414
2026-03-10T22:17:46.697Z,1773181066.697 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003136
2026-03-10T22:19:22.505Z,1773181162.505 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.271240
2026-03-10T22:19:32.961Z,1773181172.961 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003131
2026-03-10T22:19:56.660Z,1773181196.660 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-03-10T22:20:18.898Z,1773181218.898 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-10T22:20:18.898Z,1773181218.898 [Default:CheckIn:C.Wait] Stopped
2026-03-10T22:20:18.898Z,1773181218.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:20:18.898Z,1773181218.898 [Default:CheckIn:D] Running Loop=1
2026-03-10T22:20:19.291Z,1773181219.291 [Default:CheckIn:D] Stopped
2026-03-10T22:20:19.291Z,1773181219.291 [Default:CheckIn:E] Running Loop=1
2026-03-10T22:20:19.728Z,1773181219.728 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  5.729937 min 
2026-03-10T22:20:19.728Z,1773181219.728 [Default:CheckIn:E] Stopped
2026-03-10T22:20:19.728Z,1773181219.728 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-10T22:20:19.728Z,1773181219.728 [Default:CheckIn] Stopped
2026-03-10T22:20:19.729Z,1773181219.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T22:20:19.729Z,1773181219.729 [Default:CheckIn](INFO): Running loop #2
2026-03-10T22:20:19.729Z,1773181219.729 [Default:CheckIn] Running Loop=2
2026-03-10T22:20:19.729Z,1773181219.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-10T22:20:19.729Z,1773181219.729 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-10T22:20:21.705Z,1773181221.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222020.00,A,3648.15146,N,12147.22086,W,0.039,307.42,100326,,,D*76
2026-03-10T22:20:21.707Z,1773181221.707 [NAL9602](INFO): GPS fix at 20260310T222020: (36.802524, -121.787014)
2026-03-10T22:20:21.718Z,1773181221.718 [Default:CheckIn:Read_GPS] Stopped
2026-03-10T22:20:21.718Z,1773181221.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-10T22:20:28.944Z,1773181228.944 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20260310T211428/Courier0036.lzma
2026-03-10T22:20:29.946Z,1773181229.946 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Courier0036.lzma.bak
2026-03-10T22:20:29.946Z,1773181229.946 [DataOverHttps](INFO): SBD MOMSN=26709678
2026-03-10T22:20:45.551Z,1773181245.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=12813, MT Status=2, MTMSN=0
2026-03-10T22:20:45.551Z,1773181245.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-10T22:20:45.796Z,1773181245.796 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20260310T211428/Express0037.lzma
2026-03-10T22:20:46.798Z,1773181246.798 [DataOverHttps](INFO): Moved sent file to Logs/20260310T211428/Express0037.lzma.bak
2026-03-10T22:20:46.798Z,1773181246.798 [DataOverHttps](INFO): SBD MOMSN=26709681
2026-03-10T22:20:47.981Z,1773181247.981 [Default:CheckIn:Read_Iridium] Stopped
2026-03-10T22:20:47.981Z,1773181247.981 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-10T22:20:47.981Z,1773181247.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-10T22:21:20.288Z,1773181280.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=12813, MT Status=2, MTMSN=0
2026-03-10T22:21:20.288Z,1773181280.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-10T22:21:50.184Z,1773181310.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=12813, MT Status=2, MTMSN=0
2026-03-10T22:21:50.184Z,1773181310.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-10T22:22:08.367Z,1773181328.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=12813, MT Status=2, MTMSN=0
2026-03-10T22:22:08.367Z,1773181328.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-10T22:22:34.643Z,1773181354.643 [NAL9602](INFO): SBD MO Status=0, MOMSN=12813, MT Status=0, MTMSN=0
2026-03-10T22:22:34.644Z,1773181354.644 [NAL9602](INFO): No messages in MT queue
2026-03-10T22:23:01.534Z,1773181381.534 [CommandExec](IMPORTANT): got command restart application
2026-03-10T22:23:02.536Z,1773181382.536 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:02.537Z,1773181382.537 [CommandExec](INFO): Uninitializing the command executive.
2026-03-10T22:23:02.537Z,1773181382.537 [CommandExec](INFO): Uninitializing the command scheduler.
2026-03-10T22:23:02.537Z,1773181382.537 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:02.620Z,1773181382.620 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2026-03-10T22:23:02.620Z,1773181382.620 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2026-03-10T22:23:02.621Z,1773181382.621 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:02.621Z,1773181382.621 [NavChartDb](INFO): Join timeout helper Thread ID is 1063
2026-03-10T22:23:03.009Z,1773181383.009 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:03.009Z,1773181383.009 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:03.025Z,1773181383.025 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2026-03-10T22:23:03.025Z,1773181383.025 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:03.025Z,1773181383.025 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1064
2026-03-10T22:23:03.493Z,1773181383.493 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:03.493Z,1773181383.493 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:23:03.494Z,1773181383.494 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:03.500Z,1773181383.500 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2026-03-10T22:23:03.500Z,1773181383.500 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:03.501Z,1773181383.501 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1065
2026-03-10T22:23:04.461Z,1773181384.461 [CTD_Seabird](INFO): Powering down
2026-03-10T22:23:04.472Z,1773181384.472 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:04.473Z,1773181384.473 [CTD_Seabird](INFO): Powering down
2026-03-10T22:23:04.487Z,1773181384.487 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.504Z,1773181384.504 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2026-03-10T22:23:04.505Z,1773181384.505 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.505Z,1773181384.505 [Radio_Surface](INFO): Join timeout helper Thread ID is 1066
2026-03-10T22:23:04.641Z,1773181384.641 [Radio_Surface](INFO): Powering down
2026-03-10T22:23:04.641Z,1773181384.641 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:04.642Z,1773181384.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.656Z,1773181384.656 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2026-03-10T22:23:04.656Z,1773181384.656 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.657Z,1773181384.657 [Onboard](INFO): Join timeout helper Thread ID is 1067
2026-03-10T22:23:04.797Z,1773181384.797 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:04.797Z,1773181384.797 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.818Z,1773181384.818 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2026-03-10T22:23:04.818Z,1773181384.818 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:04.818Z,1773181384.818 [DataOverHttps](INFO): Join timeout helper Thread ID is 1068
2026-03-10T22:23:05.144Z,1773181385.144 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.145Z,1773181385.145 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.163Z,1773181385.163 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2026-03-10T22:23:05.163Z,1773181385.163 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.164Z,1773181385.164 [DAT](INFO): Join timeout helper Thread ID is 1069
2026-03-10T22:23:05.320Z,1773181385.320 [DAT](INFO): Powering down
2026-03-10T22:23:05.338Z,1773181385.338 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-10T22:23:05.393Z,1773181385.393 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.393Z,1773181385.393 [DAT](INFO): Powering down
2026-03-10T22:23:05.394Z,1773181385.394 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.401Z,1773181385.401 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2026-03-10T22:23:05.401Z,1773181385.401 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.402Z,1773181385.402 [BackseatComponent](INFO): Join timeout helper Thread ID is 1070
2026-03-10T22:23:05.517Z,1773181385.517 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.517Z,1773181385.517 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.533Z,1773181385.533 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2026-03-10T22:23:05.533Z,1773181385.533 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.534Z,1773181385.534 [logger](INFO): Join timeout helper Thread ID is 1071
2026-03-10T22:23:05.565Z,1773181385.565 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.565Z,1773181385.565 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.577Z,1773181385.577 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2026-03-10T22:23:05.577Z,1773181385.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.578Z,1773181385.578 [CommandLine](INFO): Join timeout helper Thread ID is 1072
2026-03-10T22:23:05.605Z,1773181385.605 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.605Z,1773181385.605 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.617Z,1773181385.617 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2026-03-10T22:23:05.617Z,1773181385.617 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.618Z,1773181385.618 [CommandExec](INFO): Join timeout helper Thread ID is 1073
2026-03-10T22:23:05.619Z,1773181385.619 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2026-03-10T22:23:05.619Z,1773181385.619 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:05.619Z,1773181385.619 [controlThread](INFO): Join timeout helper Thread ID is 1074
2026-03-10T22:23:05.724Z,1773181385.724 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-10T22:23:05.725Z,1773181385.725 [controlThread](DEBUG): Uninitializing ControlThread
2026-03-10T22:23:05.725Z,1773181385.725 [AHRS_M2](INFO): Powering down
2026-03-10T22:23:05.805Z,1773181385.805 [NAL9602](INFO): Powering down
2026-03-10T22:23:05.806Z,1773181385.806 [Sonardyne_Nano](INFO): Powering down
2026-03-10T22:23:06.013Z,1773181386.013 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2026-03-10T22:23:06.014Z,1773181386.014 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2026-03-10T22:23:06.014Z,1773181386.014 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2026-03-10T22:23:06.015Z,1773181386.015 [MissionManager](INFO): Uninitializing Mission Default
2026-03-10T22:23:06.015Z,1773181386.015 [Default] Stopped
2026-03-10T22:23:06.015Z,1773181386.015 [Default](DEBUG): Aggregate::uninitialize Default
2026-03-10T22:23:06.015Z,1773181386.015 [Default:B.GoToSurface] Stopped
2026-03-10T22:23:06.015Z,1773181386.015 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-10T22:23:06.015Z,1773181386.015 [Default:CheckIn] Stopped
2026-03-10T22:23:06.015Z,1773181386.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-10T22:23:06.015Z,1773181386.015 [Default:CheckIn:C.Wait] Stopped
2026-03-10T22:23:06.015Z,1773181386.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-10T22:23:06.018Z,1773181386.018 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2026-03-10T22:23:06.018Z,1773181386.018 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2026-03-10T22:23:06.019Z,1773181386.019 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2026-03-10T22:23:06.019Z,1773181386.019 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2026-03-10T22:23:06.019Z,1773181386.019 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2026-03-10T22:23:06.019Z,1773181386.019 [BuoyancyServo](INFO): Powering down
2026-03-10T22:23:06.032Z,1773181386.032 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2026-03-10T22:23:06.033Z,1773181386.033 [ElevatorServo](INFO): Powering down
2026-03-10T22:23:06.033Z,1773181386.033 [MassServo](DEBUG): Uninitialize Mass Servo.
2026-03-10T22:23:06.033Z,1773181386.033 [MassServo](INFO): Powering down
2026-03-10T22:23:06.034Z,1773181386.034 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2026-03-10T22:23:06.034Z,1773181386.034 [RudderServo](INFO): Powering down
2026-03-10T22:23:06.035Z,1773181386.035 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2026-03-10T22:23:06.035Z,1773181386.035 [ThrusterHE](INFO): Powering down
2026-03-10T22:23:06.036Z,1773181386.036 [SBIT](DEBUG): Uninitialize SBIT Component.
2026-03-10T22:23:06.037Z,1773181386.037 [IBIT](DEBUG): Uninitialize IBIT Component.
2026-03-10T22:23:06.037Z,1773181386.037 [CBIT](DEBUG): Uninitialize CBIT Component.
2026-03-10T22:23:06.037Z,1773181386.037 [CBIT](DEBUG): Powering off loads.
2026-03-10T22:23:06.048Z,1773181386.048 [CBIT](DEBUG): Disabling WDT.
2026-03-10T22:23:06.061Z,1773181386.061 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-10T22:23:06.061Z,1773181386.061 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-10T22:23:06.062Z,1773181386.062 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.137Z,1773181386.137 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.141Z,1773181386.141 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.147Z,1773181386.147 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.154Z,1773181386.154 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.196Z,1773181386.196 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.240Z,1773181386.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.245Z,1773181386.245 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.264Z,1773181386.264 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-10T22:23:06.265Z,1773181386.265 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-10T22:23:06.280Z,1773181386.280 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-10T22:23:06.336Z,1773181386.336 [logger ThreadHandler](INFO): Thread cancelled.