2019-07-29T16:27:26.025Z,1564417646.025 [DataOverHttps](INFO): Received command:restart logs 2019-07-29T16:27:26.058Z,1564417646.058 [CommandLine](IMPORTANT): got command restart logs 2019-07-29T16:29:38.472Z,1564417778.472 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:29:38.472Z,1564417778.472 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:29:38.472Z,1564417778.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:29:38.473Z,1564417778.473 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:29:38.893Z,1564417778.893 [Default:CheckIn:D] Stopped 2019-07-29T16:29:38.893Z,1564417778.893 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:29:39.293Z,1564417779.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.520937 min 2019-07-29T16:29:39.293Z,1564417779.293 [Default:CheckIn:E] Stopped 2019-07-29T16:29:39.293Z,1564417779.293 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn] Stopped 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn](INFO): Running loop #4 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn] Running Loop=4 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:29:39.294Z,1564417779.294 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:29:40.889Z,1564417780.889 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:29:41.283Z,1564417781.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162333.00,A,3648.39425,N,12148.78861,W,26.203,95.29,290719,,,A*72 2019-07-29T16:29:41.285Z,1564417781.285 [NAL9602](INFO): GPS fix at 20190729T162333: (36.806571, -121.813143) 2019-07-29T16:29:41.316Z,1564417781.316 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:29:41.316Z,1564417781.316 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:29:46.881Z,1564417786.881 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190729T134256/Courier0066.lzma 2019-07-29T16:29:47.687Z,1564417787.687 [DataOverHttps](INFO): Moved sent file to Logs/20190729T134256/Courier0066.lzma.bak 2019-07-29T16:29:47.687Z,1564417787.687 [DataOverHttps](INFO): SBD MOMSN=11482482 2019-07-29T16:29:53.810Z,1564417793.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=31107, MT Status=0, MTMSN=0 2019-07-29T16:29:53.810Z,1564417793.810 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:29:58.533Z,1564417798.533 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20190729T162726/Courier0000.lzma 2019-07-29T16:29:59.339Z,1564417799.339 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0000.lzma.bak 2019-07-29T16:29:59.339Z,1564417799.339 [DataOverHttps](INFO): SBD MOMSN=11482484 2019-07-29T16:30:10.249Z,1564417810.249 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190729T162726/Courier0003.lzma 2019-07-29T16:30:11.055Z,1564417811.055 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0003.lzma.bak 2019-07-29T16:30:11.055Z,1564417811.055 [DataOverHttps](INFO): SBD MOMSN=11482488 2019-07-29T16:30:22.401Z,1564417822.401 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20190729T134256/Express0067.lzma 2019-07-29T16:30:23.207Z,1564417823.207 [DataOverHttps](INFO): Moved sent file to Logs/20190729T134256/Express0067.lzma.bak 2019-07-29T16:30:23.207Z,1564417823.207 [DataOverHttps](INFO): SBD MOMSN=11482490 2019-07-29T16:30:24.508Z,1564417824.508 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T16:30:44.534Z,1564417844.534 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20190729T162726/Express0001.lzma 2019-07-29T16:30:45.339Z,1564417845.339 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0001.lzma.bak 2019-07-29T16:30:45.339Z,1564417845.339 [DataOverHttps](INFO): SBD MOMSN=11482493 2019-07-29T16:30:57.617Z,1564417857.617 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190729T162726/Express0005.lzma 2019-07-29T16:30:58.423Z,1564417858.423 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0005.lzma.bak 2019-07-29T16:30:58.423Z,1564417858.423 [DataOverHttps](INFO): SBD MOMSN=11482497 2019-07-29T16:30:59.337Z,1564417859.337 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:30:59.337Z,1564417859.337 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:30:59.338Z,1564417859.338 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:35:59.873Z,1564418159.873 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:35:59.873Z,1564418159.873 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:35:59.873Z,1564418159.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:35:59.873Z,1564418159.873 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:36:00.271Z,1564418160.271 [Default:CheckIn:D] Stopped 2019-07-29T16:36:00.271Z,1564418160.271 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:36:00.685Z,1564418160.685 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.877240 min 2019-07-29T16:36:00.685Z,1564418160.685 [Default:CheckIn:E] Stopped 2019-07-29T16:36:00.685Z,1564418160.685 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn] Stopped 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn](INFO): Running loop #5 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn] Running Loop=5 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:36:00.686Z,1564418160.686 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:36:02.361Z,1564418162.361 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:36:02.675Z,1564418162.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162954.00,A,3648.33149,N,12147.12794,W,4.199,186.37,290719,,,D*73 2019-07-29T16:36:02.677Z,1564418162.677 [NAL9602](INFO): GPS fix at 20190729T162954: (36.805525, -121.785466) 2019-07-29T16:36:02.700Z,1564418162.700 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:36:02.700Z,1564418162.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:36:12.445Z,1564418172.445 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190729T162726/Courier0004.lzma 2019-07-29T16:36:13.251Z,1564418173.251 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0004.lzma.bak 2019-07-29T16:36:13.251Z,1564418173.251 [DataOverHttps](INFO): SBD MOMSN=11482501 2019-07-29T16:36:27.841Z,1564418187.841 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190729T162726/Express0006.lzma 2019-07-29T16:36:28.647Z,1564418188.647 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0006.lzma.bak 2019-07-29T16:36:28.647Z,1564418188.647 [DataOverHttps](INFO): SBD MOMSN=11482503 2019-07-29T16:36:29.371Z,1564418189.371 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:36:29.371Z,1564418189.371 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:36:29.371Z,1564418189.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:37:05.294Z,1564418225.294 [NAL9602](INFO): SBD MO Status=0, MOMSN=31108, MT Status=0, MTMSN=0 2019-07-29T16:37:05.294Z,1564418225.294 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:37:35.994Z,1564418255.994 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T16:40:12.384Z,1564418412.384 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-07-29T16:41:29.941Z,1564418489.941 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:41:29.941Z,1564418489.941 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:41:29.941Z,1564418489.941 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:41:29.941Z,1564418489.941 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:41:30.344Z,1564418490.344 [Default:CheckIn:D] Stopped 2019-07-29T16:41:30.344Z,1564418490.344 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:41:30.750Z,1564418490.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.378457 min 2019-07-29T16:41:30.750Z,1564418490.750 [Default:CheckIn:E] Stopped 2019-07-29T16:41:30.750Z,1564418490.750 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn] Stopped 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn](INFO): Running loop #6 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn] Running Loop=6 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:41:30.751Z,1564418490.751 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:41:32.353Z,1564418492.353 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:41:32.742Z,1564418492.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163524.00,A,3648.15554,N,12147.21377,W,1.594,226.76,290719,,,D*7C 2019-07-29T16:41:32.745Z,1564418492.745 [NAL9602](INFO): GPS fix at 20190729T163524: (36.802592, -121.786896) 2019-07-29T16:41:32.809Z,1564418492.809 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:41:32.809Z,1564418492.809 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:41:38.557Z,1564418498.557 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190729T162726/Courier0009.lzma 2019-07-29T16:41:39.363Z,1564418499.363 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0009.lzma.bak 2019-07-29T16:41:39.363Z,1564418499.363 [DataOverHttps](INFO): SBD MOMSN=11482506 2019-07-29T16:41:50.297Z,1564418510.297 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20190729T162726/Express0010.lzma 2019-07-29T16:41:51.103Z,1564418511.103 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0010.lzma.bak 2019-07-29T16:41:51.103Z,1564418511.103 [DataOverHttps](INFO): SBD MOMSN=11482509 2019-07-29T16:41:51.783Z,1564418511.783 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:41:51.783Z,1564418511.783 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:41:51.783Z,1564418511.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:41:59.406Z,1564418519.406 [NAL9602](INFO): SBD MO Status=2, MOMSN=31109, MT Status=2, MTMSN=0 2019-07-29T16:41:59.406Z,1564418519.406 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T16:42:19.202Z,1564418539.202 [NAL9602](INFO): SBD MO Status=2, MOMSN=31109, MT Status=2, MTMSN=0 2019-07-29T16:42:19.202Z,1564418539.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T16:43:15.358Z,1564418595.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=31109, MT Status=2, MTMSN=0 2019-07-29T16:43:15.358Z,1564418595.358 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T16:43:40.002Z,1564418620.002 [NAL9602](INFO): SBD MO Status=0, MOMSN=31109, MT Status=0, MTMSN=0 2019-07-29T16:43:40.002Z,1564418620.002 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:44:10.707Z,1564418650.707 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T16:44:19.210Z,1564418659.210 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-29T16:44:19.211Z,1564418659.211 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-29T16:44:19.211Z,1564418659.211 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-07-29T16:44:19.214Z,1564418659.214 [BPC1](INFO): Received data from all battery sticks. 2019-07-29T16:46:52.353Z,1564418812.353 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:46:52.353Z,1564418812.353 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:46:52.353Z,1564418812.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:46:52.358Z,1564418812.358 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:46:52.749Z,1564418812.749 [Default:CheckIn:D] Stopped 2019-07-29T16:46:52.749Z,1564418812.749 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.751811 min 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn:E] Stopped 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn] Stopped 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn](INFO): Running loop #7 2019-07-29T16:46:53.158Z,1564418813.158 [Default:CheckIn] Running Loop=7 2019-07-29T16:46:53.159Z,1564418813.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:46:53.159Z,1564418813.159 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:46:54.741Z,1564418814.741 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:46:55.136Z,1564418815.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164046.00,A,3648.14134,N,12147.23885,W,1.886,297.49,290719,,,D*75 2019-07-29T16:46:55.138Z,1564418815.138 [NAL9602](INFO): GPS fix at 20190729T164046: (36.802356, -121.787314) 2019-07-29T16:46:55.169Z,1564418815.169 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:46:55.169Z,1564418815.169 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:46:58.490Z,1564418818.490 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20190729T162726/Courier0012.lzma 2019-07-29T16:46:59.131Z,1564418819.131 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0012.lzma.bak 2019-07-29T16:46:59.131Z,1564418819.131 [DataOverHttps](INFO): SBD MOMSN=11482513 2019-07-29T16:47:10.877Z,1564418830.877 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20190729T162726/Express0013.lzma 2019-07-29T16:47:11.683Z,1564418831.683 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0013.lzma.bak 2019-07-29T16:47:11.683Z,1564418831.683 [DataOverHttps](INFO): SBD MOMSN=11482517 2019-07-29T16:47:12.631Z,1564418832.631 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:47:12.631Z,1564418832.631 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:47:12.631Z,1564418832.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:48:00.254Z,1564418880.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=31110, MT Status=2, MTMSN=0 2019-07-29T16:48:00.254Z,1564418880.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T16:48:28.123Z,1564418908.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=31110, MT Status=2, MTMSN=0 2019-07-29T16:48:28.124Z,1564418908.124 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T16:48:52.404Z,1564418932.404 [NAL9602](INFO): SBD MO Status=0, MOMSN=31110, MT Status=0, MTMSN=0 2019-07-29T16:48:52.404Z,1564418932.404 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:49:23.092Z,1564418963.092 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T16:52:13.210Z,1564419133.210 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:52:13.211Z,1564419133.211 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:52:13.211Z,1564419133.211 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:52:13.211Z,1564419133.211 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:52:13.624Z,1564419133.624 [Default:CheckIn:D] Stopped 2019-07-29T16:52:13.624Z,1564419133.624 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:52:14.035Z,1564419134.035 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.099788 min 2019-07-29T16:52:14.035Z,1564419134.035 [Default:CheckIn:E] Stopped 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn] Stopped 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn](INFO): Running loop #8 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn] Running Loop=8 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:52:14.036Z,1564419134.036 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:52:15.621Z,1564419135.621 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:52:16.021Z,1564419136.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164606.00,A,3648.17707,N,12147.28193,W,0.019,313.33,290719,,,D*78 2019-07-29T16:52:16.023Z,1564419136.023 [NAL9602](INFO): GPS fix at 20190729T164606: (36.802951, -121.788032) 2019-07-29T16:52:16.048Z,1564419136.048 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:52:16.048Z,1564419136.048 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:52:21.585Z,1564419141.585 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190729T162726/Courier0015.lzma 2019-07-29T16:52:22.391Z,1564419142.391 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0015.lzma.bak 2019-07-29T16:52:22.391Z,1564419142.391 [DataOverHttps](INFO): SBD MOMSN=11482521 2019-07-29T16:52:34.161Z,1564419154.161 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20190729T162726/Express0016.lzma 2019-07-29T16:52:34.967Z,1564419154.967 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0016.lzma.bak 2019-07-29T16:52:34.967Z,1564419154.967 [DataOverHttps](INFO): SBD MOMSN=11482524 2019-07-29T16:52:35.889Z,1564419155.889 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:52:35.889Z,1564419155.889 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:52:35.889Z,1564419155.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:52:51.212Z,1564419171.212 [NAL9602](INFO): SBD MO Status=0, MOMSN=31111, MT Status=0, MTMSN=0 2019-07-29T16:52:51.212Z,1564419171.212 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:53:21.904Z,1564419201.904 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T16:57:36.470Z,1564419456.470 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T16:57:36.470Z,1564419456.470 [Default:CheckIn:C.Wait] Stopped 2019-07-29T16:57:36.470Z,1564419456.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T16:57:36.470Z,1564419456.470 [Default:CheckIn:D] Running Loop=1 2019-07-29T16:57:36.861Z,1564419456.861 [Default:CheckIn:D] Stopped 2019-07-29T16:57:36.861Z,1564419456.861 [Default:CheckIn:E] Running Loop=1 2019-07-29T16:57:37.253Z,1564419457.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.487077 min 2019-07-29T16:57:37.253Z,1564419457.253 [Default:CheckIn:E] Stopped 2019-07-29T16:57:37.253Z,1564419457.253 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T16:57:37.253Z,1564419457.253 [Default:CheckIn] Stopped 2019-07-29T16:57:37.254Z,1564419457.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T16:57:37.254Z,1564419457.254 [Default:CheckIn](INFO): Running loop #9 2019-07-29T16:57:37.254Z,1564419457.254 [Default:CheckIn] Running Loop=9 2019-07-29T16:57:37.254Z,1564419457.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T16:57:37.255Z,1564419457.255 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T16:57:38.865Z,1564419458.865 [NAL9602](DEBUG): Fix Requested 2019-07-29T16:57:39.254Z,1564419459.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165129.00,A,3648.17777,N,12147.27612,W,0.039,313.33,290719,,,D*77 2019-07-29T16:57:39.256Z,1564419459.256 [NAL9602](INFO): GPS fix at 20190729T165129: (36.802963, -121.787935) 2019-07-29T16:57:39.296Z,1564419459.296 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T16:57:39.296Z,1564419459.296 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T16:57:44.797Z,1564419464.797 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0018.lzma 2019-07-29T16:57:45.603Z,1564419465.603 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0018.lzma.bak 2019-07-29T16:57:45.603Z,1564419465.603 [DataOverHttps](INFO): SBD MOMSN=11482529 2019-07-29T16:57:56.469Z,1564419476.469 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190729T162726/Express0019.lzma 2019-07-29T16:57:57.275Z,1564419477.275 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0019.lzma.bak 2019-07-29T16:57:57.275Z,1564419477.275 [DataOverHttps](INFO): SBD MOMSN=11482532 2019-07-29T16:57:58.280Z,1564419478.280 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T16:57:58.281Z,1564419478.281 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T16:57:58.281Z,1564419478.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T16:58:01.883Z,1564419481.883 [NAL9602](INFO): SBD MO Status=0, MOMSN=31112, MT Status=0, MTMSN=0 2019-07-29T16:58:01.884Z,1564419481.884 [NAL9602](INFO): No messages in MT queue 2019-07-29T16:58:32.580Z,1564419512.580 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:02:58.843Z,1564419778.843 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:02:58.843Z,1564419778.843 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:02:58.844Z,1564419778.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:02:58.844Z,1564419778.844 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:02:59.252Z,1564419779.252 [Default:CheckIn:D] Stopped 2019-07-29T17:02:59.252Z,1564419779.252 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:02:59.648Z,1564419779.648 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.860262 min 2019-07-29T17:02:59.648Z,1564419779.648 [Default:CheckIn:E] Stopped 2019-07-29T17:02:59.648Z,1564419779.648 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:02:59.648Z,1564419779.648 [Default:CheckIn] Stopped 2019-07-29T17:02:59.649Z,1564419779.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:02:59.649Z,1564419779.649 [Default:CheckIn](INFO): Running loop #10 2019-07-29T17:02:59.649Z,1564419779.649 [Default:CheckIn] Running Loop=10 2019-07-29T17:02:59.649Z,1564419779.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:02:59.649Z,1564419779.649 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:03:01.264Z,1564419781.264 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:03:01.656Z,1564419781.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165651.00,A,3648.17819,N,12147.27947,W,0.136,96.87,290719,,,D*48 2019-07-29T17:03:01.658Z,1564419781.658 [NAL9602](INFO): GPS fix at 20190729T165651: (36.802970, -121.787991) 2019-07-29T17:03:01.700Z,1564419781.700 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:03:01.700Z,1564419781.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:03:07.081Z,1564419787.081 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0021.lzma 2019-07-29T17:03:07.887Z,1564419787.887 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0021.lzma.bak 2019-07-29T17:03:07.887Z,1564419787.887 [DataOverHttps](INFO): SBD MOMSN=11482537 2019-07-29T17:03:16.612Z,1564419796.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=31113, MT Status=0, MTMSN=0 2019-07-29T17:03:16.612Z,1564419796.612 [NAL9602](INFO): No messages in MT queue 2019-07-29T17:03:18.785Z,1564419798.785 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190729T162726/Express0022.lzma 2019-07-29T17:03:19.591Z,1564419799.591 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0022.lzma.bak 2019-07-29T17:03:19.591Z,1564419799.591 [DataOverHttps](INFO): SBD MOMSN=11482540 2019-07-29T17:03:20.265Z,1564419800.265 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:03:20.265Z,1564419800.265 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:03:20.265Z,1564419800.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:03:47.300Z,1564419827.300 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:08:20.850Z,1564420100.850 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:08:20.850Z,1564420100.850 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:08:20.850Z,1564420100.850 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:08:20.852Z,1564420100.852 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:08:21.261Z,1564420101.261 [Default:CheckIn:D] Stopped 2019-07-29T17:08:21.261Z,1564420101.261 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.227071 min 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn:E] Stopped 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn] Stopped 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn](INFO): Running loop #11 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn] Running Loop=11 2019-07-29T17:08:21.665Z,1564420101.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:08:21.666Z,1564420101.666 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:08:23.281Z,1564420103.281 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:08:23.656Z,1564420103.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170212.00,A,3648.17721,N,12147.27972,W,0.019,96.87,290719,,,D*41 2019-07-29T17:08:23.659Z,1564420103.659 [NAL9602](INFO): GPS fix at 20190729T170212: (36.802954, -121.787995) 2019-07-29T17:08:23.716Z,1564420103.716 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:08:23.716Z,1564420103.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:08:26.920Z,1564420106.920 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190729T162726/Courier0024.lzma 2019-07-29T17:08:27.539Z,1564420107.539 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0024.lzma.bak 2019-07-29T17:08:27.539Z,1564420107.539 [DataOverHttps](INFO): SBD MOMSN=11482544 2019-07-29T17:08:38.457Z,1564420118.457 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190729T162726/Express0025.lzma 2019-07-29T17:08:39.263Z,1564420119.263 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0025.lzma.bak 2019-07-29T17:08:39.263Z,1564420119.263 [DataOverHttps](INFO): SBD MOMSN=11482547 2019-07-29T17:08:39.940Z,1564420119.940 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:08:39.940Z,1564420119.940 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:08:39.940Z,1564420119.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:08:41.528Z,1564420121.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=31114, MT Status=0, MTMSN=0 2019-07-29T17:08:41.528Z,1564420121.528 [NAL9602](INFO): No messages in MT queue 2019-07-29T17:09:12.218Z,1564420152.218 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:13:40.515Z,1564420420.515 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:13:40.515Z,1564420420.515 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:13:40.515Z,1564420420.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:13:40.515Z,1564420420.515 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:13:40.905Z,1564420420.905 [Default:CheckIn:D] Stopped 2019-07-29T17:13:40.905Z,1564420420.905 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:13:41.309Z,1564420421.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.554468 min 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn:E] Stopped 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn] Stopped 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn](INFO): Running loop #12 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn] Running Loop=12 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:13:41.310Z,1564420421.310 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:13:42.909Z,1564420422.909 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:13:43.314Z,1564420423.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170731.00,A,3648.17843,N,12147.27971,W,0.078,176.45,290719,,,D*7B 2019-07-29T17:13:43.316Z,1564420423.316 [NAL9602](INFO): GPS fix at 20190729T170731: (36.802974, -121.787995) 2019-07-29T17:13:43.364Z,1564420423.364 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:13:43.364Z,1564420423.364 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:13:46.554Z,1564420426.554 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190729T162726/Courier0027.lzma 2019-07-29T17:13:47.247Z,1564420427.247 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0027.lzma.bak 2019-07-29T17:13:47.247Z,1564420427.247 [DataOverHttps](INFO): SBD MOMSN=11482551 2019-07-29T17:13:58.377Z,1564420438.377 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190729T162726/Express0028.lzma 2019-07-29T17:13:59.183Z,1564420439.183 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0028.lzma.bak 2019-07-29T17:13:59.183Z,1564420439.183 [DataOverHttps](INFO): SBD MOMSN=11482554 2019-07-29T17:13:59.953Z,1564420439.953 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:13:59.953Z,1564420439.953 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:13:59.953Z,1564420439.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:16:07.188Z,1564420567.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:16:07.188Z,1564420567.188 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:16:39.912Z,1564420599.912 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:16:39.912Z,1564420599.912 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:17:00.920Z,1564420620.920 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:17:00.920Z,1564420620.920 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:17:15.862Z,1564420635.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:17:15.862Z,1564420635.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:18:09.594Z,1564420689.594 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:18:09.594Z,1564420689.594 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:18:25.350Z,1564420705.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:18:25.350Z,1564420705.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:18:30.202Z,1564420710.202 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:18:30.202Z,1564420710.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:18:44.338Z,1564420724.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=31115, MT Status=2, MTMSN=0 2019-07-29T17:18:44.338Z,1564420724.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:18:45.148Z,1564420725.148 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-29T17:19:00.533Z,1564420740.533 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:19:00.533Z,1564420740.533 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:19:00.533Z,1564420740.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:19:00.533Z,1564420740.533 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:19:00.923Z,1564420740.923 [Default:CheckIn:D] Stopped 2019-07-29T17:19:00.923Z,1564420740.923 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:19:01.333Z,1564420741.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.888110 min 2019-07-29T17:19:01.333Z,1564420741.333 [Default:CheckIn:E] Stopped 2019-07-29T17:19:01.333Z,1564420741.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:19:01.333Z,1564420741.333 [Default:CheckIn] Stopped 2019-07-29T17:19:01.334Z,1564420741.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:19:01.334Z,1564420741.334 [Default:CheckIn](INFO): Running loop #13 2019-07-29T17:19:01.334Z,1564420741.334 [Default:CheckIn] Running Loop=13 2019-07-29T17:19:01.334Z,1564420741.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:19:01.334Z,1564420741.334 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:19:02.933Z,1564420742.933 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:19:03.340Z,1564420743.340 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171251.00,A,3648.17816,N,12147.27707,W,0.058,176.45,290719,,,D*74 2019-07-29T17:19:03.342Z,1564420743.342 [NAL9602](INFO): GPS fix at 20190729T171251: (36.802969, -121.787951) 2019-07-29T17:19:03.377Z,1564420743.377 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:19:03.377Z,1564420743.377 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:19:06.621Z,1564420746.621 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0030.lzma 2019-07-29T17:19:07.167Z,1564420747.167 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0030.lzma.bak 2019-07-29T17:19:07.167Z,1564420747.167 [DataOverHttps](INFO): SBD MOMSN=11482557 2019-07-29T17:19:18.065Z,1564420758.065 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20190729T162726/Express0031.lzma 2019-07-29T17:19:18.871Z,1564420758.871 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0031.lzma.bak 2019-07-29T17:19:18.871Z,1564420758.871 [DataOverHttps](INFO): SBD MOMSN=11482560 2019-07-29T17:19:19.581Z,1564420759.581 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:19:19.581Z,1564420759.581 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:19:19.581Z,1564420759.581 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:19:35.710Z,1564420775.710 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:24:20.157Z,1564421060.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:24:20.158Z,1564421060.158 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:24:20.158Z,1564421060.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:24:20.158Z,1564421060.158 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:24:20.569Z,1564421060.569 [Default:CheckIn:D] Stopped 2019-07-29T17:24:20.569Z,1564421060.569 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.215535 min 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn:E] Stopped 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn] Stopped 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn](INFO): Running loop #14 2019-07-29T17:24:20.977Z,1564421060.977 [Default:CheckIn] Running Loop=14 2019-07-29T17:24:20.978Z,1564421060.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:24:20.978Z,1564421060.978 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:24:22.565Z,1564421062.565 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:24:22.960Z,1564421062.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171810.00,A,3648.18180,N,12147.28016,W,0.058,340.78,290719,,,D*73 2019-07-29T17:24:22.962Z,1564421062.962 [NAL9602](INFO): GPS fix at 20190729T171810: (36.803030, -121.788003) 2019-07-29T17:24:23.008Z,1564421063.008 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:24:23.008Z,1564421063.008 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:24:26.120Z,1564421066.120 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0033.lzma 2019-07-29T17:24:26.803Z,1564421066.803 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0033.lzma.bak 2019-07-29T17:24:26.803Z,1564421066.803 [DataOverHttps](INFO): SBD MOMSN=11482564 2019-07-29T17:24:37.749Z,1564421077.749 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190729T162726/Express0034.lzma 2019-07-29T17:24:38.555Z,1564421078.555 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0034.lzma.bak 2019-07-29T17:24:38.556Z,1564421078.556 [DataOverHttps](INFO): SBD MOMSN=11482567 2019-07-29T17:24:39.233Z,1564421079.233 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:24:39.233Z,1564421079.233 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:24:39.233Z,1564421079.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:24:53.755Z,1564421093.755 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-07-29T17:24:53.837Z,1564421093.837 [NAL9602](FAULT): received: +SBDI: 2, 31115, 2, 0, 0, 0 OK 2019-07-29T17:24:53.837Z,1564421093.837 [NAL9602] Data Fault, FailCount= 1 2019-07-29T17:24:53.837Z,1564421093.837 [NAL9602](ERROR): Data Fault 2019-07-29T17:24:53.881Z,1564421093.881 [CBIT](ERROR): Data Fault in component: NAL9602 2019-07-29T17:24:54.158Z,1564421094.158 [NAL9602](INFO): Powering down 2019-07-29T17:24:55.023Z,1564421095.023 [CBIT](INFO): Clearing failed state for component NAL9602 2019-07-29T17:24:55.023Z,1564421095.023 [NAL9602] No Fault, FailCount= 1 2019-07-29T17:25:24.461Z,1564421124.461 [NAL9602](INFO): Powering up NAL9602 2019-07-29T17:25:35.364Z,1564421135.364 [NAL9602](INFO): NAL9602 initialized 2019-07-29T17:26:06.478Z,1564421166.478 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:29:39.816Z,1564421379.816 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:29:39.817Z,1564421379.817 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:29:39.817Z,1564421379.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:29:39.817Z,1564421379.817 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:29:40.257Z,1564421380.257 [Default:CheckIn:D] Stopped 2019-07-29T17:29:40.257Z,1564421380.257 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.543343 min 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn:E] Stopped 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn] Stopped 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn](INFO): Running loop #15 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn] Running Loop=15 2019-07-29T17:29:40.642Z,1564421380.642 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:29:40.643Z,1564421380.643 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:29:42.249Z,1564421382.249 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:29:42.670Z,1564421382.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172328.00,A,3648.17919,N,12147.28195,W,0.233,183.91,290719,,,A*7D 2019-07-29T17:29:42.673Z,1564421382.673 [NAL9602](INFO): GPS fix at 20190729T172328: (36.802987, -121.788032) 2019-07-29T17:29:42.721Z,1564421382.721 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:29:42.721Z,1564421382.721 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:29:44.394Z,1564421384.394 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-29T17:29:49.861Z,1564421389.861 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190729T162726/Courier0036.lzma 2019-07-29T17:29:50.667Z,1564421390.667 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0036.lzma.bak 2019-07-29T17:29:50.667Z,1564421390.667 [DataOverHttps](INFO): SBD MOMSN=11482571 2019-07-29T17:30:01.737Z,1564421401.737 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20190729T162726/Express0037.lzma 2019-07-29T17:30:02.543Z,1564421402.543 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0037.lzma.bak 2019-07-29T17:30:02.543Z,1564421402.543 [DataOverHttps](INFO): SBD MOMSN=11482574 2019-07-29T17:30:03.464Z,1564421403.464 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:30:03.464Z,1564421403.464 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:30:03.464Z,1564421403.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:30:15.110Z,1564421415.110 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:30:23.385Z,1564421423.385 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 0.000000 millimeter 2019-07-29T17:30:23.387Z,1564421423.387 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2019-07-29T17:30:23.639Z,1564421423.639 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:31:14.156Z,1564421474.156 [CommandLine](IMPORTANT): got command get platform_mass_position 2019-07-29T17:31:14.157Z,1564421474.157 [CommandLine](IMPORTANT): platform_mass_position 0.000000 m 2019-07-29T17:33:29.596Z,1564421609.596 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-07-29T17:34:10.940Z,1564421650.940 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -13.000000 millimeter 2019-07-29T17:35:04.115Z,1564421704.115 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:35:04.115Z,1564421704.115 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:35:04.115Z,1564421704.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:35:04.116Z,1564421704.116 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:35:04.513Z,1564421704.513 [Default:CheckIn:D] Stopped 2019-07-29T17:35:04.513Z,1564421704.513 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:35:04.911Z,1564421704.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.947933 min 2019-07-29T17:35:04.911Z,1564421704.911 [Default:CheckIn:E] Stopped 2019-07-29T17:35:04.911Z,1564421704.911 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:35:04.911Z,1564421704.911 [Default:CheckIn] Stopped 2019-07-29T17:35:04.911Z,1564421704.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:35:04.912Z,1564421704.912 [Default:CheckIn](INFO): Running loop #16 2019-07-29T17:35:04.912Z,1564421704.912 [Default:CheckIn] Running Loop=16 2019-07-29T17:35:04.912Z,1564421704.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:35:04.912Z,1564421704.912 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:35:06.521Z,1564421706.521 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:35:06.918Z,1564421706.918 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172853.00,A,3648.17677,N,12147.27996,W,0.175,170.47,290719,,,D*7A 2019-07-29T17:35:06.920Z,1564421706.920 [NAL9602](INFO): GPS fix at 20190729T172853: (36.802946, -121.787999) 2019-07-29T17:35:06.944Z,1564421706.944 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:35:06.944Z,1564421706.944 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:35:12.261Z,1564421712.261 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190729T162726/Courier0039.lzma 2019-07-29T17:35:13.067Z,1564421713.067 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0039.lzma.bak 2019-07-29T17:35:13.067Z,1564421713.067 [DataOverHttps](INFO): SBD MOMSN=11482578 2019-07-29T17:35:23.901Z,1564421723.901 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20190729T162726/Express0040.lzma 2019-07-29T17:35:24.282Z,1564421724.282 [NAL9602](INFO): SBD MO Status=0, MOMSN=31115, MT Status=0, MTMSN=0 2019-07-29T17:35:24.283Z,1564421724.283 [NAL9602](INFO): No messages in MT queue 2019-07-29T17:35:24.707Z,1564421724.707 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0040.lzma.bak 2019-07-29T17:35:24.707Z,1564421724.707 [DataOverHttps](INFO): SBD MOMSN=11482581 2019-07-29T17:35:25.537Z,1564421725.537 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:35:25.538Z,1564421725.538 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:35:25.538Z,1564421725.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:35:54.986Z,1564421754.986 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:36:30.064Z,1564421790.064 [CommandLine](IMPORTANT): got command get platform_buoyancy_position 2019-07-29T17:36:30.065Z,1564421790.065 [CommandLine](IMPORTANT): platform_buoyancy_position 952.177002 cc 2019-07-29T17:37:17.740Z,1564421837.740 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 13.000000 millimeter 2019-07-29T17:37:30.870Z,1564421850.870 [CommandLine](IMPORTANT): got command report mod platform_mass_position 2019-07-29T17:37:31.263Z,1564421851.263 [Reporter](INFO): platform_mass_position -0.004420 m 2019-07-29T17:37:31.675Z,1564421851.675 [Reporter](INFO): platform_mass_position -0.004098 m 2019-07-29T17:37:32.091Z,1564421852.091 [Reporter](INFO): platform_mass_position -0.003784 m 2019-07-29T17:37:32.479Z,1564421852.479 [Reporter](INFO): platform_mass_position -0.003468 m 2019-07-29T17:37:32.883Z,1564421852.883 [Reporter](INFO): platform_mass_position -0.003149 m 2019-07-29T17:37:33.295Z,1564421853.295 [Reporter](INFO): platform_mass_position -0.002827 m 2019-07-29T17:37:33.687Z,1564421853.687 [Reporter](INFO): platform_mass_position -0.002519 m 2019-07-29T17:37:34.103Z,1564421854.103 [Reporter](INFO): platform_mass_position -0.002194 m 2019-07-29T17:37:34.495Z,1564421854.495 [Reporter](INFO): platform_mass_position -0.001891 m 2019-07-29T17:37:34.918Z,1564421854.918 [Reporter](INFO): platform_mass_position -0.001571 m 2019-07-29T17:37:35.307Z,1564421855.307 [Reporter](INFO): platform_mass_position -0.001255 m 2019-07-29T17:37:35.703Z,1564421855.703 [Reporter](INFO): platform_mass_position -0.000942 m 2019-07-29T17:37:36.123Z,1564421856.123 [Reporter](INFO): platform_mass_position -0.000613 m 2019-07-29T17:37:36.543Z,1564421856.543 [Reporter](INFO): platform_mass_position -0.000285 m 2019-07-29T17:37:36.955Z,1564421856.955 [Reporter](INFO): platform_mass_position 0.000038 m 2019-07-29T17:37:37.339Z,1564421857.339 [Reporter](INFO): platform_mass_position 0.000339 m 2019-07-29T17:37:37.735Z,1564421857.735 [Reporter](INFO): platform_mass_position 0.000649 m 2019-07-29T17:37:38.155Z,1564421858.155 [Reporter](INFO): platform_mass_position 0.000977 m 2019-07-29T17:37:38.563Z,1564421858.563 [Reporter](INFO): platform_mass_position 0.001284 m 2019-07-29T17:37:38.951Z,1564421858.951 [Reporter](INFO): platform_mass_position 0.001600 m 2019-07-29T17:37:39.371Z,1564421859.371 [Reporter](INFO): platform_mass_position 0.001929 m 2019-07-29T17:37:39.767Z,1564421859.767 [Reporter](INFO): platform_mass_position 0.002239 m 2019-07-29T17:37:40.171Z,1564421860.171 [Reporter](INFO): platform_mass_position 0.002555 m 2019-07-29T17:37:40.567Z,1564421860.567 [Reporter](INFO): platform_mass_position 0.002865 m 2019-07-29T17:37:40.959Z,1564421860.959 [Reporter](INFO): platform_mass_position 0.003171 m 2019-07-29T17:37:41.379Z,1564421861.379 [Reporter](INFO): platform_mass_position 0.003491 m 2019-07-29T17:37:41.767Z,1564421861.767 [Reporter](INFO): platform_mass_position 0.003803 m 2019-07-29T17:37:42.179Z,1564421862.179 [Reporter](INFO): platform_mass_position 0.004126 m 2019-07-29T17:37:42.583Z,1564421862.583 [Reporter](INFO): platform_mass_position 0.004442 m 2019-07-29T17:37:42.987Z,1564421862.987 [Reporter](INFO): platform_mass_position 0.004758 m 2019-07-29T17:37:43.407Z,1564421863.407 [Reporter](INFO): platform_mass_position 0.005087 m 2019-07-29T17:37:43.799Z,1564421863.799 [Reporter](INFO): platform_mass_position 0.005393 m 2019-07-29T17:37:44.191Z,1564421864.191 [Reporter](INFO): platform_mass_position 0.005701 m 2019-07-29T17:37:44.607Z,1564421864.607 [Reporter](INFO): platform_mass_position 0.006017 m 2019-07-29T17:37:44.999Z,1564421864.999 [Reporter](INFO): platform_mass_position 0.006333 m 2019-07-29T17:37:45.403Z,1564421865.403 [Reporter](INFO): platform_mass_position 0.006649 m 2019-07-29T17:37:45.807Z,1564421865.807 [Reporter](INFO): platform_mass_position 0.006965 m 2019-07-29T17:37:46.223Z,1564421866.223 [Reporter](INFO): platform_mass_position 0.007291 m 2019-07-29T17:37:46.627Z,1564421866.627 [Reporter](INFO): platform_mass_position 0.007607 m 2019-07-29T17:37:47.027Z,1564421867.027 [Reporter](INFO): platform_mass_position 0.007919 m 2019-07-29T17:37:47.431Z,1564421867.431 [Reporter](INFO): platform_mass_position 0.008233 m 2019-07-29T17:37:47.831Z,1564421867.831 [Reporter](INFO): platform_mass_position 0.008546 m 2019-07-29T17:37:48.250Z,1564421868.250 [Reporter](INFO): platform_mass_position 0.008865 m 2019-07-29T17:37:48.639Z,1564421868.639 [Reporter](INFO): platform_mass_position 0.009182 m 2019-07-29T17:37:49.039Z,1564421869.039 [Reporter](INFO): platform_mass_position 0.009494 m 2019-07-29T17:37:49.451Z,1564421869.451 [Reporter](INFO): platform_mass_position 0.009817 m 2019-07-29T17:37:49.855Z,1564421869.855 [Reporter](INFO): platform_mass_position 0.010133 m 2019-07-29T17:37:50.275Z,1564421870.275 [Reporter](INFO): platform_mass_position 0.010462 m 2019-07-29T17:37:50.655Z,1564421870.655 [Reporter](INFO): platform_mass_position 0.010756 m 2019-07-29T17:37:51.067Z,1564421871.067 [Reporter](INFO): platform_mass_position 0.011079 m 2019-07-29T17:37:51.475Z,1564421871.475 [Reporter](INFO): platform_mass_position 0.011388 m 2019-07-29T17:37:51.887Z,1564421871.887 [Reporter](INFO): platform_mass_position 0.011711 m 2019-07-29T17:37:52.267Z,1564421872.267 [Reporter](INFO): platform_mass_position 0.012021 m 2019-07-29T17:37:52.679Z,1564421872.679 [Reporter](INFO): platform_mass_position 0.012343 m 2019-07-29T17:37:53.083Z,1564421873.083 [Reporter](INFO): platform_mass_position 0.012659 m 2019-07-29T17:37:53.499Z,1564421873.499 [Reporter](INFO): platform_mass_position 0.012985 m 2019-07-29T17:40:26.161Z,1564422026.161 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:40:26.161Z,1564422026.161 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:40:26.161Z,1564422026.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:40:26.161Z,1564422026.161 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:40:26.565Z,1564422026.565 [Default:CheckIn:D] Stopped 2019-07-29T17:40:26.565Z,1564422026.565 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:40:26.969Z,1564422026.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.315470 min 2019-07-29T17:40:26.969Z,1564422026.969 [Default:CheckIn:E] Stopped 2019-07-29T17:40:26.969Z,1564422026.969 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:40:26.969Z,1564422026.969 [Default:CheckIn] Stopped 2019-07-29T17:40:26.970Z,1564422026.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:40:26.970Z,1564422026.970 [Default:CheckIn](INFO): Running loop #17 2019-07-29T17:40:26.970Z,1564422026.970 [Default:CheckIn] Running Loop=17 2019-07-29T17:40:26.970Z,1564422026.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:40:26.970Z,1564422026.970 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:40:28.573Z,1564422028.573 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:40:28.968Z,1564422028.968 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173415.00,A,3648.17900,N,12147.27997,W,0.117,358.83,290719,,,D*7F 2019-07-29T17:40:28.970Z,1564422028.970 [NAL9602](INFO): GPS fix at 20190729T173415: (36.802983, -121.787999) 2019-07-29T17:40:29.009Z,1564422029.009 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:40:29.010Z,1564422029.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:40:34.425Z,1564422034.425 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0042.lzma 2019-07-29T17:40:35.231Z,1564422035.231 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0042.lzma.bak 2019-07-29T17:40:35.231Z,1564422035.231 [DataOverHttps](INFO): SBD MOMSN=11482591 2019-07-29T17:40:46.537Z,1564422046.537 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20190729T162726/Express0043.lzma 2019-07-29T17:40:47.343Z,1564422047.343 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0043.lzma.bak 2019-07-29T17:40:47.343Z,1564422047.343 [DataOverHttps](INFO): SBD MOMSN=11482594 2019-07-29T17:40:48.404Z,1564422048.404 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:40:48.404Z,1564422048.404 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:40:48.404Z,1564422048.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:41:08.989Z,1564422068.989 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:41:08.989Z,1564422068.989 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:41:13.913Z,1564422073.913 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2019-07-29T17:41:14.271Z,1564422074.271 [Reporter](INFO): platform_buoyancy_position 952.177002 cc 2019-07-29T17:41:24.806Z,1564422084.806 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-07-29T17:41:24.815Z,1564422084.815 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-07-29T17:41:25.189Z,1564422085.189 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:41:26.370Z,1564422086.370 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:41:26.370Z,1564422086.370 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:41:30.107Z,1564422090.107 [Reporter](INFO): platform_buoyancy_position 958.623291 cc 2019-07-29T17:41:30.475Z,1564422090.475 [Reporter](INFO): platform_buoyancy_position 958.757690 cc 2019-07-29T17:41:30.891Z,1564422090.891 [Reporter](INFO): platform_buoyancy_position 958.623291 cc 2019-07-29T17:41:31.303Z,1564422091.303 [Reporter](INFO): platform_buoyancy_position 956.608887 cc 2019-07-29T17:41:31.692Z,1564422091.692 [Reporter](INFO): platform_buoyancy_position 952.311279 cc 2019-07-29T17:41:32.107Z,1564422092.107 [Reporter](INFO): platform_buoyancy_position 941.567261 cc 2019-07-29T17:41:32.491Z,1564422092.491 [Reporter](INFO): platform_buoyancy_position 937.269653 cc 2019-07-29T17:41:32.887Z,1564422092.887 [Reporter](INFO): platform_buoyancy_position 932.837769 cc 2019-07-29T17:41:33.303Z,1564422093.303 [Reporter](INFO): platform_buoyancy_position 928.540161 cc 2019-07-29T17:41:33.707Z,1564422093.707 [Reporter](INFO): platform_buoyancy_position 924.242554 cc 2019-07-29T17:41:34.119Z,1564422094.119 [Reporter](INFO): platform_buoyancy_position 917.796143 cc 2019-07-29T17:41:34.511Z,1564422094.511 [Reporter](INFO): platform_buoyancy_position 913.498535 cc 2019-07-29T17:41:34.915Z,1564422094.915 [Reporter](INFO): platform_buoyancy_position 908.663757 cc 2019-07-29T17:41:35.331Z,1564422095.331 [Reporter](INFO): platform_buoyancy_position 902.754517 cc 2019-07-29T17:41:35.755Z,1564422095.755 [Reporter](INFO): platform_buoyancy_position 896.308167 cc 2019-07-29T17:41:36.131Z,1564422096.131 [Reporter](INFO): platform_buoyancy_position 892.010559 cc 2019-07-29T17:41:36.552Z,1564422096.552 [Reporter](INFO): platform_buoyancy_position 886.907166 cc 2019-07-29T17:41:36.935Z,1564422096.935 [Reporter](INFO): platform_buoyancy_position 881.266541 cc 2019-07-29T17:41:37.343Z,1564422097.343 [Reporter](INFO): platform_buoyancy_position 876.968933 cc 2019-07-29T17:41:37.739Z,1564422097.739 [Reporter](INFO): platform_buoyancy_position 872.671326 cc 2019-07-29T17:41:38.144Z,1564422098.144 [Reporter](INFO): platform_buoyancy_position 866.224915 cc 2019-07-29T17:41:38.563Z,1564422098.563 [Reporter](INFO): platform_buoyancy_position 861.927368 cc 2019-07-29T17:41:38.951Z,1564422098.951 [Reporter](INFO): platform_buoyancy_position 855.615234 cc 2019-07-29T17:41:39.355Z,1564422099.355 [Reporter](INFO): platform_buoyancy_position 851.183350 cc 2019-07-29T17:41:39.771Z,1564422099.771 [Reporter](INFO): platform_buoyancy_position 844.736938 cc 2019-07-29T17:41:40.183Z,1564422100.183 [Reporter](INFO): platform_buoyancy_position 838.424866 cc 2019-07-29T17:41:40.587Z,1564422100.587 [Reporter](INFO): platform_buoyancy_position 833.992920 cc 2019-07-29T17:41:40.995Z,1564422100.995 [Reporter](INFO): platform_buoyancy_position 829.695312 cc 2019-07-29T17:41:41.383Z,1564422101.383 [Reporter](INFO): platform_buoyancy_position 823.248962 cc 2019-07-29T17:41:41.795Z,1564422101.795 [Reporter](INFO): platform_buoyancy_position 818.951355 cc 2019-07-29T17:41:42.179Z,1564422102.179 [Reporter](INFO): platform_buoyancy_position 812.639221 cc 2019-07-29T17:41:42.583Z,1564422102.583 [Reporter](INFO): platform_buoyancy_position 808.207336 cc 2019-07-29T17:41:42.991Z,1564422102.991 [Reporter](INFO): platform_buoyancy_position 801.760925 cc 2019-07-29T17:41:43.400Z,1564422103.400 [Reporter](INFO): platform_buoyancy_position 797.463318 cc 2019-07-29T17:41:43.811Z,1564422103.811 [Reporter](INFO): platform_buoyancy_position 791.151245 cc 2019-07-29T17:41:44.200Z,1564422104.200 [Reporter](INFO): platform_buoyancy_position 786.853638 cc 2019-07-29T17:41:44.611Z,1564422104.611 [Reporter](INFO): platform_buoyancy_position 780.541565 cc 2019-07-29T17:41:45.028Z,1564422105.028 [Reporter](INFO): platform_buoyancy_position 776.109619 cc 2019-07-29T17:41:45.443Z,1564422105.443 [Reporter](INFO): platform_buoyancy_position 769.797546 cc 2019-07-29T17:41:45.831Z,1564422105.831 [Reporter](INFO): platform_buoyancy_position 765.231323 cc 2019-07-29T17:41:46.235Z,1564422106.235 [Reporter](INFO): platform_buoyancy_position 760.933716 cc 2019-07-29T17:41:46.631Z,1564422106.631 [Reporter](INFO): platform_buoyancy_position 754.487366 cc 2019-07-29T17:41:47.043Z,1564422107.043 [Reporter](INFO): platform_buoyancy_position 750.189758 cc 2019-07-29T17:41:47.439Z,1564422107.439 [Reporter](INFO): platform_buoyancy_position 743.877625 cc 2019-07-29T17:41:47.851Z,1564422107.851 [Reporter](INFO): platform_buoyancy_position 739.580017 cc 2019-07-29T17:41:48.235Z,1564422108.235 [Reporter](INFO): platform_buoyancy_position 734.610962 cc 2019-07-29T17:41:48.647Z,1564422108.647 [Reporter](INFO): platform_buoyancy_position 728.836060 cc 2019-07-29T17:41:49.047Z,1564422109.047 [Reporter](INFO): platform_buoyancy_position 724.404114 cc 2019-07-29T17:41:49.459Z,1564422109.459 [Reporter](INFO): platform_buoyancy_position 718.092041 cc 2019-07-29T17:41:49.875Z,1564422109.875 [Reporter](INFO): platform_buoyancy_position 711.914246 cc 2019-07-29T17:41:50.283Z,1564422110.283 [Reporter](INFO): platform_buoyancy_position 707.482361 cc 2019-07-29T17:41:50.660Z,1564422110.660 [Reporter](INFO): platform_buoyancy_position 702.916138 cc 2019-07-29T17:41:51.075Z,1564422111.075 [Reporter](INFO): platform_buoyancy_position 696.604004 cc 2019-07-29T17:41:51.467Z,1564422111.467 [Reporter](INFO): platform_buoyancy_position 692.172119 cc 2019-07-29T17:41:51.871Z,1564422111.871 [Reporter](INFO): platform_buoyancy_position 685.725708 cc 2019-07-29T17:41:52.279Z,1564422112.279 [Reporter](INFO): platform_buoyancy_position 681.562439 cc 2019-07-29T17:41:52.715Z,1564422112.715 [Reporter](INFO): platform_buoyancy_position 674.981750 cc 2019-07-29T17:41:53.111Z,1564422113.111 [Reporter](INFO): platform_buoyancy_position 670.684143 cc 2019-07-29T17:41:53.517Z,1564422113.517 [Reporter](INFO): platform_buoyancy_position 664.372009 cc 2019-07-29T17:41:53.900Z,1564422113.900 [Reporter](INFO): platform_buoyancy_position 660.074402 cc 2019-07-29T17:41:54.323Z,1564422114.323 [Reporter](INFO): platform_buoyancy_position 654.836731 cc 2019-07-29T17:41:54.707Z,1564422114.707 [Reporter](INFO): platform_buoyancy_position 649.330444 cc 2019-07-29T17:41:55.116Z,1564422115.116 [Reporter](INFO): platform_buoyancy_position 643.421204 cc 2019-07-29T17:41:55.508Z,1564422115.508 [Reporter](INFO): platform_buoyancy_position 638.452148 cc 2019-07-29T17:41:55.927Z,1564422115.927 [Reporter](INFO): platform_buoyancy_position 632.274353 cc 2019-07-29T17:41:56.327Z,1564422116.327 [Reporter](INFO): platform_buoyancy_position 627.708130 cc 2019-07-29T17:41:56.740Z,1564422116.740 [Reporter](INFO): platform_buoyancy_position 622.604736 cc 2019-07-29T17:41:57.135Z,1564422117.135 [Reporter](INFO): platform_buoyancy_position 617.098450 cc 2019-07-29T17:41:57.547Z,1564422117.547 [Reporter](INFO): platform_buoyancy_position 612.666504 cc 2019-07-29T17:41:57.964Z,1564422117.964 [Reporter](INFO): platform_buoyancy_position 606.354431 cc 2019-07-29T17:41:58.351Z,1564422118.351 [Reporter](INFO): platform_buoyancy_position 601.922546 cc 2019-07-29T17:41:58.799Z,1564422118.799 [Reporter](INFO): platform_buoyancy_position 595.610413 cc 2019-07-29T17:41:59.171Z,1564422119.171 [Reporter](INFO): platform_buoyancy_position 591.312805 cc 2019-07-29T17:41:59.587Z,1564422119.587 [Reporter](INFO): platform_buoyancy_position 586.075134 cc 2019-07-29T17:41:59.984Z,1564422119.984 [Reporter](INFO): platform_buoyancy_position 580.434509 cc 2019-07-29T17:42:00.387Z,1564422120.387 [Reporter](INFO): platform_buoyancy_position 574.659607 cc 2019-07-29T17:42:00.811Z,1564422120.811 [Reporter](INFO): platform_buoyancy_position 569.690552 cc 2019-07-29T17:42:01.180Z,1564422121.180 [Reporter](INFO): platform_buoyancy_position 563.647034 cc 2019-07-29T17:42:01.600Z,1564422121.600 [Reporter](INFO): platform_buoyancy_position 558.946533 cc 2019-07-29T17:42:02.016Z,1564422122.016 [Reporter](INFO): platform_buoyancy_position 552.634399 cc 2019-07-29T17:42:02.387Z,1564422122.387 [Reporter](INFO): platform_buoyancy_position 548.336792 cc 2019-07-29T17:42:02.799Z,1564422122.799 [Reporter](INFO): platform_buoyancy_position 542.830505 cc 2019-07-29T17:42:03.203Z,1564422123.203 [Reporter](INFO): platform_buoyancy_position 537.592834 cc 2019-07-29T17:42:03.611Z,1564422123.611 [Reporter](INFO): platform_buoyancy_position 531.549316 cc 2019-07-29T17:42:04.003Z,1564422124.003 [Reporter](INFO): platform_buoyancy_position 526.714539 cc 2019-07-29T17:42:04.419Z,1564422124.419 [Reporter](INFO): platform_buoyancy_position 522.282593 cc 2019-07-29T17:42:04.823Z,1564422124.823 [Reporter](INFO): platform_buoyancy_position 516.104797 cc 2019-07-29T17:42:05.211Z,1564422125.211 [Reporter](INFO): platform_buoyancy_position 511.538635 cc 2019-07-29T17:42:05.615Z,1564422125.615 [Reporter](INFO): platform_buoyancy_position 505.360901 cc 2019-07-29T17:42:06.039Z,1564422126.039 [Reporter](INFO): platform_buoyancy_position 501.063232 cc 2019-07-29T17:42:06.448Z,1564422126.448 [Reporter](INFO): platform_buoyancy_position 496.228394 cc 2019-07-29T17:42:06.839Z,1564422126.839 [Reporter](INFO): platform_buoyancy_position 490.319214 cc 2019-07-29T17:42:07.235Z,1564422127.235 [Reporter](INFO): platform_buoyancy_position 485.887360 cc 2019-07-29T17:42:07.640Z,1564422127.640 [Reporter](INFO): platform_buoyancy_position 479.575226 cc 2019-07-29T17:42:08.059Z,1564422128.059 [Reporter](INFO): platform_buoyancy_position 475.143341 cc 2019-07-29T17:42:08.443Z,1564422128.443 [Reporter](INFO): platform_buoyancy_position 468.831268 cc 2019-07-29T17:42:08.859Z,1564422128.859 [Reporter](INFO): platform_buoyancy_position 463.190643 cc 2019-07-29T17:42:09.259Z,1564422129.259 [Reporter](INFO): platform_buoyancy_position 458.221527 cc 2019-07-29T17:42:09.663Z,1564422129.663 [Reporter](INFO): platform_buoyancy_position 453.655365 cc 2019-07-29T17:42:10.080Z,1564422130.080 [Reporter](INFO): platform_buoyancy_position 449.089142 cc 2019-07-29T17:42:10.491Z,1564422130.491 [Reporter](INFO): platform_buoyancy_position 442.911346 cc 2019-07-29T17:42:10.871Z,1564422130.871 [Reporter](INFO): platform_buoyancy_position 438.210846 cc 2019-07-29T17:42:11.283Z,1564422131.283 [Reporter](INFO): platform_buoyancy_position 432.301666 cc 2019-07-29T17:42:11.663Z,1564422131.663 [Reporter](INFO): platform_buoyancy_position 425.855255 cc 2019-07-29T17:42:12.071Z,1564422132.071 [Reporter](INFO): platform_buoyancy_position 421.423370 cc 2019-07-29T17:42:12.503Z,1564422132.503 [Reporter](INFO): platform_buoyancy_position 415.111237 cc 2019-07-29T17:42:12.883Z,1564422132.883 [Reporter](INFO): platform_buoyancy_position 410.679352 cc 2019-07-29T17:42:13.292Z,1564422133.292 [Reporter](INFO): platform_buoyancy_position 406.247437 cc 2019-07-29T17:42:13.707Z,1564422133.707 [Reporter](INFO): platform_buoyancy_position 400.069641 cc 2019-07-29T17:42:14.099Z,1564422134.099 [Reporter](INFO): platform_buoyancy_position 395.369141 cc 2019-07-29T17:42:14.515Z,1564422134.515 [Reporter](INFO): platform_buoyancy_position 389.191345 cc 2019-07-29T17:42:14.915Z,1564422134.915 [Reporter](INFO): platform_buoyancy_position 382.879242 cc 2019-07-29T17:42:15.319Z,1564422135.319 [Reporter](INFO): platform_buoyancy_position 378.447327 cc 2019-07-29T17:42:15.719Z,1564422135.719 [Reporter](INFO): platform_buoyancy_position 374.015442 cc 2019-07-29T17:42:16.119Z,1564422136.119 [Reporter](INFO): platform_buoyancy_position 368.240540 cc 2019-07-29T17:42:16.527Z,1564422136.527 [Reporter](INFO): platform_buoyancy_position 363.271423 cc 2019-07-29T17:42:16.943Z,1564422136.943 [Reporter](INFO): platform_buoyancy_position 357.093628 cc 2019-07-29T17:42:17.327Z,1564422137.327 [Reporter](INFO): platform_buoyancy_position 352.796051 cc 2019-07-29T17:42:17.731Z,1564422137.731 [Reporter](INFO): platform_buoyancy_position 347.961243 cc 2019-07-29T17:42:18.139Z,1564422138.139 [Reporter](INFO): platform_buoyancy_position 339.903229 cc 2019-07-29T17:42:18.551Z,1564422138.551 [Reporter](INFO): platform_buoyancy_position 335.605621 cc 2019-07-29T17:42:18.963Z,1564422138.963 [Reporter](INFO): platform_buoyancy_position 331.308044 cc 2019-07-29T17:42:19.380Z,1564422139.380 [Reporter](INFO): platform_buoyancy_position 326.876129 cc 2019-07-29T17:42:19.787Z,1564422139.787 [Reporter](INFO): platform_buoyancy_position 320.564026 cc 2019-07-29T17:42:20.172Z,1564422140.172 [Reporter](INFO): platform_buoyancy_position 316.132141 cc 2019-07-29T17:42:20.563Z,1564422140.563 [Reporter](INFO): platform_buoyancy_position 311.834534 cc 2019-07-29T17:42:20.959Z,1564422140.959 [Reporter](INFO): platform_buoyancy_position 305.388123 cc 2019-07-29T17:42:21.375Z,1564422141.375 [Reporter](INFO): platform_buoyancy_position 299.881836 cc 2019-07-29T17:42:21.779Z,1564422141.779 [Reporter](INFO): platform_buoyancy_position 294.644135 cc 2019-07-29T17:42:22.187Z,1564422142.187 [Reporter](INFO): platform_buoyancy_position 288.332031 cc 2019-07-29T17:42:22.599Z,1564422142.599 [Reporter](INFO): platform_buoyancy_position 283.631531 cc 2019-07-29T17:42:22.995Z,1564422142.995 [Reporter](INFO): platform_buoyancy_position 277.588043 cc 2019-07-29T17:42:23.403Z,1564422143.403 [Reporter](INFO): platform_buoyancy_position 273.156128 cc 2019-07-29T17:42:23.816Z,1564422143.816 [Reporter](INFO): platform_buoyancy_position 266.709717 cc 2019-07-29T17:42:24.231Z,1564422144.231 [Reporter](INFO): platform_buoyancy_position 262.143524 cc 2019-07-29T17:42:24.615Z,1564422144.615 [Reporter](INFO): platform_buoyancy_position 256.502930 cc 2019-07-29T17:42:25.035Z,1564422145.035 [Reporter](INFO): platform_buoyancy_position 251.668121 cc 2019-07-29T17:42:25.420Z,1564422145.420 [Reporter](INFO): platform_buoyancy_position 247.370544 cc 2019-07-29T17:42:25.831Z,1564422145.831 [Reporter](INFO): platform_buoyancy_position 241.058441 cc 2019-07-29T17:42:26.251Z,1564422146.251 [Reporter](INFO): platform_buoyancy_position 236.492218 cc 2019-07-29T17:42:26.639Z,1564422146.639 [Reporter](INFO): platform_buoyancy_position 230.180115 cc 2019-07-29T17:42:27.047Z,1564422147.047 [Reporter](INFO): platform_buoyancy_position 225.882538 cc 2019-07-29T17:42:27.435Z,1564422147.435 [Reporter](INFO): platform_buoyancy_position 221.047729 cc 2019-07-29T17:42:27.851Z,1564422147.851 [Reporter](INFO): platform_buoyancy_position 215.138519 cc 2019-07-29T17:42:31.531Z,1564422151.531 [Reporter](INFO): platform_buoyancy_position 202.380035 cc 2019-07-29T17:42:46.044Z,1564422166.044 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 15.000000 millimeter 2019-07-29T17:42:48.491Z,1564422168.491 [Reporter](INFO): platform_mass_position 0.013037 m 2019-07-29T17:42:48.892Z,1564422168.892 [Reporter](INFO): platform_mass_position 0.013351 m 2019-07-29T17:42:49.307Z,1564422169.307 [Reporter](INFO): platform_mass_position 0.013676 m 2019-07-29T17:42:49.711Z,1564422169.711 [Reporter](INFO): platform_mass_position 0.013992 m 2019-07-29T17:42:50.123Z,1564422170.123 [Reporter](INFO): platform_mass_position 0.014315 m 2019-07-29T17:42:50.523Z,1564422170.523 [Reporter](INFO): platform_mass_position 0.014626 m 2019-07-29T17:42:50.922Z,1564422170.922 [Reporter](INFO): platform_mass_position 0.014934 m 2019-07-29T17:42:51.334Z,1564422171.334 [Reporter](INFO): platform_mass_position 0.014999 m 2019-07-29T17:43:05.517Z,1564422185.517 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 15.000000 millimeter 2019-07-29T17:43:09.451Z,1564422189.451 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:43:09.452Z,1564422189.452 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:43:14.120Z,1564422194.120 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter 2019-07-29T17:43:16.835Z,1564422196.835 [Reporter](INFO): platform_mass_position 0.015052 m 2019-07-29T17:43:17.239Z,1564422197.239 [Reporter](INFO): platform_mass_position 0.015369 m 2019-07-29T17:43:17.771Z,1564422197.771 [Reporter](INFO): platform_mass_position 0.015785 m 2019-07-29T17:43:18.051Z,1564422198.051 [Reporter](INFO): platform_mass_position 0.016004 m 2019-07-29T17:43:18.471Z,1564422198.471 [Reporter](INFO): platform_mass_position 0.016333 m 2019-07-29T17:43:18.891Z,1564422198.891 [Reporter](INFO): platform_mass_position 0.016661 m 2019-07-29T17:43:19.259Z,1564422199.259 [Reporter](INFO): platform_mass_position 0.016949 m 2019-07-29T17:43:19.675Z,1564422199.675 [Reporter](INFO): platform_mass_position 0.017266 m 2019-07-29T17:43:20.063Z,1564422200.063 [Reporter](INFO): platform_mass_position 0.017578 m 2019-07-29T17:43:20.463Z,1564422200.463 [Reporter](INFO): platform_mass_position 0.017892 m 2019-07-29T17:43:20.875Z,1564422200.875 [Reporter](INFO): platform_mass_position 0.018214 m 2019-07-29T17:43:21.283Z,1564422201.283 [Reporter](INFO): platform_mass_position 0.018533 m 2019-07-29T17:43:21.606Z,1564422201.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:43:21.606Z,1564422201.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:43:21.711Z,1564422201.711 [Reporter](INFO): platform_mass_position 0.018869 m 2019-07-29T17:43:22.107Z,1564422202.107 [Reporter](INFO): platform_mass_position 0.019178 m 2019-07-29T17:43:22.503Z,1564422202.503 [Reporter](INFO): platform_mass_position 0.019488 m 2019-07-29T17:43:22.899Z,1564422202.899 [Reporter](INFO): platform_mass_position 0.019795 m 2019-07-29T17:43:23.315Z,1564422203.315 [Reporter](INFO): platform_mass_position 0.019999 m 2019-07-29T17:43:30.500Z,1564422210.500 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:43:30.500Z,1564422210.500 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:43:48.274Z,1564422228.274 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:43:48.274Z,1564422228.274 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:12.512Z,1564422252.512 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:12.512Z,1564422252.512 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:21.439Z,1564422261.439 [CommandLine](IMPORTANT): got command maintain clear 2019-07-29T17:44:21.843Z,1564422261.843 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:44:24.427Z,1564422264.427 [Reporter](INFO): platform_mass_position 0.019889 m 2019-07-29T17:44:24.767Z,1564422264.767 [Reporter](INFO): platform_mass_position 0.019627 m 2019-07-29T17:44:25.170Z,1564422265.170 [Reporter](INFO): platform_mass_position 0.019310 m 2019-07-29T17:44:25.583Z,1564422265.583 [Reporter](INFO): platform_mass_position 0.018985 m 2019-07-29T17:44:25.999Z,1564422265.999 [Reporter](INFO): platform_mass_position 0.018662 m 2019-07-29T17:44:26.379Z,1564422266.379 [Reporter](INFO): platform_mass_position 0.018365 m 2019-07-29T17:44:26.863Z,1564422266.863 [Reporter](INFO): platform_mass_position 0.017983 m 2019-07-29T17:44:26.864Z,1564422266.864 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2019-07-29T17:44:27.239Z,1564422267.239 [Reporter](INFO): platform_mass_position 0.017689 m 2019-07-29T17:44:27.240Z,1564422267.240 [Reporter](INFO): platform_buoyancy_position 198.082428 cc 2019-07-29T17:44:27.623Z,1564422267.623 [Reporter](INFO): platform_mass_position 0.017388 m 2019-07-29T17:44:27.624Z,1564422267.624 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2019-07-29T17:44:28.031Z,1564422268.031 [Reporter](INFO): platform_mass_position 0.017069 m 2019-07-29T17:44:28.032Z,1564422268.032 [Reporter](INFO): platform_buoyancy_position 197.813812 cc 2019-07-29T17:44:28.447Z,1564422268.447 [Reporter](INFO): platform_mass_position 0.016756 m 2019-07-29T17:44:28.448Z,1564422268.448 [Reporter](INFO): platform_buoyancy_position 199.156830 cc 2019-07-29T17:44:28.724Z,1564422268.724 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:28.724Z,1564422268.724 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:28.891Z,1564422268.891 [Reporter](INFO): platform_mass_position 0.016396 m 2019-07-29T17:44:28.892Z,1564422268.892 [Reporter](INFO): platform_buoyancy_position 204.528839 cc 2019-07-29T17:44:29.283Z,1564422269.283 [Reporter](INFO): platform_mass_position 0.016105 m 2019-07-29T17:44:29.284Z,1564422269.284 [Reporter](INFO): platform_buoyancy_position 210.840912 cc 2019-07-29T17:44:29.667Z,1564422269.667 [Reporter](INFO): platform_mass_position 0.016001 m 2019-07-29T17:44:29.668Z,1564422269.668 [Reporter](INFO): platform_buoyancy_position 217.287323 cc 2019-07-29T17:44:30.135Z,1564422270.135 [Reporter](INFO): platform_buoyancy_position 223.733734 cc 2019-07-29T17:44:30.427Z,1564422270.427 [Reporter](INFO): platform_buoyancy_position 228.165619 cc 2019-07-29T17:44:30.803Z,1564422270.803 [Reporter](INFO): platform_buoyancy_position 234.612030 cc 2019-07-29T17:44:31.211Z,1564422271.211 [Reporter](INFO): platform_buoyancy_position 238.909637 cc 2019-07-29T17:44:31.623Z,1564422271.623 [Reporter](INFO): platform_buoyancy_position 245.356033 cc 2019-07-29T17:44:32.035Z,1564422272.035 [Reporter](INFO): platform_buoyancy_position 251.802444 cc 2019-07-29T17:44:32.447Z,1564422272.447 [Reporter](INFO): platform_buoyancy_position 257.980225 cc 2019-07-29T17:44:32.835Z,1564422272.835 [Reporter](INFO): platform_buoyancy_position 262.412140 cc 2019-07-29T17:44:33.276Z,1564422273.276 [Reporter](INFO): platform_buoyancy_position 268.992828 cc 2019-07-29T17:44:33.663Z,1564422273.663 [Reporter](INFO): platform_buoyancy_position 273.156128 cc 2019-07-29T17:44:34.035Z,1564422274.035 [Reporter](INFO): platform_buoyancy_position 278.393829 cc 2019-07-29T17:44:34.439Z,1564422274.439 [Reporter](INFO): platform_buoyancy_position 284.034424 cc 2019-07-29T17:44:34.863Z,1564422274.863 [Reporter](INFO): platform_buoyancy_position 290.480835 cc 2019-07-29T17:44:35.260Z,1564422275.260 [Reporter](INFO): platform_buoyancy_position 294.912720 cc 2019-07-29T17:44:35.663Z,1564422275.663 [Reporter](INFO): platform_buoyancy_position 299.076019 cc 2019-07-29T17:44:36.079Z,1564422276.079 [Reporter](INFO): platform_buoyancy_position 305.388123 cc 2019-07-29T17:44:36.515Z,1564422276.515 [Reporter](INFO): platform_buoyancy_position 310.222931 cc 2019-07-29T17:44:36.883Z,1564422276.883 [Reporter](INFO): platform_buoyancy_position 315.326324 cc 2019-07-29T17:44:37.213Z,1564422277.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:37.214Z,1564422277.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:37.275Z,1564422277.275 [Reporter](INFO): platform_buoyancy_position 320.564026 cc 2019-07-29T17:44:37.691Z,1564422277.691 [Reporter](INFO): platform_buoyancy_position 325.264526 cc 2019-07-29T17:44:38.083Z,1564422278.083 [Reporter](INFO): platform_buoyancy_position 331.173737 cc 2019-07-29T17:44:38.495Z,1564422278.495 [Reporter](INFO): platform_buoyancy_position 335.471344 cc 2019-07-29T17:44:38.903Z,1564422278.903 [Reporter](INFO): platform_buoyancy_position 341.917725 cc 2019-07-29T17:44:39.303Z,1564422279.303 [Reporter](INFO): platform_buoyancy_position 348.498444 cc 2019-07-29T17:44:39.695Z,1564422279.695 [Reporter](INFO): platform_buoyancy_position 352.796051 cc 2019-07-29T17:44:40.103Z,1564422280.103 [Reporter](INFO): platform_buoyancy_position 359.108124 cc 2019-07-29T17:44:40.511Z,1564422280.511 [Reporter](INFO): platform_buoyancy_position 363.540039 cc 2019-07-29T17:44:40.919Z,1564422280.919 [Reporter](INFO): platform_buoyancy_position 369.986450 cc 2019-07-29T17:44:41.308Z,1564422281.308 [Reporter](INFO): platform_buoyancy_position 374.284027 cc 2019-07-29T17:44:41.724Z,1564422281.724 [Reporter](INFO): platform_buoyancy_position 378.447327 cc 2019-07-29T17:44:42.131Z,1564422282.131 [Reporter](INFO): platform_buoyancy_position 385.028046 cc 2019-07-29T17:44:42.519Z,1564422282.519 [Reporter](INFO): platform_buoyancy_position 389.459930 cc 2019-07-29T17:44:42.919Z,1564422282.919 [Reporter](INFO): platform_buoyancy_position 395.637726 cc 2019-07-29T17:44:43.347Z,1564422283.347 [Reporter](INFO): platform_buoyancy_position 402.218445 cc 2019-07-29T17:44:43.739Z,1564422283.739 [Reporter](INFO): platform_buoyancy_position 406.381744 cc 2019-07-29T17:44:44.151Z,1564422284.151 [Reporter](INFO): platform_buoyancy_position 410.947937 cc 2019-07-29T17:44:44.540Z,1564422284.540 [Reporter](INFO): platform_buoyancy_position 417.125763 cc 2019-07-29T17:44:44.948Z,1564422284.948 [Reporter](INFO): platform_buoyancy_position 421.423370 cc 2019-07-29T17:44:45.367Z,1564422285.367 [Reporter](INFO): platform_buoyancy_position 427.869720 cc 2019-07-29T17:44:45.751Z,1564422285.751 [Reporter](INFO): platform_buoyancy_position 432.301666 cc 2019-07-29T17:44:46.094Z,1564422286.094 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:46.094Z,1564422286.094 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:46.151Z,1564422286.151 [Reporter](INFO): platform_buoyancy_position 436.867828 cc 2019-07-29T17:44:46.575Z,1564422286.575 [Reporter](INFO): platform_buoyancy_position 442.911346 cc 2019-07-29T17:44:46.975Z,1564422286.975 [Reporter](INFO): platform_buoyancy_position 447.611847 cc 2019-07-29T17:44:47.379Z,1564422287.379 [Reporter](INFO): platform_buoyancy_position 453.655365 cc 2019-07-29T17:44:47.791Z,1564422287.791 [Reporter](INFO): platform_buoyancy_position 458.087250 cc 2019-07-29T17:44:48.180Z,1564422288.180 [Reporter](INFO): platform_buoyancy_position 464.533661 cc 2019-07-29T17:44:48.595Z,1564422288.595 [Reporter](INFO): platform_buoyancy_position 468.965546 cc 2019-07-29T17:44:48.979Z,1564422288.979 [Reporter](INFO): platform_buoyancy_position 475.277618 cc 2019-07-29T17:44:49.391Z,1564422289.391 [Reporter](INFO): platform_buoyancy_position 481.724030 cc 2019-07-29T17:44:49.791Z,1564422289.791 [Reporter](INFO): platform_buoyancy_position 485.887360 cc 2019-07-29T17:44:50.219Z,1564422290.219 [Reporter](INFO): platform_buoyancy_position 491.125031 cc 2019-07-29T17:44:50.611Z,1564422290.611 [Reporter](INFO): platform_buoyancy_position 496.631378 cc 2019-07-29T17:44:51.023Z,1564422291.023 [Reporter](INFO): platform_buoyancy_position 501.063232 cc 2019-07-29T17:44:51.415Z,1564422291.415 [Reporter](INFO): platform_buoyancy_position 507.241058 cc 2019-07-29T17:44:51.824Z,1564422291.824 [Reporter](INFO): platform_buoyancy_position 511.672943 cc 2019-07-29T17:44:52.216Z,1564422292.216 [Reporter](INFO): platform_buoyancy_position 518.119324 cc 2019-07-29T17:44:52.631Z,1564422292.631 [Reporter](INFO): platform_buoyancy_position 522.551208 cc 2019-07-29T17:44:53.031Z,1564422293.031 [Reporter](INFO): platform_buoyancy_position 528.594727 cc 2019-07-29T17:44:53.435Z,1564422293.435 [Reporter](INFO): platform_buoyancy_position 533.160950 cc 2019-07-29T17:44:53.843Z,1564422293.843 [Reporter](INFO): platform_buoyancy_position 537.995728 cc 2019-07-29T17:44:54.256Z,1564422294.256 [Reporter](INFO): platform_buoyancy_position 544.173523 cc 2019-07-29T17:44:54.577Z,1564422294.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:54.578Z,1564422294.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:54.664Z,1564422294.664 [Reporter](INFO): platform_buoyancy_position 548.336792 cc 2019-07-29T17:44:55.064Z,1564422295.064 [Reporter](INFO): platform_buoyancy_position 554.648926 cc 2019-07-29T17:44:55.451Z,1564422295.451 [Reporter](INFO): platform_buoyancy_position 559.080811 cc 2019-07-29T17:44:55.855Z,1564422295.855 [Reporter](INFO): platform_buoyancy_position 565.392944 cc 2019-07-29T17:44:56.272Z,1564422296.272 [Reporter](INFO): platform_buoyancy_position 569.824829 cc 2019-07-29T17:44:56.708Z,1564422296.708 [Reporter](INFO): platform_buoyancy_position 576.271240 cc 2019-07-29T17:44:57.151Z,1564422297.151 [Reporter](INFO): platform_buoyancy_position 580.703125 cc 2019-07-29T17:44:57.572Z,1564422297.572 [Reporter](INFO): platform_buoyancy_position 586.880920 cc 2019-07-29T17:44:57.996Z,1564422297.996 [Reporter](INFO): platform_buoyancy_position 591.312805 cc 2019-07-29T17:44:58.335Z,1564422298.335 [Reporter](INFO): platform_buoyancy_position 595.610413 cc 2019-07-29T17:44:58.728Z,1564422298.728 [Reporter](INFO): platform_buoyancy_position 601.922546 cc 2019-07-29T17:44:59.143Z,1564422299.143 [Reporter](INFO): platform_buoyancy_position 607.428833 cc 2019-07-29T17:44:59.440Z,1564422299.440 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:44:59.440Z,1564422299.440 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:44:59.567Z,1564422299.567 [Reporter](INFO): platform_buoyancy_position 612.800842 cc 2019-07-29T17:44:59.947Z,1564422299.947 [Reporter](INFO): platform_buoyancy_position 618.307129 cc 2019-07-29T17:45:00.336Z,1564422300.336 [Reporter](INFO): platform_buoyancy_position 623.544800 cc 2019-07-29T17:45:00.763Z,1564422300.763 [Reporter](INFO): platform_buoyancy_position 629.185425 cc 2019-07-29T17:45:01.127Z,1564422301.127 [Reporter](INFO): platform_buoyancy_position 634.288818 cc 2019-07-29T17:45:01.548Z,1564422301.548 [Reporter](INFO): platform_buoyancy_position 638.452148 cc 2019-07-29T17:45:01.952Z,1564422301.952 [Reporter](INFO): platform_buoyancy_position 644.898499 cc 2019-07-29T17:45:02.331Z,1564422302.331 [Reporter](INFO): platform_buoyancy_position 649.330444 cc 2019-07-29T17:45:02.735Z,1564422302.735 [Reporter](INFO): platform_buoyancy_position 654.165222 cc 2019-07-29T17:45:03.144Z,1564422303.144 [Reporter](INFO): platform_buoyancy_position 659.940125 cc 2019-07-29T17:45:03.555Z,1564422303.555 [Reporter](INFO): platform_buoyancy_position 664.237732 cc 2019-07-29T17:45:03.972Z,1564422303.972 [Reporter](INFO): platform_buoyancy_position 670.818420 cc 2019-07-29T17:45:04.352Z,1564422304.352 [Reporter](INFO): platform_buoyancy_position 675.116028 cc 2019-07-29T17:45:04.767Z,1564422304.767 [Reporter](INFO): platform_buoyancy_position 681.562439 cc 2019-07-29T17:45:05.156Z,1564422305.156 [Reporter](INFO): platform_buoyancy_position 685.860046 cc 2019-07-29T17:45:05.559Z,1564422305.559 [Reporter](INFO): platform_buoyancy_position 692.037842 cc 2019-07-29T17:45:05.963Z,1564422305.963 [Reporter](INFO): platform_buoyancy_position 696.604004 cc 2019-07-29T17:45:06.376Z,1564422306.376 [Reporter](INFO): platform_buoyancy_position 702.916138 cc 2019-07-29T17:45:06.779Z,1564422306.779 [Reporter](INFO): platform_buoyancy_position 707.348022 cc 2019-07-29T17:45:07.195Z,1564422307.195 [Reporter](INFO): platform_buoyancy_position 711.511353 cc 2019-07-29T17:45:07.522Z,1564422307.522 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:45:07.522Z,1564422307.522 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:45:07.596Z,1564422307.596 [Reporter](INFO): platform_buoyancy_position 717.957764 cc 2019-07-29T17:45:07.996Z,1564422307.996 [Reporter](INFO): platform_buoyancy_position 722.255310 cc 2019-07-29T17:45:08.411Z,1564422308.411 [Reporter](INFO): platform_buoyancy_position 726.821533 cc 2019-07-29T17:45:08.835Z,1564422308.835 [Reporter](INFO): platform_buoyancy_position 732.999329 cc 2019-07-29T17:45:09.199Z,1564422309.199 [Reporter](INFO): platform_buoyancy_position 737.431213 cc 2019-07-29T17:45:09.603Z,1564422309.603 [Reporter](INFO): platform_buoyancy_position 743.071838 cc 2019-07-29T17:45:10.011Z,1564422310.011 [Reporter](INFO): platform_buoyancy_position 748.040955 cc 2019-07-29T17:45:10.419Z,1564422310.419 [Reporter](INFO): platform_buoyancy_position 754.487366 cc 2019-07-29T17:45:10.835Z,1564422310.835 [Reporter](INFO): platform_buoyancy_position 758.919250 cc 2019-07-29T17:45:11.220Z,1564422311.220 [Reporter](INFO): platform_buoyancy_position 763.216858 cc 2019-07-29T17:45:11.635Z,1564422311.635 [Reporter](INFO): platform_buoyancy_position 769.663208 cc 2019-07-29T17:45:12.055Z,1564422312.055 [Reporter](INFO): platform_buoyancy_position 774.095154 cc 2019-07-29T17:45:12.431Z,1564422312.431 [Reporter](INFO): platform_buoyancy_position 780.272949 cc 2019-07-29T17:45:12.831Z,1564422312.831 [Reporter](INFO): platform_buoyancy_position 784.570557 cc 2019-07-29T17:45:13.239Z,1564422313.239 [Reporter](INFO): platform_buoyancy_position 791.151245 cc 2019-07-29T17:45:13.647Z,1564422313.647 [Reporter](INFO): platform_buoyancy_position 795.583130 cc 2019-07-29T17:45:14.063Z,1564422314.063 [Reporter](INFO): platform_buoyancy_position 801.895264 cc 2019-07-29T17:45:14.448Z,1564422314.448 [Reporter](INFO): platform_buoyancy_position 806.192810 cc 2019-07-29T17:45:14.863Z,1564422314.863 [Reporter](INFO): platform_buoyancy_position 812.504944 cc 2019-07-29T17:45:15.255Z,1564422315.255 [Reporter](INFO): platform_buoyancy_position 816.936829 cc 2019-07-29T17:45:15.663Z,1564422315.663 [Reporter](INFO): platform_buoyancy_position 823.248962 cc 2019-07-29T17:45:16.063Z,1564422316.063 [Reporter](INFO): platform_buoyancy_position 827.680847 cc 2019-07-29T17:45:16.414Z,1564422316.414 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:45:16.414Z,1564422316.414 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:45:16.471Z,1564422316.471 [Reporter](INFO): platform_buoyancy_position 833.992920 cc 2019-07-29T17:45:16.891Z,1564422316.891 [Reporter](INFO): platform_buoyancy_position 838.290527 cc 2019-07-29T17:45:17.292Z,1564422317.292 [Reporter](INFO): platform_buoyancy_position 844.736938 cc 2019-07-29T17:45:17.683Z,1564422317.683 [Reporter](INFO): platform_buoyancy_position 849.168823 cc 2019-07-29T17:45:18.092Z,1564422318.092 [Reporter](INFO): platform_buoyancy_position 853.466431 cc 2019-07-29T17:45:18.507Z,1564422318.507 [Reporter](INFO): platform_buoyancy_position 859.912842 cc 2019-07-29T17:45:18.891Z,1564422318.891 [Reporter](INFO): platform_buoyancy_position 864.076172 cc 2019-07-29T17:45:19.295Z,1564422319.295 [Reporter](INFO): platform_buoyancy_position 868.642334 cc 2019-07-29T17:45:19.703Z,1564422319.703 [Reporter](INFO): platform_buoyancy_position 874.820129 cc 2019-07-29T17:45:20.111Z,1564422320.111 [Reporter](INFO): platform_buoyancy_position 879.252075 cc 2019-07-29T17:45:20.555Z,1564422320.555 [Reporter](INFO): platform_buoyancy_position 885.564148 cc 2019-07-29T17:45:20.939Z,1564422320.939 [Reporter](INFO): platform_buoyancy_position 892.010559 cc 2019-07-29T17:45:21.323Z,1564422321.323 [Reporter](INFO): platform_buoyancy_position 896.576721 cc 2019-07-29T17:45:21.728Z,1564422321.728 [Reporter](INFO): platform_buoyancy_position 902.888855 cc 2019-07-29T17:45:22.143Z,1564422322.143 [Reporter](INFO): platform_buoyancy_position 907.186462 cc 2019-07-29T17:45:22.539Z,1564422322.539 [Reporter](INFO): platform_buoyancy_position 913.498535 cc 2019-07-29T17:45:22.940Z,1564422322.940 [Reporter](INFO): platform_buoyancy_position 917.796143 cc 2019-07-29T17:45:23.344Z,1564422323.344 [Reporter](INFO): platform_buoyancy_position 924.242554 cc 2019-07-29T17:45:23.751Z,1564422323.751 [Reporter](INFO): platform_buoyancy_position 928.540161 cc 2019-07-29T17:45:24.155Z,1564422324.155 [Reporter](INFO): platform_buoyancy_position 933.643555 cc 2019-07-29T17:45:24.552Z,1564422324.552 [Reporter](INFO): platform_buoyancy_position 939.284180 cc 2019-07-29T17:45:28.687Z,1564422328.687 [Reporter](INFO): platform_buoyancy_position 952.177002 cc 2019-07-29T17:45:33.385Z,1564422333.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=31116, MT Status=2, MTMSN=0 2019-07-29T17:45:33.385Z,1564422333.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T17:45:33.782Z,1564422333.782 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-29T17:45:49.157Z,1564422349.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:45:49.157Z,1564422349.157 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:45:49.157Z,1564422349.157 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:45:49.158Z,1564422349.158 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:45:49.564Z,1564422349.564 [Default:CheckIn:D] Stopped 2019-07-29T17:45:49.564Z,1564422349.564 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:45:49.972Z,1564422349.972 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.698796 min 2019-07-29T17:45:49.972Z,1564422349.972 [Default:CheckIn:E] Stopped 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn] Stopped 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn](INFO): Running loop #18 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn] Running Loop=18 2019-07-29T17:45:49.973Z,1564422349.973 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:45:49.974Z,1564422349.974 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:45:51.569Z,1564422351.569 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:45:51.959Z,1564422351.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173937.00,A,3648.17728,N,12147.28096,W,0.078,358.83,290719,,,D*79 2019-07-29T17:45:51.961Z,1564422351.961 [NAL9602](INFO): GPS fix at 20190729T173937: (36.802955, -121.788016) 2019-07-29T17:45:51.986Z,1564422351.986 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:45:51.986Z,1564422351.986 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:45:58.149Z,1564422358.149 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190729T162726/Courier0045.lzma 2019-07-29T17:45:58.955Z,1564422358.955 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0045.lzma.bak 2019-07-29T17:45:58.955Z,1564422358.955 [DataOverHttps](INFO): SBD MOMSN=11482601 2019-07-29T17:46:10.250Z,1564422370.250 [DataOverHttps](INFO): Sending 347 bytes from file Logs/20190729T162726/Express0046.lzma 2019-07-29T17:46:11.055Z,1564422371.055 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0046.lzma.bak 2019-07-29T17:46:11.055Z,1564422371.055 [DataOverHttps](INFO): SBD MOMSN=11482604 2019-07-29T17:46:11.781Z,1564422371.781 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:46:11.781Z,1564422371.781 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:46:11.782Z,1564422371.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:46:24.282Z,1564422384.282 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:47:33.380Z,1564422453.380 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-07-29T17:47:33.381Z,1564422453.381 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-07-29T17:47:33.411Z,1564422453.411 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:47:38.315Z,1564422458.315 [Reporter](INFO): platform_buoyancy_position 958.757690 cc 2019-07-29T17:47:39.495Z,1564422459.495 [Reporter](INFO): platform_buoyancy_position 956.743164 cc 2019-07-29T17:47:39.895Z,1564422459.895 [Reporter](INFO): platform_buoyancy_position 952.445557 cc 2019-07-29T17:47:40.291Z,1564422460.291 [Reporter](INFO): platform_buoyancy_position 941.567261 cc 2019-07-29T17:47:40.695Z,1564422460.695 [Reporter](INFO): platform_buoyancy_position 938.881226 cc 2019-07-29T17:47:41.099Z,1564422461.099 [Reporter](INFO): platform_buoyancy_position 933.777832 cc 2019-07-29T17:47:41.515Z,1564422461.515 [Reporter](INFO): platform_buoyancy_position 928.674438 cc 2019-07-29T17:47:41.919Z,1564422461.919 [Reporter](INFO): platform_buoyancy_position 924.242554 cc 2019-07-29T17:47:42.340Z,1564422462.340 [Reporter](INFO): platform_buoyancy_position 917.930420 cc 2019-07-29T17:47:42.783Z,1564422462.783 [Reporter](INFO): platform_buoyancy_position 913.632874 cc 2019-07-29T17:47:43.245Z,1564422463.245 [Reporter](INFO): platform_buoyancy_position 907.186462 cc 2019-07-29T17:47:43.584Z,1564422463.584 [Reporter](INFO): platform_buoyancy_position 902.754517 cc 2019-07-29T17:47:43.999Z,1564422463.999 [Reporter](INFO): platform_buoyancy_position 896.442444 cc 2019-07-29T17:47:44.395Z,1564422464.395 [Reporter](INFO): platform_buoyancy_position 892.010559 cc 2019-07-29T17:47:44.751Z,1564422464.751 [Reporter](INFO): platform_buoyancy_position 887.712952 cc 2019-07-29T17:47:45.167Z,1564422465.167 [Reporter](INFO): platform_buoyancy_position 881.400818 cc 2019-07-29T17:47:45.603Z,1564422465.603 [Reporter](INFO): platform_buoyancy_position 877.103271 cc 2019-07-29T17:47:45.984Z,1564422465.984 [Reporter](INFO): platform_buoyancy_position 872.402771 cc 2019-07-29T17:47:46.375Z,1564422466.375 [Reporter](INFO): platform_buoyancy_position 866.224915 cc 2019-07-29T17:47:46.819Z,1564422466.819 [Reporter](INFO): platform_buoyancy_position 861.793030 cc 2019-07-29T17:47:47.163Z,1564422467.163 [Reporter](INFO): platform_buoyancy_position 855.480957 cc 2019-07-29T17:47:47.595Z,1564422467.595 [Reporter](INFO): platform_buoyancy_position 851.183350 cc 2019-07-29T17:47:47.983Z,1564422467.983 [Reporter](INFO): platform_buoyancy_position 844.871216 cc 2019-07-29T17:47:48.371Z,1564422468.371 [Reporter](INFO): platform_buoyancy_position 840.439331 cc 2019-07-29T17:47:48.780Z,1564422468.780 [Reporter](INFO): platform_buoyancy_position 833.992920 cc 2019-07-29T17:47:49.207Z,1564422469.207 [Reporter](INFO): platform_buoyancy_position 829.829651 cc 2019-07-29T17:47:49.579Z,1564422469.579 [Reporter](INFO): platform_buoyancy_position 823.383240 cc 2019-07-29T17:47:49.987Z,1564422469.987 [Reporter](INFO): platform_buoyancy_position 818.951355 cc 2019-07-29T17:47:50.395Z,1564422470.395 [Reporter](INFO): platform_buoyancy_position 812.639221 cc 2019-07-29T17:47:50.803Z,1564422470.803 [Reporter](INFO): platform_buoyancy_position 808.207336 cc 2019-07-29T17:47:51.211Z,1564422471.211 [Reporter](INFO): platform_buoyancy_position 801.895264 cc 2019-07-29T17:47:51.603Z,1564422471.603 [Reporter](INFO): platform_buoyancy_position 797.463318 cc 2019-07-29T17:47:52.008Z,1564422472.008 [Reporter](INFO): platform_buoyancy_position 792.225647 cc 2019-07-29T17:47:52.428Z,1564422472.428 [Reporter](INFO): platform_buoyancy_position 786.853638 cc 2019-07-29T17:47:52.815Z,1564422472.815 [Reporter](INFO): platform_buoyancy_position 782.421753 cc 2019-07-29T17:47:53.223Z,1564422473.223 [Reporter](INFO): platform_buoyancy_position 775.975342 cc 2019-07-29T17:47:53.627Z,1564422473.627 [Reporter](INFO): platform_buoyancy_position 771.409119 cc 2019-07-29T17:47:54.039Z,1564422474.039 [Reporter](INFO): platform_buoyancy_position 765.365662 cc 2019-07-29T17:47:54.439Z,1564422474.439 [Reporter](INFO): platform_buoyancy_position 760.933716 cc 2019-07-29T17:47:54.855Z,1564422474.855 [Reporter](INFO): platform_buoyancy_position 754.621643 cc 2019-07-29T17:47:55.243Z,1564422475.243 [Reporter](INFO): platform_buoyancy_position 750.189758 cc 2019-07-29T17:47:55.652Z,1564422475.652 [Reporter](INFO): platform_buoyancy_position 743.877625 cc 2019-07-29T17:47:56.064Z,1564422476.064 [Reporter](INFO): platform_buoyancy_position 739.445740 cc 2019-07-29T17:47:56.591Z,1564422476.591 [Reporter](INFO): platform_buoyancy_position 732.999329 cc 2019-07-29T17:47:56.872Z,1564422476.872 [Reporter](INFO): platform_buoyancy_position 728.836060 cc 2019-07-29T17:47:57.295Z,1564422477.295 [Reporter](INFO): platform_buoyancy_position 724.269836 cc 2019-07-29T17:47:57.699Z,1564422477.699 [Reporter](INFO): platform_buoyancy_position 718.092041 cc 2019-07-29T17:47:58.083Z,1564422478.083 [Reporter](INFO): platform_buoyancy_position 713.257263 cc 2019-07-29T17:47:58.468Z,1564422478.468 [Reporter](INFO): platform_buoyancy_position 707.213745 cc 2019-07-29T17:47:58.883Z,1564422478.883 [Reporter](INFO): platform_buoyancy_position 702.916138 cc 2019-07-29T17:47:59.291Z,1564422479.291 [Reporter](INFO): platform_buoyancy_position 696.872620 cc 2019-07-29T17:47:59.683Z,1564422479.683 [Reporter](INFO): platform_buoyancy_position 692.172119 cc 2019-07-29T17:48:00.091Z,1564422480.091 [Reporter](INFO): platform_buoyancy_position 685.725708 cc 2019-07-29T17:48:00.491Z,1564422480.491 [Reporter](INFO): platform_buoyancy_position 681.562439 cc 2019-07-29T17:48:00.899Z,1564422480.899 [Reporter](INFO): platform_buoyancy_position 675.116028 cc 2019-07-29T17:48:01.311Z,1564422481.311 [Reporter](INFO): platform_buoyancy_position 670.952759 cc 2019-07-29T17:48:01.719Z,1564422481.719 [Reporter](INFO): platform_buoyancy_position 664.237732 cc 2019-07-29T17:48:02.128Z,1564422482.128 [Reporter](INFO): platform_buoyancy_position 659.940125 cc 2019-07-29T17:48:02.523Z,1564422482.523 [Reporter](INFO): platform_buoyancy_position 655.508240 cc 2019-07-29T17:48:02.911Z,1564422482.911 [Reporter](INFO): platform_buoyancy_position 649.196106 cc 2019-07-29T17:48:03.319Z,1564422483.319 [Reporter](INFO): platform_buoyancy_position 644.898499 cc 2019-07-29T17:48:03.779Z,1564422483.779 [Reporter](INFO): platform_buoyancy_position 638.586426 cc 2019-07-29T17:48:04.131Z,1564422484.131 [Reporter](INFO): platform_buoyancy_position 633.617310 cc 2019-07-29T17:48:04.547Z,1564422484.547 [Reporter](INFO): platform_buoyancy_position 627.842407 cc 2019-07-29T17:48:04.951Z,1564422484.951 [Reporter](INFO): platform_buoyancy_position 623.276245 cc 2019-07-29T17:48:05.363Z,1564422485.363 [Reporter](INFO): platform_buoyancy_position 616.829834 cc 2019-07-29T17:48:05.752Z,1564422485.752 [Reporter](INFO): platform_buoyancy_position 612.666504 cc 2019-07-29T17:48:06.167Z,1564422486.167 [Reporter](INFO): platform_buoyancy_position 606.354431 cc 2019-07-29T17:48:06.555Z,1564422486.555 [Reporter](INFO): platform_buoyancy_position 602.056824 cc 2019-07-29T17:48:06.959Z,1564422486.959 [Reporter](INFO): platform_buoyancy_position 595.610413 cc 2019-07-29T17:48:07.367Z,1564422487.367 [Reporter](INFO): platform_buoyancy_position 591.312805 cc 2019-07-29T17:48:07.775Z,1564422487.775 [Reporter](INFO): platform_buoyancy_position 586.880920 cc 2019-07-29T17:48:08.191Z,1564422488.191 [Reporter](INFO): platform_buoyancy_position 580.434509 cc 2019-07-29T17:48:08.579Z,1564422488.579 [Reporter](INFO): platform_buoyancy_position 576.136902 cc 2019-07-29T17:48:08.984Z,1564422488.984 [Reporter](INFO): platform_buoyancy_position 569.824829 cc 2019-07-29T17:48:09.399Z,1564422489.399 [Reporter](INFO): platform_buoyancy_position 565.392944 cc 2019-07-29T17:48:09.783Z,1564422489.783 [Reporter](INFO): platform_buoyancy_position 558.946533 cc 2019-07-29T17:48:10.319Z,1564422490.319 [Reporter](INFO): platform_buoyancy_position 552.500122 cc 2019-07-29T17:48:10.608Z,1564422490.608 [Reporter](INFO): platform_buoyancy_position 548.336792 cc 2019-07-29T17:48:11.027Z,1564422491.027 [Reporter](INFO): platform_buoyancy_position 543.367737 cc 2019-07-29T17:48:11.431Z,1564422491.431 [Reporter](INFO): platform_buoyancy_position 537.727112 cc 2019-07-29T17:48:11.835Z,1564422491.835 [Reporter](INFO): platform_buoyancy_position 531.415039 cc 2019-07-29T17:48:12.219Z,1564422492.219 [Reporter](INFO): platform_buoyancy_position 526.848816 cc 2019-07-29T17:48:12.607Z,1564422492.607 [Reporter](INFO): platform_buoyancy_position 522.551208 cc 2019-07-29T17:48:13.016Z,1564422493.016 [Reporter](INFO): platform_buoyancy_position 516.239136 cc 2019-07-29T17:48:13.423Z,1564422493.423 [Reporter](INFO): platform_buoyancy_position 511.672943 cc 2019-07-29T17:48:13.879Z,1564422493.879 [Reporter](INFO): platform_buoyancy_position 505.360901 cc 2019-07-29T17:48:14.297Z,1564422494.297 [Reporter](INFO): platform_buoyancy_position 501.063232 cc 2019-07-29T17:48:14.739Z,1564422494.739 [Reporter](INFO): platform_buoyancy_position 494.616852 cc 2019-07-29T17:48:15.079Z,1564422495.079 [Reporter](INFO): platform_buoyancy_position 490.184998 cc 2019-07-29T17:48:15.479Z,1564422495.479 [Reporter](INFO): platform_buoyancy_position 485.887360 cc 2019-07-29T17:48:15.903Z,1564422495.903 [Reporter](INFO): platform_buoyancy_position 479.306671 cc 2019-07-29T17:48:16.271Z,1564422496.271 [Reporter](INFO): platform_buoyancy_position 475.143341 cc 2019-07-29T17:48:16.699Z,1564422496.699 [Reporter](INFO): platform_buoyancy_position 468.831268 cc 2019-07-29T17:48:17.079Z,1564422497.079 [Reporter](INFO): platform_buoyancy_position 464.533661 cc 2019-07-29T17:48:17.491Z,1564422497.491 [Reporter](INFO): platform_buoyancy_position 458.355865 cc 2019-07-29T17:48:17.915Z,1564422497.915 [Reporter](INFO): platform_buoyancy_position 453.789642 cc 2019-07-29T17:48:18.315Z,1564422498.315 [Reporter](INFO): platform_buoyancy_position 449.492035 cc 2019-07-29T17:48:18.711Z,1564422498.711 [Reporter](INFO): platform_buoyancy_position 443.045624 cc 2019-07-29T17:48:19.140Z,1564422499.140 [Reporter](INFO): platform_buoyancy_position 436.599274 cc 2019-07-29T17:48:19.548Z,1564422499.548 [Reporter](INFO): platform_buoyancy_position 432.167328 cc 2019-07-29T17:48:19.946Z,1564422499.946 [Reporter](INFO): platform_buoyancy_position 425.989532 cc 2019-07-29T17:48:20.307Z,1564422500.307 [Reporter](INFO): platform_buoyancy_position 421.423370 cc 2019-07-29T17:48:20.695Z,1564422500.695 [Reporter](INFO): platform_buoyancy_position 415.648468 cc 2019-07-29T17:48:21.111Z,1564422501.111 [Reporter](INFO): platform_buoyancy_position 410.679352 cc 2019-07-29T17:48:21.519Z,1564422501.519 [Reporter](INFO): platform_buoyancy_position 404.232941 cc 2019-07-29T17:48:21.919Z,1564422501.919 [Reporter](INFO): platform_buoyancy_position 400.069641 cc 2019-07-29T17:48:22.323Z,1564422502.323 [Reporter](INFO): platform_buoyancy_position 395.637726 cc 2019-07-29T17:48:22.684Z,1564422502.684 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 13.000000 millimeter 2019-07-29T17:48:22.686Z,1564422502.686 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2019-07-29T17:48:22.738Z,1564422502.738 [Reporter](INFO): platform_buoyancy_position 389.459930 cc 2019-07-29T17:48:22.743Z,1564422502.743 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:48:23.143Z,1564422503.143 [Reporter](INFO): platform_buoyancy_position 382.744934 cc 2019-07-29T17:48:23.592Z,1564422503.592 [Reporter](INFO): platform_buoyancy_position 378.447327 cc 2019-07-29T17:48:23.939Z,1564422503.939 [Reporter](INFO): platform_buoyancy_position 374.015442 cc 2019-07-29T17:48:24.442Z,1564422504.443 [Reporter](INFO): platform_buoyancy_position 367.837646 cc 2019-07-29T17:48:25.155Z,1564422505.155 [Reporter](INFO): platform_buoyancy_position 363.405731 cc 2019-07-29T17:48:25.332Z,1564422505.332 [Reporter](INFO): platform_mass_position 0.015885 m 2019-07-29T17:48:25.333Z,1564422505.333 [Reporter](INFO): platform_buoyancy_position 356.959351 cc 2019-07-29T17:48:25.691Z,1564422505.691 [Reporter](INFO): platform_mass_position 0.015603 m 2019-07-29T17:48:25.692Z,1564422505.692 [Reporter](INFO): platform_buoyancy_position 350.781525 cc 2019-07-29T17:48:26.086Z,1564422506.086 [Reporter](INFO): platform_mass_position 0.015303 m 2019-07-29T17:48:26.087Z,1564422506.087 [Reporter](INFO): platform_buoyancy_position 346.349640 cc 2019-07-29T17:48:26.487Z,1564422506.487 [Reporter](INFO): platform_mass_position 0.014980 m 2019-07-29T17:48:26.488Z,1564422506.488 [Reporter](INFO): platform_buoyancy_position 339.768951 cc 2019-07-29T17:48:26.910Z,1564422506.910 [Reporter](INFO): platform_mass_position 0.014661 m 2019-07-29T17:48:26.911Z,1564422506.911 [Reporter](INFO): platform_buoyancy_position 335.605621 cc 2019-07-29T17:48:27.299Z,1564422507.299 [Reporter](INFO): platform_mass_position 0.014345 m 2019-07-29T17:48:27.300Z,1564422507.300 [Reporter](INFO): platform_buoyancy_position 331.039429 cc 2019-07-29T17:48:27.711Z,1564422507.711 [Reporter](INFO): platform_mass_position 0.014022 m 2019-07-29T17:48:27.712Z,1564422507.712 [Reporter](INFO): platform_buoyancy_position 324.861633 cc 2019-07-29T17:48:28.119Z,1564422508.119 [Reporter](INFO): platform_mass_position 0.013703 m 2019-07-29T17:48:28.120Z,1564422508.120 [Reporter](INFO): platform_buoyancy_position 320.429749 cc 2019-07-29T17:48:28.507Z,1564422508.507 [Reporter](INFO): platform_mass_position 0.013399 m 2019-07-29T17:48:28.508Z,1564422508.508 [Reporter](INFO): platform_buoyancy_position 316.266449 cc 2019-07-29T17:48:28.923Z,1564422508.923 [Reporter](INFO): platform_mass_position 0.013073 m 2019-07-29T17:48:28.924Z,1564422508.924 [Reporter](INFO): platform_buoyancy_position 309.685730 cc 2019-07-29T17:48:29.323Z,1564422509.323 [Reporter](INFO): platform_mass_position 0.013001 m 2019-07-29T17:48:29.324Z,1564422509.324 [Reporter](INFO): platform_buoyancy_position 305.388123 cc 2019-07-29T17:48:29.795Z,1564422509.795 [Reporter](INFO): platform_buoyancy_position 299.210327 cc 2019-07-29T17:48:30.083Z,1564422510.083 [Reporter](INFO): platform_buoyancy_position 294.778442 cc 2019-07-29T17:48:30.500Z,1564422510.500 [Reporter](INFO): platform_buoyancy_position 288.197723 cc 2019-07-29T17:48:30.900Z,1564422510.900 [Reporter](INFO): platform_buoyancy_position 282.154236 cc 2019-07-29T17:48:31.319Z,1564422511.319 [Reporter](INFO): platform_buoyancy_position 277.588043 cc 2019-07-29T17:48:31.744Z,1564422511.744 [Reporter](INFO): platform_buoyancy_position 271.141632 cc 2019-07-29T17:48:32.112Z,1564422512.112 [Reporter](INFO): platform_buoyancy_position 266.441132 cc 2019-07-29T17:48:32.535Z,1564422512.535 [Reporter](INFO): platform_buoyancy_position 260.397644 cc 2019-07-29T17:48:32.915Z,1564422512.915 [Reporter](INFO): platform_buoyancy_position 256.100037 cc 2019-07-29T17:48:33.315Z,1564422513.315 [Reporter](INFO): platform_buoyancy_position 251.668121 cc 2019-07-29T17:48:33.728Z,1564422513.728 [Reporter](INFO): platform_buoyancy_position 245.356033 cc 2019-07-29T17:48:34.136Z,1564422514.136 [Reporter](INFO): platform_buoyancy_position 240.924133 cc 2019-07-29T17:48:34.523Z,1564422514.523 [Reporter](INFO): platform_buoyancy_position 234.612030 cc 2019-07-29T17:48:34.939Z,1564422514.939 [Reporter](INFO): platform_buoyancy_position 230.045837 cc 2019-07-29T17:48:35.355Z,1564422515.355 [Reporter](INFO): platform_buoyancy_position 225.748230 cc 2019-07-29T17:48:35.743Z,1564422515.743 [Reporter](INFO): platform_buoyancy_position 219.436127 cc 2019-07-29T17:48:36.147Z,1564422516.147 [Reporter](INFO): platform_buoyancy_position 215.272827 cc 2019-07-29T17:48:36.599Z,1564422516.599 [Reporter](INFO): platform_buoyancy_position 208.826416 cc 2019-07-29T17:48:55.060Z,1564422535.060 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter 2019-07-29T17:48:57.141Z,1564422537.141 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-07-29T17:48:57.141Z,1564422537.141 [DropWeight] Hardware Fault, FailCount= 1 2019-07-29T17:48:57.145Z,1564422537.145 [DropWeight](ERROR): Hardware Fault 2019-07-29T17:48:57.248Z,1564422537.248 [CommandLine](FAULT): Scheduling is paused 2019-07-29T17:48:57.248Z,1564422537.248 [CBIT](INFO): Critical error at 20190729T174857 2019-07-29T17:48:57.251Z,1564422537.251 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-07-29T17:48:57.251Z,1564422537.251 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-07-29T17:48:57.253Z,1564422537.253 [Reporter](INFO): platform_mass_position 0.013084 m 2019-07-29T17:48:57.644Z,1564422537.644 [CBIT](INFO): Critical error at 20190729T174857 2019-07-29T17:48:57.648Z,1564422537.648 [Reporter](INFO): platform_mass_position 0.013392 m 2019-07-29T17:48:58.079Z,1564422538.079 [Reporter](INFO): platform_mass_position 0.013705 m 2019-07-29T17:48:58.435Z,1564422538.435 [Reporter](INFO): platform_mass_position 0.014008 m 2019-07-29T17:48:58.847Z,1564422538.847 [Reporter](INFO): platform_mass_position 0.014330 m 2019-07-29T17:48:59.247Z,1564422539.247 [Reporter](INFO): platform_mass_position 0.014643 m 2019-07-29T17:48:59.659Z,1564422539.659 [Reporter](INFO): platform_mass_position 0.014966 m 2019-07-29T17:49:00.043Z,1564422540.043 [Reporter](INFO): platform_mass_position 0.015263 m 2019-07-29T17:49:00.451Z,1564422540.451 [Reporter](INFO): platform_mass_position 0.015585 m 2019-07-29T17:49:00.866Z,1564422540.866 [Reporter](INFO): platform_mass_position 0.015899 m 2019-07-29T17:49:01.255Z,1564422541.255 [Reporter](INFO): platform_mass_position 0.016215 m 2019-07-29T17:49:01.659Z,1564422541.659 [Reporter](INFO): platform_mass_position 0.016531 m 2019-07-29T17:49:02.087Z,1564422542.087 [Reporter](INFO): platform_mass_position 0.016866 m 2019-07-29T17:49:02.471Z,1564422542.471 [Reporter](INFO): platform_mass_position 0.017167 m 2019-07-29T17:49:02.887Z,1564422542.887 [Reporter](INFO): platform_mass_position 0.017492 m 2019-07-29T17:49:03.283Z,1564422543.283 [Reporter](INFO): platform_mass_position 0.017802 m 2019-07-29T17:49:03.683Z,1564422543.683 [Reporter](INFO): platform_mass_position 0.018112 m 2019-07-29T17:49:04.147Z,1564422544.147 [Reporter](INFO): platform_mass_position 0.018478 m 2019-07-29T17:49:04.510Z,1564422544.510 [Reporter](INFO): platform_mass_position 0.018750 m 2019-07-29T17:49:04.930Z,1564422544.930 [Reporter](INFO): platform_mass_position 0.019079 m 2019-07-29T17:49:05.375Z,1564422545.375 [Reporter](INFO): platform_mass_position 0.019439 m 2019-07-29T17:49:05.719Z,1564422545.719 [Reporter](INFO): platform_mass_position 0.019708 m 2019-07-29T17:49:06.123Z,1564422546.123 [Reporter](INFO): platform_mass_position 0.020005 m 2019-07-29T17:49:27.956Z,1564422567.956 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 15.000000 millimeter 2019-07-29T17:49:30.867Z,1564422570.867 [Reporter](INFO): platform_mass_position 0.019948 m 2019-07-29T17:49:31.288Z,1564422571.288 [Reporter](INFO): platform_mass_position 0.019623 m 2019-07-29T17:49:31.671Z,1564422571.671 [Reporter](INFO): platform_mass_position 0.019303 m 2019-07-29T17:49:32.119Z,1564422572.119 [Reporter](INFO): platform_mass_position 0.018953 m 2019-07-29T17:49:32.495Z,1564422572.495 [Reporter](INFO): platform_mass_position 0.018662 m 2019-07-29T17:49:32.895Z,1564422572.895 [Reporter](INFO): platform_mass_position 0.018349 m 2019-07-29T17:49:33.311Z,1564422573.311 [Reporter](INFO): platform_mass_position 0.018023 m 2019-07-29T17:49:33.675Z,1564422573.675 [Reporter](INFO): platform_mass_position 0.017738 m 2019-07-29T17:49:34.071Z,1564422574.071 [Reporter](INFO): platform_mass_position 0.017425 m 2019-07-29T17:49:34.480Z,1564422574.480 [Reporter](INFO): platform_mass_position 0.017106 m 2019-07-29T17:49:34.883Z,1564422574.883 [Reporter](INFO): platform_mass_position 0.016790 m 2019-07-29T17:49:35.301Z,1564422575.301 [Reporter](INFO): platform_mass_position 0.016468 m 2019-07-29T17:49:35.711Z,1564422575.711 [Reporter](INFO): platform_mass_position 0.016142 m 2019-07-29T17:49:36.095Z,1564422576.095 [Reporter](INFO): platform_mass_position 0.015844 m 2019-07-29T17:49:36.507Z,1564422576.507 [Reporter](INFO): platform_mass_position 0.015525 m 2019-07-29T17:49:36.917Z,1564422576.917 [Reporter](INFO): platform_mass_position 0.015212 m 2019-07-29T17:49:37.300Z,1564422577.300 [Reporter](INFO): platform_mass_position 0.015001 m 2019-07-29T17:49:57.720Z,1564422597.720 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 16.000000 2019-07-29T17:49:57.720Z,1564422597.720 [CommandLine](FAULT): Incomplete syntax. Try: help maintain 2019-07-29T17:50:03.271Z,1564422603.271 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 16.000000 millimeter 2019-07-29T17:50:05.623Z,1564422605.623 [Reporter](INFO): platform_mass_position 0.015064 m 2019-07-29T17:50:06.035Z,1564422606.035 [Reporter](INFO): platform_mass_position 0.015386 m 2019-07-29T17:50:06.411Z,1564422606.411 [Reporter](INFO): platform_mass_position 0.015680 m 2019-07-29T17:50:06.827Z,1564422606.827 [Reporter](INFO): platform_mass_position 0.016000 m 2019-07-29T17:50:58.112Z,1564422658.112 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 150.000000 cubic_centimeter 2019-07-29T17:51:02.596Z,1564422662.596 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2019-07-29T17:51:02.976Z,1564422662.976 [Reporter](INFO): platform_buoyancy_position 198.082428 cc 2019-07-29T17:51:03.387Z,1564422663.387 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2019-07-29T17:51:03.776Z,1564422663.776 [Reporter](INFO): platform_buoyancy_position 198.082428 cc 2019-07-29T17:51:04.180Z,1564422664.180 [Reporter](INFO): platform_buoyancy_position 197.948120 cc 2019-07-29T17:51:04.588Z,1564422664.588 [Reporter](INFO): platform_buoyancy_position 195.933624 cc 2019-07-29T17:51:04.000Z,1564422665.000 [Reporter](INFO): platform_buoyancy_position 193.784821 cc 2019-07-29T17:51:05.455Z,1564422665.455 [Reporter](INFO): platform_buoyancy_position 193.650513 cc 2019-07-29T17:51:05.816Z,1564422665.816 [Reporter](INFO): platform_buoyancy_position 191.636017 cc 2019-07-29T17:51:06.232Z,1564422666.232 [Reporter](INFO): platform_buoyancy_position 189.487213 cc 2019-07-29T17:51:06.620Z,1564422666.620 [Reporter](INFO): platform_buoyancy_position 187.338440 cc 2019-07-29T17:51:07.072Z,1564422667.072 [Reporter](INFO): platform_buoyancy_position 187.204132 cc 2019-07-29T17:51:07.420Z,1564422667.420 [Reporter](INFO): platform_buoyancy_position 186.935516 cc 2019-07-29T17:51:07.835Z,1564422667.835 [Reporter](INFO): platform_buoyancy_position 185.323914 cc 2019-07-29T17:51:08.247Z,1564422668.247 [Reporter](INFO): platform_buoyancy_position 184.383820 cc 2019-07-29T17:51:08.639Z,1564422668.639 [Reporter](INFO): platform_buoyancy_position 182.906525 cc 2019-07-29T17:51:09.055Z,1564422669.055 [Reporter](INFO): platform_buoyancy_position 180.892029 cc 2019-07-29T17:51:09.432Z,1564422669.432 [Reporter](INFO): platform_buoyancy_position 180.757721 cc 2019-07-29T17:51:09.852Z,1564422669.852 [Reporter](INFO): platform_buoyancy_position 178.743225 cc 2019-07-29T17:51:10.236Z,1564422670.236 [Reporter](INFO): platform_buoyancy_position 176.728729 cc 2019-07-29T17:51:10.647Z,1564422670.647 [Reporter](INFO): platform_buoyancy_position 176.460114 cc 2019-07-29T17:51:11.055Z,1564422671.055 [Reporter](INFO): platform_buoyancy_position 174.445618 cc 2019-07-29T17:51:11.455Z,1564422671.455 [Reporter](INFO): platform_buoyancy_position 172.565430 cc 2019-07-29T17:51:11.864Z,1564422671.864 [Reporter](INFO): platform_buoyancy_position 170.148010 cc 2019-07-29T17:51:12.219Z,1564422672.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T17:51:12.219Z,1564422672.219 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:51:12.219Z,1564422672.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:51:12.220Z,1564422672.220 [Default:CheckIn:D] Running Loop=1 2019-07-29T17:51:12.619Z,1564422672.619 [Default:CheckIn:D] Stopped 2019-07-29T17:51:12.619Z,1564422672.619 [Default:CheckIn:E] Running Loop=1 2019-07-29T17:51:12.660Z,1564422672.660 [Reporter](INFO): platform_buoyancy_position 167.999237 cc 2019-07-29T17:51:13.029Z,1564422673.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.083040 min 2019-07-29T17:51:13.029Z,1564422673.029 [Default:CheckIn:E] Stopped 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn] Stopped 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn](INFO): Running loop #19 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn] Running Loop=19 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:51:13.030Z,1564422673.030 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:51:13.080Z,1564422673.080 [Reporter](INFO): platform_buoyancy_position 167.864929 cc 2019-07-29T17:51:13.495Z,1564422673.495 [Reporter](INFO): platform_buoyancy_position 166.656219 cc 2019-07-29T17:51:13.879Z,1564422673.879 [Reporter](INFO): platform_buoyancy_position 165.716125 cc 2019-07-29T17:51:14.280Z,1564422674.280 [Reporter](INFO): platform_buoyancy_position 163.701630 cc 2019-07-29T17:51:14.633Z,1564422674.633 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:51:14.700Z,1564422674.700 [Reporter](INFO): platform_buoyancy_position 162.627228 cc 2019-07-29T17:51:15.024Z,1564422675.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174500.00,A,3648.17664,N,12147.28116,W,0.447,202.30,290719,,,D*78 2019-07-29T17:51:15.026Z,1564422675.026 [NAL9602](INFO): GPS fix at 20190729T174500: (36.802944, -121.788019) 2019-07-29T17:51:15.089Z,1564422675.089 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:51:15.089Z,1564422675.089 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:51:15.132Z,1564422675.132 [Reporter](INFO): platform_buoyancy_position 161.149933 cc 2019-07-29T17:51:15.528Z,1564422675.528 [Reporter](INFO): platform_buoyancy_position 159.404022 cc 2019-07-29T17:51:19.341Z,1564422679.341 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20190729T162726/Courier0048.lzma 2019-07-29T17:51:19.931Z,1564422679.931 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0048.lzma.bak 2019-07-29T17:51:19.931Z,1564422679.931 [DataOverHttps](INFO): SBD MOMSN=11482614 2019-07-29T17:51:31.545Z,1564422691.545 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20190729T162726/Express0049.lzma 2019-07-29T17:51:32.351Z,1564422692.351 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0049.lzma.bak 2019-07-29T17:51:32.351Z,1564422692.351 [DataOverHttps](INFO): SBD MOMSN=11482620 2019-07-29T17:51:33.823Z,1564422693.823 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:51:33.823Z,1564422693.823 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:51:33.823Z,1564422693.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:51:52.719Z,1564422712.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=31116, MT Status=0, MTMSN=0 2019-07-29T17:51:52.720Z,1564422712.720 [NAL9602](INFO): No messages in MT queue 2019-07-29T17:51:58.392Z,1564422718.392 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-29T17:51:58.393Z,1564422718.393 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-29T17:51:58.394Z,1564422718.394 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-07-29T17:51:58.396Z,1564422718.396 [BPC1](INFO): Received data from all battery sticks. 2019-07-29T17:52:01.743Z,1564422721.743 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 16.000000 millimeter 2019-07-29T17:52:23.426Z,1564422743.426 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:52:24.854Z,1564422744.854 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 17.000000 millimeter 2019-07-29T17:52:27.171Z,1564422747.171 [Reporter](INFO): platform_mass_position 0.016045 m 2019-07-29T17:52:27.583Z,1564422747.583 [Reporter](INFO): platform_mass_position 0.016369 m 2019-07-29T17:52:27.987Z,1564422747.987 [Reporter](INFO): platform_mass_position 0.016685 m 2019-07-29T17:52:28.403Z,1564422748.403 [Reporter](INFO): platform_mass_position 0.017004 m 2019-07-29T17:53:25.888Z,1564422805.888 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2019-07-29T17:53:25.889Z,1564422805.889 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2019-07-29T17:53:26.088Z,1564422806.088 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2019-07-29T17:53:26.107Z,1564422806.107 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2019-07-29T17:53:26.115Z,1564422806.115 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2019-07-29T17:53:26.146Z,1564422806.146 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2019-07-29T17:53:26.158Z,1564422806.158 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min 2019-07-29T17:53:26.177Z,1564422806.177 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool 2019-07-29T17:53:26.181Z,1564422806.181 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool 2019-07-29T17:53:26.193Z,1564422806.193 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count 2019-07-29T17:53:26.201Z,1564422806.201 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count 2019-07-29T17:53:26.208Z,1564422806.208 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 % 2019-07-29T17:53:26.212Z,1564422806.212 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2019-07-29T17:53:26.235Z,1564422806.235 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool 2019-07-29T17:53:26.243Z,1564422806.243 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool 2019-07-29T17:53:26.252Z,1564422806.252 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2019-07-29T17:53:26.283Z,1564422806.283 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool 2019-07-29T17:53:26.287Z,1564422806.287 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool 2019-07-29T17:53:26.309Z,1564422806.309 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool 2019-07-29T17:53:26.331Z,1564422806.331 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2019-07-29T17:53:26.335Z,1564422806.335 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2019-07-29T17:53:26.356Z,1564422806.356 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2019-07-29T17:53:26.360Z,1564422806.360 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool 2019-07-29T17:53:26.365Z,1564422806.365 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2019-07-29T17:53:26.384Z,1564422806.384 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2019-07-29T17:53:26.388Z,1564422806.388 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count 2019-07-29T17:53:26.418Z,1564422806.418 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2019-07-29T17:53:26.421Z,1564422806.421 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2019-07-29T17:53:26.425Z,1564422806.425 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m 2019-07-29T17:53:26.447Z,1564422806.447 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m 2019-07-29T17:53:26.451Z,1564422806.451 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2019-07-29T17:53:26.519Z,1564422806.519 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2019-07-29T17:53:26.527Z,1564422806.527 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC 2019-07-29T17:53:26.531Z,1564422806.531 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2019-07-29T17:53:26.559Z,1564422806.559 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2019-07-29T17:53:26.563Z,1564422806.563 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2019-07-29T17:53:26.604Z,1564422806.604 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2019-07-29T17:53:26.608Z,1564422806.608 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2019-07-29T17:53:26.612Z,1564422806.612 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2019-07-29T17:53:26.615Z,1564422806.615 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m 2019-07-29T17:53:26.619Z,1564422806.619 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m 2019-07-29T17:53:26.623Z,1564422806.623 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg 2019-07-29T17:53:26.679Z,1564422806.679 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg 2019-07-29T17:53:26.691Z,1564422806.691 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m 2019-07-29T17:53:26.701Z,1564422806.701 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3 2019-07-29T17:53:26.713Z,1564422806.713 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m 2019-07-29T17:53:26.725Z,1564422806.725 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg 2019-07-29T17:53:26.741Z,1564422806.741 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg 2019-07-29T17:53:26.754Z,1564422806.754 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb 2019-07-29T17:53:26.766Z,1564422806.766 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m 2019-07-29T17:53:26.786Z,1564422806.786 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg 2019-07-29T17:53:26.797Z,1564422806.797 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg 2019-07-29T17:53:26.809Z,1564422806.809 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m 2019-07-29T17:53:26.927Z,1564422806.927 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-07-29T17:53:26.984Z,1564422806.984 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2019-07-29T17:53:27.052Z,1564422807.052 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-07-29T17:53:27.104Z,1564422807.104 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-07-29T17:53:27.140Z,1564422807.140 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-07-29T17:53:27.259Z,1564422807.259 [MissionManager](DEBUG): Set either this or PeakDetectNO3Active to True in order to sample Nitrate. EnabledISUS must also be true. Maximum pitch to sample ISUS. (EnabledISUS must also be true.) 60.0 Minimum pitch to sample ISUS. (EnabledISUS must also be true.) -60.0 Turns on peak detection of Cholorphyll. If greater than zero, report a peak every window. If NaN or zero, this variable is ignored. NaN Turns on reporting of the highest peak value on yo-yo profiles in a horizontal sliding window (of length numProfilesSlidingwindow) If tracking a patch Width of boxcar filter applied to yoyo-wise chl peaks to pick out the highest peak. 3 Length of horizontal sliding window. The highest yoyo-wise chl peak (after low-pass filtering by a filter of length FilterWidthHorizontal) within this window. 100 When filtered horizontal value is this fraction of the peak, consider it outside the patch. 80 Turns on peak detection of Nitrate (and turns on ISUS). Turns on peak detection of Dissolved Oil. Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor. Turns on seawater temperature derivative. Automatically set to True if the Aanderaa O2 sensor is installed. Set to false to disable reading Aandera O2. Automatically set to true if the Neil Brown CTD is enabled. Set to false to disable reading from the Neil Brown. Automatically set to true if the CTD is enabled. Set to false to disable reading from the CTD. Automatically set to true if the NPS Turbulence sensor is enabled. Set to false to disable reading from the NPS Turbulence sensor. Automatically set to true if the the WetLabs BB2FL is enabled. Set to false to disable the WetLabs BB2FL. Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set to false to disable the WetLabs SeaOWL UV-A. Automatically set to true if the the WetLabs UBAT is enabled. Set to false to disable the WetLabs UBAT. Automatically set to True if ISUS is enabled. Set to false to disable ISUS. Low-pass window length (based on depth sensor sampling interval 0.4 second) for low-pass filtering. 20 Median filter length (only for chlorophyll fluorescence which tends to have spikes) 5 Shallow depth bound for detecting chl peak on each descent or ascent profile. NaN Deep depth bound for detecting chl peak on each descent or ascent profile. NaN Shallow depth bound for detecting any peak on each descent or ascent profile. NaN Deep depth bound for detecting any peak on each descent or ascent profile. NaN 0 0 0 NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 2019-07-29T17:53:27.262Z,1564422807.262 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2019-07-29T17:53:27.337Z,1564422807.337 [Default] Stopped 2019-07-29T17:53:27.337Z,1564422807.337 [Default](DEBUG): Aggregate::uninitialize Default 2019-07-29T17:53:27.338Z,1564422807.338 [Default:B.GoToSurface] Stopped 2019-07-29T17:53:27.338Z,1564422807.338 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-07-29T17:53:27.338Z,1564422807.338 [Default:CheckIn] Stopped 2019-07-29T17:53:27.338Z,1564422807.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T17:53:27.338Z,1564422807.338 [Default:CheckIn:C.Wait] Stopped 2019-07-29T17:53:27.338Z,1564422807.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:53:27.338Z,1564422807.338 [MissionManager](IMPORTANT): Started mission Science 2019-07-29T17:53:27.339Z,1564422807.339 [Science] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science](DEBUG): Aggregate::initialize Science 2019-07-29T17:53:27.339Z,1564422807.339 [Science:A] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:B] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:C] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:D] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:E] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:F] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:G] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:H] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:Read_Oil] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:PeakDetectChl] Running Loop=1 2019-07-29T17:53:27.339Z,1564422807.339 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2019-07-29T17:53:27.340Z,1564422807.340 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2019-07-29T17:53:27.340Z,1564422807.340 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-07-29T17:53:27.349Z,1564422807.349 [Science:HighestChlPeakReport] Running Loop=1 2019-07-29T17:53:27.350Z,1564422807.350 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport 2019-07-29T17:53:27.350Z,1564422807.350 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2019-07-29T17:53:27.350Z,1564422807.350 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2019-07-29T17:53:27.350Z,1564422807.350 [Science:PeakDetectNO3] Running Loop=1 2019-07-29T17:53:27.350Z,1564422807.350 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2019-07-29T17:53:27.350Z,1564422807.350 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2019-07-29T17:53:27.351Z,1564422807.351 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-07-29T17:53:27.352Z,1564422807.352 [Science:PeakDetectOil] Running Loop=1 2019-07-29T17:53:27.352Z,1564422807.352 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil 2019-07-29T17:53:27.352Z,1564422807.352 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2019-07-29T17:53:27.352Z,1564422807.352 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-07-29T17:53:27.364Z,1564422807.364 [Science:PeakDetectFDOM] Running Loop=1 2019-07-29T17:53:27.364Z,1564422807.364 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM 2019-07-29T17:53:27.364Z,1564422807.364 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2019-07-29T17:53:27.364Z,1564422807.364 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-07-29T17:53:27.367Z,1564422807.367 [Science:PeakDetectFDOM] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectOil] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectNO3] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2019-07-29T17:53:27.368Z,1564422807.368 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-07-29T17:53:27.368Z,1564422807.368 [Science:HighestChlPeakReport] Stopped 2019-07-29T17:53:27.369Z,1564422807.369 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport 2019-07-29T17:53:27.369Z,1564422807.369 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2019-07-29T17:53:27.369Z,1564422807.369 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2019-07-29T17:53:27.369Z,1564422807.369 [Science:PeakDetectChl] Stopped 2019-07-29T17:53:27.369Z,1564422807.369 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2019-07-29T17:53:27.369Z,1564422807.369 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2019-07-29T17:53:27.377Z,1564422807.377 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-07-29T17:53:27.377Z,1564422807.377 [Science:Read_Oil] Stopped 2019-07-29T17:53:27.377Z,1564422807.377 [Science:H] Stopped 2019-07-29T17:53:27.377Z,1564422807.377 [Science:G] Running Loop=1 2019-07-29T17:53:27.378Z,1564422807.378 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2019-07-29T17:53:27.378Z,1564422807.378 [Science:F] Stopped 2019-07-29T17:53:27.378Z,1564422807.378 [Science:E] Stopped 2019-07-29T17:53:27.378Z,1564422807.378 [Science:D] Running Loop=1 2019-07-29T17:53:27.379Z,1564422807.379 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2019-07-29T17:53:27.380Z,1564422807.380 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2019-07-29T17:53:27.380Z,1564422807.380 [Science:C] Running Loop=1 2019-07-29T17:53:27.380Z,1564422807.380 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2019-07-29T17:53:27.381Z,1564422807.381 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2019-07-29T17:53:27.393Z,1564422807.393 [Science:B] Stopped 2019-07-29T17:53:27.393Z,1564422807.393 [Science:A] Running Loop=1 2019-07-29T17:53:27.394Z,1564422807.394 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2019-07-29T17:53:27.459Z,1564422807.459 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-07-29T17:53:27.834Z,1564422807.834 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2019-07-29T17:53:27.834Z,1564422807.834 [WetLabsBB2FL](INFO): Powering up 2019-07-29T17:53:30.471Z,1564422810.471 [CTD_Seabird](ERROR): Failed to parse device response: 2019-07-29T17:53:32.479Z,1564422812.479 [CTD_Seabird](ERROR): Salinity reading out of range: 1.864233 psu 2019-07-29T17:53:33.483Z,1564422813.483 [CTD_Seabird](ERROR): Salinity reading out of range: 1.831112 psu 2019-07-29T17:53:34.487Z,1564422814.487 [CTD_Seabird](ERROR): Salinity reading out of range: 1.859646 psu 2019-07-29T17:53:35.491Z,1564422815.491 [CTD_Seabird](ERROR): Salinity reading out of range: 1.817965 psu 2019-07-29T17:53:36.495Z,1564422816.495 [CTD_Seabird](ERROR): Salinity reading out of range: 1.859619 psu 2019-07-29T17:53:37.500Z,1564422817.500 [CTD_Seabird](ERROR): Salinity reading out of range: 1.840538 psu 2019-07-29T17:53:38.503Z,1564422818.503 [CTD_Seabird](ERROR): Salinity reading out of range: 1.817815 psu 2019-07-29T17:53:39.508Z,1564422819.508 [CTD_Seabird](ERROR): Salinity reading out of range: 1.846083 psu 2019-07-29T17:53:40.511Z,1564422820.511 [CTD_Seabird](ERROR): Salinity reading out of range: 1.847404 psu 2019-07-29T17:53:41.515Z,1564422821.515 [CTD_Seabird](ERROR): Salinity reading out of range: 1.826330 psu 2019-07-29T17:53:42.519Z,1564422822.519 [CTD_Seabird](ERROR): Salinity reading out of range: 1.841937 psu 2019-07-29T17:53:43.524Z,1564422823.524 [CTD_Seabird](ERROR): Salinity reading out of range: 1.846388 psu 2019-07-29T17:53:44.527Z,1564422824.527 [CTD_Seabird](ERROR): Salinity reading out of range: 1.835152 psu 2019-07-29T17:53:45.531Z,1564422825.531 [CTD_Seabird](ERROR): Salinity reading out of range: 1.842446 psu 2019-07-29T17:53:46.535Z,1564422826.535 [CTD_Seabird](ERROR): Salinity reading out of range: 1.841955 psu 2019-07-29T17:53:47.540Z,1564422827.540 [CTD_Seabird](ERROR): Salinity reading out of range: 1.842503 psu 2019-07-29T17:53:48.543Z,1564422828.543 [CTD_Seabird](ERROR): Salinity reading out of range: 1.560668 psu 2019-07-29T17:53:49.548Z,1564422829.548 [CTD_Seabird](ERROR): Salinity reading out of range: 0.903075 psu 2019-07-29T17:53:50.551Z,1564422830.551 [CTD_Seabird](ERROR): Salinity reading out of range: 0.799661 psu 2019-07-29T17:53:51.556Z,1564422831.556 [CTD_Seabird](ERROR): Salinity reading out of range: 1.139479 psu 2019-07-29T17:53:52.560Z,1564422832.560 [CTD_Seabird](ERROR): Salinity reading out of range: 1.465790 psu 2019-07-29T17:53:53.564Z,1564422833.564 [CTD_Seabird](ERROR): Salinity reading out of range: 1.351325 psu 2019-07-29T17:53:54.568Z,1564422834.568 [CTD_Seabird](ERROR): Salinity reading out of range: 0.774960 psu 2019-07-29T17:53:55.572Z,1564422835.572 [CTD_Seabird](ERROR): Salinity reading out of range: 0.470912 psu 2019-07-29T17:53:56.576Z,1564422836.576 [CTD_Seabird](ERROR): Salinity reading out of range: 1.128394 psu 2019-07-29T17:53:57.580Z,1564422837.580 [CTD_Seabird](ERROR): Salinity reading out of range: 2.729403 psu 2019-07-29T17:53:58.584Z,1564422838.584 [CTD_Seabird](ERROR): Salinity reading out of range: 1.536778 psu 2019-07-29T17:53:59.588Z,1564422839.588 [CTD_Seabird](ERROR): Salinity reading out of range: 0.534429 psu 2019-07-29T17:54:00.592Z,1564422840.592 [CTD_Seabird](ERROR): Salinity reading out of range: 0.725270 psu 2019-07-29T17:54:01.596Z,1564422841.596 [CTD_Seabird](ERROR): Salinity reading out of range: 2.057797 psu 2019-07-29T17:54:02.599Z,1564422842.599 [CTD_Seabird](ERROR): Salinity reading out of range: 2.189519 psu 2019-07-29T17:54:03.603Z,1564422843.603 [CTD_Seabird](ERROR): Salinity reading out of range: 1.397172 psu 2019-07-29T17:54:04.607Z,1564422844.607 [CTD_Seabird](ERROR): Salinity reading out of range: 2.755675 psu 2019-07-29T17:54:05.611Z,1564422845.611 [CTD_Seabird](ERROR): Salinity reading out of range: 2.658673 psu 2019-07-29T17:54:06.615Z,1564422846.615 [CTD_Seabird](ERROR): Salinity reading out of range: 1.638665 psu 2019-07-29T17:54:07.619Z,1564422847.619 [CTD_Seabird](ERROR): Salinity reading out of range: 1.895724 psu 2019-07-29T17:54:08.623Z,1564422848.623 [CTD_Seabird](ERROR): Salinity reading out of range: 3.220759 psu 2019-07-29T17:54:09.627Z,1564422849.627 [CTD_Seabird](ERROR): Salinity reading out of range: 2.414931 psu 2019-07-29T17:54:10.631Z,1564422850.631 [CTD_Seabird](ERROR): Salinity reading out of range: 2.138577 psu 2019-07-29T17:54:11.635Z,1564422851.635 [CTD_Seabird](ERROR): Salinity reading out of range: 2.889611 psu 2019-07-29T17:54:12.639Z,1564422852.639 [CTD_Seabird](ERROR): Salinity reading out of range: 2.702956 psu 2019-07-29T17:54:13.643Z,1564422853.643 [CTD_Seabird](ERROR): Salinity reading out of range: 2.399672 psu 2019-07-29T17:54:14.647Z,1564422854.647 [CTD_Seabird](ERROR): Salinity reading out of range: 2.776605 psu 2019-07-29T17:54:15.651Z,1564422855.651 [CTD_Seabird](ERROR): Salinity reading out of range: 2.930680 psu 2019-07-29T17:54:16.655Z,1564422856.655 [CTD_Seabird](ERROR): Salinity reading out of range: 2.601811 psu 2019-07-29T17:54:17.659Z,1564422857.659 [CTD_Seabird](ERROR): Salinity reading out of range: 2.683234 psu 2019-07-29T17:54:18.663Z,1564422858.663 [CTD_Seabird](ERROR): Salinity reading out of range: 2.848160 psu 2019-07-29T17:54:19.667Z,1564422859.667 [CTD_Seabird](ERROR): Salinity reading out of range: 2.712037 psu 2019-07-29T17:54:20.671Z,1564422860.671 [CTD_Seabird](ERROR): Salinity reading out of range: 2.702357 psu 2019-07-29T17:54:21.675Z,1564422861.675 [CTD_Seabird](ERROR): Salinity reading out of range: 2.803798 psu 2019-07-29T17:54:22.679Z,1564422862.679 [CTD_Seabird](ERROR): Salinity reading out of range: 2.740276 psu 2019-07-29T17:54:23.683Z,1564422863.683 [CTD_Seabird](ERROR): Salinity reading out of range: 2.744571 psu 2019-07-29T17:54:24.687Z,1564422864.687 [CTD_Seabird](ERROR): Salinity reading out of range: 2.767997 psu 2019-07-29T17:54:25.691Z,1564422865.691 [CTD_Seabird](ERROR): Salinity reading out of range: 2.734853 psu 2019-07-29T17:54:26.695Z,1564422866.695 [CTD_Seabird](ERROR): Salinity reading out of range: 2.759033 psu 2019-07-29T17:54:27.699Z,1564422867.699 [CTD_Seabird](ERROR): Salinity reading out of range: 2.773024 psu 2019-07-29T17:54:28.703Z,1564422868.703 [CTD_Seabird](ERROR): Salinity reading out of range: 2.721219 psu 2019-07-29T17:54:29.707Z,1564422869.707 [CTD_Seabird](ERROR): Salinity reading out of range: 2.765274 psu 2019-07-29T17:54:30.711Z,1564422870.711 [CTD_Seabird](ERROR): Salinity reading out of range: 2.769048 psu 2019-07-29T17:54:31.715Z,1564422871.715 [CTD_Seabird](ERROR): Salinity reading out of range: 2.730016 psu 2019-07-29T17:54:32.720Z,1564422872.720 [CTD_Seabird](ERROR): Salinity reading out of range: 2.759088 psu 2019-07-29T17:54:33.723Z,1564422873.723 [CTD_Seabird](ERROR): Salinity reading out of range: 2.915978 psu 2019-07-29T17:54:34.727Z,1564422874.727 [CTD_Seabird](ERROR): Salinity reading out of range: 2.725502 psu 2019-07-29T17:54:35.731Z,1564422875.731 [CTD_Seabird](ERROR): Salinity reading out of range: 2.594168 psu 2019-07-29T17:54:36.735Z,1564422876.735 [CTD_Seabird](ERROR): Salinity reading out of range: 2.868901 psu 2019-07-29T17:54:37.778Z,1564422877.778 [CTD_Seabird](ERROR): Salinity reading out of range: 2.585783 psu 2019-07-29T17:54:38.778Z,1564422878.778 [CTD_Seabird](ERROR): Salinity reading out of range: 2.691729 psu 2019-07-29T17:54:39.782Z,1564422879.782 [CTD_Seabird](ERROR): Salinity reading out of range: 2.915040 psu 2019-07-29T17:54:39.806Z,1564422879.806 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 18.000000 millimeter 2019-07-29T17:54:40.786Z,1564422880.786 [CTD_Seabird](ERROR): Salinity reading out of range: 2.734713 psu 2019-07-29T17:54:41.790Z,1564422881.790 [CTD_Seabird](ERROR): Salinity reading out of range: 2.673234 psu 2019-07-29T17:54:42.180Z,1564422882.180 [Reporter](INFO): platform_mass_position 0.017071 m 2019-07-29T17:54:42.185Z,1564422882.185 [CommandLine](IMPORTANT): got command stop 2019-07-29T17:54:42.185Z,1564422882.185 [CommandLine](IMPORTANT): Scheduling is paused 2019-07-29T17:54:42.188Z,1564422882.188 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-07-29T17:54:42.522Z,1564422882.522 [MissionManager](INFO): MissionManager is completed. 2019-07-29T17:54:42.522Z,1564422882.522 [MissionManager](INFO): Uninitializing Mission Science 2019-07-29T17:54:42.522Z,1564422882.522 [Science] Stopped 2019-07-29T17:54:42.522Z,1564422882.522 [Science](DEBUG): Aggregate::uninitialize Science 2019-07-29T17:54:42.522Z,1564422882.522 [Science:A] Stopped 2019-07-29T17:54:42.522Z,1564422882.522 [Science:C] Stopped 2019-07-29T17:54:42.522Z,1564422882.522 [Science:D] Stopped 2019-07-29T17:54:42.523Z,1564422882.523 [Science:G] Stopped 2019-07-29T17:54:42.622Z,1564422882.622 [Reporter](INFO): platform_mass_position 0.017415 m 2019-07-29T17:54:42.933Z,1564422882.933 [MissionManager](IMPORTANT): Started mission Default 2019-07-29T17:54:42.933Z,1564422882.933 [Default] Running Loop=1 2019-07-29T17:54:42.933Z,1564422882.933 [Default](DEBUG): Aggregate::initialize Default 2019-07-29T17:54:42.933Z,1564422882.933 [Default:B.GoToSurface] Running Loop=1 2019-07-29T17:54:42.934Z,1564422882.934 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-07-29T17:54:42.934Z,1564422882.934 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-07-29T17:54:42.934Z,1564422882.934 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-07-29T17:54:42.935Z,1564422882.935 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-07-29T17:54:42.935Z,1564422882.935 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-07-29T17:54:42.935Z,1564422882.935 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-07-29T17:54:42.936Z,1564422882.936 [Default:A.Wait] Running Loop=1 2019-07-29T17:54:42.936Z,1564422882.936 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:54:43.026Z,1564422883.026 [WetLabsBB2FL](INFO): Powering down 2019-07-29T17:54:43.030Z,1564422883.030 [Reporter](INFO): platform_mass_position 0.017729 m 2019-07-29T17:54:43.401Z,1564422883.401 [Aanderaa_O2](INFO): Powering down 2019-07-29T17:54:43.499Z,1564422883.499 [Reporter](INFO): platform_mass_position 0.017999 m 2019-07-29T17:54:56.253Z,1564422896.253 [Default:A.Wait](INFO): Done Waiting. 2019-07-29T17:54:56.253Z,1564422896.253 [Default:A.Wait] Stopped 2019-07-29T17:54:56.253Z,1564422896.253 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T17:54:56.686Z,1564422896.686 [Default:CheckIn] Running Loop=1 2019-07-29T17:54:56.686Z,1564422896.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T17:54:56.686Z,1564422896.686 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T17:54:58.277Z,1564422898.277 [NAL9602](DEBUG): Fix Requested 2019-07-29T17:54:58.660Z,1564422898.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174843.00,A,3648.17708,N,12147.28113,W,0.117,202.30,290719,,,D*7C 2019-07-29T17:54:58.662Z,1564422898.662 [NAL9602](INFO): GPS fix at 20190729T174843: (36.802951, -121.788019) 2019-07-29T17:54:58.716Z,1564422898.716 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T17:54:58.716Z,1564422898.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T17:55:04.245Z,1564422904.245 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20190729T162726/Courier0051.lzma 2019-07-29T17:55:05.051Z,1564422905.051 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0051.lzma.bak 2019-07-29T17:55:05.051Z,1564422905.051 [DataOverHttps](INFO): SBD MOMSN=11482633 2019-07-29T17:55:13.500Z,1564422913.500 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 19.000000 millimeter 2019-07-29T17:55:15.667Z,1564422915.667 [NAL9602](INFO): SBD MO Status=0, MOMSN=31117, MT Status=0, MTMSN=0 2019-07-29T17:55:15.668Z,1564422915.668 [NAL9602](INFO): No messages in MT queue 2019-07-29T17:55:15.803Z,1564422915.803 [Reporter](INFO): platform_mass_position 0.018109 m 2019-07-29T17:55:16.143Z,1564422916.143 [Reporter](INFO): platform_mass_position 0.018372 m 2019-07-29T17:55:16.559Z,1564422916.559 [Reporter](INFO): platform_mass_position 0.018700 m 2019-07-29T17:55:16.968Z,1564422916.968 [Reporter](INFO): platform_mass_position 0.019005 m 2019-07-29T17:55:18.942Z,1564422918.942 [DataOverHttps](INFO): Sending 573 bytes from file Logs/20190729T162726/Express0052.lzma 2019-07-29T17:55:19.747Z,1564422919.747 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0052.lzma.bak 2019-07-29T17:55:19.747Z,1564422919.747 [DataOverHttps](INFO): SBD MOMSN=11482636 2019-07-29T17:55:20.525Z,1564422920.525 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T17:55:20.525Z,1564422920.525 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T17:55:20.525Z,1564422920.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T17:55:37.499Z,1564422937.499 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 17.000000 millimeter 2019-07-29T17:55:39.607Z,1564422939.607 [Reporter](INFO): platform_mass_position 0.018941 m 2019-07-29T17:55:40.007Z,1564422940.007 [Reporter](INFO): platform_mass_position 0.018630 m 2019-07-29T17:55:40.404Z,1564422940.404 [Reporter](INFO): platform_mass_position 0.018325 m 2019-07-29T17:55:40.811Z,1564422940.811 [Reporter](INFO): platform_mass_position 0.018006 m 2019-07-29T17:55:41.209Z,1564422941.209 [Reporter](INFO): platform_mass_position 0.017690 m 2019-07-29T17:55:41.609Z,1564422941.609 [Reporter](INFO): platform_mass_position 0.017377 m 2019-07-29T17:55:42.020Z,1564422942.020 [Reporter](INFO): platform_mass_position 0.017054 m 2019-07-29T17:55:42.427Z,1564422942.427 [Reporter](INFO): platform_mass_position 0.017001 m 2019-07-29T17:55:46.384Z,1564422946.384 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T17:56:24.398Z,1564422984.398 [CommandLine](IMPORTANT): got command maintain clear 2019-07-29T17:56:24.449Z,1564422984.449 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-07-29T17:56:27.427Z,1564422987.427 [Reporter](INFO): platform_mass_position 0.016909 m 2019-07-29T17:56:27.804Z,1564422987.804 [Reporter](INFO): platform_mass_position 0.016616 m 2019-07-29T17:56:28.208Z,1564422988.208 [Reporter](INFO): platform_mass_position 0.016299 m 2019-07-29T17:56:28.631Z,1564422988.631 [Reporter](INFO): platform_mass_position 0.015996 m 2019-07-29T17:56:29.435Z,1564422989.435 [Reporter](INFO): platform_buoyancy_position 152.823303 cc 2019-07-29T17:56:29.799Z,1564422989.799 [Reporter](INFO): platform_buoyancy_position 152.957611 cc 2019-07-29T17:56:31.023Z,1564422991.023 [Reporter](INFO): platform_buoyancy_position 154.972107 cc 2019-07-29T17:56:31.404Z,1564422991.404 [Reporter](INFO): platform_buoyancy_position 161.418518 cc 2019-07-29T17:56:31.830Z,1564422991.830 [Reporter](INFO): platform_buoyancy_position 167.864929 cc 2019-07-29T17:56:32.235Z,1564422992.235 [Reporter](INFO): platform_buoyancy_position 173.639832 cc 2019-07-29T17:56:32.616Z,1564422992.616 [Reporter](INFO): platform_buoyancy_position 178.608917 cc 2019-07-29T17:56:33.027Z,1564422993.027 [Reporter](INFO): platform_buoyancy_position 185.189636 cc 2019-07-29T17:56:33.432Z,1564422993.432 [Reporter](INFO): platform_buoyancy_position 191.636017 cc 2019-07-29T17:56:33.840Z,1564422993.840 [Reporter](INFO): platform_buoyancy_position 195.933624 cc 2019-07-29T17:56:34.251Z,1564422994.251 [Reporter](INFO): platform_buoyancy_position 202.245728 cc 2019-07-29T17:56:34.640Z,1564422994.640 [Reporter](INFO): platform_buoyancy_position 206.677612 cc 2019-07-29T17:56:35.044Z,1564422995.044 [Reporter](INFO): platform_buoyancy_position 212.989716 cc 2019-07-29T17:56:35.474Z,1564422995.474 [Reporter](INFO): platform_buoyancy_position 219.436127 cc 2019-07-29T17:56:35.852Z,1564422995.852 [Reporter](INFO): platform_buoyancy_position 223.733734 cc 2019-07-29T17:56:36.259Z,1564422996.259 [Reporter](INFO): platform_buoyancy_position 228.837128 cc 2019-07-29T17:56:36.668Z,1564422996.668 [Reporter](INFO): platform_buoyancy_position 234.477722 cc 2019-07-29T17:56:37.071Z,1564422997.071 [Reporter](INFO): platform_buoyancy_position 239.178223 cc 2019-07-29T17:56:37.460Z,1564422997.460 [Reporter](INFO): platform_buoyancy_position 245.221741 cc 2019-07-29T17:56:37.875Z,1564422997.875 [Reporter](INFO): platform_buoyancy_position 249.653625 cc 2019-07-29T17:56:38.288Z,1564422998.288 [Reporter](INFO): platform_buoyancy_position 255.965714 cc 2019-07-29T17:56:38.680Z,1564422998.680 [Reporter](INFO): platform_buoyancy_position 260.666229 cc 2019-07-29T17:56:39.079Z,1564422999.079 [Reporter](INFO): platform_buoyancy_position 266.709717 cc 2019-07-29T17:56:39.480Z,1564422999.480 [Reporter](INFO): platform_buoyancy_position 271.007324 cc 2019-07-29T17:56:39.891Z,1564422999.891 [Reporter](INFO): platform_buoyancy_position 277.185120 cc 2019-07-29T17:56:40.299Z,1564423000.299 [Reporter](INFO): platform_buoyancy_position 281.885620 cc 2019-07-29T17:56:40.712Z,1564423000.712 [Reporter](INFO): platform_buoyancy_position 286.720428 cc 2019-07-29T17:56:41.096Z,1564423001.096 [Reporter](INFO): platform_buoyancy_position 292.495331 cc 2019-07-29T17:56:41.515Z,1564423001.515 [Reporter](INFO): platform_buoyancy_position 297.195831 cc 2019-07-29T17:56:41.931Z,1564423001.931 [Reporter](INFO): platform_buoyancy_position 303.239319 cc 2019-07-29T17:56:42.316Z,1564423002.316 [Reporter](INFO): platform_buoyancy_position 307.536926 cc 2019-07-29T17:56:42.715Z,1564423002.715 [Reporter](INFO): platform_buoyancy_position 314.117645 cc 2019-07-29T17:56:43.128Z,1564423003.128 [Reporter](INFO): platform_buoyancy_position 318.415222 cc 2019-07-29T17:56:43.531Z,1564423003.531 [Reporter](INFO): platform_buoyancy_position 322.981445 cc 2019-07-29T17:56:43.939Z,1564423003.939 [Reporter](INFO): platform_buoyancy_position 329.024933 cc 2019-07-29T17:56:44.336Z,1564423004.336 [Reporter](INFO): platform_buoyancy_position 333.456848 cc 2019-07-29T17:56:44.740Z,1564423004.740 [Reporter](INFO): platform_buoyancy_position 339.768951 cc 2019-07-29T17:56:45.166Z,1564423005.166 [Reporter](INFO): platform_buoyancy_position 346.215332 cc 2019-07-29T17:56:45.544Z,1564423005.544 [Reporter](INFO): platform_buoyancy_position 350.647247 cc 2019-07-29T17:56:45.943Z,1564423005.943 [Reporter](INFO): platform_buoyancy_position 356.959351 cc 2019-07-29T17:56:46.351Z,1564423006.351 [Reporter](INFO): platform_buoyancy_position 361.256927 cc 2019-07-29T17:56:46.760Z,1564423006.760 [Reporter](INFO): platform_buoyancy_position 367.837646 cc 2019-07-29T17:56:47.168Z,1564423007.168 [Reporter](INFO): platform_buoyancy_position 372.000946 cc 2019-07-29T17:56:47.587Z,1564423007.587 [Reporter](INFO): platform_buoyancy_position 376.432831 cc 2019-07-29T17:56:47.979Z,1564423007.979 [Reporter](INFO): platform_buoyancy_position 382.744934 cc 2019-07-29T17:56:48.384Z,1564423008.384 [Reporter](INFO): platform_buoyancy_position 387.176849 cc 2019-07-29T17:56:48.775Z,1564423008.775 [Reporter](INFO): platform_buoyancy_position 393.623230 cc 2019-07-29T17:56:49.176Z,1564423009.176 [Reporter](INFO): platform_buoyancy_position 398.592346 cc 2019-07-29T17:56:49.588Z,1564423009.588 [Reporter](INFO): platform_buoyancy_position 404.367249 cc 2019-07-29T17:56:49.991Z,1564423009.991 [Reporter](INFO): platform_buoyancy_position 408.799133 cc 2019-07-29T17:56:50.404Z,1564423010.404 [Reporter](INFO): platform_buoyancy_position 414.976959 cc 2019-07-29T17:56:50.808Z,1564423010.808 [Reporter](INFO): platform_buoyancy_position 419.408844 cc 2019-07-29T17:56:51.199Z,1564423011.199 [Reporter](INFO): platform_buoyancy_position 425.720917 cc 2019-07-29T17:56:51.612Z,1564423011.612 [Reporter](INFO): platform_buoyancy_position 430.018524 cc 2019-07-29T17:56:52.027Z,1564423012.027 [Reporter](INFO): platform_buoyancy_position 436.464935 cc 2019-07-29T17:56:52.411Z,1564423012.411 [Reporter](INFO): platform_buoyancy_position 440.762543 cc 2019-07-29T17:56:52.816Z,1564423012.816 [Reporter](INFO): platform_buoyancy_position 445.194427 cc 2019-07-29T17:56:53.232Z,1564423013.232 [Reporter](INFO): platform_buoyancy_position 451.640839 cc 2019-07-29T17:56:53.655Z,1564423013.655 [Reporter](INFO): platform_buoyancy_position 455.938446 cc 2019-07-29T17:56:54.068Z,1564423014.068 [Reporter](INFO): platform_buoyancy_position 462.250519 cc 2019-07-29T17:56:54.460Z,1564423014.460 [Reporter](INFO): platform_buoyancy_position 468.562653 cc 2019-07-29T17:56:54.875Z,1564423014.875 [Reporter](INFO): platform_buoyancy_position 473.128876 cc 2019-07-29T17:56:55.252Z,1564423015.252 [Reporter](INFO): platform_buoyancy_position 479.575226 cc 2019-07-29T17:56:55.678Z,1564423015.678 [Reporter](INFO): platform_buoyancy_position 483.872833 cc 2019-07-29T17:56:56.096Z,1564423016.096 [Reporter](INFO): platform_buoyancy_position 488.170441 cc 2019-07-29T17:56:56.464Z,1564423016.464 [Reporter](INFO): platform_buoyancy_position 494.616852 cc 2019-07-29T17:56:56.876Z,1564423016.876 [Reporter](INFO): platform_buoyancy_position 498.914459 cc 2019-07-29T17:56:57.380Z,1564423017.380 [Reporter](INFO): platform_buoyancy_position 505.360901 cc 2019-07-29T17:56:57.680Z,1564423017.680 [Reporter](INFO): platform_buoyancy_position 509.524139 cc 2019-07-29T17:56:58.080Z,1564423018.080 [Reporter](INFO): platform_buoyancy_position 515.970520 cc 2019-07-29T17:56:58.495Z,1564423018.495 [Reporter](INFO): platform_buoyancy_position 520.402405 cc 2019-07-29T17:56:58.908Z,1564423018.908 [Reporter](INFO): platform_buoyancy_position 526.848816 cc 2019-07-29T17:56:59.296Z,1564423019.296 [Reporter](INFO): platform_buoyancy_position 531.146423 cc 2019-07-29T17:56:59.699Z,1564423019.699 [Reporter](INFO): platform_buoyancy_position 537.189941 cc 2019-07-29T17:57:00.100Z,1564423020.100 [Reporter](INFO): platform_buoyancy_position 541.890442 cc 2019-07-29T17:57:00.500Z,1564423020.500 [Reporter](INFO): platform_buoyancy_position 546.188049 cc 2019-07-29T17:57:00.895Z,1564423020.895 [Reporter](INFO): platform_buoyancy_position 552.500122 cc 2019-07-29T17:57:01.308Z,1564423021.308 [Reporter](INFO): platform_buoyancy_position 557.066345 cc 2019-07-29T17:57:01.723Z,1564423021.723 [Reporter](INFO): platform_buoyancy_position 563.244141 cc 2019-07-29T17:57:02.107Z,1564423022.107 [Reporter](INFO): platform_buoyancy_position 567.810303 cc 2019-07-29T17:57:02.520Z,1564423022.520 [Reporter](INFO): platform_buoyancy_position 574.122437 cc 2019-07-29T17:57:02.915Z,1564423022.915 [Reporter](INFO): platform_buoyancy_position 578.285706 cc 2019-07-29T17:57:03.331Z,1564423023.331 [Reporter](INFO): platform_buoyancy_position 584.329224 cc 2019-07-29T17:57:03.736Z,1564423023.736 [Reporter](INFO): platform_buoyancy_position 589.029724 cc 2019-07-29T17:57:04.148Z,1564423024.148 [Reporter](INFO): platform_buoyancy_position 593.461609 cc 2019-07-29T17:57:04.539Z,1564423024.539 [Reporter](INFO): platform_buoyancy_position 599.773743 cc 2019-07-29T17:57:04.951Z,1564423024.951 [Reporter](INFO): platform_buoyancy_position 604.205627 cc 2019-07-29T17:57:05.340Z,1564423025.340 [Reporter](INFO): platform_buoyancy_position 610.652039 cc 2019-07-29T17:57:05.743Z,1564423025.743 [Reporter](INFO): platform_buoyancy_position 614.949646 cc 2019-07-29T17:57:06.143Z,1564423026.143 [Reporter](INFO): platform_buoyancy_position 621.261719 cc 2019-07-29T17:57:06.556Z,1564423026.556 [Reporter](INFO): platform_buoyancy_position 625.559326 cc 2019-07-29T17:57:06.963Z,1564423026.963 [Reporter](INFO): platform_buoyancy_position 632.005737 cc 2019-07-29T17:57:07.376Z,1564423027.376 [Reporter](INFO): platform_buoyancy_position 636.303345 cc 2019-07-29T17:57:07.768Z,1564423027.768 [Reporter](INFO): platform_buoyancy_position 641.943909 cc 2019-07-29T17:57:08.179Z,1564423028.179 [Reporter](INFO): platform_buoyancy_position 647.047302 cc 2019-07-29T17:57:08.567Z,1564423028.567 [Reporter](INFO): platform_buoyancy_position 651.479248 cc 2019-07-29T17:57:08.976Z,1564423028.976 [Reporter](INFO): platform_buoyancy_position 657.791321 cc 2019-07-29T17:57:09.379Z,1564423029.379 [Reporter](INFO): platform_buoyancy_position 662.088928 cc 2019-07-29T17:57:09.784Z,1564423029.784 [Reporter](INFO): platform_buoyancy_position 668.535339 cc 2019-07-29T17:57:10.192Z,1564423030.192 [Reporter](INFO): platform_buoyancy_position 672.832947 cc 2019-07-29T17:57:10.599Z,1564423030.599 [Reporter](INFO): platform_buoyancy_position 679.413635 cc 2019-07-29T17:57:11.020Z,1564423031.020 [Reporter](INFO): platform_buoyancy_position 683.711243 cc 2019-07-29T17:57:11.404Z,1564423031.404 [Reporter](INFO): platform_buoyancy_position 690.023315 cc 2019-07-29T17:57:11.826Z,1564423031.826 [Reporter](INFO): platform_buoyancy_position 694.320923 cc 2019-07-29T17:57:12.232Z,1564423032.232 [Reporter](INFO): platform_buoyancy_position 699.961548 cc 2019-07-29T17:57:12.616Z,1564423032.616 [Reporter](INFO): platform_buoyancy_position 705.199219 cc 2019-07-29T17:57:13.011Z,1564423033.011 [Reporter](INFO): platform_buoyancy_position 709.631104 cc 2019-07-29T17:57:13.428Z,1564423033.428 [Reporter](INFO): platform_buoyancy_position 715.808960 cc 2019-07-29T17:57:13.840Z,1564423033.840 [Reporter](INFO): platform_buoyancy_position 720.240845 cc 2019-07-29T17:57:14.233Z,1564423034.233 [Reporter](INFO): platform_buoyancy_position 726.284363 cc 2019-07-29T17:57:14.636Z,1564423034.636 [Reporter](INFO): platform_buoyancy_position 730.850525 cc 2019-07-29T17:57:15.047Z,1564423035.047 [Reporter](INFO): platform_buoyancy_position 735.282410 cc 2019-07-29T17:57:15.443Z,1564423035.443 [Reporter](INFO): platform_buoyancy_position 741.057312 cc 2019-07-29T17:57:15.844Z,1564423035.844 [Reporter](INFO): platform_buoyancy_position 746.026428 cc 2019-07-29T17:57:16.256Z,1564423036.256 [Reporter](INFO): platform_buoyancy_position 752.338562 cc 2019-07-29T17:57:16.659Z,1564423036.659 [Reporter](INFO): platform_buoyancy_position 756.770447 cc 2019-07-29T17:57:17.064Z,1564423037.064 [Reporter](INFO): platform_buoyancy_position 762.411011 cc 2019-07-29T17:57:17.476Z,1564423037.476 [Reporter](INFO): platform_buoyancy_position 767.514465 cc 2019-07-29T17:57:17.867Z,1564423037.867 [Reporter](INFO): platform_buoyancy_position 771.812012 cc 2019-07-29T17:57:18.279Z,1564423038.279 [Reporter](INFO): platform_buoyancy_position 778.124146 cc 2019-07-29T17:57:18.672Z,1564423038.672 [Reporter](INFO): platform_buoyancy_position 782.690369 cc 2019-07-29T17:57:19.071Z,1564423039.071 [Reporter](INFO): platform_buoyancy_position 788.868164 cc 2019-07-29T17:57:19.476Z,1564423039.476 [Reporter](INFO): platform_buoyancy_position 793.300049 cc 2019-07-29T17:57:19.892Z,1564423039.892 [Reporter](INFO): platform_buoyancy_position 799.746460 cc 2019-07-29T17:57:20.303Z,1564423040.303 [Reporter](INFO): platform_buoyancy_position 804.178345 cc 2019-07-29T17:57:20.712Z,1564423040.712 [Reporter](INFO): platform_buoyancy_position 810.356140 cc 2019-07-29T17:57:21.092Z,1564423041.092 [Reporter](INFO): platform_buoyancy_position 814.653748 cc 2019-07-29T17:57:21.512Z,1564423041.512 [Reporter](INFO): platform_buoyancy_position 821.100159 cc 2019-07-29T17:57:21.920Z,1564423041.920 [Reporter](INFO): platform_buoyancy_position 825.532043 cc 2019-07-29T17:57:22.335Z,1564423042.335 [Reporter](INFO): platform_buoyancy_position 831.844116 cc 2019-07-29T17:57:22.760Z,1564423042.760 [Reporter](INFO): platform_buoyancy_position 836.410339 cc 2019-07-29T17:57:23.128Z,1564423043.128 [Reporter](INFO): platform_buoyancy_position 842.588135 cc 2019-07-29T17:57:23.552Z,1564423043.552 [Reporter](INFO): platform_buoyancy_position 847.020020 cc 2019-07-29T17:57:23.951Z,1564423043.951 [Reporter](INFO): platform_buoyancy_position 852.123413 cc 2019-07-29T17:57:24.364Z,1564423044.364 [Reporter](INFO): platform_buoyancy_position 857.629761 cc 2019-07-29T17:57:24.756Z,1564423044.756 [Reporter](INFO): platform_buoyancy_position 862.061646 cc 2019-07-29T17:57:25.183Z,1564423045.183 [Reporter](INFO): platform_buoyancy_position 868.373718 cc 2019-07-29T17:57:25.572Z,1564423045.572 [Reporter](INFO): platform_buoyancy_position 872.671326 cc 2019-07-29T17:57:25.948Z,1564423045.948 [Reporter](INFO): platform_buoyancy_position 877.506165 cc 2019-07-29T17:57:26.355Z,1564423046.355 [Reporter](INFO): platform_buoyancy_position 883.549622 cc 2019-07-29T17:57:26.759Z,1564423046.759 [Reporter](INFO): platform_buoyancy_position 888.115845 cc 2019-07-29T17:57:27.168Z,1564423047.168 [Reporter](INFO): platform_buoyancy_position 894.159363 cc 2019-07-29T17:57:27.575Z,1564423047.575 [Reporter](INFO): platform_buoyancy_position 900.740051 cc 2019-07-29T17:57:27.971Z,1564423047.971 [Reporter](INFO): platform_buoyancy_position 905.037659 cc 2019-07-29T17:57:28.372Z,1564423048.372 [Reporter](INFO): platform_buoyancy_position 909.738159 cc 2019-07-29T17:57:28.797Z,1564423048.797 [Reporter](INFO): platform_buoyancy_position 915.647339 cc 2019-07-29T17:57:29.208Z,1564423049.208 [Reporter](INFO): platform_buoyancy_position 922.093750 cc 2019-07-29T17:57:29.580Z,1564423049.580 [Reporter](INFO): platform_buoyancy_position 926.391357 cc 2019-07-29T17:57:29.999Z,1564423049.999 [Reporter](INFO): platform_buoyancy_position 932.569092 cc 2019-07-29T17:57:30.400Z,1564423050.400 [Reporter](INFO): platform_buoyancy_position 937.135376 cc 2019-07-29T17:57:34.464Z,1564423054.464 [Reporter](INFO): platform_buoyancy_position 952.311279 cc 2019-07-29T18:00:21.261Z,1564423221.261 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T18:00:21.261Z,1564423221.261 [Default:CheckIn:C.Wait] Stopped 2019-07-29T18:00:21.261Z,1564423221.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T18:00:21.262Z,1564423221.262 [Default:CheckIn:D] Running Loop=1 2019-07-29T18:00:21.661Z,1564423221.661 [Default:CheckIn:D] Stopped 2019-07-29T18:00:21.661Z,1564423221.661 [Default:CheckIn:E] Running Loop=1 2019-07-29T18:00:22.076Z,1564423222.076 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.645462 min 2019-07-29T18:00:22.076Z,1564423222.076 [Default:CheckIn:E] Stopped 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn] Stopped 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn](INFO): Running loop #2 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn] Running Loop=2 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T18:00:22.077Z,1564423222.077 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T18:00:23.669Z,1564423223.669 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:00:24.072Z,1564423224.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175408.00,A,3648.17683,N,12147.28172,W,0.311,147.12,290719,,,D*7D 2019-07-29T18:00:24.078Z,1564423224.078 [NAL9602](INFO): GPS fix at 20190729T175408: (36.802947, -121.788029) 2019-07-29T18:00:24.133Z,1564423224.133 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T18:00:24.133Z,1564423224.133 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T18:00:27.166Z,1564423227.166 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190729T162726/Courier0054.lzma 2019-07-29T18:00:27.964Z,1564423227.964 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0054.lzma.bak 2019-07-29T18:00:27.964Z,1564423227.964 [DataOverHttps](INFO): SBD MOMSN=11482648 2019-07-29T18:00:30.596Z,1564423230.596 [NAL9602](INFO): SBD MO Status=0, MOMSN=31118, MT Status=0, MTMSN=0 2019-07-29T18:00:30.596Z,1564423230.596 [NAL9602](INFO): No messages in MT queue 2019-07-29T18:00:40.001Z,1564423240.001 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20190729T162726/Express0055.lzma 2019-07-29T18:00:40.807Z,1564423240.807 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0055.lzma.bak 2019-07-29T18:00:40.807Z,1564423240.807 [DataOverHttps](INFO): SBD MOMSN=11482651 2019-07-29T18:00:41.540Z,1564423241.540 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T18:00:41.540Z,1564423241.540 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T18:00:41.540Z,1564423241.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T18:01:01.296Z,1564423261.296 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T18:03:57.474Z,1564423437.474 [CBIT](INFO): Clearing failed state for component DropWeight 2019-07-29T18:03:57.474Z,1564423437.474 [DropWeight] No Fault, FailCount= 1 2019-07-29T18:05:42.102Z,1564423542.102 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T18:05:42.102Z,1564423542.102 [Default:CheckIn:C.Wait] Stopped 2019-07-29T18:05:42.102Z,1564423542.102 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T18:05:42.103Z,1564423542.103 [Default:CheckIn:D] Running Loop=1 2019-07-29T18:05:42.503Z,1564423542.503 [Default:CheckIn:D] Stopped 2019-07-29T18:05:42.503Z,1564423542.503 [Default:CheckIn:E] Running Loop=1 2019-07-29T18:05:42.908Z,1564423542.908 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.992841 min 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn:E] Stopped 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn] Stopped 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn](INFO): Running loop #3 2019-07-29T18:05:42.909Z,1564423542.909 [Default:CheckIn] Running Loop=3 2019-07-29T18:05:42.910Z,1564423542.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T18:05:42.910Z,1564423542.910 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T18:05:44.530Z,1564423544.530 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:05:44.908Z,1564423544.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175928.00,A,3648.16992,N,12147.27892,W,0.253,157.58,290719,,,D*7C 2019-07-29T18:05:44.910Z,1564423544.910 [NAL9602](INFO): GPS fix at 20190729T175928: (36.802832, -121.787982) 2019-07-29T18:05:44.964Z,1564423544.964 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T18:05:44.964Z,1564423544.964 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T18:05:48.028Z,1564423548.028 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190729T162726/Courier0057.lzma 2019-07-29T18:05:48.691Z,1564423548.691 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0057.lzma.bak 2019-07-29T18:05:48.691Z,1564423548.691 [DataOverHttps](INFO): SBD MOMSN=11482658 2019-07-29T18:06:00.517Z,1564423560.517 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190729T162726/Express0058.lzma 2019-07-29T18:06:01.323Z,1564423561.323 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0058.lzma.bak 2019-07-29T18:06:01.323Z,1564423561.323 [DataOverHttps](INFO): SBD MOMSN=11482661 2019-07-29T18:06:02.004Z,1564423562.004 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T18:06:02.004Z,1564423562.004 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T18:06:02.004Z,1564423562.004 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T18:07:37.719Z,1564423657.719 [NAL9602](INFO): SBD MO Status=2, MOMSN=31119, MT Status=2, MTMSN=0 2019-07-29T18:07:37.719Z,1564423657.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-07-29T18:10:46.789Z,1564423846.789 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-29T18:11:02.579Z,1564423862.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T18:11:02.580Z,1564423862.580 [Default:CheckIn:C.Wait] Stopped 2019-07-29T18:11:02.580Z,1564423862.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T18:11:02.580Z,1564423862.580 [Default:CheckIn:D] Running Loop=1 2019-07-29T18:11:02.993Z,1564423862.993 [Default:CheckIn:D] Stopped 2019-07-29T18:11:02.993Z,1564423862.993 [Default:CheckIn:E] Running Loop=1 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.334329 min 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn:E] Stopped 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn] Stopped 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn](INFO): Running loop #4 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn] Running Loop=4 2019-07-29T18:11:03.372Z,1564423863.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T18:11:03.373Z,1564423863.373 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T18:11:04.981Z,1564423864.981 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:11:05.380Z,1564423865.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180448.00,A,3648.17327,N,12147.28349,W,1.205,174.50,290719,,,D*71 2019-07-29T18:11:05.382Z,1564423865.382 [NAL9602](INFO): GPS fix at 20190729T180448: (36.802888, -121.788058) 2019-07-29T18:11:05.420Z,1564423865.420 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T18:11:05.420Z,1564423865.420 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T18:11:08.521Z,1564423868.521 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190729T162726/Courier0060.lzma 2019-07-29T18:11:09.183Z,1564423869.183 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0060.lzma.bak 2019-07-29T18:11:09.183Z,1564423869.183 [DataOverHttps](INFO): SBD MOMSN=11482666 2019-07-29T18:11:20.105Z,1564423880.105 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20190729T162726/Express0061.lzma 2019-07-29T18:11:20.911Z,1564423880.911 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0061.lzma.bak 2019-07-29T18:11:20.911Z,1564423880.911 [DataOverHttps](INFO): SBD MOMSN=11482669 2019-07-29T18:11:21.637Z,1564423881.637 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T18:11:21.637Z,1564423881.637 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T18:11:21.637Z,1564423881.637 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T18:11:37.368Z,1564423897.368 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T18:16:22.218Z,1564424182.218 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T18:16:22.218Z,1564424182.218 [Default:CheckIn:C.Wait] Stopped 2019-07-29T18:16:22.218Z,1564424182.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T18:16:22.219Z,1564424182.219 [Default:CheckIn:D] Running Loop=1 2019-07-29T18:16:22.619Z,1564424182.619 [Default:CheckIn:D] Stopped 2019-07-29T18:16:22.619Z,1564424182.619 [Default:CheckIn:E] Running Loop=1 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.661436 min 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn:E] Stopped 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn] Stopped 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:16:23.029Z,1564424183.029 [Default:CheckIn](INFO): Running loop #5 2019-07-29T18:16:23.030Z,1564424183.030 [Default:CheckIn] Running Loop=5 2019-07-29T18:16:23.030Z,1564424183.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T18:16:23.030Z,1564424183.030 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T18:16:24.649Z,1564424184.649 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:16:25.024Z,1564424185.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181007.00,A,3648.15623,N,12147.28442,W,2.002,167.17,290719,,,A*72 2019-07-29T18:16:25.026Z,1564424185.026 [NAL9602](INFO): GPS fix at 20190729T181007: (36.802604, -121.788074) 2019-07-29T18:16:25.077Z,1564424185.077 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T18:16:25.077Z,1564424185.077 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-29T18:16:27.744Z,1564424187.744 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190729T162726/Courier0063.lzma 2019-07-29T18:16:28.627Z,1564424188.627 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Courier0063.lzma.bak 2019-07-29T18:16:28.627Z,1564424188.627 [DataOverHttps](INFO): SBD MOMSN=11482710 2019-07-29T18:16:40.217Z,1564424200.217 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20190729T162726/Express0064.lzma 2019-07-29T18:16:41.023Z,1564424201.023 [DataOverHttps](INFO): Moved sent file to Logs/20190729T162726/Express0064.lzma.bak 2019-07-29T18:16:41.023Z,1564424201.023 [DataOverHttps](INFO): SBD MOMSN=11482713 2019-07-29T18:16:41.695Z,1564424201.695 [Default:CheckIn:Read_Iridium] Stopped 2019-07-29T18:16:41.695Z,1564424201.695 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-29T18:16:41.695Z,1564424201.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-29T18:16:55.815Z,1564424215.815 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-07-29T18:16:55.893Z,1564424215.893 [NAL9602](FAULT): received: +CSQ:0 OK119, 2, 0, 0, 0 OK 2019-07-29T18:16:55.893Z,1564424215.893 [NAL9602] Data Fault, FailCount= 1 2019-07-29T18:16:55.893Z,1564424215.893 [NAL9602](ERROR): Data Fault 2019-07-29T18:16:55.942Z,1564424215.942 [CBIT](ERROR): Data Fault in component: NAL9602 2019-07-29T18:16:56.212Z,1564424216.212 [NAL9602](INFO): Powering down 2019-07-29T18:16:57.062Z,1564424217.062 [CBIT](INFO): Clearing failed state for component NAL9602 2019-07-29T18:16:57.062Z,1564424217.062 [NAL9602] No Fault, FailCount= 1 2019-07-29T18:17:26.509Z,1564424246.509 [NAL9602](INFO): Powering up NAL9602 2019-07-29T18:17:37.427Z,1564424257.427 [NAL9602](INFO): NAL9602 initialized 2019-07-29T18:18:08.534Z,1564424288.534 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-29T18:21:42.278Z,1564424502.278 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-29T18:21:42.278Z,1564424502.278 [Default:CheckIn:C.Wait] Stopped 2019-07-29T18:21:42.278Z,1564424502.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-29T18:21:42.278Z,1564424502.278 [Default:CheckIn:D] Running Loop=1 2019-07-29T18:21:42.732Z,1564424502.732 [Default:CheckIn:D] Stopped 2019-07-29T18:21:42.732Z,1564424502.732 [Default:CheckIn:E] Running Loop=1 2019-07-29T18:21:43.104Z,1564424503.104 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.996647 min 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn:E] Stopped 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn] Stopped 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn](INFO): Running loop #6 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn] Running Loop=6 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-29T18:21:43.105Z,1564424503.105 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-29T18:21:44.691Z,1564424504.691 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:21:47.096Z,1564424507.096 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-29T18:21:47.929Z,1564424507.929 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:21:50.343Z,1564424510.343 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:21:53.173Z,1564424513.173 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:21:55.209Z,1564424515.209 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:21:57.214Z,1564424517.214 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:00.461Z,1564424520.461 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:03.274Z,1564424523.274 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:06.518Z,1564424526.518 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:09.346Z,1564424529.346 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:12.565Z,1564424532.565 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:15.398Z,1564424535.398 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:18.626Z,1564424538.626 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:21.453Z,1564424541.453 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:24.685Z,1564424544.685 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:27.514Z,1564424547.514 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:30.749Z,1564424550.749 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:33.582Z,1564424553.582 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:36.401Z,1564424556.401 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:39.634Z,1564424559.634 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:42.486Z,1564424562.486 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:45.289Z,1564424565.289 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:48.522Z,1564424568.522 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:51.354Z,1564424571.354 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:54.581Z,1564424574.581 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:22:57.413Z,1564424577.413 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:00.653Z,1564424580.653 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:03.481Z,1564424583.481 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:06.702Z,1564424586.702 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:09.542Z,1564424589.542 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:12.785Z,1564424592.785 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:15.589Z,1564424595.589 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:18.821Z,1564424598.821 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:19.838Z,1564424599.838 [CommandLine](IMPORTANT): got command quit 2019-07-29T18:23:20.851Z,1564424600.851 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:20.851Z,1564424600.851 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:20.961Z,1564424600.961 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-07-29T18:23:20.962Z,1564424600.962 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:20.962Z,1564424600.962 [CommandLine](INFO): Join timeout helper Thread ID is 8452 2019-07-29T18:23:20.970Z,1564424600.970 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-07-29T18:23:20.970Z,1564424600.970 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:20.970Z,1564424600.970 [NavChartDb](INFO): Join timeout helper Thread ID is 8453 2019-07-29T18:23:21.202Z,1564424601.202 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:21.202Z,1564424601.202 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:21.205Z,1564424601.205 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-07-29T18:23:21.205Z,1564424601.205 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:21.206Z,1564424601.206 [Radio_Surface](INFO): Join timeout helper Thread ID is 8454 2019-07-29T18:23:21.414Z,1564424601.414 [Radio_Surface](INFO): Powering down 2019-07-29T18:23:21.418Z,1564424601.418 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:21.419Z,1564424601.419 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:21.429Z,1564424601.429 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-07-29T18:23:21.429Z,1564424601.429 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:21.430Z,1564424601.430 [DataOverHttps](INFO): Join timeout helper Thread ID is 8455 2019-07-29T18:23:21.649Z,1564424601.649 [NAL9602](DEBUG): Fix Requested 2019-07-29T18:23:23.406Z,1564424603.406 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:23.412Z,1564424603.412 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.417Z,1564424603.417 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-07-29T18:23:23.418Z,1564424603.418 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.418Z,1564424603.418 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8456 2019-07-29T18:23:23.622Z,1564424603.622 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:23.622Z,1564424603.622 [WetLabsBB2FL](INFO): Powering down 2019-07-29T18:23:23.623Z,1564424603.623 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.638Z,1564424603.638 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-07-29T18:23:23.638Z,1564424603.638 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.639Z,1564424603.639 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8457 2019-07-29T18:23:23.861Z,1564424603.861 [CTD_Seabird](INFO): Powering down 2019-07-29T18:23:23.873Z,1564424603.873 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:23.873Z,1564424603.873 [CTD_Seabird](INFO): Powering down 2019-07-29T18:23:23.885Z,1564424603.885 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.902Z,1564424603.902 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-07-29T18:23:23.902Z,1564424603.902 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.902Z,1564424603.902 [logger](INFO): Join timeout helper Thread ID is 8458 2019-07-29T18:23:23.929Z,1564424603.929 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:23.930Z,1564424603.930 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.942Z,1564424603.942 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-07-29T18:23:23.942Z,1564424603.942 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.942Z,1564424603.942 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-07-29T18:23:23.942Z,1564424603.942 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:23.943Z,1564424603.943 [controlThread](INFO): Join timeout helper Thread ID is 8459 2019-07-29T18:23:24.041Z,1564424604.041 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-29T18:23:24.042Z,1564424604.042 [controlThread](DEBUG): Uninitializing ControlThread 2019-07-29T18:23:24.042Z,1564424604.042 [Aanderaa_O2](INFO): Powering down 2019-07-29T18:23:24.044Z,1564424604.044 [AHRS_M2](INFO): Powering down 2019-07-29T18:23:24.114Z,1564424604.114 [NAL9602](INFO): Powering down 2019-07-29T18:23:24.115Z,1564424604.115 [DAT](INFO): Powering down 2019-07-29T18:23:24.234Z,1564424604.234 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-07-29T18:23:24.235Z,1564424604.235 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-07-29T18:23:24.235Z,1564424604.235 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-07-29T18:23:24.236Z,1564424604.236 [MissionManager](INFO): Uninitializing Mission Default 2019-07-29T18:23:24.236Z,1564424604.236 [Default] Stopped 2019-07-29T18:23:24.236Z,1564424604.236 [Default](DEBUG): Aggregate::uninitialize Default 2019-07-29T18:23:24.236Z,1564424604.236 [Default:B.GoToSurface] Stopped 2019-07-29T18:23:24.236Z,1564424604.236 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-07-29T18:23:24.237Z,1564424604.237 [Default:CheckIn] Stopped 2019-07-29T18:23:24.237Z,1564424604.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-29T18:23:24.237Z,1564424604.237 [Default:CheckIn:Read_GPS] Stopped 2019-07-29T18:23:24.239Z,1564424604.239 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-07-29T18:23:24.239Z,1564424604.239 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-07-29T18:23:24.240Z,1564424604.240 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-07-29T18:23:24.240Z,1564424604.240 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-07-29T18:23:24.240Z,1564424604.240 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-07-29T18:23:24.240Z,1564424604.240 [BuoyancyServo](INFO): Powering down 2019-07-29T18:23:24.253Z,1564424604.253 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-07-29T18:23:24.253Z,1564424604.253 [ElevatorServo](INFO): Powering down 2019-07-29T18:23:24.254Z,1564424604.254 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-07-29T18:23:24.254Z,1564424604.254 [MassServo](INFO): Powering down 2019-07-29T18:23:24.255Z,1564424604.255 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-07-29T18:23:24.255Z,1564424604.255 [RudderServo](INFO): Powering down 2019-07-29T18:23:24.256Z,1564424604.256 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-07-29T18:23:24.256Z,1564424604.256 [ThrusterServo](INFO): Powering down 2019-07-29T18:23:24.257Z,1564424604.257 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-07-29T18:23:24.257Z,1564424604.257 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-07-29T18:23:24.258Z,1564424604.258 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-07-29T18:23:24.258Z,1564424604.258 [CBIT](DEBUG): Powering off loads. 2019-07-29T18:23:24.269Z,1564424604.269 [CBIT](DEBUG): Disabling WDT. 2019-07-29T18:23:24.281Z,1564424604.281 [CBIT](DEBUG): Opening all GF detection circuits. 2019-07-29T18:23:24.282Z,1564424604.282 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.329Z,1564424604.329 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.410Z,1564424604.410 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.515Z,1564424604.515 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.520Z,1564424604.520 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.569Z,1564424604.569 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-29T18:23:24.650Z,1564424604.650 [logger ThreadHandler](INFO): Thread cancelled.