2018-08-02T14:10:40.902Z,1533219040.902 [CommandLine](IMPORTANT): got command restart logs 2018-08-02T14:11:31.685Z,1533219091.685 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T14:11:31.685Z,1533219091.685 [Default:CheckIn:C.Wait] Stopped 2018-08-02T14:11:31.685Z,1533219091.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T14:11:31.685Z,1533219091.685 [Default:CheckIn:D] Running Loop=1 2018-08-02T14:11:32.085Z,1533219092.085 [Default:CheckIn:D] Stopped 2018-08-02T14:11:32.085Z,1533219092.085 [Default:CheckIn:E] Running Loop=1 2018-08-02T14:11:32.486Z,1533219092.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 410.936198 min 2018-08-02T14:11:32.486Z,1533219092.486 [Default:CheckIn:E] Stopped 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn] Stopped 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn](INFO): Running loop #39 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn] Running Loop=39 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T14:11:32.487Z,1533219092.487 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T14:11:33.242Z,1533219093.242 [NAL9602](INFO): Powering up 2018-08-02T14:11:44.058Z,1533219104.058 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:11:44.854Z,1533219104.854 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T14:11:44.854Z,1533219104.854 [NAL9602] Data Fault, FailCount= 3 2018-08-02T14:11:44.854Z,1533219104.854 [NAL9602](ERROR): Data Fault 2018-08-02T14:11:44.943Z,1533219104.943 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T14:11:45.258Z,1533219105.258 [NAL9602](INFO): Powering down 2018-08-02T14:11:46.122Z,1533219106.122 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T14:11:46.122Z,1533219106.122 [NAL9602] No Fault, FailCount= 3 2018-08-02T14:12:15.654Z,1533219135.654 [NAL9602](INFO): Powering up NAL9602 2018-08-02T14:12:26.854Z,1533219146.854 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:16:32.666Z,1533219392.666 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T14:11:32.5Z 2018-08-02T14:16:32.666Z,1533219392.666 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T14:16:32.667Z,1533219392.667 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T14:16:37.339Z,1533219397.339 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T044706/Courier0124.lzma 2018-08-02T14:16:38.132Z,1533219398.132 [DataOverHttps](INFO): Moved sent file to Logs/20180802T044706/Courier0124.lzma.bak 2018-08-02T14:16:38.132Z,1533219398.132 [DataOverHttps](INFO): SBD MOMSN=8394901 2018-08-02T14:16:47.905Z,1533219407.905 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0000.lzma 2018-08-02T14:16:48.703Z,1533219408.703 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0000.lzma.bak 2018-08-02T14:16:48.703Z,1533219408.703 [DataOverHttps](INFO): SBD MOMSN=8394903 2018-08-02T14:17:00.579Z,1533219420.579 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0003.lzma 2018-08-02T14:17:01.359Z,1533219421.359 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0003.lzma.bak 2018-08-02T14:17:01.359Z,1533219421.359 [DataOverHttps](INFO): SBD MOMSN=8394906 2018-08-02T14:17:12.555Z,1533219432.555 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20180802T044706/Express0125.lzma 2018-08-02T14:17:13.343Z,1533219433.343 [DataOverHttps](INFO): Moved sent file to Logs/20180802T044706/Express0125.lzma.bak 2018-08-02T14:17:13.344Z,1533219433.344 [DataOverHttps](INFO): SBD MOMSN=8394908 2018-08-02T14:17:23.778Z,1533219443.778 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20180802T141040/Express0001.lzma 2018-08-02T14:17:24.563Z,1533219444.563 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0001.lzma.bak 2018-08-02T14:17:24.563Z,1533219444.563 [DataOverHttps](INFO): SBD MOMSN=8394917 2018-08-02T14:17:30.257Z,1533219450.257 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T14:17:34.653Z,1533219454.653 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20180802T141040/Express0004.lzma 2018-08-02T14:17:35.451Z,1533219455.451 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0004.lzma.bak 2018-08-02T14:17:35.451Z,1533219455.451 [DataOverHttps](INFO): SBD MOMSN=8394922 2018-08-02T14:17:36.559Z,1533219456.559 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T14:17:36.560Z,1533219456.560 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T14:17:36.560Z,1533219456.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T14:18:01.022Z,1533219481.022 [NAL9602](INFO): Powering down 2018-08-02T14:22:36.832Z,1533219756.832 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T14:22:36.832Z,1533219756.832 [Default:CheckIn:C.Wait] Stopped 2018-08-02T14:22:36.832Z,1533219756.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T14:22:36.833Z,1533219756.833 [Default:CheckIn:D] Running Loop=1 2018-08-02T14:22:37.231Z,1533219757.231 [Default:CheckIn:D] Stopped 2018-08-02T14:22:37.231Z,1533219757.231 [Default:CheckIn:E] Running Loop=1 2018-08-02T14:22:37.658Z,1533219757.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 422.021973 min 2018-08-02T14:22:37.658Z,1533219757.658 [Default:CheckIn:E] Stopped 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn] Stopped 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn](INFO): Running loop #40 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn] Running Loop=40 2018-08-02T14:22:37.659Z,1533219757.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T14:22:37.660Z,1533219757.660 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T14:22:41.206Z,1533219761.206 [NAL9602](INFO): Powering up 2018-08-02T14:22:52.106Z,1533219772.106 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:22:52.902Z,1533219772.902 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T14:22:52.902Z,1533219772.902 [NAL9602] Data Fault, FailCount= 4 2018-08-02T14:22:52.902Z,1533219772.902 [NAL9602](ERROR): Data Fault 2018-08-02T14:22:52.988Z,1533219772.988 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T14:22:53.306Z,1533219773.306 [NAL9602](INFO): Powering down 2018-08-02T14:22:54.161Z,1533219774.161 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T14:22:54.162Z,1533219774.162 [NAL9602] No Fault, FailCount= 4 2018-08-02T14:23:23.709Z,1533219803.709 [NAL9602](INFO): Powering up NAL9602 2018-08-02T14:23:34.902Z,1533219814.902 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:27:40.780Z,1533220060.780 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T14:22:37.7Z 2018-08-02T14:27:40.780Z,1533220060.780 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T14:27:40.781Z,1533220060.781 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T14:27:46.724Z,1533220066.724 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0006.lzma 2018-08-02T14:27:47.523Z,1533220067.523 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0006.lzma.bak 2018-08-02T14:27:47.523Z,1533220067.523 [DataOverHttps](INFO): SBD MOMSN=8394933 2018-08-02T14:27:56.851Z,1533220076.851 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20180802T141040/Express0007.lzma 2018-08-02T14:27:57.615Z,1533220077.615 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0007.lzma.bak 2018-08-02T14:27:57.615Z,1533220077.615 [DataOverHttps](INFO): SBD MOMSN=8394935 2018-08-02T14:27:58.749Z,1533220078.749 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T14:27:58.749Z,1533220078.749 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T14:27:58.749Z,1533220078.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T14:28:40.974Z,1533220120.974 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T14:29:11.442Z,1533220151.442 [NAL9602](INFO): Powering down 2018-08-02T14:32:59.151Z,1533220379.151 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T14:32:59.151Z,1533220379.151 [Default:CheckIn:C.Wait] Stopped 2018-08-02T14:32:59.151Z,1533220379.151 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T14:32:59.152Z,1533220379.152 [Default:CheckIn:D] Running Loop=1 2018-08-02T14:32:59.556Z,1533220379.556 [Default:CheckIn:D] Stopped 2018-08-02T14:32:59.557Z,1533220379.557 [Default:CheckIn:E] Running Loop=1 2018-08-02T14:32:59.960Z,1533220379.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 432.394043 min 2018-08-02T14:32:59.964Z,1533220379.964 [Default:CheckIn:E] Stopped 2018-08-02T14:32:59.964Z,1533220379.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T14:32:59.964Z,1533220379.964 [Default:CheckIn] Stopped 2018-08-02T14:32:59.965Z,1533220379.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T14:32:59.965Z,1533220379.965 [Default:CheckIn](INFO): Running loop #41 2018-08-02T14:32:59.965Z,1533220379.965 [Default:CheckIn] Running Loop=41 2018-08-02T14:32:59.965Z,1533220379.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T14:32:59.965Z,1533220379.965 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T14:33:03.144Z,1533220383.144 [NAL9602](INFO): Powering up 2018-08-02T14:33:14.298Z,1533220394.298 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:35:47.707Z,1533220547.707 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T14:35:47.707Z,1533220547.707 [BPC1] Communications Fault, FailCount= 1 2018-08-02T14:35:47.707Z,1533220547.707 [BPC1](ERROR): Communications Fault 2018-08-02T14:35:47.735Z,1533220547.735 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T14:35:48.955Z,1533220548.955 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T14:35:48.955Z,1533220548.955 [BPC1] No Fault, FailCount= 1 2018-08-02T14:38:00.070Z,1533220680.070 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T14:32:59.0Z 2018-08-02T14:38:00.070Z,1533220680.070 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T14:38:00.070Z,1533220680.070 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T14:38:04.191Z,1533220684.191 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0009.lzma 2018-08-02T14:38:04.979Z,1533220684.979 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0009.lzma.bak 2018-08-02T14:38:04.979Z,1533220684.979 [DataOverHttps](INFO): SBD MOMSN=8394954 2018-08-02T14:38:15.418Z,1533220695.418 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20180802T141040/Express0010.lzma 2018-08-02T14:38:16.187Z,1533220696.187 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0010.lzma.bak 2018-08-02T14:38:16.187Z,1533220696.187 [DataOverHttps](INFO): SBD MOMSN=8394956 2018-08-02T14:38:17.332Z,1533220697.332 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T14:38:17.333Z,1533220697.333 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T14:38:17.333Z,1533220697.333 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T14:38:20.540Z,1533220700.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T14:38:51.014Z,1533220731.014 [NAL9602](INFO): Powering down 2018-08-02T14:43:11.686Z,1533220991.686 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T14:43:11.686Z,1533220991.686 [BPC1] Communications Fault, FailCount= 2 2018-08-02T14:43:11.686Z,1533220991.686 [BPC1](ERROR): Communications Fault 2018-08-02T14:43:11.731Z,1533220991.731 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T14:43:12.916Z,1533220992.916 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T14:43:12.916Z,1533220992.916 [BPC1] No Fault, FailCount= 2 2018-08-02T14:43:17.706Z,1533220997.706 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T14:43:17.706Z,1533220997.706 [Default:CheckIn:C.Wait] Stopped 2018-08-02T14:43:17.706Z,1533220997.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T14:43:17.706Z,1533220997.706 [Default:CheckIn:D] Running Loop=1 2018-08-02T14:43:18.112Z,1533220998.112 [Default:CheckIn:D] Stopped 2018-08-02T14:43:18.113Z,1533220998.113 [Default:CheckIn:E] Running Loop=1 2018-08-02T14:43:21.219Z,1533221001.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 442.703320 min 2018-08-02T14:43:21.219Z,1533221001.219 [Default:CheckIn:E] Stopped 2018-08-02T14:43:21.219Z,1533221001.219 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T14:43:21.219Z,1533221001.219 [Default:CheckIn] Stopped 2018-08-02T14:43:21.219Z,1533221001.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T14:43:21.220Z,1533221001.220 [Default:CheckIn](INFO): Running loop #42 2018-08-02T14:43:21.220Z,1533221001.220 [Default:CheckIn] Running Loop=42 2018-08-02T14:43:21.220Z,1533221001.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T14:43:21.220Z,1533221001.220 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T14:43:21.698Z,1533221001.698 [NAL9602](INFO): Powering up 2018-08-02T14:43:32.894Z,1533221012.894 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:43:33.690Z,1533221013.690 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T14:43:33.690Z,1533221013.690 [NAL9602] Data Fault, FailCount= 5 2018-08-02T14:43:33.690Z,1533221013.690 [NAL9602](ERROR): Data Fault 2018-08-02T14:43:33.778Z,1533221013.778 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T14:43:33.778Z,1533221013.778 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-08-02T14:43:34.094Z,1533221014.094 [NAL9602](INFO): Powering down 2018-08-02T14:43:34.181Z,1533221014.181 [CBIT](INFO): Critical error at 20180802T144333 2018-08-02T14:45:33.891Z,1533221133.891 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T14:45:33.891Z,1533221133.891 [NAL9602] No Fault, FailCount= 5 2018-08-02T14:45:34.234Z,1533221134.234 [NAL9602](INFO): Powering up NAL9602 2018-08-02T14:45:45.434Z,1533221145.434 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:48:21.521Z,1533221301.521 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T14:43:21.2Z 2018-08-02T14:48:21.521Z,1533221301.521 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T14:48:21.521Z,1533221301.521 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T14:48:26.496Z,1533221306.496 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20180802T141040/Courier0012.lzma 2018-08-02T14:48:27.287Z,1533221307.287 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0012.lzma.bak 2018-08-02T14:48:27.287Z,1533221307.287 [DataOverHttps](INFO): SBD MOMSN=8394969 2018-08-02T14:48:37.427Z,1533221317.427 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20180802T141040/Express0013.lzma 2018-08-02T14:48:38.191Z,1533221318.191 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0013.lzma.bak 2018-08-02T14:48:38.191Z,1533221318.191 [DataOverHttps](INFO): SBD MOMSN=8394972 2018-08-02T14:48:39.333Z,1533221319.333 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T14:48:39.333Z,1533221319.333 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T14:48:39.333Z,1533221319.333 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T14:49:34.207Z,1533221374.207 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T14:49:34.208Z,1533221374.208 [BPC1] Communications Fault, FailCount= 3 2018-08-02T14:49:34.208Z,1533221374.208 [BPC1](ERROR): Communications Fault 2018-08-02T14:49:34.258Z,1533221374.258 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T14:49:35.455Z,1533221375.455 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T14:49:35.455Z,1533221375.455 [BPC1] No Fault, FailCount= 3 2018-08-02T14:50:48.798Z,1533221448.798 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T14:51:19.201Z,1533221479.201 [NAL9602](INFO): Powering down 2018-08-02T14:53:39.757Z,1533221619.757 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T14:53:39.757Z,1533221619.757 [Default:CheckIn:C.Wait] Stopped 2018-08-02T14:53:39.758Z,1533221619.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T14:53:39.758Z,1533221619.758 [Default:CheckIn:D] Running Loop=1 2018-08-02T14:53:40.165Z,1533221620.165 [Default:CheckIn:D] Stopped 2018-08-02T14:53:40.165Z,1533221620.165 [Default:CheckIn:E] Running Loop=1 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 453.070866 min 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn:E] Stopped 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn] Stopped 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T14:53:40.567Z,1533221620.567 [Default:CheckIn](INFO): Running loop #43 2018-08-02T14:53:40.568Z,1533221620.568 [Default:CheckIn] Running Loop=43 2018-08-02T14:53:40.568Z,1533221620.568 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T14:53:40.568Z,1533221620.568 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T14:53:44.124Z,1533221624.124 [NAL9602](INFO): Powering up 2018-08-02T14:53:55.030Z,1533221635.030 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:54:27.028Z,1533221667.028 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T14:54:27.101Z,1533221667.101 [NAL9602](FAULT): received: +CSQ:0 OK 2018-08-02T14:54:27.101Z,1533221667.101 [NAL9602] Data Fault, FailCount= 1 2018-08-02T14:54:27.101Z,1533221667.101 [NAL9602](ERROR): Data Fault 2018-08-02T14:54:27.184Z,1533221667.184 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T14:54:27.430Z,1533221667.430 [NAL9602](INFO): Powering down 2018-08-02T14:54:28.291Z,1533221668.291 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T14:54:28.291Z,1533221668.291 [NAL9602] No Fault, FailCount= 1 2018-08-02T14:54:57.810Z,1533221697.810 [NAL9602](INFO): Powering up NAL9602 2018-08-02T14:55:08.614Z,1533221708.614 [NAL9602](INFO): NAL9602 initialized 2018-08-02T14:56:43.844Z,1533221803.844 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T14:56:43.844Z,1533221803.844 [BPC1] Communications Fault, FailCount= 4 2018-08-02T14:56:43.844Z,1533221803.844 [BPC1](ERROR): Communications Fault 2018-08-02T14:56:43.877Z,1533221803.877 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T14:56:44.796Z,1533221804.796 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T14:56:44.796Z,1533221804.796 [BPC1] No Fault, FailCount= 4 2018-08-02T14:58:40.836Z,1533221920.836 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T14:53:40.6Z 2018-08-02T14:58:40.836Z,1533221920.836 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T14:58:40.836Z,1533221920.836 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T14:58:44.582Z,1533221924.582 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0015.lzma 2018-08-02T14:58:45.103Z,1533221925.103 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0015.lzma.bak 2018-08-02T14:58:45.103Z,1533221925.103 [DataOverHttps](INFO): SBD MOMSN=8394990 2018-08-02T14:58:55.344Z,1533221935.344 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20180802T141040/Express0016.lzma 2018-08-02T14:58:56.136Z,1533221936.136 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0016.lzma.bak 2018-08-02T14:58:56.136Z,1533221936.136 [DataOverHttps](INFO): SBD MOMSN=8394992 2018-08-02T14:58:57.309Z,1533221937.309 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T14:58:57.309Z,1533221937.309 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T14:58:57.309Z,1533221937.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T14:59:00.477Z,1533221940.477 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T14:59:30.978Z,1533221970.978 [NAL9602](INFO): Powering down 2018-08-02T15:03:57.730Z,1533222237.730 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:03:57.730Z,1533222237.730 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:03:57.730Z,1533222237.730 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:03:57.730Z,1533222237.730 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:03:58.135Z,1533222238.135 [Default:CheckIn:D] Stopped 2018-08-02T15:03:58.135Z,1533222238.135 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:04:01.417Z,1533222241.417 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 463.370345 min 2018-08-02T15:04:01.417Z,1533222241.417 [Default:CheckIn:E] Stopped 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn] Stopped 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn](INFO): Running loop #44 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn] Running Loop=44 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:04:01.418Z,1533222241.418 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:04:01.854Z,1533222241.854 [NAL9602](INFO): Powering up 2018-08-02T15:04:13.054Z,1533222253.054 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:04:13.854Z,1533222253.854 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T15:04:13.854Z,1533222253.854 [NAL9602] Data Fault, FailCount= 2 2018-08-02T15:04:13.854Z,1533222253.854 [NAL9602](ERROR): Data Fault 2018-08-02T15:04:13.949Z,1533222253.949 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:04:14.254Z,1533222254.254 [NAL9602](INFO): Powering down 2018-08-02T15:04:15.115Z,1533222255.115 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:04:15.115Z,1533222255.115 [NAL9602] No Fault, FailCount= 2 2018-08-02T15:04:44.655Z,1533222284.655 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:04:55.457Z,1533222295.457 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:05:53.549Z,1533222353.549 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T15:05:53.549Z,1533222353.549 [BPC1] Communications Fault, FailCount= 5 2018-08-02T15:05:53.549Z,1533222353.549 [BPC1](ERROR): Communications Fault 2018-08-02T15:05:53.616Z,1533222353.616 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T15:05:54.821Z,1533222354.821 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T15:05:54.821Z,1533222354.821 [BPC1] No Fault, FailCount= 5 2018-08-02T15:09:01.600Z,1533222541.600 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:04:01.4Z 2018-08-02T15:09:01.600Z,1533222541.600 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T15:09:01.601Z,1533222541.601 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T15:09:07.340Z,1533222547.340 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0018.lzma 2018-08-02T15:09:08.139Z,1533222548.139 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0018.lzma.bak 2018-08-02T15:09:08.139Z,1533222548.139 [DataOverHttps](INFO): SBD MOMSN=8395004 2018-08-02T15:09:18.249Z,1533222558.249 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20180802T141040/Express0019.lzma 2018-08-02T15:09:19.035Z,1533222559.035 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0019.lzma.bak 2018-08-02T15:09:19.035Z,1533222559.035 [DataOverHttps](INFO): SBD MOMSN=8395006 2018-08-02T15:09:20.150Z,1533222560.150 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T15:09:20.150Z,1533222560.150 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T15:09:20.150Z,1533222560.150 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T15:09:58.253Z,1533222598.253 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T15:10:29.046Z,1533222629.046 [NAL9602](INFO): Powering down 2018-08-02T15:13:26.972Z,1533222806.972 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T15:13:26.972Z,1533222806.972 [BPC1] Communications Fault, FailCount= 6 2018-08-02T15:13:26.972Z,1533222806.972 [BPC1](ERROR): Communications Fault 2018-08-02T15:13:26.998Z,1533222806.998 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T15:13:28.197Z,1533222808.197 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T15:13:28.197Z,1533222808.197 [BPC1] No Fault, FailCount= 6 2018-08-02T15:14:20.588Z,1533222860.588 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:14:20.588Z,1533222860.588 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:14:20.589Z,1533222860.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:14:20.589Z,1533222860.589 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:14:20.987Z,1533222860.987 [Default:CheckIn:D] Stopped 2018-08-02T15:14:20.987Z,1533222860.987 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:14:21.395Z,1533222861.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 473.751204 min 2018-08-02T15:14:21.395Z,1533222861.395 [Default:CheckIn:E] Stopped 2018-08-02T15:14:21.395Z,1533222861.395 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn] Stopped 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn](INFO): Running loop #45 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn] Running Loop=45 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:14:21.396Z,1533222861.396 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:14:24.570Z,1533222864.570 [NAL9602](INFO): Powering up 2018-08-02T15:14:35.722Z,1533222875.722 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:15:07.722Z,1533222907.722 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T15:15:07.805Z,1533222907.805 [NAL9602](FAULT): received: +CSQ:0 OK 2018-08-02T15:15:07.805Z,1533222907.805 [NAL9602] Data Fault, FailCount= 3 2018-08-02T15:15:07.805Z,1533222907.805 [NAL9602](ERROR): Data Fault 2018-08-02T15:15:07.895Z,1533222907.895 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:15:08.122Z,1533222908.122 [NAL9602](INFO): Powering down 2018-08-02T15:15:08.976Z,1533222908.976 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:15:08.976Z,1533222908.976 [NAL9602] No Fault, FailCount= 3 2018-08-02T15:15:38.234Z,1533222938.234 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:15:49.434Z,1533222949.434 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:15:50.234Z,1533222950.234 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T15:15:50.234Z,1533222950.234 [NAL9602] Data Fault, FailCount= 4 2018-08-02T15:15:50.234Z,1533222950.234 [NAL9602](ERROR): Data Fault 2018-08-02T15:15:50.327Z,1533222950.327 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:15:50.634Z,1533222950.634 [NAL9602](INFO): Powering down 2018-08-02T15:15:51.490Z,1533222951.490 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:15:51.490Z,1533222951.490 [NAL9602] No Fault, FailCount= 4 2018-08-02T15:16:21.034Z,1533222981.034 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:16:32.086Z,1533222992.086 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:19:21.776Z,1533223161.776 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:14:21.4Z 2018-08-02T15:19:21.776Z,1533223161.776 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T15:19:21.776Z,1533223161.776 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T15:19:26.356Z,1533223166.356 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20180802T141040/Courier0021.lzma 2018-08-02T15:19:27.155Z,1533223167.155 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0021.lzma.bak 2018-08-02T15:19:27.155Z,1533223167.155 [DataOverHttps](INFO): SBD MOMSN=8395026 2018-08-02T15:19:37.267Z,1533223177.267 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20180802T141040/Express0022.lzma 2018-08-02T15:19:38.062Z,1533223178.062 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0022.lzma.bak 2018-08-02T15:19:38.062Z,1533223178.062 [DataOverHttps](INFO): SBD MOMSN=8395028 2018-08-02T15:19:39.135Z,1533223179.135 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T15:19:39.199Z,1533223179.199 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T15:19:39.199Z,1533223179.199 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T15:19:39.200Z,1533223179.200 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T15:20:09.330Z,1533223209.330 [NAL9602](INFO): Powering down 2018-08-02T15:24:39.426Z,1533223479.426 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:24:39.426Z,1533223479.426 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:24:39.427Z,1533223479.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:24:39.427Z,1533223479.427 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:24:39.827Z,1533223479.827 [Default:CheckIn:D] Stopped 2018-08-02T15:24:39.827Z,1533223479.827 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:24:40.233Z,1533223480.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 484.065234 min 2018-08-02T15:24:40.233Z,1533223480.233 [Default:CheckIn:E] Stopped 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn] Stopped 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn](INFO): Running loop #46 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn] Running Loop=46 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:24:40.234Z,1533223480.234 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:24:43.454Z,1533223483.454 [NAL9602](INFO): Powering up 2018-08-02T15:24:54.602Z,1533223494.602 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:25:26.602Z,1533223526.602 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T15:25:26.678Z,1533223526.678 [NAL9602](FAULT): received: +CSQ:0 OK 2018-08-02T15:25:26.678Z,1533223526.678 [NAL9602] Data Fault, FailCount= 5 2018-08-02T15:25:26.678Z,1533223526.678 [NAL9602](ERROR): Data Fault 2018-08-02T15:25:26.755Z,1533223526.755 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:25:26.755Z,1533223526.755 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-08-02T15:25:27.006Z,1533223527.006 [NAL9602](INFO): Powering down 2018-08-02T15:25:27.090Z,1533223527.090 [CBIT](INFO): Critical error at 20180802T152526 2018-08-02T15:27:26.824Z,1533223646.824 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:27:26.824Z,1533223646.824 [NAL9602] No Fault, FailCount= 5 2018-08-02T15:27:27.130Z,1533223647.130 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:27:38.322Z,1533223658.322 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:27:39.130Z,1533223659.130 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T15:27:39.130Z,1533223659.130 [NAL9602] Data Fault, FailCount= 1 2018-08-02T15:27:39.130Z,1533223659.130 [NAL9602](ERROR): Data Fault 2018-08-02T15:27:39.213Z,1533223659.213 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:27:39.522Z,1533223659.522 [NAL9602](INFO): Powering down 2018-08-02T15:27:40.388Z,1533223660.388 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:27:40.388Z,1533223660.388 [NAL9602] No Fault, FailCount= 1 2018-08-02T15:28:09.830Z,1533223689.830 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:28:21.030Z,1533223701.030 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:29:40.308Z,1533223780.308 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:24:40.2Z 2018-08-02T15:29:40.308Z,1533223780.308 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T15:29:40.317Z,1533223780.317 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T15:29:44.358Z,1533223784.358 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180802T141040/Courier0024.lzma 2018-08-02T15:29:45.151Z,1533223785.151 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0024.lzma.bak 2018-08-02T15:29:45.151Z,1533223785.151 [DataOverHttps](INFO): SBD MOMSN=8395042 2018-08-02T15:29:54.398Z,1533223794.398 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20180802T141040/Express0025.lzma 2018-08-02T15:29:55.155Z,1533223795.155 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0025.lzma.bak 2018-08-02T15:29:55.155Z,1533223795.155 [DataOverHttps](INFO): SBD MOMSN=8395045 2018-08-02T15:29:56.305Z,1533223796.305 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T15:29:56.305Z,1533223796.305 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T15:29:56.305Z,1533223796.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T15:29:59.540Z,1533223799.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T15:30:29.678Z,1533223829.678 [NAL9602](INFO): Powering down 2018-08-02T15:34:56.753Z,1533224096.753 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:34:56.754Z,1533224096.754 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:34:56.754Z,1533224096.754 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:34:56.754Z,1533224096.754 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:34:57.160Z,1533224097.160 [Default:CheckIn:D] Stopped 2018-08-02T15:34:57.160Z,1533224097.160 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:35:00.352Z,1533224100.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 494.354102 min 2018-08-02T15:35:00.352Z,1533224100.352 [Default:CheckIn:E] Stopped 2018-08-02T15:35:00.352Z,1533224100.352 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:35:00.352Z,1533224100.352 [Default:CheckIn] Stopped 2018-08-02T15:35:00.352Z,1533224100.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:35:00.353Z,1533224100.353 [Default:CheckIn](INFO): Running loop #47 2018-08-02T15:35:00.353Z,1533224100.353 [Default:CheckIn] Running Loop=47 2018-08-02T15:35:00.353Z,1533224100.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:35:00.353Z,1533224100.353 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:35:00.786Z,1533224100.786 [NAL9602](INFO): Powering up 2018-08-02T15:35:11.978Z,1533224111.978 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:35:43.978Z,1533224143.978 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T15:35:44.061Z,1533224144.061 [NAL9602](FAULT): received: +CSQ:0 OK 2018-08-02T15:35:44.061Z,1533224144.061 [NAL9602] Data Fault, FailCount= 2 2018-08-02T15:35:44.061Z,1533224144.061 [NAL9602](ERROR): Data Fault 2018-08-02T15:35:44.153Z,1533224144.153 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:35:44.378Z,1533224144.378 [NAL9602](INFO): Powering down 2018-08-02T15:35:45.238Z,1533224145.238 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:35:45.238Z,1533224145.238 [NAL9602] No Fault, FailCount= 2 2018-08-02T15:36:14.754Z,1533224174.754 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:36:25.958Z,1533224185.958 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:40:00.979Z,1533224400.979 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:35:00.4Z 2018-08-02T15:40:00.979Z,1533224400.979 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T15:40:00.979Z,1533224400.979 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T15:40:05.975Z,1533224405.975 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0027.lzma 2018-08-02T15:40:06.765Z,1533224406.765 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0027.lzma.bak 2018-08-02T15:40:06.765Z,1533224406.765 [DataOverHttps](INFO): SBD MOMSN=8395063 2018-08-02T15:40:16.792Z,1533224416.792 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T15:40:16.901Z,1533224416.901 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20180802T141040/Express0028.lzma 2018-08-02T15:40:17.675Z,1533224417.675 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0028.lzma.bak 2018-08-02T15:40:17.675Z,1533224417.675 [DataOverHttps](INFO): SBD MOMSN=8395065 2018-08-02T15:40:18.791Z,1533224418.791 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T15:40:18.791Z,1533224418.791 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T15:40:18.791Z,1533224418.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T15:40:47.261Z,1533224447.261 [NAL9602](INFO): Powering down 2018-08-02T15:45:19.288Z,1533224719.288 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:45:19.289Z,1533224719.289 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:45:19.289Z,1533224719.289 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:45:19.289Z,1533224719.289 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:45:19.692Z,1533224719.692 [Default:CheckIn:D] Stopped 2018-08-02T15:45:19.692Z,1533224719.692 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:45:22.830Z,1533224722.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 504.729655 min 2018-08-02T15:45:22.830Z,1533224722.830 [Default:CheckIn:E] Stopped 2018-08-02T15:45:22.830Z,1533224722.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:45:22.830Z,1533224722.830 [Default:CheckIn] Stopped 2018-08-02T15:45:22.830Z,1533224722.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:45:22.831Z,1533224722.831 [Default:CheckIn](INFO): Running loop #48 2018-08-02T15:45:22.831Z,1533224722.831 [Default:CheckIn] Running Loop=48 2018-08-02T15:45:22.831Z,1533224722.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:45:22.831Z,1533224722.831 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:45:23.258Z,1533224723.258 [NAL9602](INFO): Powering up 2018-08-02T15:45:34.458Z,1533224734.458 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:45:35.258Z,1533224735.258 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T15:45:35.258Z,1533224735.258 [NAL9602] Data Fault, FailCount= 3 2018-08-02T15:45:35.258Z,1533224735.258 [NAL9602](ERROR): Data Fault 2018-08-02T15:45:35.345Z,1533224735.345 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T15:45:35.662Z,1533224735.662 [NAL9602](INFO): Powering down 2018-08-02T15:45:36.522Z,1533224736.522 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T15:45:36.522Z,1533224736.522 [NAL9602] No Fault, FailCount= 3 2018-08-02T15:46:06.058Z,1533224766.058 [NAL9602](INFO): Powering up NAL9602 2018-08-02T15:46:17.262Z,1533224777.262 [NAL9602](INFO): NAL9602 initialized 2018-08-02T15:50:23.073Z,1533225023.073 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:45:22.8Z 2018-08-02T15:50:23.073Z,1533225023.073 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T15:50:23.073Z,1533225023.073 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T15:50:27.995Z,1533225027.995 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0030.lzma 2018-08-02T15:50:28.787Z,1533225028.787 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0030.lzma.bak 2018-08-02T15:50:28.787Z,1533225028.787 [DataOverHttps](INFO): SBD MOMSN=8395080 2018-08-02T15:50:38.156Z,1533225038.156 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20180802T141040/Express0031.lzma 2018-08-02T15:50:38.955Z,1533225038.955 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0031.lzma.bak 2018-08-02T15:50:38.956Z,1533225038.956 [DataOverHttps](INFO): SBD MOMSN=8395082 2018-08-02T15:50:40.166Z,1533225040.166 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T15:50:40.166Z,1533225040.166 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T15:50:40.166Z,1533225040.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T15:51:20.650Z,1533225080.650 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T15:51:51.068Z,1533225111.068 [NAL9602](INFO): Powering down 2018-08-02T15:52:36.366Z,1533225156.366 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.445506 seconds old. 2018-08-02T15:52:36.718Z,1533225156.718 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.798308 seconds old. 2018-08-02T15:52:37.120Z,1533225157.120 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.200307 seconds old. 2018-08-02T15:55:40.346Z,1533225340.346 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T15:55:40.347Z,1533225340.347 [Default:CheckIn:C.Wait] Stopped 2018-08-02T15:55:40.347Z,1533225340.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T15:55:40.347Z,1533225340.347 [Default:CheckIn:D] Running Loop=1 2018-08-02T15:55:40.748Z,1533225340.748 [Default:CheckIn:D] Stopped 2018-08-02T15:55:40.749Z,1533225340.749 [Default:CheckIn:E] Running Loop=1 2018-08-02T15:55:41.155Z,1533225341.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 515.080566 min 2018-08-02T15:55:41.155Z,1533225341.155 [Default:CheckIn:E] Stopped 2018-08-02T15:55:41.155Z,1533225341.155 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T15:55:41.155Z,1533225341.155 [Default:CheckIn] Stopped 2018-08-02T15:55:41.156Z,1533225341.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T15:55:41.156Z,1533225341.156 [Default:CheckIn](INFO): Running loop #49 2018-08-02T15:55:41.156Z,1533225341.156 [Default:CheckIn] Running Loop=49 2018-08-02T15:55:41.156Z,1533225341.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T15:55:41.156Z,1533225341.156 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T15:55:44.390Z,1533225344.390 [NAL9602](INFO): Powering up 2018-08-02T15:55:55.526Z,1533225355.526 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:00:41.328Z,1533225641.328 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T15:55:41.2Z 2018-08-02T16:00:41.328Z,1533225641.328 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:00:41.329Z,1533225641.329 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:00:45.138Z,1533225645.138 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0033.lzma 2018-08-02T16:00:45.639Z,1533225645.639 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0033.lzma.bak 2018-08-02T16:00:45.640Z,1533225645.640 [DataOverHttps](INFO): SBD MOMSN=8395100 2018-08-02T16:00:55.775Z,1533225655.775 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20180802T141040/Express0034.lzma 2018-08-02T16:00:56.555Z,1533225656.555 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0034.lzma.bak 2018-08-02T16:00:56.555Z,1533225656.555 [DataOverHttps](INFO): SBD MOMSN=8395102 2018-08-02T16:00:57.688Z,1533225657.688 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:00:57.688Z,1533225657.688 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:00:57.688Z,1533225657.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:01:00.922Z,1533225660.922 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:01:31.422Z,1533225691.422 [NAL9602](INFO): Powering down 2018-08-02T16:05:58.120Z,1533225958.120 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:05:58.121Z,1533225958.121 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:05:58.121Z,1533225958.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:05:58.122Z,1533225958.122 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:05:58.520Z,1533225958.520 [Default:CheckIn:D] Stopped 2018-08-02T16:05:58.520Z,1533225958.520 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:06:01.666Z,1533225961.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 525.376790 min 2018-08-02T16:06:01.666Z,1533225961.666 [Default:CheckIn:E] Stopped 2018-08-02T16:06:01.666Z,1533225961.666 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:06:01.666Z,1533225961.666 [Default:CheckIn] Stopped 2018-08-02T16:06:01.667Z,1533225961.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:06:01.667Z,1533225961.667 [Default:CheckIn](INFO): Running loop #50 2018-08-02T16:06:01.667Z,1533225961.667 [Default:CheckIn] Running Loop=50 2018-08-02T16:06:01.667Z,1533225961.667 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:06:01.667Z,1533225961.667 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:06:02.090Z,1533225962.090 [NAL9602](INFO): Powering up 2018-08-02T16:06:12.894Z,1533225972.894 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:06:13.690Z,1533225973.690 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T16:06:13.690Z,1533225973.690 [NAL9602] Data Fault, FailCount= 4 2018-08-02T16:06:13.690Z,1533225973.690 [NAL9602](ERROR): Data Fault 2018-08-02T16:06:13.780Z,1533225973.780 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T16:06:14.090Z,1533225974.090 [NAL9602](INFO): Powering down 2018-08-02T16:06:14.946Z,1533225974.946 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T16:06:14.946Z,1533225974.946 [NAL9602] No Fault, FailCount= 4 2018-08-02T16:06:44.494Z,1533226004.494 [NAL9602](INFO): Powering up NAL9602 2018-08-02T16:06:55.690Z,1533226015.690 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:11:03.020Z,1533226263.020 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:06:01.7Z 2018-08-02T16:11:03.020Z,1533226263.020 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:11:03.021Z,1533226263.021 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:11:08.804Z,1533226268.804 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0036.lzma 2018-08-02T16:11:09.599Z,1533226269.599 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0036.lzma.bak 2018-08-02T16:11:09.599Z,1533226269.599 [DataOverHttps](INFO): SBD MOMSN=8395116 2018-08-02T16:11:20.643Z,1533226280.643 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180802T141040/Express0037.lzma 2018-08-02T16:11:21.427Z,1533226281.427 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0037.lzma.bak 2018-08-02T16:11:21.428Z,1533226281.428 [DataOverHttps](INFO): SBD MOMSN=8395118 2018-08-02T16:11:22.560Z,1533226282.560 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:11:22.560Z,1533226282.560 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:11:22.561Z,1533226282.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:12:01.890Z,1533226321.890 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:12:32.305Z,1533226352.305 [NAL9602](INFO): Powering down 2018-08-02T16:16:22.935Z,1533226582.935 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:16:22.935Z,1533226582.935 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:16:22.935Z,1533226582.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:16:22.936Z,1533226582.936 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:16:23.336Z,1533226583.336 [Default:CheckIn:D] Stopped 2018-08-02T16:16:23.336Z,1533226583.336 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:16:23.736Z,1533226583.736 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 535.790365 min 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn:E] Stopped 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn] Stopped 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn](INFO): Running loop #51 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn] Running Loop=51 2018-08-02T16:16:23.737Z,1533226583.737 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:16:23.738Z,1533226583.738 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:16:27.217Z,1533226587.217 [NAL9602](INFO): Powering up 2018-08-02T16:16:38.123Z,1533226598.123 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:17:10.122Z,1533226630.122 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T16:17:10.205Z,1533226630.205 [NAL9602](FAULT): received: +CSQ:0 OK 2018-08-02T16:17:10.205Z,1533226630.205 [NAL9602] Data Fault, FailCount= 5 2018-08-02T16:17:10.205Z,1533226630.205 [NAL9602](ERROR): Data Fault 2018-08-02T16:17:10.268Z,1533226630.268 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T16:17:10.268Z,1533226630.268 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-08-02T16:17:10.526Z,1533226630.526 [NAL9602](INFO): Powering down 2018-08-02T16:17:10.601Z,1533226630.601 [CBIT](INFO): Critical error at 20180802T161710 2018-08-02T16:19:10.289Z,1533226750.289 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T16:19:10.289Z,1533226750.289 [NAL9602] No Fault, FailCount= 5 2018-08-02T16:19:10.622Z,1533226750.622 [NAL9602](INFO): Powering up NAL9602 2018-08-02T16:19:21.822Z,1533226761.822 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:19:22.622Z,1533226762.622 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T16:19:22.622Z,1533226762.622 [NAL9602] Data Fault, FailCount= 1 2018-08-02T16:19:22.622Z,1533226762.622 [NAL9602](ERROR): Data Fault 2018-08-02T16:19:22.695Z,1533226762.695 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T16:19:23.025Z,1533226763.025 [NAL9602](INFO): Powering down 2018-08-02T16:19:26.611Z,1533226766.611 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T16:19:26.611Z,1533226766.611 [NAL9602] No Fault, FailCount= 1 2018-08-02T16:19:53.426Z,1533226793.426 [NAL9602](INFO): Powering up NAL9602 2018-08-02T16:20:04.626Z,1533226804.626 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:21:26.713Z,1533226886.713 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:16:23.7Z 2018-08-02T16:21:26.713Z,1533226886.713 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:21:26.713Z,1533226886.713 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:21:31.642Z,1533226891.642 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180802T141040/Courier0039.lzma 2018-08-02T16:21:32.439Z,1533226892.439 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0039.lzma.bak 2018-08-02T16:21:32.439Z,1533226892.439 [DataOverHttps](INFO): SBD MOMSN=8395136 2018-08-02T16:21:41.730Z,1533226901.730 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T16:21:41.730Z,1533226901.730 [BPC1] Communications Fault, FailCount= 1 2018-08-02T16:21:41.730Z,1533226901.730 [BPC1](ERROR): Communications Fault 2018-08-02T16:21:41.788Z,1533226901.788 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T16:21:41.812Z,1533226901.812 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20180802T141040/Express0040.lzma 2018-08-02T16:21:41.820Z,1533226901.820 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:21:42.603Z,1533226902.603 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0040.lzma.bak 2018-08-02T16:21:42.604Z,1533226902.604 [DataOverHttps](INFO): SBD MOMSN=8395139 2018-08-02T16:21:42.696Z,1533226902.696 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T16:21:42.696Z,1533226902.696 [BPC1] No Fault, FailCount= 1 2018-08-02T16:21:43.792Z,1533226903.792 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:21:43.793Z,1533226903.793 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:21:43.793Z,1533226903.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:22:12.412Z,1533226932.412 [NAL9602](INFO): Powering down 2018-08-02T16:26:44.016Z,1533227204.016 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:26:44.016Z,1533227204.016 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:26:44.016Z,1533227204.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:26:44.017Z,1533227204.017 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:26:44.407Z,1533227204.407 [Default:CheckIn:D] Stopped 2018-08-02T16:26:44.407Z,1533227204.407 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:26:44.803Z,1533227204.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 546.141536 min 2018-08-02T16:26:44.803Z,1533227204.803 [Default:CheckIn:E] Stopped 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn] Stopped 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn](INFO): Running loop #52 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn] Running Loop=52 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:26:44.804Z,1533227204.804 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:26:48.070Z,1533227208.070 [NAL9602](INFO): Powering up 2018-08-02T16:26:59.234Z,1533227219.234 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:27:44.079Z,1533227264.079 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T16:27:44.079Z,1533227264.079 [BPC1] Communications Fault, FailCount= 2 2018-08-02T16:27:44.079Z,1533227264.079 [BPC1](ERROR): Communications Fault 2018-08-02T16:27:44.105Z,1533227264.105 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T16:27:48.011Z,1533227268.011 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T16:27:48.011Z,1533227268.011 [BPC1] No Fault, FailCount= 2 2018-08-02T16:31:45.174Z,1533227505.174 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:26:44.8Z 2018-08-02T16:31:45.175Z,1533227505.175 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:31:45.175Z,1533227505.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:31:49.111Z,1533227509.111 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0042.lzma 2018-08-02T16:31:49.895Z,1533227509.895 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0042.lzma.bak 2018-08-02T16:31:49.895Z,1533227509.895 [DataOverHttps](INFO): SBD MOMSN=8395158 2018-08-02T16:31:59.312Z,1533227519.312 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20180802T141040/Express0043.lzma 2018-08-02T16:32:00.117Z,1533227520.117 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0043.lzma.bak 2018-08-02T16:32:00.117Z,1533227520.117 [DataOverHttps](INFO): SBD MOMSN=8395160 2018-08-02T16:32:01.235Z,1533227521.235 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:32:01.235Z,1533227521.235 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:32:01.235Z,1533227521.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:32:04.376Z,1533227524.376 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:32:34.482Z,1533227554.482 [NAL9602](INFO): Powering down 2018-08-02T16:33:48.629Z,1533227628.629 [BPC1](ERROR): Failed to receive data from both battery packs 2018-08-02T16:33:48.630Z,1533227628.630 [BPC1] Communications Fault, FailCount= 3 2018-08-02T16:33:48.630Z,1533227628.630 [BPC1](ERROR): Communications Fault 2018-08-02T16:33:48.660Z,1533227628.660 [CBIT](ERROR): Communications Fault in component: BPC1 2018-08-02T16:33:49.859Z,1533227629.859 [CBIT](INFO): Clearing failed state for component BPC1 2018-08-02T16:33:49.859Z,1533227629.859 [BPC1] No Fault, FailCount= 3 2018-08-02T16:37:01.610Z,1533227821.610 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:37:01.610Z,1533227821.610 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:37:01.610Z,1533227821.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:37:01.610Z,1533227821.610 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:37:02.056Z,1533227822.056 [Default:CheckIn:D] Stopped 2018-08-02T16:37:02.056Z,1533227822.056 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:37:05.212Z,1533227825.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 556.435677 min 2018-08-02T16:37:05.212Z,1533227825.212 [Default:CheckIn:E] Stopped 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn] Stopped 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn](INFO): Running loop #53 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn] Running Loop=53 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:37:05.213Z,1533227825.213 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:37:05.670Z,1533227825.670 [NAL9602](INFO): Powering up 2018-08-02T16:37:16.866Z,1533227836.866 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:37:17.666Z,1533227837.666 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T16:37:17.666Z,1533227837.666 [NAL9602] Data Fault, FailCount= 2 2018-08-02T16:37:17.666Z,1533227837.666 [NAL9602](ERROR): Data Fault 2018-08-02T16:37:17.770Z,1533227837.770 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T16:37:18.070Z,1533227838.070 [NAL9602](INFO): Powering down 2018-08-02T16:37:18.930Z,1533227838.930 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T16:37:18.930Z,1533227838.930 [NAL9602] No Fault, FailCount= 2 2018-08-02T16:37:48.467Z,1533227868.467 [NAL9602](INFO): Powering up NAL9602 2018-08-02T16:37:59.666Z,1533227879.666 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:38:54.483Z,1533227934.483 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:38:54.484Z,1533227934.484 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:40:02.107Z,1533228002.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:40:02.107Z,1533228002.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:40:28.591Z,1533228028.591 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:40:28.591Z,1533228028.591 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:40:36.991Z,1533228036.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:40:36.991Z,1533228036.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:40:45.792Z,1533228045.792 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:40:45.792Z,1533228045.792 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:40:53.787Z,1533228053.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:40:53.787Z,1533228053.787 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:42:05.458Z,1533228125.458 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:37:05.2Z 2018-08-02T16:42:05.458Z,1533228125.458 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:42:05.458Z,1533228125.458 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:42:06.230Z,1533228126.230 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180802T141040/Courier0045.lzma 2018-08-02T16:42:07.127Z,1533228127.127 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0045.lzma.bak 2018-08-02T16:42:07.128Z,1533228127.128 [DataOverHttps](INFO): SBD MOMSN=8395180 2018-08-02T16:42:21.578Z,1533228141.578 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180802T141040/Express0046.lzma 2018-08-02T16:42:22.375Z,1533228142.375 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0046.lzma.bak 2018-08-02T16:42:22.375Z,1533228142.375 [DataOverHttps](INFO): SBD MOMSN=8395182 2018-08-02T16:42:23.493Z,1533228143.493 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:42:23.493Z,1533228143.493 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:42:23.493Z,1533228143.493 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:43:05.674Z,1533228185.674 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:43:36.121Z,1533228216.121 [NAL9602](INFO): Powering down 2018-08-02T16:43:59.003Z,1533228239.003 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2018-08-02T16:43:59.387Z,1533228239.387 [Reporter](INFO): platform_buoyancy_position 956.608914 cc 2018-08-02T16:44:00.544Z,1533228240.544 [CommandLine](IMPORTANT): got command failComponent 2018-08-02T16:44:00.544Z,1533228240.544 [CommandLine](IMPORTANT): Failed components: 2018-08-02T16:44:00.544Z,1533228240.544 [CommandLine](IMPORTANT): No failed Components. 2018-08-02T16:44:13.359Z,1533228253.359 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2018-08-02T16:44:13.360Z,1533228253.360 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2018-08-02T16:44:13.426Z,1533228253.426 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T16:44:18.249Z,1533228258.249 [Reporter](INFO): platform_buoyancy_position 956.474629 cc 2018-08-02T16:44:19.016Z,1533228259.016 [Reporter](INFO): platform_buoyancy_position 954.460062 cc 2018-08-02T16:44:19.428Z,1533228259.428 [Reporter](INFO): platform_buoyancy_position 945.864886 cc 2018-08-02T16:44:19.812Z,1533228259.812 [Reporter](INFO): platform_buoyancy_position 943.581806 cc 2018-08-02T16:44:20.208Z,1533228260.208 [Reporter](INFO): platform_buoyancy_position 941.433012 cc 2018-08-02T16:44:20.629Z,1533228260.629 [Reporter](INFO): platform_buoyancy_position 937.269651 cc 2018-08-02T16:44:21.028Z,1533228261.028 [Reporter](INFO): platform_buoyancy_position 932.569092 cc 2018-08-02T16:44:21.412Z,1533228261.412 [Reporter](INFO): platform_buoyancy_position 927.868648 cc 2018-08-02T16:44:21.796Z,1533228261.796 [Reporter](INFO): platform_buoyancy_position 922.093750 cc 2018-08-02T16:44:22.201Z,1533228262.201 [Reporter](INFO): platform_buoyancy_position 917.796162 cc 2018-08-02T16:44:22.608Z,1533228262.608 [Reporter](INFO): platform_buoyancy_position 912.558462 cc 2018-08-02T16:44:23.008Z,1533228263.008 [Reporter](INFO): platform_buoyancy_position 907.052134 cc 2018-08-02T16:44:23.409Z,1533228263.409 [Reporter](INFO): platform_buoyancy_position 902.620261 cc 2018-08-02T16:44:23.824Z,1533228263.824 [Reporter](INFO): platform_buoyancy_position 896.442449 cc 2018-08-02T16:44:24.216Z,1533228264.216 [Reporter](INFO): platform_buoyancy_position 891.741947 cc 2018-08-02T16:44:27.324Z,1533228267.324 [Reporter](INFO): platform_buoyancy_position 851.183373 cc 2018-08-02T16:44:27.469Z,1533228267.469 [Reporter](INFO): platform_buoyancy_position 849.303149 cc 2018-08-02T16:44:27.848Z,1533228267.848 [Reporter](INFO): platform_buoyancy_position 844.871218 cc 2018-08-02T16:44:28.248Z,1533228268.248 [Reporter](INFO): platform_buoyancy_position 838.424894 cc 2018-08-02T16:44:28.644Z,1533228268.644 [Reporter](INFO): platform_buoyancy_position 834.127248 cc 2018-08-02T16:44:29.020Z,1533228269.020 [Reporter](INFO): platform_buoyancy_position 829.292403 cc 2018-08-02T16:44:29.428Z,1533228269.428 [Reporter](INFO): platform_buoyancy_position 823.383220 cc 2018-08-02T16:44:29.820Z,1533228269.820 [Reporter](INFO): platform_buoyancy_position 818.548433 cc 2018-08-02T16:44:30.236Z,1533228270.236 [Reporter](INFO): platform_buoyancy_position 812.639249 cc 2018-08-02T16:44:30.632Z,1533228270.632 [Reporter](INFO): platform_buoyancy_position 807.938748 cc 2018-08-02T16:44:31.052Z,1533228271.052 [Reporter](INFO): platform_buoyancy_position 801.895279 cc 2018-08-02T16:44:31.440Z,1533228271.440 [Reporter](INFO): platform_buoyancy_position 797.597633 cc 2018-08-02T16:44:31.840Z,1533228271.840 [Reporter](INFO): platform_buoyancy_position 791.016966 cc 2018-08-02T16:44:32.240Z,1533228272.240 [Reporter](INFO): platform_buoyancy_position 786.853663 cc 2018-08-02T16:44:32.641Z,1533228272.641 [Reporter](INFO): platform_buoyancy_position 780.407223 cc 2018-08-02T16:44:33.060Z,1533228273.060 [Reporter](INFO): platform_buoyancy_position 775.975350 cc 2018-08-02T16:44:33.448Z,1533228273.448 [Reporter](INFO): platform_buoyancy_position 769.663195 cc 2018-08-02T16:44:33.848Z,1533228273.848 [Reporter](INFO): platform_buoyancy_position 765.231322 cc 2018-08-02T16:44:34.256Z,1533228274.256 [Reporter](INFO): platform_buoyancy_position 760.933734 cc 2018-08-02T16:44:34.648Z,1533228274.648 [Reporter](INFO): platform_buoyancy_position 754.487352 cc 2018-08-02T16:44:35.056Z,1533228275.056 [Reporter](INFO): platform_buoyancy_position 750.189763 cc 2018-08-02T16:44:35.420Z,1533228275.420 [Reporter](INFO): platform_buoyancy_position 745.623547 cc 2018-08-02T16:44:35.824Z,1533228275.824 [Reporter](INFO): platform_buoyancy_position 739.445735 cc 2018-08-02T16:44:36.237Z,1533228276.237 [Reporter](INFO): platform_buoyancy_position 735.013862 cc 2018-08-02T16:44:36.620Z,1533228276.620 [Reporter](INFO): platform_buoyancy_position 729.104620 cc 2018-08-02T16:44:37.025Z,1533228277.025 [Reporter](INFO): platform_buoyancy_position 724.404119 cc 2018-08-02T16:44:37.444Z,1533228277.444 [Reporter](INFO): platform_buoyancy_position 717.957737 cc 2018-08-02T16:44:37.836Z,1533228277.836 [Reporter](INFO): platform_buoyancy_position 713.660149 cc 2018-08-02T16:44:38.248Z,1533228278.248 [Reporter](INFO): platform_buoyancy_position 707.482337 cc 2018-08-02T16:44:38.648Z,1533228278.648 [Reporter](INFO): platform_buoyancy_position 703.050406 cc 2018-08-02T16:44:39.060Z,1533228279.060 [Reporter](INFO): platform_buoyancy_position 697.409851 cc 2018-08-02T16:44:39.432Z,1533228279.432 [Reporter](INFO): platform_buoyancy_position 692.172092 cc 2018-08-02T16:44:39.816Z,1533228279.816 [Reporter](INFO): platform_buoyancy_position 687.874504 cc 2018-08-02T16:44:40.221Z,1533228280.221 [Reporter](INFO): platform_buoyancy_position 681.831036 cc 2018-08-02T16:44:40.621Z,1533228280.621 [Reporter](INFO): platform_buoyancy_position 677.130534 cc 2018-08-02T16:44:41.028Z,1533228281.028 [Reporter](INFO): platform_buoyancy_position 671.355636 cc 2018-08-02T16:44:41.420Z,1533228281.420 [Reporter](INFO): platform_buoyancy_position 666.386564 cc 2018-08-02T16:44:41.832Z,1533228281.832 [Reporter](INFO): platform_buoyancy_position 660.074409 cc 2018-08-02T16:44:42.249Z,1533228282.249 [Reporter](INFO): platform_buoyancy_position 655.642536 cc 2018-08-02T16:44:42.644Z,1533228282.644 [Reporter](INFO): platform_buoyancy_position 650.001981 cc 2018-08-02T16:44:43.044Z,1533228283.044 [Reporter](INFO): platform_buoyancy_position 644.898508 cc 2018-08-02T16:44:43.437Z,1533228283.437 [Reporter](INFO): platform_buoyancy_position 640.600978 cc 2018-08-02T16:44:43.840Z,1533228283.840 [Reporter](INFO): platform_buoyancy_position 634.288823 cc 2018-08-02T16:44:44.229Z,1533228284.229 [Reporter](INFO): platform_buoyancy_position 629.991235 cc 2018-08-02T16:44:44.660Z,1533228284.660 [Reporter](INFO): platform_buoyancy_position 623.410509 cc 2018-08-02T16:44:45.021Z,1533228285.021 [Reporter](INFO): platform_buoyancy_position 619.112921 cc 2018-08-02T16:44:45.436Z,1533228285.436 [Reporter](INFO): platform_buoyancy_position 613.203738 cc 2018-08-02T16:44:45.824Z,1533228285.824 [Reporter](INFO): platform_buoyancy_position 608.368893 cc 2018-08-02T16:44:46.224Z,1533228286.224 [Reporter](INFO): platform_buoyancy_position 604.071363 cc 2018-08-02T16:44:46.628Z,1533228286.628 [Reporter](INFO): platform_buoyancy_position 597.759208 cc 2018-08-02T16:44:47.044Z,1533228287.044 [Reporter](INFO): platform_buoyancy_position 593.193050 cc 2018-08-02T16:44:47.440Z,1533228287.440 [Reporter](INFO): platform_buoyancy_position 589.029747 cc 2018-08-02T16:44:47.832Z,1533228287.832 [Reporter](INFO): platform_buoyancy_position 582.717650 cc 2018-08-02T16:44:48.254Z,1533228288.254 [Reporter](INFO): platform_buoyancy_position 577.479892 cc 2018-08-02T16:44:48.645Z,1533228288.645 [Reporter](INFO): platform_buoyancy_position 571.839279 cc 2018-08-02T16:44:49.032Z,1533228289.032 [Reporter](INFO): platform_buoyancy_position 566.064380 cc 2018-08-02T16:44:49.460Z,1533228289.460 [Reporter](INFO): platform_buoyancy_position 561.095309 cc 2018-08-02T16:44:49.816Z,1533228289.816 [Reporter](INFO): platform_buoyancy_position 556.797720 cc 2018-08-02T16:44:50.246Z,1533228290.246 [Reporter](INFO): platform_buoyancy_position 550.351338 cc 2018-08-02T16:44:50.624Z,1533228290.624 [Reporter](INFO): platform_buoyancy_position 545.919407 cc 2018-08-02T16:44:51.032Z,1533228291.032 [Reporter](INFO): platform_buoyancy_position 539.741653 cc 2018-08-02T16:44:51.429Z,1533228291.429 [Reporter](INFO): platform_buoyancy_position 535.444007 cc 2018-08-02T16:44:51.836Z,1533228291.836 [Reporter](INFO): platform_buoyancy_position 529.266254 cc 2018-08-02T16:44:52.229Z,1533228292.229 [Reporter](INFO): platform_buoyancy_position 524.565752 cc 2018-08-02T16:44:52.638Z,1533228292.638 [Reporter](INFO): platform_buoyancy_position 518.387940 cc 2018-08-02T16:44:53.040Z,1533228293.040 [Reporter](INFO): platform_buoyancy_position 513.821724 cc 2018-08-02T16:44:53.440Z,1533228293.440 [Reporter](INFO): platform_buoyancy_position 507.509685 cc 2018-08-02T16:44:53.832Z,1533228293.832 [Reporter](INFO): platform_buoyancy_position 503.212039 cc 2018-08-02T16:44:54.244Z,1533228294.244 [Reporter](INFO): platform_buoyancy_position 498.914451 cc 2018-08-02T16:44:54.617Z,1533228294.617 [Reporter](INFO): platform_buoyancy_position 494.079606 cc 2018-08-02T16:44:55.033Z,1533228295.033 [Reporter](INFO): platform_buoyancy_position 488.170452 cc 2018-08-02T16:44:55.440Z,1533228295.440 [Reporter](INFO): platform_buoyancy_position 483.604206 cc 2018-08-02T16:44:55.836Z,1533228295.836 [Reporter](INFO): platform_buoyancy_position 477.292138 cc 2018-08-02T16:44:56.232Z,1533228296.232 [Reporter](INFO): platform_buoyancy_position 473.128865 cc 2018-08-02T16:44:56.636Z,1533228296.636 [Reporter](INFO): platform_buoyancy_position 466.548139 cc 2018-08-02T16:44:57.028Z,1533228297.028 [Reporter](INFO): platform_buoyancy_position 462.384865 cc 2018-08-02T16:44:57.421Z,1533228297.421 [Reporter](INFO): platform_buoyancy_position 457.012851 cc 2018-08-02T16:44:57.828Z,1533228297.828 [Reporter](INFO): platform_buoyancy_position 451.640837 cc 2018-08-02T16:44:58.228Z,1533228298.228 [Reporter](INFO): platform_buoyancy_position 447.343220 cc 2018-08-02T16:44:58.642Z,1533228298.642 [Reporter](INFO): platform_buoyancy_position 441.702665 cc 2018-08-02T16:44:59.052Z,1533228299.052 [Reporter](INFO): platform_buoyancy_position 436.464936 cc 2018-08-02T16:44:59.444Z,1533228299.444 [Reporter](INFO): platform_buoyancy_position 430.152868 cc 2018-08-02T16:44:59.832Z,1533228299.832 [Reporter](INFO): platform_buoyancy_position 425.720908 cc 2018-08-02T16:45:00.256Z,1533228300.256 [Reporter](INFO): platform_buoyancy_position 419.543125 cc 2018-08-02T16:45:00.616Z,1533228300.616 [Reporter](INFO): platform_buoyancy_position 415.111223 cc 2018-08-02T16:45:01.020Z,1533228301.020 [Reporter](INFO): platform_buoyancy_position 409.202039 cc 2018-08-02T16:45:01.424Z,1533228301.424 [Reporter](INFO): platform_buoyancy_position 404.232938 cc 2018-08-02T16:45:01.832Z,1533228301.832 [Reporter](INFO): platform_buoyancy_position 397.920841 cc 2018-08-02T16:45:02.220Z,1533228302.220 [Reporter](INFO): platform_buoyancy_position 393.354654 cc 2018-08-02T16:45:02.642Z,1533228302.642 [Reporter](INFO): platform_buoyancy_position 386.505330 cc 2018-08-02T16:45:03.044Z,1533228303.044 [Reporter](INFO): platform_buoyancy_position 380.864745 cc 2018-08-02T16:45:03.444Z,1533228303.444 [Reporter](INFO): platform_buoyancy_position 376.298558 cc 2018-08-02T16:45:03.848Z,1533228303.848 [Reporter](INFO): platform_buoyancy_position 372.135255 cc 2018-08-02T16:45:04.241Z,1533228304.241 [Reporter](INFO): platform_buoyancy_position 367.434754 cc 2018-08-02T16:45:04.644Z,1533228304.644 [Reporter](INFO): platform_buoyancy_position 361.256942 cc 2018-08-02T16:45:05.024Z,1533228305.024 [Reporter](INFO): platform_buoyancy_position 356.959354 cc 2018-08-02T16:45:05.420Z,1533228305.420 [Reporter](INFO): platform_buoyancy_position 352.124538 cc 2018-08-02T16:45:05.820Z,1533228305.820 [Reporter](INFO): platform_buoyancy_position 346.483954 cc 2018-08-02T16:45:06.228Z,1533228306.228 [Reporter](INFO): platform_buoyancy_position 339.903228 cc 2018-08-02T16:45:06.628Z,1533228306.628 [Reporter](INFO): platform_buoyancy_position 335.471355 cc 2018-08-02T16:45:07.037Z,1533228307.037 [Reporter](INFO): platform_buoyancy_position 331.039424 cc 2018-08-02T16:45:07.448Z,1533228307.448 [Reporter](INFO): platform_buoyancy_position 325.130241 cc 2018-08-02T16:45:07.844Z,1533228307.844 [Reporter](INFO): platform_buoyancy_position 320.564024 cc 2018-08-02T16:45:08.232Z,1533228308.232 [Reporter](INFO): platform_buoyancy_position 316.132151 cc 2018-08-02T16:45:08.644Z,1533228308.644 [Reporter](INFO): platform_buoyancy_position 309.954339 cc 2018-08-02T16:45:09.014Z,1533228309.014 [Reporter](INFO): platform_buoyancy_position 305.388123 cc 2018-08-02T16:45:09.418Z,1533228309.418 [Reporter](INFO): platform_buoyancy_position 301.090535 cc 2018-08-02T16:45:09.828Z,1533228309.828 [Reporter](INFO): platform_buoyancy_position 294.778438 cc 2018-08-02T16:45:10.225Z,1533228310.225 [Reporter](INFO): platform_buoyancy_position 288.600655 cc 2018-08-02T16:45:10.633Z,1533228310.633 [Reporter](INFO): platform_buoyancy_position 284.303038 cc 2018-08-02T16:45:11.044Z,1533228311.044 [Reporter](INFO): platform_buoyancy_position 279.602536 cc 2018-08-02T16:45:11.445Z,1533228311.445 [Reporter](INFO): platform_buoyancy_position 273.290440 cc 2018-08-02T16:45:11.848Z,1533228311.848 [Reporter](INFO): platform_buoyancy_position 268.992822 cc 2018-08-02T16:45:12.256Z,1533228312.256 [Reporter](INFO): platform_buoyancy_position 262.546411 cc 2018-08-02T16:45:12.640Z,1533228312.640 [Reporter](INFO): platform_buoyancy_position 258.114538 cc 2018-08-02T16:45:13.032Z,1533228313.032 [Reporter](INFO): platform_buoyancy_position 251.533842 cc 2018-08-02T16:45:13.444Z,1533228313.444 [Reporter](INFO): platform_buoyancy_position 247.101940 cc 2018-08-02T16:45:13.828Z,1533228313.828 [Reporter](INFO): platform_buoyancy_position 242.267124 cc 2018-08-02T16:45:14.240Z,1533228314.240 [Reporter](INFO): platform_buoyancy_position 236.760839 cc 2018-08-02T16:45:14.624Z,1533228314.624 [Reporter](INFO): platform_buoyancy_position 231.926024 cc 2018-08-02T16:45:15.028Z,1533228315.028 [Reporter](INFO): platform_buoyancy_position 226.016811 cc 2018-08-02T16:45:15.429Z,1533228315.429 [Reporter](INFO): platform_buoyancy_position 221.719238 cc 2018-08-02T16:45:15.838Z,1533228315.838 [Reporter](INFO): platform_buoyancy_position 216.347224 cc 2018-08-02T16:45:19.484Z,1533228319.484 [Reporter](INFO): platform_buoyancy_position 202.514319 cc 2018-08-02T16:47:24.035Z,1533228444.035 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:47:24.035Z,1533228444.035 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:47:24.035Z,1533228444.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:47:24.035Z,1533228444.035 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:47:24.438Z,1533228444.438 [Default:CheckIn:D] Stopped 2018-08-02T16:47:24.438Z,1533228444.438 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 566.808724 min 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn:E] Stopped 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn] Stopped 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:47:28.801Z,1533228448.801 [Default:CheckIn](INFO): Running loop #54 2018-08-02T16:47:28.802Z,1533228448.802 [Default:CheckIn] Running Loop=54 2018-08-02T16:47:28.802Z,1533228448.802 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:47:28.802Z,1533228448.802 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:47:29.307Z,1533228449.307 [NAL9602](INFO): Powering up 2018-08-02T16:47:40.510Z,1533228460.510 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:48:27.718Z,1533228507.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:48:27.719Z,1533228507.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:48:39.517Z,1533228519.517 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T16:48:41.835Z,1533228521.835 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:48:41.835Z,1533228521.835 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:48:50.310Z,1533228530.310 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.117771 CHAN A1 (24V): -0.021398 CHAN A2 (12V): -0.004458 CHAN A3 (5V): -0.004482 CHAN B0 (3.3V): -0.002217 CHAN B1 (3.15aV): -0.001606 CHAN B2 (3.15bV): -0.001122 CHAN B3 (GND): -0.000939 OPEN: 0.012640 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T16:49:04.635Z,1533228544.635 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:49:04.635Z,1533228544.635 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:49:23.035Z,1533228563.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:49:23.035Z,1533228563.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:49:30.760Z,1533228570.760 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:49:30.760Z,1533228570.760 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:49:49.155Z,1533228589.155 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:49:49.156Z,1533228589.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:50:10.755Z,1533228610.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T16:50:10.756Z,1533228610.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T16:52:29.030Z,1533228749.030 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:47:28.8Z 2018-08-02T16:52:29.030Z,1533228749.030 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T16:52:29.030Z,1533228749.030 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T16:52:33.867Z,1533228753.867 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0048.lzma 2018-08-02T16:52:34.658Z,1533228754.658 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0048.lzma.bak 2018-08-02T16:52:34.658Z,1533228754.658 [DataOverHttps](INFO): SBD MOMSN=8395187 2018-08-02T16:52:43.907Z,1533228763.907 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-08-02T16:52:44.025Z,1533228764.025 [DataOverHttps](INFO): Sending 556 bytes from file Logs/20180802T141040/Express0049.lzma 2018-08-02T16:52:44.823Z,1533228764.823 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0049.lzma.bak 2018-08-02T16:52:44.823Z,1533228764.823 [DataOverHttps](INFO): SBD MOMSN=8395189 2018-08-02T16:52:45.966Z,1533228765.966 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T16:52:45.966Z,1533228765.966 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T16:52:45.966Z,1533228765.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T16:53:14.402Z,1533228794.402 [NAL9602](INFO): Powering down 2018-08-02T16:55:20.522Z,1533228920.522 [CommandLine](IMPORTANT): got command gfscan 2018-08-02T16:55:20.591Z,1533228920.591 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T16:55:31.416Z,1533228931.416 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.116544 CHAN A1 (24V): -0.022240 CHAN A2 (12V): -0.005561 CHAN A3 (5V): -0.003661 CHAN B0 (3.3V): -0.002977 CHAN B1 (3.15aV): -0.002338 CHAN B2 (3.15bV): -0.001349 CHAN B3 (GND): -0.001415 OPEN: 0.009881 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T16:57:46.430Z,1533229066.430 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T16:57:46.430Z,1533229066.430 [Default:CheckIn:C.Wait] Stopped 2018-08-02T16:57:46.430Z,1533229066.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T16:57:46.430Z,1533229066.430 [Default:CheckIn:D] Running Loop=1 2018-08-02T16:57:46.858Z,1533229066.858 [Default:CheckIn:D] Stopped 2018-08-02T16:57:46.858Z,1533229066.858 [Default:CheckIn:E] Running Loop=1 2018-08-02T16:57:47.284Z,1533229067.284 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 577.182422 min 2018-08-02T16:57:47.284Z,1533229067.284 [Default:CheckIn:E] Stopped 2018-08-02T16:57:47.284Z,1533229067.284 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T16:57:47.284Z,1533229067.284 [Default:CheckIn] Stopped 2018-08-02T16:57:47.284Z,1533229067.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T16:57:47.297Z,1533229067.297 [Default:CheckIn](INFO): Running loop #55 2018-08-02T16:57:47.297Z,1533229067.297 [Default:CheckIn] Running Loop=55 2018-08-02T16:57:47.297Z,1533229067.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T16:57:47.297Z,1533229067.297 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T16:57:54.079Z,1533229074.079 [NAL9602](INFO): Powering up 2018-08-02T16:58:04.966Z,1533229084.966 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:58:05.766Z,1533229085.766 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-08-02T16:58:05.766Z,1533229085.766 [NAL9602] Data Fault, FailCount= 3 2018-08-02T16:58:05.766Z,1533229085.766 [NAL9602](ERROR): Data Fault 2018-08-02T16:58:05.881Z,1533229085.881 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T16:58:06.166Z,1533229086.166 [NAL9602](INFO): Powering down 2018-08-02T16:58:07.089Z,1533229087.089 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T16:58:07.089Z,1533229087.089 [NAL9602] No Fault, FailCount= 3 2018-08-02T16:58:36.570Z,1533229116.570 [NAL9602](INFO): Powering up NAL9602 2018-08-02T16:58:47.766Z,1533229127.766 [NAL9602](INFO): NAL9602 initialized 2018-08-02T16:59:43.841Z,1533229183.841 [CommandLine](IMPORTANT): got command gfscan 2018-08-02T16:59:43.865Z,1533229183.865 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T16:59:56.575Z,1533229196.575 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.113845 CHAN A1 (24V): -0.024005 CHAN A2 (12V): -0.004569 CHAN A3 (5V): -0.002748 CHAN B0 (3.3V): -0.001204 CHAN B1 (3.15aV): -0.001720 CHAN B2 (3.15bV): -0.001231 CHAN B3 (GND): 0.000256 OPEN: -0.000822 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T17:00:29.827Z,1533229229.827 [CommandLine](IMPORTANT): got command gfscan 2018-08-02T17:00:30.131Z,1533229230.131 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T17:00:40.961Z,1533229240.961 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.114033 CHAN A1 (24V): -0.022857 CHAN A2 (12V): -0.005767 CHAN A3 (5V): -0.002611 CHAN B0 (3.3V): -0.000972 CHAN B1 (3.15aV): -0.000896 CHAN B2 (3.15bV): -0.001444 CHAN B3 (GND): -0.000041 OPEN: 0.004234 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T17:00:41.670Z,1533229241.670 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-08-02T17:00:42.193Z,1533229242.193 [NAL9602](FAULT): received: 2018-08-02T17:00:42.193Z,1533229242.193 [NAL9602] Data Fault, FailCount= 4 2018-08-02T17:00:42.193Z,1533229242.193 [NAL9602](ERROR): Data Fault 2018-08-02T17:00:42.290Z,1533229242.290 [CBIT](ERROR): Data Fault in component: NAL9602 2018-08-02T17:00:42.322Z,1533229242.322 [NAL9602](INFO): Powering down 2018-08-02T17:00:43.166Z,1533229243.166 [CBIT](INFO): Clearing failed state for component NAL9602 2018-08-02T17:00:43.167Z,1533229243.167 [NAL9602] No Fault, FailCount= 4 2018-08-02T17:01:12.702Z,1533229272.702 [NAL9602](INFO): Powering up NAL9602 2018-08-02T17:01:17.898Z,1533229277.898 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-08-02T17:01:23.502Z,1533229283.502 [NAL9602](INFO): NAL9602 initialized 2018-08-02T17:02:17.898Z,1533229337.898 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-08-02T17:02:47.547Z,1533229367.547 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-02T16:57:47.3Z 2018-08-02T17:02:47.547Z,1533229367.547 [Default:CheckIn:Read_GPS] Stopped 2018-08-02T17:02:47.547Z,1533229367.547 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-08-02T17:02:53.957Z,1533229373.957 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=181.889862 2018-08-02T17:02:59.042Z,1533229379.042 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002855 2018-08-02T17:03:03.655Z,1533229383.655 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180802T141040/Courier0051.lzma 2018-08-02T17:03:04.423Z,1533229384.423 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Courier0051.lzma.bak 2018-08-02T17:03:04.424Z,1533229384.424 [DataOverHttps](INFO): SBD MOMSN=8395249 2018-08-02T17:03:14.278Z,1533229394.278 [CommandLine](IMPORTANT): got command maintain clear 2018-08-02T17:03:14.583Z,1533229394.583 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T17:03:14.995Z,1533229394.995 [DataOverHttps](INFO): Sending 547 bytes from file Logs/20180802T141040/Express0052.lzma 2018-08-02T17:03:15.791Z,1533229395.791 [DataOverHttps](INFO): Moved sent file to Logs/20180802T141040/Express0052.lzma.bak 2018-08-02T17:03:15.791Z,1533229395.791 [DataOverHttps](INFO): SBD MOMSN=8395251 2018-08-02T17:03:16.934Z,1533229396.934 [Default:CheckIn:Read_Iridium] Stopped 2018-08-02T17:03:16.934Z,1533229396.934 [Default:CheckIn:C.Wait] Running Loop=1 2018-08-02T17:03:16.934Z,1533229396.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-08-02T17:03:19.566Z,1533229399.566 [CommandLine](IMPORTANT): got command report 2018-08-02T17:03:19.567Z,1533229399.567 [Reporter](INFO): platform_buoyancy_position reporting changes 2018-08-02T17:03:20.345Z,1533229400.345 [NAL9602](INFO): SBD MO Status=2, MOMSN=22737, MT Status=2, MTMSN=0 2018-08-02T17:03:20.345Z,1533229400.345 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T17:03:21.388Z,1533229401.388 [Reporter](INFO): platform_buoyancy_position 202.380033 cc 2018-08-02T17:03:21.744Z,1533229401.744 [Reporter](INFO): platform_buoyancy_position 202.245734 cc 2018-08-02T17:03:22.580Z,1533229402.580 [Reporter](INFO): platform_buoyancy_position 202.380033 cc 2018-08-02T17:03:22.953Z,1533229402.953 [Reporter](INFO): platform_buoyancy_position 206.677607 cc 2018-08-02T17:03:23.352Z,1533229403.352 [Reporter](INFO): platform_buoyancy_position 211.109524 cc 2018-08-02T17:03:23.760Z,1533229403.760 [Reporter](INFO): platform_buoyancy_position 217.287321 cc 2018-08-02T17:03:24.160Z,1533229404.160 [Reporter](INFO): platform_buoyancy_position 221.584924 cc 2018-08-02T17:03:24.548Z,1533229404.548 [Reporter](INFO): platform_buoyancy_position 227.897035 cc 2018-08-02T17:03:24.948Z,1533229404.948 [Reporter](INFO): platform_buoyancy_position 232.328923 cc 2018-08-02T17:03:25.340Z,1533229405.340 [Reporter](INFO): platform_buoyancy_position 238.909633 cc 2018-08-02T17:03:25.752Z,1533229405.752 [Reporter](INFO): platform_buoyancy_position 245.221745 cc 2018-08-02T17:03:26.160Z,1533229406.160 [Reporter](INFO): platform_buoyancy_position 249.519304 cc 2018-08-02T17:03:26.560Z,1533229406.560 [Reporter](INFO): platform_buoyancy_position 255.965715 cc 2018-08-02T17:03:26.953Z,1533229406.953 [Reporter](INFO): platform_buoyancy_position 261.203444 cc 2018-08-02T17:03:27.368Z,1533229407.368 [Reporter](INFO): platform_buoyancy_position 266.844028 cc 2018-08-02T17:03:27.748Z,1533229407.748 [Reporter](INFO): platform_buoyancy_position 271.141645 cc 2018-08-02T17:03:28.148Z,1533229408.148 [Reporter](INFO): platform_buoyancy_position 275.573519 cc 2018-08-02T17:03:28.565Z,1533229408.565 [Reporter](INFO): platform_buoyancy_position 281.751330 cc 2018-08-02T17:03:28.956Z,1533229408.956 [Reporter](INFO): platform_buoyancy_position 288.197713 cc 2018-08-02T17:03:29.373Z,1533229409.373 [Reporter](INFO): platform_buoyancy_position 292.629644 cc 2018-08-02T17:03:29.761Z,1533229409.761 [Reporter](INFO): platform_buoyancy_position 298.941741 cc 2018-08-02T17:03:30.160Z,1533229410.160 [Reporter](INFO): platform_buoyancy_position 303.239329 cc 2018-08-02T17:03:30.556Z,1533229410.556 [Reporter](INFO): platform_buoyancy_position 307.536917 cc 2018-08-02T17:03:30.972Z,1533229410.972 [Reporter](INFO): platform_buoyancy_position 312.908931 cc 2018-08-02T17:03:31.344Z,1533229411.344 [Reporter](INFO): platform_buoyancy_position 318.280945 cc 2018-08-02T17:03:31.764Z,1533229411.764 [Reporter](INFO): platform_buoyancy_position 324.727327 cc 2018-08-02T17:03:32.144Z,1533229412.144 [Reporter](INFO): platform_buoyancy_position 329.024944 cc 2018-08-02T17:03:32.552Z,1533229412.552 [Reporter](INFO): platform_buoyancy_position 333.322532 cc 2018-08-02T17:03:32.960Z,1533229412.960 [Reporter](INFO): platform_buoyancy_position 339.768943 cc 2018-08-02T17:03:33.352Z,1533229413.352 [Reporter](INFO): platform_buoyancy_position 344.200846 cc 2018-08-02T17:03:33.764Z,1533229413.764 [Reporter](INFO): platform_buoyancy_position 350.512943 cc 2018-08-02T17:03:34.168Z,1533229414.168 [Reporter](INFO): platform_buoyancy_position 356.959354 cc 2018-08-02T17:03:34.573Z,1533229414.573 [Reporter](INFO): platform_buoyancy_position 361.256942 cc 2018-08-02T17:03:34.960Z,1533229414.960 [Reporter](INFO): platform_buoyancy_position 365.688844 cc 2018-08-02T17:03:35.357Z,1533229415.357 [Reporter](INFO): platform_buoyancy_position 371.866627 cc 2018-08-02T17:03:35.760Z,1533229415.760 [Reporter](INFO): platform_buoyancy_position 376.432843 cc 2018-08-02T17:03:36.158Z,1533229416.158 [Reporter](INFO): platform_buoyancy_position 380.730431 cc 2018-08-02T17:03:36.552Z,1533229416.552 [Reporter](INFO): platform_buoyancy_position 387.176842 cc 2018-08-02T17:03:36.971Z,1533229416.971 [Reporter](INFO): platform_buoyancy_position 391.608744 cc 2018-08-02T17:03:37.352Z,1533229417.352 [Reporter](INFO): platform_buoyancy_position 397.920841 cc 2018-08-02T17:03:37.752Z,1533229417.752 [Reporter](INFO): platform_buoyancy_position 404.232938 cc 2018-08-02T17:03:38.164Z,1533229418.164 [Reporter](INFO): platform_buoyancy_position 408.530555 cc 2018-08-02T17:03:38.552Z,1533229418.552 [Reporter](INFO): platform_buoyancy_position 412.828143 cc 2018-08-02T17:03:38.960Z,1533229418.960 [Reporter](INFO): platform_buoyancy_position 417.260046 cc 2018-08-02T17:03:39.376Z,1533229419.376 [Reporter](INFO): platform_buoyancy_position 423.572114 cc 2018-08-02T17:03:39.760Z,1533229419.760 [Reporter](INFO): platform_buoyancy_position 429.749984 cc 2018-08-02T17:03:40.164Z,1533229420.164 [Reporter](INFO): platform_buoyancy_position 434.450456 cc 2018-08-02T17:03:40.564Z,1533229420.564 [Reporter](INFO): platform_buoyancy_position 440.896809 cc 2018-08-02T17:03:40.968Z,1533229420.968 [Reporter](INFO): platform_buoyancy_position 445.194426 cc 2018-08-02T17:03:41.372Z,1533229421.372 [Reporter](INFO): platform_buoyancy_position 449.492043 cc 2018-08-02T17:03:41.778Z,1533229421.778 [Reporter](INFO): platform_buoyancy_position 453.923916 cc 2018-08-02T17:03:42.156Z,1533229422.156 [Reporter](INFO): platform_buoyancy_position 460.101728 cc 2018-08-02T17:03:42.467Z,1533229422.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=22737, MT Status=0, MTMSN=0 2018-08-02T17:03:42.467Z,1533229422.467 [NAL9602](INFO): No messages in MT queue 2018-08-02T17:03:42.692Z,1533229422.692 [Reporter](INFO): platform_buoyancy_position 466.682453 cc 2018-08-02T17:03:43.092Z,1533229423.092 [Reporter](INFO): platform_buoyancy_position 473.128865 cc 2018-08-02T17:03:43.301Z,1533229423.301 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.358803 seconds old. 2018-08-02T17:03:43.368Z,1533229423.368 [Reporter](INFO): platform_buoyancy_position 477.426423 cc 2018-08-02T17:03:43.698Z,1533229423.698 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.756121 seconds old. 2018-08-02T17:03:43.752Z,1533229423.752 [Reporter](INFO): platform_buoyancy_position 483.872835 cc 2018-08-02T17:03:44.095Z,1533229424.095 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.153525 seconds old. 2018-08-02T17:03:44.140Z,1533229424.140 [Reporter](INFO): platform_buoyancy_position 487.633282 cc 2018-08-02T17:03:44.508Z,1533229424.508 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.565978 seconds old. 2018-08-02T17:03:44.573Z,1533229424.573 [Reporter](INFO): platform_buoyancy_position 492.199440 cc 2018-08-02T17:03:44.950Z,1533229424.950 [Reporter](INFO): platform_buoyancy_position 496.765657 cc 2018-08-02T17:03:45.352Z,1533229425.352 [Reporter](INFO): platform_buoyancy_position 503.077754 cc 2018-08-02T17:03:45.748Z,1533229425.748 [Reporter](INFO): platform_buoyancy_position 507.375342 cc 2018-08-02T17:03:46.160Z,1533229426.160 [Reporter](INFO): platform_buoyancy_position 513.553096 cc 2018-08-02T17:03:46.564Z,1533229426.564 [Reporter](INFO): platform_buoyancy_position 518.119312 cc 2018-08-02T17:03:46.978Z,1533229426.978 [Reporter](INFO): platform_buoyancy_position 524.297124 cc 2018-08-02T17:03:47.380Z,1533229427.380 [Reporter](INFO): platform_buoyancy_position 528.997625 cc 2018-08-02T17:03:47.808Z,1533229427.808 [Reporter](INFO): platform_buoyancy_position 535.175437 cc 2018-08-02T17:03:48.184Z,1533229428.184 [Reporter](INFO): platform_buoyancy_position 539.607310 cc 2018-08-02T17:03:48.576Z,1533229428.576 [Reporter](INFO): platform_buoyancy_position 544.039242 cc 2018-08-02T17:03:48.965Z,1533229428.965 [Reporter](INFO): platform_buoyancy_position 550.217053 cc 2018-08-02T17:03:49.379Z,1533229429.379 [Reporter](INFO): platform_buoyancy_position 554.648926 cc 2018-08-02T17:03:49.772Z,1533229429.772 [Reporter](INFO): platform_buoyancy_position 559.483713 cc 2018-08-02T17:03:50.172Z,1533229430.172 [Reporter](INFO): platform_buoyancy_position 565.392955 cc 2018-08-02T17:03:50.569Z,1533229430.569 [Reporter](INFO): platform_buoyancy_position 569.824828 cc 2018-08-02T17:03:50.994Z,1533229430.994 [Reporter](INFO): platform_buoyancy_position 576.136925 cc 2018-08-02T17:03:51.360Z,1533229431.360 [Reporter](INFO): platform_buoyancy_position 580.568856 cc 2018-08-02T17:03:51.770Z,1533229431.770 [Reporter](INFO): platform_buoyancy_position 585.940783 cc 2018-08-02T17:03:52.172Z,1533229432.172 [Reporter](INFO): platform_buoyancy_position 591.312826 cc 2018-08-02T17:03:52.576Z,1533229432.576 [Reporter](INFO): platform_buoyancy_position 595.610414 cc 2018-08-02T17:03:52.956Z,1533229432.956 [Reporter](INFO): platform_buoyancy_position 601.519656 cc 2018-08-02T17:03:53.376Z,1533229433.376 [Reporter](INFO): platform_buoyancy_position 606.354442 cc 2018-08-02T17:03:53.735Z,1533229433.735 [Reporter](INFO): platform_buoyancy_position 610.786316 cc 2018-08-02T17:03:54.180Z,1533229434.180 [Reporter](INFO): platform_buoyancy_position 616.964127 cc 2018-08-02T17:03:54.650Z,1533229434.650 [Reporter](INFO): platform_buoyancy_position 623.276224 cc 2018-08-02T17:03:54.945Z,1533229434.945 [Reporter](INFO): platform_buoyancy_position 627.842383 cc 2018-08-02T17:03:55.358Z,1533229435.358 [Reporter](INFO): platform_buoyancy_position 632.140029 cc 2018-08-02T17:03:55.765Z,1533229435.765 [Reporter](INFO): platform_buoyancy_position 638.452126 cc 2018-08-02T17:03:56.172Z,1533229436.172 [Reporter](INFO): platform_buoyancy_position 642.749772 cc 2018-08-02T17:03:56.564Z,1533229436.564 [Reporter](INFO): platform_buoyancy_position 647.315930 cc 2018-08-02T17:03:56.621Z,1533229436.621 [CommandLine](IMPORTANT): got command gfscan 2018-08-02T17:03:56.971Z,1533229436.971 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T17:03:56.972Z,1533229436.972 [Reporter](INFO): platform_buoyancy_position 653.493742 cc 2018-08-02T17:03:57.386Z,1533229437.386 [Reporter](INFO): platform_buoyancy_position 657.925673 cc 2018-08-02T17:03:57.753Z,1533229437.753 [Reporter](INFO): platform_buoyancy_position 662.894687 cc 2018-08-02T17:03:58.160Z,1533229438.160 [Reporter](INFO): platform_buoyancy_position 668.669643 cc 2018-08-02T17:03:58.643Z,1533229438.643 [Reporter](INFO): platform_buoyancy_position 675.116025 cc 2018-08-02T17:03:58.976Z,1533229438.976 [Reporter](INFO): platform_buoyancy_position 679.279387 cc 2018-08-02T17:03:59.368Z,1533229439.368 [Reporter](INFO): platform_buoyancy_position 683.711260 cc 2018-08-02T17:03:59.838Z,1533229439.838 [Reporter](INFO): platform_buoyancy_position 690.023298 cc 2018-08-02T17:04:00.168Z,1533229440.168 [Reporter](INFO): platform_buoyancy_position 694.455288 cc 2018-08-02T17:04:00.537Z,1533229440.537 [Reporter](INFO): platform_buoyancy_position 699.424301 cc 2018-08-02T17:04:01.030Z,1533229441.030 [Reporter](INFO): platform_buoyancy_position 705.467828 cc 2018-08-02T17:04:01.349Z,1533229441.349 [Reporter](INFO): platform_buoyancy_position 709.899701 cc 2018-08-02T17:04:01.748Z,1533229441.748 [Reporter](INFO): platform_buoyancy_position 715.808943 cc 2018-08-02T17:04:02.222Z,1533229442.222 [Reporter](INFO): platform_buoyancy_position 720.375101 cc 2018-08-02T17:04:02.549Z,1533229442.549 [Reporter](INFO): platform_buoyancy_position 726.687256 cc 2018-08-02T17:04:02.961Z,1533229442.961 [Reporter](INFO): platform_buoyancy_position 730.984844 cc 2018-08-02T17:04:03.353Z,1533229443.353 [Reporter](INFO): platform_buoyancy_position 737.028313 cc 2018-08-02T17:04:03.850Z,1533229443.850 [Reporter](INFO): platform_buoyancy_position 741.594529 cc 2018-08-02T17:04:04.176Z,1533229444.176 [Reporter](INFO): platform_buoyancy_position 748.040969 cc 2018-08-02T17:04:04.556Z,1533229444.556 [Reporter](INFO): platform_buoyancy_position 752.338558 cc 2018-08-02T17:04:05.055Z,1533229445.055 [Reporter](INFO): platform_buoyancy_position 758.650654 cc 2018-08-02T17:04:05.345Z,1533229445.345 [Reporter](INFO): platform_buoyancy_position 763.082528 cc 2018-08-02T17:04:05.740Z,1533229445.740 [Reporter](INFO): platform_buoyancy_position 767.514459 cc 2018-08-02T17:04:06.258Z,1533229446.258 [Reporter](INFO): platform_buoyancy_position 773.826556 cc 2018-08-02T17:04:06.569Z,1533229446.569 [Reporter](INFO): platform_buoyancy_position 778.258429 cc 2018-08-02T17:04:06.943Z,1533229446.943 [Reporter](INFO): platform_buoyancy_position 784.570584 cc 2018-08-02T17:04:07.411Z,1533229447.411 [Reporter](INFO): platform_buoyancy_position 789.002457 cc 2018-08-02T17:04:07.746Z,1533229447.746 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.113583 CHAN A1 (24V): -0.024355 CHAN A2 (12V): -0.005273 CHAN A3 (5V): -0.002481 CHAN B0 (3.3V): -0.001401 CHAN B1 (3.15aV): -0.001409 CHAN B2 (3.15bV): -0.001614 CHAN B3 (GND): 0.000692 OPEN: 0.003996 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T17:04:07.762Z,1533229447.762 [Reporter](INFO): platform_buoyancy_position 795.314496 cc 2018-08-02T17:04:08.157Z,1533229448.157 [Reporter](INFO): platform_buoyancy_position 799.746485 cc 2018-08-02T17:04:08.561Z,1533229448.561 [Reporter](INFO): platform_buoyancy_position 805.789896 cc 2018-08-02T17:04:08.965Z,1533229448.965 [Reporter](INFO): platform_buoyancy_position 810.490397 cc 2018-08-02T17:04:09.364Z,1533229449.364 [Reporter](INFO): platform_buoyancy_position 815.459527 cc 2018-08-02T17:04:09.752Z,1533229449.752 [Reporter](INFO): platform_buoyancy_position 821.100140 cc 2018-08-02T17:04:10.165Z,1533229450.165 [Reporter](INFO): platform_buoyancy_position 825.532072 cc 2018-08-02T17:04:10.534Z,1533229450.534 [Reporter](INFO): platform_buoyancy_position 831.844111 cc 2018-08-02T17:04:10.936Z,1533229450.936 [Reporter](INFO): platform_buoyancy_position 836.141699 cc 2018-08-02T17:04:11.348Z,1533229451.348 [Reporter](INFO): platform_buoyancy_position 842.185225 cc 2018-08-02T17:04:11.746Z,1533229451.746 [Reporter](INFO): platform_buoyancy_position 846.885727 cc 2018-08-02T17:04:12.145Z,1533229452.145 [Reporter](INFO): platform_buoyancy_position 851.586228 cc 2018-08-02T17:04:12.556Z,1533229452.556 [Reporter](INFO): platform_buoyancy_position 857.764040 cc 2018-08-02T17:04:12.950Z,1533229452.950 [Reporter](INFO): platform_buoyancy_position 861.927343 cc 2018-08-02T17:04:13.254Z,1533229453.254 [NAL9602](INFO): Powering down 2018-08-02T17:04:13.364Z,1533229453.364 [Reporter](INFO): platform_buoyancy_position 868.239440 cc 2018-08-02T17:04:13.764Z,1533229453.764 [Reporter](INFO): platform_buoyancy_position 872.671313 cc 2018-08-02T17:04:14.162Z,1533229454.162 [Reporter](INFO): platform_buoyancy_position 877.774728 cc 2018-08-02T17:04:14.572Z,1533229454.572 [Reporter](INFO): platform_buoyancy_position 883.415341 cc 2018-08-02T17:04:14.956Z,1533229454.956 [Reporter](INFO): platform_buoyancy_position 887.847214 cc 2018-08-02T17:04:15.372Z,1533229455.372 [Reporter](INFO): platform_buoyancy_position 894.293655 cc 2018-08-02T17:04:15.756Z,1533229455.756 [Reporter](INFO): platform_buoyancy_position 899.397011 cc 2018-08-02T17:04:16.132Z,1533229456.132 [Reporter](INFO): platform_buoyancy_position 905.037683 cc 2018-08-02T17:04:16.532Z,1533229456.532 [Reporter](INFO): platform_buoyancy_position 909.335271 cc 2018-08-02T17:04:16.942Z,1533229456.942 [Reporter](INFO): platform_buoyancy_position 915.647368 cc 2018-08-02T17:04:17.344Z,1533229457.344 [Reporter](INFO): platform_buoyancy_position 920.079241 cc 2018-08-02T17:04:17.737Z,1533229457.737 [Reporter](INFO): platform_buoyancy_position 926.391338 cc 2018-08-02T17:04:20.985Z,1533229460.985 [Reporter](INFO): platform_buoyancy_position 952.176983 cc 2018-08-02T17:04:33.617Z,1533229473.617 [CommandLine](IMPORTANT): got command gfscan 2018-08-02T17:04:33.865Z,1533229473.865 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-02T17:04:44.655Z,1533229484.655 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.113440 CHAN A1 (24V): -0.023396 CHAN A2 (12V): -0.004979 CHAN A3 (5V): -0.002680 CHAN B0 (3.3V): -0.001248 CHAN B1 (3.15aV): -0.000763 CHAN B2 (3.15bV): -0.001019 CHAN B3 (GND): -0.000103 OPEN: 0.003501 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-02T17:04:59.876Z,1533229499.876 [CommandLine](IMPORTANT): got command show variable limitFwd 2018-08-02T17:04:59.929Z,1533229499.929 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter) 2018-08-02T17:05:22.329Z,1533229522.329 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2018-08-02T17:05:22.330Z,1533229522.330 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 39.999999 mm 2018-08-02T17:05:28.726Z,1533229528.726 [CommandLine](IMPORTANT): got command show variable limitAft 2018-08-02T17:05:28.767Z,1533229528.767 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter) 2018-08-02T17:05:35.050Z,1533229535.050 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2018-08-02T17:05:35.051Z,1533229535.051 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -13.000000 mm 2018-08-02T17:05:54.133Z,1533229554.133 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 4.000000 centimeter 2018-08-02T17:05:54.134Z,1533229554.134 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2018-08-02T17:05:54.289Z,1533229554.289 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T17:06:01.488Z,1533229561.488 [CommandLine](IMPORTANT): got command report mod platform_mass_position 2018-08-02T17:06:01.609Z,1533229561.609 [Reporter](INFO): platform_mass_position 0.013853 m 2018-08-02T17:06:01.995Z,1533229561.995 [Reporter](INFO): platform_mass_position 0.014155 m 2018-08-02T17:06:02.384Z,1533229562.384 [Reporter](INFO): platform_mass_position 0.014460 m 2018-08-02T17:06:02.776Z,1533229562.776 [Reporter](INFO): platform_mass_position 0.014767 m 2018-08-02T17:06:03.169Z,1533229563.169 [Reporter](INFO): platform_mass_position 0.015070 m 2018-08-02T17:06:03.583Z,1533229563.583 [Reporter](INFO): platform_mass_position 0.015397 m 2018-08-02T17:06:03.995Z,1533229563.995 [Reporter](INFO): platform_mass_position 0.015720 m 2018-08-02T17:06:04.395Z,1533229564.395 [Reporter](INFO): platform_mass_position 0.016028 m 2018-08-02T17:06:04.783Z,1533229564.783 [Reporter](INFO): platform_mass_position 0.016335 m 2018-08-02T17:06:05.196Z,1533229565.196 [Reporter](INFO): platform_mass_position 0.016661 m 2018-08-02T17:06:05.588Z,1533229565.588 [Reporter](INFO): platform_mass_position 0.016967 m 2018-08-02T17:06:05.988Z,1533229565.988 [Reporter](INFO): platform_mass_position 0.017280 m 2018-08-02T17:06:06.384Z,1533229566.384 [Reporter](INFO): platform_mass_position 0.017590 m 2018-08-02T17:06:06.783Z,1533229566.783 [Reporter](INFO): platform_mass_position 0.017900 m 2018-08-02T17:06:07.180Z,1533229567.180 [Reporter](INFO): platform_mass_position 0.018213 m 2018-08-02T17:06:07.588Z,1533229567.588 [Reporter](INFO): platform_mass_position 0.018532 m 2018-08-02T17:06:07.995Z,1533229567.995 [Reporter](INFO): platform_mass_position 0.018850 m 2018-08-02T17:06:08.400Z,1533229568.400 [Reporter](INFO): platform_mass_position 0.019168 m 2018-08-02T17:06:08.811Z,1533229568.811 [Reporter](INFO): platform_mass_position 0.019485 m 2018-08-02T17:06:09.182Z,1533229569.182 [Reporter](INFO): platform_mass_position 0.019772 m 2018-08-02T17:06:09.584Z,1533229569.584 [Reporter](INFO): platform_mass_position 0.020095 m 2018-08-02T17:06:09.987Z,1533229569.987 [Reporter](INFO): platform_mass_position 0.020408 m 2018-08-02T17:06:10.411Z,1533229570.411 [Reporter](INFO): platform_mass_position 0.020739 m 2018-08-02T17:06:10.796Z,1533229570.796 [Reporter](INFO): platform_mass_position 0.021034 m 2018-08-02T17:06:11.188Z,1533229571.188 [Reporter](INFO): platform_mass_position 0.021350 m 2018-08-02T17:06:11.595Z,1533229571.595 [Reporter](INFO): platform_mass_position 0.021668 m 2018-08-02T17:06:12.012Z,1533229572.012 [Reporter](INFO): platform_mass_position 0.021995 m 2018-08-02T17:06:12.383Z,1533229572.383 [Reporter](INFO): platform_mass_position 0.022283 m 2018-08-02T17:06:12.781Z,1533229572.781 [Reporter](INFO): platform_mass_position 0.022596 m 2018-08-02T17:06:13.196Z,1533229573.196 [Reporter](INFO): platform_mass_position 0.022921 m 2018-08-02T17:06:13.604Z,1533229573.604 [Reporter](INFO): platform_mass_position 0.023239 m 2018-08-02T17:06:13.995Z,1533229573.995 [Reporter](INFO): platform_mass_position 0.023546 m 2018-08-02T17:06:14.390Z,1533229574.390 [Reporter](INFO): platform_mass_position 0.023842 m 2018-08-02T17:06:14.772Z,1533229574.772 [Reporter](INFO): platform_mass_position 0.024155 m 2018-08-02T17:06:15.204Z,1533229575.204 [Reporter](INFO): platform_mass_position 0.024493 m 2018-08-02T17:06:15.603Z,1533229575.603 [Reporter](INFO): platform_mass_position 0.024801 m 2018-08-02T17:06:15.983Z,1533229575.983 [Reporter](INFO): platform_mass_position 0.025101 m 2018-08-02T17:06:16.384Z,1533229576.384 [Reporter](INFO): platform_mass_position 0.025417 m 2018-08-02T17:06:16.772Z,1533229576.772 [Reporter](INFO): platform_mass_position 0.025720 m 2018-08-02T17:06:17.172Z,1533229577.172 [Reporter](INFO): platform_mass_position 0.026033 m 2018-08-02T17:06:17.576Z,1533229577.576 [Reporter](INFO): platform_mass_position 0.026349 m 2018-08-02T17:06:17.976Z,1533229577.976 [Reporter](INFO): platform_mass_position 0.026662 m 2018-08-02T17:06:21.108Z,1533229581.108 [Reporter](INFO): platform_mass_position 0.029110 m 2018-08-02T17:06:21.256Z,1533229581.256 [Reporter](INFO): platform_mass_position 0.029229 m 2018-08-02T17:06:21.620Z,1533229581.620 [Reporter](INFO): platform_mass_position 0.029514 m 2018-08-02T17:06:22.036Z,1533229582.036 [Reporter](INFO): platform_mass_position 0.029839 m 2018-08-02T17:06:22.472Z,1533229582.472 [Reporter](INFO): platform_mass_position 0.030181 m 2018-08-02T17:06:22.840Z,1533229582.840 [Reporter](INFO): platform_mass_position 0.030469 m 2018-08-02T17:06:23.244Z,1533229583.244 [Reporter](INFO): platform_mass_position 0.030785 m 2018-08-02T17:06:23.644Z,1533229583.644 [Reporter](INFO): platform_mass_position 0.031098 m 2018-08-02T17:06:24.048Z,1533229584.048 [Reporter](INFO): platform_mass_position 0.031414 m 2018-08-02T17:06:24.440Z,1533229584.440 [Reporter](INFO): platform_mass_position 0.031721 m 2018-08-02T17:06:24.856Z,1533229584.856 [Reporter](INFO): platform_mass_position 0.032047 m 2018-08-02T17:06:25.248Z,1533229585.248 [Reporter](INFO): platform_mass_position 0.032353 m 2018-08-02T17:06:25.657Z,1533229585.657 [Reporter](INFO): platform_mass_position 0.032673 m 2018-08-02T17:06:26.036Z,1533229586.036 [Reporter](INFO): platform_mass_position 0.032970 m 2018-08-02T17:06:26.453Z,1533229586.453 [Reporter](INFO): platform_mass_position 0.033292 m 2018-08-02T17:06:26.860Z,1533229586.860 [Reporter](INFO): platform_mass_position 0.033615 m 2018-08-02T17:06:27.284Z,1533229587.284 [Reporter](INFO): platform_mass_position 0.033947 m 2018-08-02T17:06:27.648Z,1533229587.648 [Reporter](INFO): platform_mass_position 0.034231 m 2018-08-02T17:06:28.040Z,1533229588.040 [Reporter](INFO): platform_mass_position 0.034538 m 2018-08-02T17:06:28.452Z,1533229588.452 [Reporter](INFO): platform_mass_position 0.034861 m 2018-08-02T17:06:28.837Z,1533229588.837 [Reporter](INFO): platform_mass_position 0.035161 m 2018-08-02T17:06:29.252Z,1533229589.252 [Reporter](INFO): platform_mass_position 0.035487 m 2018-08-02T17:06:29.636Z,1533229589.636 [Reporter](INFO): platform_mass_position 0.035787 m 2018-08-02T17:06:30.056Z,1533229590.056 [Reporter](INFO): platform_mass_position 0.036116 m 2018-08-02T17:06:30.428Z,1533229590.428 [Reporter](INFO): platform_mass_position 0.036406 m 2018-08-02T17:06:30.868Z,1533229590.868 [Reporter](INFO): platform_mass_position 0.036751 m 2018-08-02T17:06:31.267Z,1533229591.267 [Reporter](INFO): platform_mass_position 0.037064 m 2018-08-02T17:06:31.643Z,1533229591.643 [Reporter](INFO): platform_mass_position 0.037352 m 2018-08-02T17:06:32.052Z,1533229592.052 [Reporter](INFO): platform_mass_position 0.037678 m 2018-08-02T17:06:32.436Z,1533229592.436 [Reporter](INFO): platform_mass_position 0.037978 m 2018-08-02T17:06:32.847Z,1533229592.847 [Reporter](INFO): platform_mass_position 0.038294 m 2018-08-02T17:06:33.248Z,1533229593.248 [Reporter](INFO): platform_mass_position 0.038610 m 2018-08-02T17:06:33.636Z,1533229593.636 [Reporter](INFO): platform_mass_position 0.038916 m 2018-08-02T17:06:34.027Z,1533229594.027 [MassServo](FAULT): Reading outside of valid range:427061.000000 2018-08-02T17:06:34.496Z,1533229594.496 [Reporter](INFO): platform_mass_position 0.039172 m 2018-08-02T17:07:00.283Z,1533229620.283 [CommandLine](IMPORTANT): got command maintain clear 2018-08-02T17:07:00.511Z,1533229620.511 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T17:07:01.272Z,1533229621.272 [Reporter](INFO): platform_mass_position 0.038888 m 2018-08-02T17:07:01.628Z,1533229621.628 [Reporter](INFO): platform_mass_position 0.038609 m 2018-08-02T17:07:02.060Z,1533229622.060 [Reporter](INFO): platform_mass_position 0.038271 m 2018-08-02T17:07:02.444Z,1533229622.444 [Reporter](INFO): platform_mass_position 0.037971 m 2018-08-02T17:07:02.836Z,1533229622.836 [Reporter](INFO): platform_mass_position 0.037664 m 2018-08-02T17:07:03.248Z,1533229623.248 [Reporter](INFO): platform_mass_position 0.037342 m 2018-08-02T17:07:03.668Z,1533229623.668 [Reporter](INFO): platform_mass_position 0.037013 m 2018-08-02T17:07:04.040Z,1533229624.040 [Reporter](INFO): platform_mass_position 0.036722 m 2018-08-02T17:07:04.436Z,1533229624.436 [Reporter](INFO): platform_mass_position 0.036412 m 2018-08-02T17:07:04.863Z,1533229624.863 [Reporter](INFO): platform_mass_position 0.036081 m 2018-08-02T17:07:05.252Z,1533229625.252 [Reporter](INFO): platform_mass_position 0.035774 m 2018-08-02T17:07:05.655Z,1533229625.655 [Reporter](INFO): platform_mass_position 0.035461 m 2018-08-02T17:07:06.048Z,1533229626.048 [Reporter](INFO): platform_mass_position 0.035151 m 2018-08-02T17:07:06.448Z,1533229626.448 [Reporter](INFO): platform_mass_position 0.034838 m 2018-08-02T17:07:06.841Z,1533229626.841 [Reporter](INFO): platform_mass_position 0.034531 m 2018-08-02T17:07:07.240Z,1533229627.240 [Reporter](INFO): platform_mass_position 0.034218 m 2018-08-02T17:07:07.632Z,1533229627.632 [Reporter](INFO): platform_mass_position 0.033914 m 2018-08-02T17:07:08.040Z,1533229628.040 [Reporter](INFO): platform_mass_position 0.033592 m 2018-08-02T17:07:08.444Z,1533229628.444 [Reporter](INFO): platform_mass_position 0.033276 m 2018-08-02T17:07:08.824Z,1533229628.824 [Reporter](INFO): platform_mass_position 0.032978 m 2018-08-02T17:07:09.235Z,1533229629.235 [Reporter](INFO): platform_mass_position 0.032657 m 2018-08-02T17:07:09.664Z,1533229629.664 [Reporter](INFO): platform_mass_position 0.032324 m 2018-08-02T17:07:10.016Z,1533229630.016 [Reporter](INFO): platform_mass_position 0.032045 m 2018-08-02T17:07:10.448Z,1533229630.448 [Reporter](INFO): platform_mass_position 0.031707 m 2018-08-02T17:07:10.837Z,1533229630.837 [Reporter](INFO): platform_mass_position 0.031405 m 2018-08-02T17:07:11.248Z,1533229631.248 [Reporter](INFO): platform_mass_position 0.031081 m 2018-08-02T17:07:11.668Z,1533229631.668 [Reporter](INFO): platform_mass_position 0.030753 m 2018-08-02T17:07:12.036Z,1533229632.036 [Reporter](INFO): platform_mass_position 0.030465 m 2018-08-02T17:07:12.432Z,1533229632.432 [Reporter](INFO): platform_mass_position 0.030155 m 2018-08-02T17:07:12.848Z,1533229632.848 [Reporter](INFO): platform_mass_position 0.029832 m 2018-08-02T17:07:13.220Z,1533229633.220 [Reporter](INFO): platform_mass_position 0.029538 m 2018-08-02T17:07:13.648Z,1533229633.648 [Reporter](INFO): platform_mass_position 0.029202 m 2018-08-02T17:07:14.048Z,1533229634.048 [Reporter](INFO): platform_mass_position 0.028890 m 2018-08-02T17:07:14.440Z,1533229634.440 [Reporter](INFO): platform_mass_position 0.028583 m 2018-08-02T17:07:14.864Z,1533229634.864 [Reporter](INFO): platform_mass_position 0.028251 m 2018-08-02T17:07:15.264Z,1533229635.264 [Reporter](INFO): platform_mass_position 0.027938 m 2018-08-02T17:07:15.660Z,1533229635.660 [Reporter](INFO): platform_mass_position 0.027629 m 2018-08-02T17:07:16.040Z,1533229636.040 [Reporter](INFO): platform_mass_position 0.027331 m 2018-08-02T17:07:16.497Z,1533229636.497 [Reporter](INFO): platform_mass_position 0.026974 m 2018-08-02T17:07:16.836Z,1533229636.836 [Reporter](INFO): platform_mass_position 0.026708 m 2018-08-02T17:07:17.223Z,1533229637.223 [Reporter](INFO): platform_mass_position 0.026408 m 2018-08-02T17:07:17.652Z,1533229637.652 [Reporter](INFO): platform_mass_position 0.026070 m 2018-08-02T17:07:18.031Z,1533229638.031 [Reporter](INFO): platform_mass_position 0.025775 m 2018-08-02T17:07:21.256Z,1533229641.256 [Reporter](INFO): platform_mass_position 0.023249 m 2018-08-02T17:07:21.392Z,1533229641.392 [Reporter](INFO): platform_mass_position 0.023143 m 2018-08-02T17:07:21.776Z,1533229641.776 [Reporter](INFO): platform_mass_position 0.022845 m 2018-08-02T17:07:22.193Z,1533229642.193 [Reporter](INFO): platform_mass_position 0.022517 m 2018-08-02T17:07:22.579Z,1533229642.579 [Reporter](INFO): platform_mass_position 0.022217 m 2018-08-02T17:07:22.972Z,1533229642.972 [Reporter](INFO): platform_mass_position 0.021906 m 2018-08-02T17:07:23.380Z,1533229643.380 [Reporter](INFO): platform_mass_position 0.021587 m 2018-08-02T17:07:23.772Z,1533229643.772 [Reporter](INFO): platform_mass_position 0.021280 m 2018-08-02T17:07:24.196Z,1533229644.196 [Reporter](INFO): platform_mass_position 0.020948 m 2018-08-02T17:07:24.580Z,1533229644.580 [Reporter](INFO): platform_mass_position 0.020648 m 2018-08-02T17:07:24.996Z,1533229644.996 [Reporter](INFO): platform_mass_position 0.020322 m 2018-08-02T17:07:25.372Z,1533229645.372 [Reporter](INFO): platform_mass_position 0.020028 m 2018-08-02T17:07:25.800Z,1533229645.800 [Reporter](INFO): platform_mass_position 0.019693 m 2018-08-02T17:07:26.233Z,1533229646.233 [Reporter](INFO): platform_mass_position 0.019358 m 2018-08-02T17:07:26.616Z,1533229646.616 [Reporter](INFO): platform_mass_position 0.019054 m 2018-08-02T17:07:27.004Z,1533229647.004 [Reporter](INFO): platform_mass_position 0.018751 m 2018-08-02T17:07:27.419Z,1533229647.419 [Reporter](INFO): platform_mass_position 0.018425 m 2018-08-02T17:07:27.808Z,1533229647.808 [Reporter](INFO): platform_mass_position 0.018121 m 2018-08-02T17:07:28.203Z,1533229648.203 [Reporter](INFO): platform_mass_position 0.017819 m 2018-08-02T17:07:28.600Z,1533229648.600 [Reporter](INFO): platform_mass_position 0.017502 m 2018-08-02T17:07:29.004Z,1533229649.004 [Reporter](INFO): platform_mass_position 0.017185 m 2018-08-02T17:07:29.408Z,1533229649.408 [Reporter](INFO): platform_mass_position 0.016869 m 2018-08-02T17:07:29.808Z,1533229649.808 [Reporter](INFO): platform_mass_position 0.016556 m 2018-08-02T17:07:30.215Z,1533229650.215 [Reporter](INFO): platform_mass_position 0.016237 m 2018-08-02T17:07:30.588Z,1533229650.588 [Reporter](INFO): platform_mass_position 0.015946 m 2018-08-02T17:07:31.032Z,1533229651.032 [Reporter](INFO): platform_mass_position 0.015598 m 2018-08-02T17:07:31.404Z,1533229651.404 [Reporter](INFO): platform_mass_position 0.015307 m 2018-08-02T17:07:31.852Z,1533229651.852 [Reporter](INFO): platform_mass_position 0.014957 m 2018-08-02T17:07:32.204Z,1533229652.204 [Reporter](INFO): platform_mass_position 0.014681 m 2018-08-02T17:07:32.620Z,1533229652.620 [Reporter](INFO): platform_mass_position 0.014355 m 2018-08-02T17:07:33.008Z,1533229653.008 [Reporter](INFO): platform_mass_position 0.014052 m 2018-08-02T17:07:33.416Z,1533229653.416 [Reporter](INFO): platform_mass_position 0.013739 m 2018-08-02T17:07:33.808Z,1533229653.808 [Reporter](INFO): platform_mass_position 0.013425 m 2018-08-02T17:07:34.208Z,1533229654.208 [Reporter](INFO): platform_mass_position 0.013113 m 2018-08-02T17:07:34.620Z,1533229654.620 [Reporter](INFO): platform_mass_position 0.012796 m 2018-08-02T17:07:35.019Z,1533229655.019 [Reporter](INFO): platform_mass_position 0.012483 m 2018-08-02T17:07:35.408Z,1533229655.408 [Reporter](INFO): platform_mass_position 0.012174 m 2018-08-02T17:07:35.840Z,1533229655.840 [Reporter](INFO): platform_mass_position 0.011835 m 2018-08-02T17:07:36.228Z,1533229656.228 [Reporter](INFO): platform_mass_position 0.011532 m 2018-08-02T17:07:36.640Z,1533229656.640 [Reporter](INFO): platform_mass_position 0.011209 m 2018-08-02T17:07:37.007Z,1533229657.007 [Reporter](INFO): platform_mass_position 0.010922 m 2018-08-02T17:07:37.423Z,1533229657.423 [Reporter](INFO): platform_mass_position 0.010599 m 2018-08-02T17:07:37.796Z,1533229657.796 [Reporter](INFO): platform_mass_position 0.010305 m 2018-08-02T17:07:38.216Z,1533229658.216 [Reporter](INFO): platform_mass_position 0.009995 m 2018-08-02T17:07:54.780Z,1533229674.780 [CommandLine](INFO): End of History 2018-08-02T17:07:54.803Z,1533229674.803 [CommandLine](INFO): End of History 2018-08-02T17:07:54.827Z,1533229674.827 [CommandLine](INFO): End of History 2018-08-02T17:07:54.925Z,1533229674.925 [CommandLine](INFO): End of History 2018-08-02T17:07:55.002Z,1533229675.002 [CommandLine](INFO): End of History 2018-08-02T17:07:55.060Z,1533229675.060 [CommandLine](INFO): End of History 2018-08-02T17:07:55.084Z,1533229675.084 [CommandLine](INFO): End of History 2018-08-02T17:07:55.130Z,1533229675.130 [CommandLine](INFO): End of History 2018-08-02T17:07:55.166Z,1533229675.166 [CommandLine](INFO): End of History 2018-08-02T17:07:55.190Z,1533229675.190 [CommandLine](INFO): End of History 2018-08-02T17:07:55.231Z,1533229675.231 [CommandLine](INFO): End of History 2018-08-02T17:07:55.318Z,1533229675.318 [CommandLine](INFO): End of History 2018-08-02T17:07:55.383Z,1533229675.383 [CommandLine](INFO): End of History 2018-08-02T17:07:55.434Z,1533229675.434 [CommandLine](INFO): End of History 2018-08-02T17:07:55.489Z,1533229675.489 [CommandLine](INFO): End of History 2018-08-02T17:07:55.524Z,1533229675.524 [CommandLine](INFO): End of History 2018-08-02T17:07:55.548Z,1533229675.548 [CommandLine](INFO): End of History 2018-08-02T17:07:55.593Z,1533229675.593 [CommandLine](INFO): End of History 2018-08-02T17:07:55.626Z,1533229675.626 [CommandLine](INFO): End of History 2018-08-02T17:07:55.654Z,1533229675.654 [CommandLine](INFO): End of History 2018-08-02T17:07:55.776Z,1533229675.776 [CommandLine](INFO): End of History 2018-08-02T17:07:55.834Z,1533229675.834 [CommandLine](INFO): End of History 2018-08-02T17:07:55.887Z,1533229675.887 [CommandLine](INFO): End of History 2018-08-02T17:07:55.912Z,1533229675.912 [CommandLine](INFO): End of History 2018-08-02T17:07:55.953Z,1533229675.953 [CommandLine](INFO): End of History 2018-08-02T17:07:55.989Z,1533229675.989 [CommandLine](INFO): End of History 2018-08-02T17:07:56.013Z,1533229676.013 [CommandLine](INFO): End of History 2018-08-02T17:07:56.054Z,1533229676.054 [CommandLine](INFO): End of History 2018-08-02T17:07:56.161Z,1533229676.161 [CommandLine](INFO): End of History 2018-08-02T17:08:17.411Z,1533229697.411 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-08-02T17:08:17.412Z,1533229697.412 [Default:CheckIn:C.Wait] Stopped 2018-08-02T17:08:17.412Z,1533229697.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-08-02T17:08:17.412Z,1533229697.412 [Default:CheckIn:D] Running Loop=1 2018-08-02T17:08:17.779Z,1533229697.779 [Default:CheckIn:D] Stopped 2018-08-02T17:08:17.779Z,1533229697.779 [Default:CheckIn:E] Running Loop=1 2018-08-02T17:08:18.199Z,1533229698.199 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 587.697786 min 2018-08-02T17:08:18.199Z,1533229698.199 [Default:CheckIn:E] Stopped 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn] Stopped 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn](INFO): Running loop #56 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn] Running Loop=56 2018-08-02T17:08:18.200Z,1533229698.200 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-08-02T17:08:18.201Z,1533229698.201 [Default:CheckIn:Read_GPS] Running Loop=1 2018-08-02T17:08:27.768Z,1533229707.768 [NAL9602](INFO): Powering up 2018-08-02T17:08:28.601Z,1533229708.601 [CommandLine](IMPORTANT): got command failComponent 2018-08-02T17:08:28.602Z,1533229708.602 [CommandLine](IMPORTANT): Failed components: 2018-08-02T17:08:28.602Z,1533229708.602 [CommandLine](IMPORTANT): No failed Components. 2018-08-02T17:08:33.305Z,1533229713.305 [CommandLine](IMPORTANT): got command failComponent 2018-08-02T17:08:33.305Z,1533229713.305 [CommandLine](IMPORTANT): Failed components: 2018-08-02T17:08:33.305Z,1533229713.305 [CommandLine](IMPORTANT): No failed Components. 2018-08-02T17:08:36.533Z,1533229716.533 [CommandLine](IMPORTANT): got command show stack 2018-08-02T17:08:36.533Z,1533229716.533 [CommandLine](IMPORTANT): Behavior Stack: 2018-08-02T17:08:36.534Z,1533229716.534 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2018-08-02T17:08:36.534Z,1533229716.534 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS 2018-08-02T17:08:38.930Z,1533229718.930 [NAL9602](INFO): NAL9602 initialized 2018-08-02T17:08:39.620Z,1533229719.620 [CommandLine](IMPORTANT): got command show variable limitaft 2018-08-02T17:08:39.646Z,1533229719.646 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter) 2018-08-02T17:08:45.736Z,1533229725.736 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2018-08-02T17:08:45.740Z,1533229725.740 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -13.000000 mm 2018-08-02T17:09:02.144Z,1533229742.144 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -13.000000 millimeter 2018-08-02T17:09:02.145Z,1533229742.145 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2018-08-02T17:09:02.231Z,1533229742.231 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T17:09:04.696Z,1533229744.696 [Reporter](INFO): platform_mass_position 0.009923 m 2018-08-02T17:09:05.097Z,1533229745.097 [Reporter](INFO): platform_mass_position 0.009611 m 2018-08-02T17:09:05.524Z,1533229745.524 [Reporter](INFO): platform_mass_position 0.009276 m 2018-08-02T17:09:05.904Z,1533229745.904 [Reporter](INFO): platform_mass_position 0.008979 m 2018-08-02T17:09:06.312Z,1533229746.312 [Reporter](INFO): platform_mass_position 0.008660 m 2018-08-02T17:09:06.700Z,1533229746.700 [Reporter](INFO): platform_mass_position 0.008359 m 2018-08-02T17:09:07.104Z,1533229747.104 [Reporter](INFO): platform_mass_position 0.008040 m 2018-08-02T17:09:07.500Z,1533229747.500 [Reporter](INFO): platform_mass_position 0.007730 m 2018-08-02T17:09:07.912Z,1533229747.912 [Reporter](INFO): platform_mass_position 0.007407 m 2018-08-02T17:09:08.300Z,1533229748.300 [Reporter](INFO): platform_mass_position 0.007104 m 2018-08-02T17:09:08.708Z,1533229748.708 [Reporter](INFO): platform_mass_position 0.006784 m 2018-08-02T17:09:09.088Z,1533229749.088 [Reporter](INFO): platform_mass_position 0.006487 m 2018-08-02T17:09:09.491Z,1533229749.491 [Reporter](INFO): platform_mass_position 0.006171 m 2018-08-02T17:09:09.896Z,1533229749.896 [Reporter](INFO): platform_mass_position 0.005855 m 2018-08-02T17:09:10.304Z,1533229750.304 [Reporter](INFO): platform_mass_position 0.005535 m 2018-08-02T17:09:10.696Z,1533229750.696 [Reporter](INFO): platform_mass_position 0.005229 m 2018-08-02T17:09:11.112Z,1533229751.112 [Reporter](INFO): platform_mass_position 0.004903 m 2018-08-02T17:09:11.507Z,1533229751.507 [Reporter](INFO): platform_mass_position 0.004596 m 2018-08-02T17:09:11.916Z,1533229751.916 [Reporter](INFO): platform_mass_position 0.004274 m 2018-08-02T17:09:12.312Z,1533229752.312 [Reporter](INFO): platform_mass_position 0.003964 m 2018-08-02T17:09:12.700Z,1533229752.700 [Reporter](INFO): platform_mass_position 0.003660 m 2018-08-02T17:09:13.104Z,1533229753.104 [Reporter](INFO): platform_mass_position 0.003344 m 2018-08-02T17:09:13.528Z,1533229753.528 [Reporter](INFO): platform_mass_position 0.003012 m 2018-08-02T17:09:13.888Z,1533229753.888 [Reporter](INFO): platform_mass_position 0.002731 m 2018-08-02T17:09:14.312Z,1533229754.312 [Reporter](INFO): platform_mass_position 0.002399 m 2018-08-02T17:09:14.704Z,1533229754.704 [Reporter](INFO): platform_mass_position 0.002091 m 2018-08-02T17:09:15.091Z,1533229755.091 [Reporter](INFO): platform_mass_position 0.001788 m 2018-08-02T17:09:15.499Z,1533229755.499 [Reporter](INFO): platform_mass_position 0.001469 m 2018-08-02T17:09:15.900Z,1533229755.900 [Reporter](INFO): platform_mass_position 0.001156 m 2018-08-02T17:09:16.299Z,1533229756.299 [Reporter](INFO): platform_mass_position 0.000843 m 2018-08-02T17:09:16.704Z,1533229756.704 [Reporter](INFO): platform_mass_position 0.000527 m 2018-08-02T17:09:17.092Z,1533229757.092 [Reporter](INFO): platform_mass_position 0.000223 m 2018-08-02T17:09:17.504Z,1533229757.504 [Reporter](INFO): platform_mass_position -0.000100 m 2018-08-02T17:09:17.908Z,1533229757.908 [Reporter](INFO): platform_mass_position -0.000416 m 2018-08-02T17:09:18.320Z,1533229758.320 [Reporter](INFO): platform_mass_position -0.000738 m 2018-08-02T17:09:21.424Z,1533229761.424 [Reporter](INFO): platform_mass_position -0.003167 m 2018-08-02T17:09:21.560Z,1533229761.560 [Reporter](INFO): platform_mass_position -0.003274 m 2018-08-02T17:09:21.952Z,1533229761.952 [Reporter](INFO): platform_mass_position -0.003581 m 2018-08-02T17:09:22.352Z,1533229762.352 [Reporter](INFO): platform_mass_position -0.003894 m 2018-08-02T17:09:22.748Z,1533229762.748 [Reporter](INFO): platform_mass_position -0.004204 m 2018-08-02T17:09:23.160Z,1533229763.160 [Reporter](INFO): platform_mass_position -0.004526 m 2018-08-02T17:09:23.552Z,1533229763.552 [Reporter](INFO): platform_mass_position -0.004833 m 2018-08-02T17:09:23.936Z,1533229763.936 [Reporter](INFO): platform_mass_position -0.005133 m 2018-08-02T17:09:24.356Z,1533229764.356 [Reporter](INFO): platform_mass_position -0.005459 m 2018-08-02T17:09:24.744Z,1533229764.744 [Reporter](INFO): platform_mass_position -0.005766 m 2018-08-02T17:09:25.148Z,1533229765.148 [Reporter](INFO): platform_mass_position -0.006082 m 2018-08-02T17:09:25.544Z,1533229765.544 [Reporter](INFO): platform_mass_position -0.006392 m 2018-08-02T17:09:25.939Z,1533229765.939 [Reporter](INFO): platform_mass_position -0.006698 m 2018-08-02T17:09:26.356Z,1533229766.356 [Reporter](INFO): platform_mass_position -0.007027 m 2018-08-02T17:09:26.740Z,1533229766.740 [Reporter](INFO): platform_mass_position -0.007327 m 2018-08-02T17:09:27.170Z,1533229767.170 [Reporter](INFO): platform_mass_position -0.007663 m 2018-08-02T17:09:27.544Z,1533229767.544 [Reporter](INFO): platform_mass_position -0.007957 m 2018-08-02T17:09:27.948Z,1533229767.948 [Reporter](INFO): platform_mass_position -0.008273 m 2018-08-02T17:09:28.339Z,1533229768.339 [Reporter](INFO): platform_mass_position -0.008574 m 2018-08-02T17:09:28.740Z,1533229768.740 [Reporter](INFO): platform_mass_position -0.008889 m 2018-08-02T17:09:29.152Z,1533229769.152 [Reporter](INFO): platform_mass_position -0.009215 m 2018-08-02T17:09:29.560Z,1533229769.560 [Reporter](INFO): platform_mass_position -0.009535 m 2018-08-02T17:09:29.944Z,1533229769.944 [Reporter](INFO): platform_mass_position -0.009835 m 2018-08-02T17:09:30.348Z,1533229770.348 [Reporter](INFO): platform_mass_position -0.010151 m 2018-08-02T17:09:30.748Z,1533229770.748 [Reporter](INFO): platform_mass_position -0.010464 m 2018-08-02T17:09:31.152Z,1533229771.152 [Reporter](INFO): platform_mass_position -0.010780 m 2018-08-02T17:09:31.570Z,1533229771.570 [Reporter](INFO): platform_mass_position -0.011106 m 2018-08-02T17:09:31.973Z,1533229771.973 [Reporter](INFO): platform_mass_position -0.011419 m 2018-08-02T17:09:32.336Z,1533229772.336 [Reporter](INFO): platform_mass_position -0.011706 m 2018-08-02T17:09:32.736Z,1533229772.736 [Reporter](INFO): platform_mass_position -0.012020 m 2018-08-02T17:09:33.136Z,1533229773.136 [Reporter](INFO): platform_mass_position -0.012330 m 2018-08-02T17:09:33.544Z,1533229773.544 [Reporter](INFO): platform_mass_position -0.012652 m 2018-08-02T17:09:33.956Z,1533229773.956 [Reporter](INFO): platform_mass_position -0.012975 m 2018-08-02T17:10:04.746Z,1533229804.746 [CommandLine](IMPORTANT): got command maintain clear 2018-08-02T17:10:05.115Z,1533229805.115 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-02T17:10:07.507Z,1533229807.507 [NAL9602](INFO): SBD MO Status=2, MOMSN=22738, MT Status=2, MTMSN=0 2018-08-02T17:10:07.507Z,1533229807.507 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T17:10:07.636Z,1533229807.636 [Reporter](INFO): platform_mass_position -0.012872 m 2018-08-02T17:10:08.012Z,1533229808.012 [Reporter](INFO): platform_mass_position -0.012578 m 2018-08-02T17:10:08.395Z,1533229808.395 [Reporter](INFO): platform_mass_position -0.012280 m 2018-08-02T17:10:08.835Z,1533229808.835 [Reporter](INFO): platform_mass_position -0.011936 m 2018-08-02T17:10:09.196Z,1533229809.196 [Reporter](INFO): platform_mass_position -0.011652 m 2018-08-02T17:10:09.592Z,1533229809.592 [Reporter](INFO): platform_mass_position -0.011345 m 2018-08-02T17:10:10.016Z,1533229810.016 [Reporter](INFO): platform_mass_position -0.011013 m 2018-08-02T17:10:10.412Z,1533229810.412 [Reporter](INFO): platform_mass_position -0.010700 m 2018-08-02T17:10:10.812Z,1533229810.812 [Reporter](INFO): platform_mass_position -0.010387 m 2018-08-02T17:10:11.191Z,1533229811.191 [Reporter](INFO): platform_mass_position -0.010093 m 2018-08-02T17:10:11.628Z,1533229811.628 [Reporter](INFO): platform_mass_position -0.009749 m 2018-08-02T17:10:11.000Z,1533229812.000 [Reporter](INFO): platform_mass_position -0.009457 m 2018-08-02T17:10:12.415Z,1533229812.415 [Reporter](INFO): platform_mass_position -0.009134 m 2018-08-02T17:10:12.811Z,1533229812.811 [Reporter](INFO): platform_mass_position -0.008823 m 2018-08-02T17:10:13.208Z,1533229813.208 [Reporter](INFO): platform_mass_position -0.008512 m 2018-08-02T17:10:13.624Z,1533229813.624 [Reporter](INFO): platform_mass_position -0.008186 m 2018-08-02T17:10:14.028Z,1533229814.028 [Reporter](INFO): platform_mass_position -0.007869 m 2018-08-02T17:10:14.388Z,1533229814.388 [Reporter](INFO): platform_mass_position -0.007588 m 2018-08-02T17:10:14.820Z,1533229814.820 [Reporter](INFO): platform_mass_position -0.007250 m 2018-08-02T17:10:15.204Z,1533229815.204 [Reporter](INFO): platform_mass_position -0.006950 m 2018-08-02T17:10:15.612Z,1533229815.612 [Reporter](INFO): platform_mass_position -0.006631 m 2018-08-02T17:10:16.024Z,1533229816.024 [Reporter](INFO): platform_mass_position -0.006309 m 2018-08-02T17:10:16.420Z,1533229816.420 [Reporter](INFO): platform_mass_position -0.005998 m 2018-08-02T17:10:16.804Z,1533229816.804 [Reporter](INFO): platform_mass_position -0.005698 m 2018-08-02T17:10:17.220Z,1533229817.220 [Reporter](INFO): platform_mass_position -0.005372 m 2018-08-02T17:10:17.624Z,1533229817.624 [Reporter](INFO): platform_mass_position -0.005056 m 2018-08-02T17:10:17.000Z,1533229818.000 [Reporter](INFO): platform_mass_position -0.004762 m 2018-08-02T17:10:18.440Z,1533229818.440 [Reporter](INFO): platform_mass_position -0.004417 m 2018-08-02T17:10:21.548Z,1533229821.548 [Reporter](INFO): platform_mass_position -0.001986 m 2018-08-02T17:10:21.692Z,1533229821.692 [Reporter](INFO): platform_mass_position -0.001872 m 2018-08-02T17:10:22.076Z,1533229822.076 [Reporter](INFO): platform_mass_position -0.001576 m 2018-08-02T17:10:22.484Z,1533229822.484 [Reporter](INFO): platform_mass_position -0.001253 m 2018-08-02T17:10:22.864Z,1533229822.864 [Reporter](INFO): platform_mass_position -0.000956 m 2018-08-02T17:10:23.279Z,1533229823.279 [Reporter](INFO): platform_mass_position -0.000633 m 2018-08-02T17:10:23.672Z,1533229823.672 [Reporter](INFO): platform_mass_position -0.000323 m 2018-08-02T17:10:24.076Z,1533229824.076 [Reporter](INFO): platform_mass_position -0.000007 m 2018-08-02T17:10:24.489Z,1533229824.489 [Reporter](INFO): platform_mass_position 0.000316 m 2018-08-02T17:10:24.863Z,1533229824.863 [Reporter](INFO): platform_mass_position 0.000606 m 2018-08-02T17:10:25.288Z,1533229825.288 [Reporter](INFO): platform_mass_position 0.000942 m 2018-08-02T17:10:25.687Z,1533229825.687 [Reporter](INFO): platform_mass_position 0.001254 m 2018-08-02T17:10:26.088Z,1533229826.088 [Reporter](INFO): platform_mass_position 0.001568 m 2018-08-02T17:10:26.483Z,1533229826.483 [Reporter](INFO): platform_mass_position 0.001874 m 2018-08-02T17:10:26.888Z,1533229826.888 [Reporter](INFO): platform_mass_position 0.002194 m 2018-08-02T17:10:27.276Z,1533229827.276 [Reporter](INFO): platform_mass_position 0.002497 m 2018-08-02T17:10:27.676Z,1533229827.676 [Reporter](INFO): platform_mass_position 0.002811 m 2018-08-02T17:10:28.083Z,1533229828.083 [Reporter](INFO): platform_mass_position 0.003130 m 2018-08-02T17:10:28.471Z,1533229828.471 [Reporter](INFO): platform_mass_position 0.003430 m 2018-08-02T17:10:28.880Z,1533229828.880 [Reporter](INFO): platform_mass_position 0.003753 m 2018-08-02T17:10:29.288Z,1533229829.288 [Reporter](INFO): platform_mass_position 0.004072 m 2018-08-02T17:10:29.711Z,1533229829.711 [Reporter](INFO): platform_mass_position 0.004403 m 2018-08-02T17:10:30.080Z,1533229830.080 [Reporter](INFO): platform_mass_position 0.004692 m 2018-08-02T17:10:30.499Z,1533229830.499 [Reporter](INFO): platform_mass_position 0.005017 m 2018-08-02T17:10:30.892Z,1533229830.892 [Reporter](INFO): platform_mass_position 0.005327 m 2018-08-02T17:10:31.344Z,1533229831.344 [Reporter](INFO): platform_mass_position 0.005681 m 2018-08-02T17:10:31.692Z,1533229831.692 [Reporter](INFO): platform_mass_position 0.005953 m 2018-08-02T17:10:32.084Z,1533229832.084 [Reporter](INFO): platform_mass_position 0.006260 m 2018-08-02T17:10:32.508Z,1533229832.508 [Reporter](INFO): platform_mass_position 0.006592 m 2018-08-02T17:10:32.895Z,1533229832.895 [Reporter](INFO): platform_mass_position 0.006895 m 2018-08-02T17:10:33.296Z,1533229833.296 [Reporter](INFO): platform_mass_position 0.007209 m 2018-08-02T17:10:33.668Z,1533229833.668 [Reporter](INFO): platform_mass_position 0.007500 m 2018-08-02T17:10:34.075Z,1533229834.075 [Reporter](INFO): platform_mass_position 0.007819 m 2018-08-02T17:10:34.471Z,1533229834.471 [Reporter](INFO): platform_mass_position 0.008126 m 2018-08-02T17:10:34.956Z,1533229834.956 [Reporter](INFO): platform_mass_position 0.008508 m 2018-08-02T17:10:35.287Z,1533229835.287 [Reporter](INFO): platform_mass_position 0.008767 m 2018-08-02T17:10:35.668Z,1533229835.668 [Reporter](INFO): platform_mass_position 0.009065 m 2018-08-02T17:10:36.084Z,1533229836.084 [Reporter](INFO): platform_mass_position 0.009391 m 2018-08-02T17:10:36.480Z,1533229836.480 [Reporter](INFO): platform_mass_position 0.009700 m 2018-08-02T17:10:36.880Z,1533229836.880 [Reporter](INFO): platform_mass_position 0.010004 m 2018-08-02T17:10:58.278Z,1533229858.278 [CommandLine](IMPORTANT): got command show stack 2018-08-02T17:10:58.278Z,1533229858.278 [CommandLine](IMPORTANT): Behavior Stack: 2018-08-02T17:10:58.278Z,1533229858.278 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2018-08-02T17:10:58.278Z,1533229858.278 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS 2018-08-02T17:10:59.967Z,1533229859.967 [NAL9602](INFO): SBD MO Status=2, MOMSN=22738, MT Status=2, MTMSN=0 2018-08-02T17:10:59.968Z,1533229859.968 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-08-02T17:11:04.289Z,1533229864.289 [CommandLine](IMPORTANT): got command report 2018-08-02T17:11:04.290Z,1533229864.290 [Reporter](INFO): platform_buoyancy_position reporting changes 2018-08-02T17:11:04.290Z,1533229864.290 [Reporter](INFO): platform_mass_position reporting changes 2018-08-02T17:11:06.255Z,1533229866.255 [CommandLine](IMPORTANT): got command report clear 2018-08-02T17:11:13.016Z,1533229873.016 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2018-08-02T17:11:13.257Z,1533229873.257 [Reporter](INFO): platform_buoyancy_position 952.176983 cc 2018-08-02T17:11:22.491Z,1533229882.491 [DataOverHttps](IMPORTANT): SBD MTMSN=20180802T170416 2018-08-02T17:11:22.514Z,1533229882.514 [NAL9602](INFO): SBD MO Status=2, MOMSN=22738, MT Status=2, MTMSN=0 2018-08-02T17:11:22.515Z,1533229882.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2