2021-09-01T17:06:11.236Z,1630515971.236 [CommandExec](IMPORTANT): got command restart logs 2021-09-01T17:06:23.092Z,1630515983.092 [NAL9602](INFO): Powering up NAL9602 2021-09-01T17:06:25.127Z,1630515985.127 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2021-09-01T17:06:25.127Z,1630515985.127 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:06:25.138Z,1630515985.138 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:06:25.555Z,1630515985.555 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:06:25.556Z,1630515985.556 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2021-09-01T17:06:34.004Z,1630515994.004 [NAL9602](INFO): NAL9602 initialized 2021-09-01T17:09:26.127Z,1630516166.127 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2021-09-01T17:09:26.127Z,1630516166.127 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:09:26.139Z,1630516166.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:09:26.548Z,1630516166.548 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:09:26.548Z,1630516166.548 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2021-09-01T17:11:04.326Z,1630516264.326 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-09-01T17:06:04.1Z 2021-09-01T17:11:04.326Z,1630516264.326 [Default:CheckIn:Read_GPS] Stopped 2021-09-01T17:11:04.327Z,1630516264.327 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-09-01T17:11:09.009Z,1630516269.009 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210901T155953/Courier0022.lzma 2021-09-01T17:11:10.016Z,1630516270.016 [DataOverHttps](INFO): Moved sent file to Logs/20210901T155953/Courier0022.lzma.bak 2021-09-01T17:11:10.016Z,1630516270.016 [DataOverHttps](INFO): SBD MOMSN=15906271 2021-09-01T17:11:33.684Z,1630516293.684 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210901T170611/Courier0000.lzma 2021-09-01T17:11:34.548Z,1630516294.548 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Courier0000.lzma.bak 2021-09-01T17:11:34.548Z,1630516294.548 [DataOverHttps](INFO): SBD MOMSN=15906273 2021-09-01T17:11:52.502Z,1630516312.502 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210901T170611/Courier0003.lzma 2021-09-01T17:11:53.504Z,1630516313.504 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Courier0003.lzma.bak 2021-09-01T17:11:53.505Z,1630516313.505 [DataOverHttps](INFO): SBD MOMSN=15906276 2021-09-01T17:12:11.654Z,1630516331.654 [DataOverHttps](INFO): Sending 1646 bytes from file Logs/20210901T155953/Express0023.lzma 2021-09-01T17:12:12.652Z,1630516332.652 [DataOverHttps](INFO): Moved sent file to Logs/20210901T155953/Express0023.lzma.bak 2021-09-01T17:12:12.652Z,1630516332.652 [DataOverHttps](INFO): SBD MOMSN=15906278 2021-09-01T17:12:27.119Z,1630516347.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2021-09-01T17:12:27.119Z,1630516347.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:12:27.171Z,1630516347.171 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:12:27.536Z,1630516347.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:12:27.536Z,1630516347.536 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2021-09-01T17:12:32.022Z,1630516352.022 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20210901T170611/Express0001.lzma 2021-09-01T17:12:33.024Z,1630516353.024 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Express0001.lzma.bak 2021-09-01T17:12:33.024Z,1630516353.024 [DataOverHttps](INFO): SBD MOMSN=15906348 2021-09-01T17:12:50.490Z,1630516370.490 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210901T170611/Express0004.lzma 2021-09-01T17:12:51.492Z,1630516371.492 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Express0004.lzma.bak 2021-09-01T17:12:51.492Z,1630516371.492 [DataOverHttps](INFO): SBD MOMSN=15906351 2021-09-01T17:12:54.141Z,1630516374.141 [Default:CheckIn:Read_Iridium] Stopped 2021-09-01T17:12:54.142Z,1630516374.142 [Default:CheckIn:C.Wait] Running Loop=1 2021-09-01T17:12:54.142Z,1630516374.142 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-09-01T17:14:57.051Z,1630516497.051 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-09-01T17:14:57.054Z,1630516497.054 [BPC1](INFO): Received data from all battery sticks. 2021-09-01T17:15:28.164Z,1630516528.164 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2021-09-01T17:15:28.164Z,1630516528.164 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:15:28.177Z,1630516528.177 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:15:28.583Z,1630516528.583 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:15:28.583Z,1630516528.583 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2021-09-01T17:16:36.400Z,1630516596.400 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-09-01T17:16:36.400Z,1630516596.400 [NAL9602] Data Fault, FailCount= 2 2021-09-01T17:16:36.400Z,1630516596.400 [NAL9602](ERROR): Data Fault 2021-09-01T17:16:36.418Z,1630516596.418 [CBIT](ERROR): Data Fault in component: NAL9602 2021-09-01T17:16:36.814Z,1630516596.814 [NAL9602](INFO): Powering down 2021-09-01T17:16:37.634Z,1630516597.634 [CBIT](INFO): Clearing failed state for component NAL9602 2021-09-01T17:16:37.634Z,1630516597.634 [NAL9602] No Fault, FailCount= 2 2021-09-01T17:17:07.112Z,1630516627.112 [NAL9602](INFO): Powering up NAL9602 2021-09-01T17:17:18.020Z,1630516638.020 [NAL9602](INFO): NAL9602 initialized 2021-09-01T17:17:54.797Z,1630516674.797 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-09-01T17:17:54.797Z,1630516674.797 [Default:CheckIn:C.Wait] Stopped 2021-09-01T17:17:54.797Z,1630516674.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-09-01T17:17:54.798Z,1630516674.798 [Default:CheckIn:D] Running Loop=1 2021-09-01T17:17:55.214Z,1630516675.214 [Default:CheckIn:D] Stopped 2021-09-01T17:17:55.214Z,1630516675.214 [Default:CheckIn:E] Running Loop=1 2021-09-01T17:17:55.649Z,1630516675.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.080139 min 2021-09-01T17:17:55.649Z,1630516675.649 [Default:CheckIn:E] Stopped 2021-09-01T17:17:55.649Z,1630516675.649 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-09-01T17:17:55.649Z,1630516675.649 [Default:CheckIn] Stopped 2021-09-01T17:17:55.650Z,1630516675.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-09-01T17:17:55.650Z,1630516675.650 [Default:CheckIn](INFO): Running loop #2 2021-09-01T17:17:55.650Z,1630516675.650 [Default:CheckIn] Running Loop=2 2021-09-01T17:17:55.650Z,1630516675.650 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-09-01T17:17:55.650Z,1630516675.650 [Default:CheckIn:Read_GPS] Running Loop=1 2021-09-01T17:18:29.167Z,1630516709.167 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26 2021-09-01T17:18:29.167Z,1630516709.167 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:18:29.215Z,1630516709.215 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:18:29.573Z,1630516709.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:18:29.573Z,1630516709.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26 2021-09-01T17:21:30.156Z,1630516890.156 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27 2021-09-01T17:21:30.156Z,1630516890.156 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-01T17:21:30.168Z,1630516890.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-01T17:21:30.636Z,1630516890.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-01T17:21:30.636Z,1630516890.636 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27 2021-09-01T17:22:26.963Z,1630516946.963 [CommandExec](IMPORTANT): got command report touch CommandExec.durationOfLastRun 2021-09-01T17:22:27.120Z,1630516947.120 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:27.528Z,1630516947.528 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:27.915Z,1630516947.915 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:28.365Z,1630516948.365 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:28.750Z,1630516948.750 [Reporter](INFO): CommandExec.durationOfLastRun 0.000089 s 2021-09-01T17:22:29.145Z,1630516949.145 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:29.526Z,1630516949.526 [Reporter](INFO): CommandExec.durationOfLastRun 0.000076 s 2021-09-01T17:22:29.933Z,1630516949.933 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:30.345Z,1630516950.345 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:30.758Z,1630516950.758 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:31.174Z,1630516951.174 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:31.585Z,1630516951.585 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:31.980Z,1630516951.980 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:32.377Z,1630516952.377 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:32.762Z,1630516952.762 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:33.168Z,1630516953.168 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:33.577Z,1630516953.577 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:33.992Z,1630516953.992 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:34.412Z,1630516954.412 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:34.821Z,1630516954.821 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:35.235Z,1630516955.235 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:35.649Z,1630516955.649 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:22:36.029Z,1630516956.029 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:36.444Z,1630516956.444 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:36.847Z,1630516956.847 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:37.256Z,1630516957.256 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:22:37.650Z,1630516957.650 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:38.140Z,1630516958.140 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:22:38.428Z,1630516958.428 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:38.836Z,1630516958.836 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:39.245Z,1630516959.245 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:39.628Z,1630516959.628 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:40.036Z,1630516960.036 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:40.465Z,1630516960.465 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:40.871Z,1630516960.871 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:41.300Z,1630516961.300 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:41.658Z,1630516961.658 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:42.060Z,1630516962.060 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:42.473Z,1630516962.473 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:42.859Z,1630516962.859 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:43.296Z,1630516963.296 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:43.688Z,1630516963.688 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:44.098Z,1630516964.098 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:44.520Z,1630516964.520 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:22:44.884Z,1630516964.884 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:45.288Z,1630516965.288 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:45.701Z,1630516965.701 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:46.114Z,1630516966.114 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:46.534Z,1630516966.534 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:46.946Z,1630516966.946 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:47.326Z,1630516967.326 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:47.753Z,1630516967.753 [Reporter](INFO): CommandExec.durationOfLastRun 0.000293 s 2021-09-01T17:22:48.115Z,1630516968.115 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:48.520Z,1630516968.520 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:48.925Z,1630516968.925 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:49.343Z,1630516969.343 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:49.756Z,1630516969.756 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:50.176Z,1630516970.176 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:50.539Z,1630516970.539 [Reporter](INFO): CommandExec.durationOfLastRun 0.000078 s 2021-09-01T17:22:50.953Z,1630516970.953 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:51.369Z,1630516971.369 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:51.755Z,1630516971.755 [Reporter](INFO): CommandExec.durationOfLastRun 0.000212 s 2021-09-01T17:22:52.177Z,1630516972.177 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:52.577Z,1630516972.577 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:53.013Z,1630516973.013 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:53.366Z,1630516973.366 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:22:53.771Z,1630516973.771 [Reporter](INFO): CommandExec.durationOfLastRun 0.000075 s 2021-09-01T17:22:54.184Z,1630516974.184 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:54.601Z,1630516974.601 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:22:54.983Z,1630516974.983 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:55.409Z,1630516975.409 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:22:55.794Z,1630516975.794 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-09-01T17:17:55.7Z 2021-09-01T17:22:55.794Z,1630516975.794 [Default:CheckIn:Read_GPS] Stopped 2021-09-01T17:22:55.795Z,1630516975.795 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-09-01T17:22:55.805Z,1630516975.805 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:56.223Z,1630516976.223 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:56.599Z,1630516976.599 [Reporter](INFO): CommandExec.durationOfLastRun 0.000076 s 2021-09-01T17:22:57.079Z,1630516977.079 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:22:57.437Z,1630516977.437 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:22:57.812Z,1630516977.812 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:58.229Z,1630516978.229 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:22:58.696Z,1630516978.696 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:22:59.065Z,1630516979.065 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:22:59.471Z,1630516979.471 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:22:59.853Z,1630516979.853 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:00.308Z,1630516980.308 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:23:00.664Z,1630516980.664 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:23:01.085Z,1630516981.085 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:23:01.500Z,1630516981.500 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:01.901Z,1630516981.901 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:23:02.285Z,1630516982.285 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:02.656Z,1630516982.656 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:03.076Z,1630516983.076 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:03.150Z,1630516983.150 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210901T170611/Courier0006.lzma 2021-09-01T17:23:03.498Z,1630516983.498 [Reporter](INFO): CommandExec.durationOfLastRun 0.000088 s 2021-09-01T17:23:03.896Z,1630516983.896 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:23:04.153Z,1630516984.153 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Courier0006.lzma.bak 2021-09-01T17:23:04.154Z,1630516984.154 [DataOverHttps](IMPORTANT): SBD MOMSN=15906354, MTMSN=20210901T172303 2021-09-01T17:23:04.286Z,1630516984.286 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:04.725Z,1630516984.725 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:05.093Z,1630516985.093 [Reporter](INFO): CommandExec.durationOfLastRun 0.000077 s 2021-09-01T17:23:05.553Z,1630516985.553 [Reporter](INFO): CommandExec.durationOfLastRun 0.000088 s 2021-09-01T17:23:05.936Z,1630516985.936 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:06.305Z,1630516986.305 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:06.760Z,1630516986.760 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:23:07.128Z,1630516987.128 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:23:07.536Z,1630516987.536 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:07.921Z,1630516987.921 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:23:08.340Z,1630516988.340 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:08.751Z,1630516988.751 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:23:09.195Z,1630516989.195 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:09.577Z,1630516989.577 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:23:10.000Z,1630516990.000 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:23:10.385Z,1630516990.385 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:10.776Z,1630516990.776 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:23:11.184Z,1630516991.184 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:11.602Z,1630516991.602 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:11.875Z,1630516991.875 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.xml;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 15 min;set keepstation.Latitude 36.807 arcdeg;set keepstation.Longitude -121.82436 arcdeg;set keepstation.Depth 20 m" 3otlu 1 2 2021-09-01T17:23:11.943Z,1630516991.943 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.xml;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 15 min;set keepstation.Latitude 36.807 arcdeg;set keepstation.Longitude -121.82436 arcdeg;set keepstation.Depth 20 m" 3otlu 1 2.000000 2021-09-01T17:23:11.943Z,1630516991.943 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3otlu 2021-09-01T17:23:11.945Z,1630516991.945 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 2 with id='3otlu'): "load Transport/keepstation.xml;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 15 min;set keepstation.Latitude 36.807 arcdeg;set keepstation.Longitude -121.82436 arcdeg;set keepstation.Depth 20 m", ASAP 2021-09-01T17:23:12.019Z,1630516992.019 [Reporter](INFO): CommandExec.durationOfLastRun 0.000095 s 2021-09-01T17:23:12.355Z,1630516992.355 [Reporter](INFO): CommandExec.durationOfLastRun 0.000096 s 2021-09-01T17:23:12.764Z,1630516992.764 [Reporter](INFO): CommandExec.durationOfLastRun 0.000099 s 2021-09-01T17:23:13.088Z,1630516993.088 [DataOverHttps](IMPORTANT): SBD MTMSN=20210901T172311 2021-09-01T17:23:13.203Z,1630516993.203 [Reporter](INFO): CommandExec.durationOfLastRun 0.000098 s 2021-09-01T17:23:13.588Z,1630516993.588 [Reporter](INFO): CommandExec.durationOfLastRun 0.000096 s 2021-09-01T17:23:13.972Z,1630516993.972 [Reporter](INFO): CommandExec.durationOfLastRun 0.000096 s 2021-09-01T17:23:14.397Z,1630516994.397 [Reporter](INFO): CommandExec.durationOfLastRun 0.000098 s 2021-09-01T17:23:14.794Z,1630516994.794 [Reporter](INFO): CommandExec.durationOfLastRun 0.000097 s 2021-09-01T17:23:15.211Z,1630516995.211 [Reporter](INFO): CommandExec.durationOfLastRun 0.000100 s 2021-09-01T17:23:15.587Z,1630516995.587 [Reporter](INFO): CommandExec.durationOfLastRun 0.000100 s 2021-09-01T17:23:16.058Z,1630516996.058 [Reporter](INFO): CommandExec.durationOfLastRun 0.000092 s 2021-09-01T17:23:16.420Z,1630516996.420 [Reporter](INFO): CommandExec.durationOfLastRun 0.000094 s 2021-09-01T17:23:16.835Z,1630516996.835 [Reporter](INFO): CommandExec.durationOfLastRun 0.000099 s 2021-09-01T17:23:17.247Z,1630516997.247 [Reporter](INFO): CommandExec.durationOfLastRun 0.000096 s 2021-09-01T17:23:17.660Z,1630516997.660 [Reporter](INFO): CommandExec.durationOfLastRun 0.000097 s 2021-09-01T17:23:18.077Z,1630516998.077 [Reporter](INFO): CommandExec.durationOfLastRun 0.000097 s 2021-09-01T17:23:18.447Z,1630516998.447 [Reporter](INFO): CommandExec.durationOfLastRun 0.000097 s 2021-09-01T17:23:18.851Z,1630516998.851 [Reporter](INFO): CommandExec.durationOfLastRun 0.000098 s 2021-09-01T17:23:19.269Z,1630516999.269 [Reporter](INFO): CommandExec.durationOfLastRun 0.000097 s 2021-09-01T17:23:19.682Z,1630516999.682 [Reporter](INFO): CommandExec.durationOfLastRun 0.000099 s 2021-09-01T17:23:20.057Z,1630517000.057 [Reporter](INFO): CommandExec.durationOfLastRun 0.000094 s 2021-09-01T17:23:20.477Z,1630517000.477 [Reporter](INFO): CommandExec.durationOfLastRun 0.000099 s 2021-09-01T17:23:20.519Z,1630517000.519 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20210901T170611/Express0007.lzma 2021-09-01T17:23:20.521Z,1630517000.521 [DataOverHttps](INFO): Received command: sched asap "set keepstation.DepthDeadband 10 m;set keepstation.Radius 200 m;set keepstation.MaxDepth 33 m;run" 3otlu 2 2 2021-09-01T17:23:20.526Z,1630517000.526 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.DepthDeadband 10 m;set keepstation.Radius 200 m;set keepstation.MaxDepth 33 m;run" 3otlu 2 2.000000 2021-09-01T17:23:20.526Z,1630517000.526 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3otlu 2021-09-01T17:23:20.527Z,1630517000.527 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 2 with id='3otlu'): "set keepstation.DepthDeadband 10 m;set keepstation.Radius 200 m;set keepstation.MaxDepth 33 m;run", ASAP 2021-09-01T17:23:20.592Z,1630517000.592 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.xml 2021-09-01T17:23:20.593Z,1630517000.593 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation.xml 2021-09-01T17:23:20.684Z,1630517000.684 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2021-09-01T17:23:20.687Z,1630517000.687 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2021-09-01T17:23:20.691Z,1630517000.691 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2021-09-01T17:23:20.695Z,1630517000.695 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2021-09-01T17:23:20.698Z,1630517000.698 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2021-09-01T17:23:20.701Z,1630517000.701 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2021-09-01T17:23:20.725Z,1630517000.725 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2021-09-01T17:23:20.729Z,1630517000.729 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2021-09-01T17:23:20.761Z,1630517000.761 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2021-09-01T17:23:20.765Z,1630517000.765 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2021-09-01T17:23:20.782Z,1630517000.782 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2021-09-01T17:23:20.786Z,1630517000.786 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2021-09-01T17:23:20.919Z,1630517000.919 [Reporter](INFO): CommandExec.durationOfLastRun 0.005636 s 2021-09-01T17:23:20.936Z,1630517000.936 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h 2021-09-01T17:23:20.940Z,1630517000.940 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2021-09-01T17:23:20.963Z,1630517000.963 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2021-09-01T17:23:20.966Z,1630517000.966 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2021-09-01T17:23:20.976Z,1630517000.976 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2021-09-01T17:23:20.979Z,1630517000.979 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2021-09-01T17:23:20.982Z,1630517000.982 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2021-09-01T17:23:21.001Z,1630517001.001 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-09-01T17:23:21.048Z,1630517001.048 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2021-09-01T17:23:21.097Z,1630517001.097 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2021-09-01T17:23:21.100Z,1630517001.100 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2021-09-01T17:23:21.117Z,1630517001.117 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2021-09-01T17:23:21.118Z,1630517001.118 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2021-09-01T17:23:21.146Z,1630517001.146 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2021-09-01T17:23:21.171Z,1630517001.171 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2021-09-01T17:23:21.205Z,1630517001.205 [keepstation:F.Pitch](DEBUG): Construct. 2021-09-01T17:23:21.221Z,1630517001.221 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2021-09-01T17:23:21.225Z,1630517001.225 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2021-09-01T17:23:21.267Z,1630517001.267 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2021-09-01T17:23:21.270Z,1630517001.270 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2021-09-01T17:23:21.305Z,1630517001.305 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2021-09-01T17:23:21.323Z,1630517001.323 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2021-09-01T17:23:21.332Z,1630517001.332 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2021-09-01T17:23:21.387Z,1630517001.387 [MissionManager](DEBUG): Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. Maximum duration of mission 4 How often to surface for communications 45 Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. NaN Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. NaN Depth held during drift mode 30 Depth for initial approach to station. 15 How much vertical drift from the specified depth is allowed in drift mode 4 Vehicle transit speed. 0.75 Radius of circle around waypoint to hold. 200 Maximum allowable depth during the mission. 45 Minimum distance from the shoreline to maintain 2 Need a separate aggregate for transit if we want to specify a different depth for the approach. Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. 2021-09-01T17:23:21.387Z,1630517001.387 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.xml 2021-09-01T17:23:21.992Z,1630517001.992 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Express0007.lzma.bak 2021-09-01T17:23:21.992Z,1630517001.992 [DataOverHttps](INFO): SBD MOMSN=15906357 2021-09-01T17:23:38.768Z,1630517018.768 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 1.000000 hour 2021-09-01T17:23:38.769Z,1630517018.769 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 15.000000 minute 2021-09-01T17:23:38.769Z,1630517018.769 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.806999 angular_degree 2021-09-01T17:23:38.770Z,1630517018.770 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.824364 angular_degree 2021-09-01T17:23:38.775Z,1630517018.775 [CommandExec](IMPORTANT): got command set keepstation.Depth 20.000000 meter 2021-09-01T17:23:39.112Z,1630517019.112 [Reporter](INFO): CommandExec.durationOfLastRun 18.200684 s 2021-09-01T17:23:45.422Z,1630517025.422 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210901T170611/Courier0009.lzma 2021-09-01T17:23:46.424Z,1630517026.424 [DataOverHttps](INFO): Moved sent file to Logs/20210901T170611/Courier0009.lzma.bak 2021-09-01T17:23:46.424Z,1630517026.424 [DataOverHttps](INFO): SBD MOMSN=15906363 2021-09-01T17:23:48.247Z,1630517028.247 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband 10.000000 meter 2021-09-01T17:23:48.248Z,1630517028.248 [CommandExec](IMPORTANT): got command set keepstation.Radius 200.000000 meter 2021-09-01T17:23:48.249Z,1630517028.249 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 33.000000 meter 2021-09-01T17:23:48.249Z,1630517028.249 [CommandExec](IMPORTANT): got command run 2021-09-01T17:23:48.290Z,1630517028.290 [CommandExec](IMPORTANT): Running 2021-09-01T17:23:48.338Z,1630517028.338 [Default] Stopped 2021-09-01T17:23:48.338Z,1630517028.338 [Default](DEBUG): Aggregate::uninitialize Default 2021-09-01T17:23:48.338Z,1630517028.338 [Default:B.GoToSurface] Stopped 2021-09-01T17:23:48.338Z,1630517028.338 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-09-01T17:23:48.338Z,1630517028.338 [Default:CheckIn] Stopped 2021-09-01T17:23:48.338Z,1630517028.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-09-01T17:23:48.346Z,1630517028.346 [Default:CheckIn:Read_Iridium] Stopped 2021-09-01T17:23:48.347Z,1630517028.347 [MissionManager](IMPORTANT): Started mission keepstation 2021-09-01T17:23:48.347Z,1630517028.347 [keepstation] Running Loop=1 2021-09-01T17:23:48.347Z,1630517028.347 [keepstation](DEBUG): Aggregate::initialize keepstation 2021-09-01T17:23:48.347Z,1630517028.347 [keepstation:StandardEnvelopes] Running Loop=1 2021-09-01T17:23:48.347Z,1630517028.347 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2021-09-01T17:23:48.347Z,1630517028.347 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:F.Pitch] Running Loop=1 2021-09-01T17:23:48.348Z,1630517028.348 [keepstation:F.Pitch](DEBUG): Initialize. 2021-09-01T17:23:48.349Z,1630517028.349 [keepstation:B] Running Loop=1 2021-09-01T17:23:48.349Z,1630517028.349 [keepstation:F.Pitch] Running Loop=1 2021-09-01T17:23:48.349Z,1630517028.349 [keepstation:StandardEnvelopes] Running Loop=1 2021-09-01T17:23:48.350Z,1630517028.350 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-09-01T17:23:48.350Z,1630517028.350 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:48.350Z,1630517028.350 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-09-01T17:23:48.358Z,1630517028.358 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-09-01T17:23:48.359Z,1630517028.359 [keepstation:B] Stopped 2021-09-01T17:23:48.359Z,1630517028.359 [keepstation:D] Running Loop=1 2021-09-01T17:23:48.359Z,1630517028.359 [keepstation:NeedComms] Running Loop=1 2021-09-01T17:23:48.359Z,1630517028.359 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2021-09-01T17:23:48.360Z,1630517028.360 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2021-09-01T17:23:48.360Z,1630517028.360 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-09-01T17:23:48.360Z,1630517028.360 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2021-09-01T17:23:48.360Z,1630517028.360 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2021-09-01T17:23:48.361Z,1630517028.361 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2021-09-01T17:23:48.361Z,1630517028.361 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-09-01T17:23:48.361Z,1630517028.361 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-09-01T17:23:48.362Z,1630517028.362 [keepstation:NeedComms:A] Running Loop=1 2021-09-01T17:23:48.371Z,1630517028.371 [keepstation:NeedComms:A](ERROR): data element is not active. 2021-09-01T17:23:48.371Z,1630517028.371 [keepstation:NeedComms:A](INFO): last time_fix was: nan second since 1970/01/01T00:00:00Z 2021-09-01T17:23:48.371Z,1630517028.371 [keepstation:NeedComms:A] Stopped 2021-09-01T17:23:48.423Z,1630517028.423 [Reporter](INFO): CommandExec.durationOfLastRun 0.000089 s 2021-09-01T17:23:48.735Z,1630517028.735 [keepstation:D] Stopped 2021-09-01T17:23:48.735Z,1630517028.735 [keepstation:E] Running Loop=1 2021-09-01T17:23:48.735Z,1630517028.735 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:48.737Z,1630517028.737 [keepstation:NeedComms:C] Running Loop=1 2021-09-01T17:23:48.747Z,1630517028.747 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:49.152Z,1630517029.152 [keepstation:E] Stopped 2021-09-01T17:23:49.152Z,1630517029.152 [keepstation:StartingMission] Running Loop=1 2021-09-01T17:23:49.152Z,1630517029.152 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2021-09-01T17:23:49.152Z,1630517029.152 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:49.154Z,1630517029.154 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-09-01T17:23:49.165Z,1630517029.165 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:49.554Z,1630517029.554 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:49.598Z,1630517029.598 [Reporter](INFO): CommandExec.durationOfLastRun 0.000076 s 2021-09-01T17:23:49.954Z,1630517029.954 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:49.955Z,1630517029.955 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2021-09-01T17:23:49.966Z,1630517029.966 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:50.334Z,1630517030.334 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:50.362Z,1630517030.362 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:50.766Z,1630517030.766 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:50.795Z,1630517030.795 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:23:51.146Z,1630517031.146 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:51.203Z,1630517031.203 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:51.564Z,1630517031.564 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:51.613Z,1630517031.613 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:51.973Z,1630517031.973 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:51.997Z,1630517031.997 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:52.390Z,1630517032.390 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:52.412Z,1630517032.412 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:52.801Z,1630517032.801 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:52.826Z,1630517032.826 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:23:53.185Z,1630517033.185 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:53.242Z,1630517033.242 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:53.605Z,1630517033.605 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:53.640Z,1630517033.640 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:53.999Z,1630517033.999 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:54.019Z,1630517034.019 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:54.398Z,1630517034.398 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:54.443Z,1630517034.443 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:54.575Z,1630517034.575 [CommandExec](IMPORTANT): got command stop 2021-09-01T17:23:54.576Z,1630517034.576 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2021-09-01T17:23:54.787Z,1630517034.787 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active. 2021-09-01T17:23:54.788Z,1630517034.788 [MissionManager](INFO): MissionManager is completed. 2021-09-01T17:23:54.788Z,1630517034.788 [MissionManager](INFO): Uninitializing Mission keepstation 2021-09-01T17:23:54.788Z,1630517034.788 [keepstation] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:NeedComms] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:NeedComms:B.GoToSurface] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:NeedComms:C] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:StandardEnvelopes] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2021-09-01T17:23:54.789Z,1630517034.789 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:F.Pitch] Stopped 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:StartingMission] Stopped 2021-09-01T17:23:54.790Z,1630517034.790 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2021-09-01T17:23:54.814Z,1630517034.814 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:55.214Z,1630517035.214 [MissionManager](IMPORTANT): Started mission Default 2021-09-01T17:23:55.215Z,1630517035.215 [Default] Running Loop=1 2021-09-01T17:23:55.215Z,1630517035.215 [Default](DEBUG): Aggregate::initialize Default 2021-09-01T17:23:55.215Z,1630517035.215 [Default:B.GoToSurface] Running Loop=1 2021-09-01T17:23:55.215Z,1630517035.215 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-09-01T17:23:55.215Z,1630517035.215 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-09-01T17:23:55.215Z,1630517035.215 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-09-01T17:23:55.216Z,1630517035.216 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-09-01T17:23:55.216Z,1630517035.216 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-09-01T17:23:55.217Z,1630517035.217 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-09-01T17:23:55.217Z,1630517035.217 [Default:A.Wait] Running Loop=1 2021-09-01T17:23:55.217Z,1630517035.217 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-09-01T17:23:55.229Z,1630517035.229 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:23:55.678Z,1630517035.678 [Reporter](INFO): CommandExec.durationOfLastRun 0.000088 s 2021-09-01T17:23:56.020Z,1630517036.020 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:56.413Z,1630517036.413 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:23:56.828Z,1630517036.828 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:23:57.217Z,1630517037.217 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:57.624Z,1630517037.624 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:58.053Z,1630517038.053 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:58.445Z,1630517038.445 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:23:58.909Z,1630517038.909 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:23:59.278Z,1630517039.278 [Reporter](INFO): CommandExec.durationOfLastRun 0.000085 s 2021-09-01T17:23:59.647Z,1630517039.647 [Reporter](INFO): CommandExec.durationOfLastRun 0.000079 s 2021-09-01T17:24:00.056Z,1630517040.056 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:24:00.476Z,1630517040.476 [Reporter](INFO): CommandExec.durationOfLastRun 0.000082 s 2021-09-01T17:24:00.860Z,1630517040.860 [Reporter](INFO): CommandExec.durationOfLastRun 0.000094 s 2021-09-01T17:24:01.305Z,1630517041.305 [Reporter](INFO): CommandExec.durationOfLastRun 0.000086 s 2021-09-01T17:24:01.689Z,1630517041.689 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:24:02.140Z,1630517042.140 [Reporter](INFO): CommandExec.durationOfLastRun 0.000087 s 2021-09-01T17:24:02.469Z,1630517042.469 [Reporter](INFO): CommandExec.durationOfLastRun 0.000083 s 2021-09-01T17:24:02.873Z,1630517042.873 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s 2021-09-01T17:24:03.286Z,1630517043.286 [Reporter](INFO): CommandExec.durationOfLastRun 0.000078 s 2021-09-01T17:24:03.700Z,1630517043.700 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:24:04.088Z,1630517044.088 [Reporter](INFO): CommandExec.durationOfLastRun 0.000080 s 2021-09-01T17:24:04.503Z,1630517044.503 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:24:04.916Z,1630517044.916 [Reporter](INFO): CommandExec.durationOfLastRun 0.000081 s 2021-09-01T17:24:05.326Z,1630517045.326 [Reporter](INFO): CommandExec.durationOfLastRun 0.000084 s