2018-07-30T21:50:57.546Z,1532987457.546 [CommandLine](IMPORTANT): got command restart logs 2018-07-30T21:51:03.874Z,1532987463.874 [NAL9602](INFO): Powering down 2018-07-30T21:52:22.498Z,1532987542.498 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T21:52:22.498Z,1532987542.498 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-30T21:52:22.498Z,1532987542.498 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T21:52:22.513Z,1532987542.513 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T21:52:22.877Z,1532987542.877 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T21:52:22.877Z,1532987542.877 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-30T21:52:23.268Z,1532987543.268 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T21:52:23.273Z,1532987543.273 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T21:52:23.273Z,1532987543.273 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T21:55:23.285Z,1532987723.285 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T21:55:23.286Z,1532987723.286 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-30T21:55:23.286Z,1532987723.286 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T21:55:23.317Z,1532987723.317 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T21:55:23.673Z,1532987723.673 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T21:55:23.673Z,1532987723.673 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-30T21:55:24.055Z,1532987724.055 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T21:55:24.055Z,1532987724.055 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T21:55:24.056Z,1532987724.056 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T21:55:33.669Z,1532987733.669 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-30T21:55:33.669Z,1532987733.669 [Default:CheckIn:C.Wait] Stopped 2018-07-30T21:55:33.669Z,1532987733.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-30T21:55:33.669Z,1532987733.669 [Default:CheckIn:D] Running Loop=1 2018-07-30T21:55:34.063Z,1532987734.063 [Default:CheckIn:D] Stopped 2018-07-30T21:55:34.063Z,1532987734.063 [Default:CheckIn:E] Running Loop=1 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.459481 min 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn:E] Stopped 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn] Stopped 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T21:55:34.474Z,1532987734.474 [Default:CheckIn](INFO): Running loop #6 2018-07-30T21:55:34.475Z,1532987734.475 [Default:CheckIn] Running Loop=6 2018-07-30T21:55:34.475Z,1532987734.475 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-30T21:55:34.475Z,1532987734.475 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-30T21:55:35.231Z,1532987735.231 [NAL9602](INFO): Powering up 2018-07-30T21:55:45.640Z,1532987745.640 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-07-30T21:55:45.640Z,1532987745.640 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-2768,-32768,V 2018-07-30T21:55:46.415Z,1532987746.415 [NAL9602](INFO): NAL9602 initialized 2018-07-30T21:56:02.959Z,1532987762.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=572, MT Status=0, MTMSN=0 2018-07-30T21:56:02.960Z,1532987762.960 [NAL9602](INFO): No messages in MT queue 2018-07-30T21:56:34.170Z,1532987794.170 [NAL9602](INFO): GPS fix at 20180730T215632: (36.805655, -121.792258) 2018-07-30T21:56:34.249Z,1532987794.249 [Default:CheckIn:Read_GPS] Stopped 2018-07-30T21:56:34.249Z,1532987794.249 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-30T21:56:38.878Z,1532987798.878 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0037.lzma 2018-07-30T21:56:39.661Z,1532987799.661 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0037.lzma.bak 2018-07-30T21:56:39.661Z,1532987799.661 [DataOverHttps](INFO): SBD MOMSN=8386935 2018-07-30T21:56:48.727Z,1532987808.727 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180730T215057/Courier0000.lzma 2018-07-30T21:56:49.473Z,1532987809.473 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Courier0000.lzma.bak 2018-07-30T21:56:49.473Z,1532987809.473 [DataOverHttps](INFO): SBD MOMSN=8386937 2018-07-30T21:56:58.769Z,1532987818.769 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T215057/Courier0003.lzma 2018-07-30T21:56:59.548Z,1532987819.548 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Courier0003.lzma.bak 2018-07-30T21:56:59.549Z,1532987819.549 [DataOverHttps](INFO): SBD MOMSN=8386941 2018-07-30T21:57:09.667Z,1532987829.667 [NAL9602](INFO): Powering down 2018-07-30T21:57:09.845Z,1532987829.845 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20180730T203657/Express0038.lzma 2018-07-30T21:57:10.602Z,1532987830.602 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0038.lzma.bak 2018-07-30T21:57:10.602Z,1532987830.602 [DataOverHttps](INFO): SBD MOMSN=8386943 2018-07-30T21:57:20.932Z,1532987840.932 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20180730T215057/Express0001.lzma 2018-07-30T21:57:21.724Z,1532987841.724 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Express0001.lzma.bak 2018-07-30T21:57:21.725Z,1532987841.725 [DataOverHttps](INFO): SBD MOMSN=8386946 2018-07-30T21:57:31.405Z,1532987851.405 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T215057/Express0004.lzma 2018-07-30T21:57:32.202Z,1532987852.202 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Express0004.lzma.bak 2018-07-30T21:57:32.202Z,1532987852.202 [DataOverHttps](INFO): SBD MOMSN=8386950 2018-07-30T21:57:32.716Z,1532987852.716 [Default:CheckIn:Read_Iridium] Stopped 2018-07-30T21:57:32.716Z,1532987852.716 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-30T21:57:32.716Z,1532987852.716 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-30T21:58:24.389Z,1532987904.389 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T21:58:24.389Z,1532987904.389 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-30T21:58:24.389Z,1532987904.389 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T21:58:24.411Z,1532987904.411 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T21:58:24.776Z,1532987904.776 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T21:58:24.777Z,1532987904.777 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-30T21:58:25.164Z,1532987905.164 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T21:58:25.164Z,1532987905.164 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T21:58:25.169Z,1532987905.169 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T21:58:37.733Z,1532987917.733 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-07-30T22:01:25.343Z,1532988085.343 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:01:25.343Z,1532988085.343 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-30T22:01:25.343Z,1532988085.343 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:01:25.381Z,1532988085.381 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:01:25.750Z,1532988085.750 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:01:25.750Z,1532988085.750 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-30T22:01:26.135Z,1532988086.135 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:01:26.135Z,1532988086.135 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:01:26.136Z,1532988086.136 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:02:33.066Z,1532988153.066 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-30T22:02:33.067Z,1532988153.067 [Default:CheckIn:C.Wait] Stopped 2018-07-30T22:02:33.067Z,1532988153.067 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-30T22:02:33.068Z,1532988153.068 [Default:CheckIn:D] Running Loop=1 2018-07-30T22:02:33.467Z,1532988153.467 [Default:CheckIn:D] Stopped 2018-07-30T22:02:33.467Z,1532988153.467 [Default:CheckIn:E] Running Loop=1 2018-07-30T22:02:33.863Z,1532988153.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.449552 min 2018-07-30T22:02:33.863Z,1532988153.863 [Default:CheckIn:E] Stopped 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn] Stopped 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn](INFO): Running loop #7 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn] Running Loop=7 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-30T22:02:33.864Z,1532988153.864 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-30T22:02:37.198Z,1532988157.198 [NAL9602](INFO): Powering up 2018-07-30T22:02:48.374Z,1532988168.374 [NAL9602](INFO): NAL9602 initialized 2018-07-30T22:03:21.580Z,1532988201.580 [NAL9602](INFO): SBD MO Status=2, MOMSN=573, MT Status=2, MTMSN=0 2018-07-30T22:03:21.580Z,1532988201.580 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-30T22:03:56.476Z,1532988236.476 [NAL9602](INFO): SBD MO Status=0, MOMSN=573, MT Status=0, MTMSN=0 2018-07-30T22:03:56.476Z,1532988236.476 [NAL9602](INFO): No messages in MT queue 2018-07-30T22:04:03.262Z,1532988243.262 [NAL9602](INFO): GPS fix at 20180730T220401: (36.802727, -121.786962) 2018-07-30T22:04:03.343Z,1532988243.343 [Default:CheckIn:Read_GPS] Stopped 2018-07-30T22:04:03.343Z,1532988243.343 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-30T22:04:08.279Z,1532988248.279 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180730T215057/Courier0006.lzma 2018-07-30T22:04:09.005Z,1532988249.005 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Courier0006.lzma.bak 2018-07-30T22:04:09.005Z,1532988249.005 [DataOverHttps](INFO): SBD MOMSN=8386956 2018-07-30T22:04:18.467Z,1532988258.467 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180730T215057/Express0007.lzma 2018-07-30T22:04:19.261Z,1532988259.261 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Express0007.lzma.bak 2018-07-30T22:04:19.261Z,1532988259.261 [DataOverHttps](INFO): SBD MOMSN=8386959 2018-07-30T22:04:19.781Z,1532988259.781 [Default:CheckIn:Read_Iridium] Stopped 2018-07-30T22:04:19.781Z,1532988259.781 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-30T22:04:19.781Z,1532988259.781 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-30T22:04:26.380Z,1532988266.380 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:04:26.385Z,1532988266.385 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-30T22:04:26.385Z,1532988266.385 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:04:26.422Z,1532988266.422 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:04:26.818Z,1532988266.818 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:04:26.818Z,1532988266.818 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-30T22:04:27.171Z,1532988267.171 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:04:27.171Z,1532988267.171 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:04:27.172Z,1532988267.172 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:04:38.322Z,1532988278.322 [NAL9602](INFO): Powering down 2018-07-30T22:07:12.577Z,1532988432.577 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-07-30T22:07:12.577Z,1532988432.577 [DropWeight] Hardware Fault, FailCount= 1 2018-07-30T22:07:12.577Z,1532988432.577 [DropWeight](ERROR): Hardware Fault 2018-07-30T22:07:12.665Z,1532988432.665 [CommandLine](FAULT): Scheduling is paused 2018-07-30T22:07:12.665Z,1532988432.665 [CBIT](INFO): Critical error at 20180730T220712 2018-07-30T22:07:12.667Z,1532988432.667 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-07-30T22:07:12.668Z,1532988432.668 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-07-30T22:07:13.047Z,1532988433.047 [CBIT](INFO): Critical error at 20180730T220712 2018-07-30T22:07:27.201Z,1532988447.201 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:07:27.201Z,1532988447.201 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-30T22:07:27.201Z,1532988447.201 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:07:27.227Z,1532988447.227 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:07:27.634Z,1532988447.634 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:07:27.634Z,1532988447.634 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-30T22:07:27.990Z,1532988447.990 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:07:27.990Z,1532988447.990 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:07:27.991Z,1532988447.991 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:08:42.097Z,1532988522.097 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-07-30T22:08:42.097Z,1532988522.097 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18073022095077,35.0, -0.1, 0.0 0 2018-07-30T22:09:20.494Z,1532988560.494 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-30T22:09:20.494Z,1532988560.494 [Default:CheckIn:C.Wait] Stopped 2018-07-30T22:09:20.495Z,1532988560.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-30T22:09:20.495Z,1532988560.495 [Default:CheckIn:D] Running Loop=1 2018-07-30T22:09:20.887Z,1532988560.887 [Default:CheckIn:D] Stopped 2018-07-30T22:09:20.887Z,1532988560.887 [Default:CheckIn:E] Running Loop=1 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.239889 min 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn:E] Stopped 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn] Stopped 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T22:09:24.090Z,1532988564.090 [Default:CheckIn](INFO): Running loop #8 2018-07-30T22:09:24.091Z,1532988564.091 [Default:CheckIn] Running Loop=8 2018-07-30T22:09:24.091Z,1532988564.091 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-30T22:09:24.091Z,1532988564.091 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-30T22:09:24.541Z,1532988564.541 [NAL9602](INFO): Powering up 2018-07-30T22:09:35.742Z,1532988575.742 [NAL9602](INFO): NAL9602 initialized 2018-07-30T22:09:52.943Z,1532988592.943 [NAL9602](INFO): SBD MO Status=0, MOMSN=574, MT Status=0, MTMSN=0 2018-07-30T22:09:52.943Z,1532988592.943 [NAL9602](INFO): No messages in MT queue 2018-07-30T22:10:02.141Z,1532988602.141 [NAL9602](INFO): GPS fix at 20180730T221000: (36.802694, -121.786985) 2018-07-30T22:10:02.221Z,1532988602.221 [Default:CheckIn:Read_GPS] Stopped 2018-07-30T22:10:02.222Z,1532988602.222 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-30T22:10:06.765Z,1532988606.765 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20180730T215057/Courier0009.lzma 2018-07-30T22:10:07.501Z,1532988607.501 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Courier0009.lzma.bak 2018-07-30T22:10:07.501Z,1532988607.501 [DataOverHttps](INFO): SBD MOMSN=8386968 2018-07-30T22:10:16.585Z,1532988616.585 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20180730T215057/Express0010.lzma 2018-07-30T22:10:17.357Z,1532988617.357 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Express0010.lzma.bak 2018-07-30T22:10:17.357Z,1532988617.357 [DataOverHttps](INFO): SBD MOMSN=8386973 2018-07-30T22:10:17.900Z,1532988617.900 [Default:CheckIn:Read_Iridium] Stopped 2018-07-30T22:10:17.901Z,1532988617.901 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-30T22:10:17.901Z,1532988617.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-30T22:10:28.113Z,1532988628.113 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:10:28.113Z,1532988628.113 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-30T22:10:28.113Z,1532988628.113 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:10:28.140Z,1532988628.140 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:10:28.518Z,1532988628.518 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:10:28.518Z,1532988628.518 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-30T22:10:28.878Z,1532988628.878 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:10:28.879Z,1532988628.879 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:10:28.879Z,1532988628.879 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:10:36.849Z,1532988636.849 [NAL9602](INFO): Powering down 2018-07-30T22:13:23.372Z,1532988803.372 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-07-30T22:13:29.000Z,1532988809.000 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:13:29.001Z,1532988809.001 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-30T22:13:29.001Z,1532988809.001 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:13:29.015Z,1532988809.015 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:13:29.437Z,1532988809.437 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:13:29.437Z,1532988809.437 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-30T22:13:29.799Z,1532988809.799 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:13:29.800Z,1532988809.800 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:13:29.800Z,1532988809.800 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:15:18.492Z,1532988918.492 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-30T22:15:18.492Z,1532988918.492 [Default:CheckIn:C.Wait] Stopped 2018-07-30T22:15:18.492Z,1532988918.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-30T22:15:18.492Z,1532988918.492 [Default:CheckIn:D] Running Loop=1 2018-07-30T22:15:18.902Z,1532988918.902 [Default:CheckIn:D] Stopped 2018-07-30T22:15:18.903Z,1532988918.903 [Default:CheckIn:E] Running Loop=1 2018-07-30T22:15:19.299Z,1532988919.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.206807 min 2018-07-30T22:15:19.299Z,1532988919.299 [Default:CheckIn:E] Stopped 2018-07-30T22:15:19.299Z,1532988919.299 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-30T22:15:19.299Z,1532988919.299 [Default:CheckIn] Stopped 2018-07-30T22:15:19.300Z,1532988919.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T22:15:19.300Z,1532988919.300 [Default:CheckIn](INFO): Running loop #9 2018-07-30T22:15:19.300Z,1532988919.300 [Default:CheckIn] Running Loop=9 2018-07-30T22:15:19.300Z,1532988919.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-30T22:15:19.300Z,1532988919.300 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-30T22:15:22.562Z,1532988922.562 [NAL9602](INFO): Powering up 2018-07-30T22:15:33.726Z,1532988933.726 [NAL9602](INFO): NAL9602 initialized 2018-07-30T22:15:51.331Z,1532988951.331 [NAL9602](INFO): SBD MO Status=0, MOMSN=575, MT Status=0, MTMSN=0 2018-07-30T22:15:51.332Z,1532988951.332 [NAL9602](INFO): No messages in MT queue 2018-07-30T22:16:29.876Z,1532988989.876 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:16:29.877Z,1532988989.877 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-07-30T22:16:29.877Z,1532988989.877 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:16:29.899Z,1532988989.899 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:16:30.303Z,1532988990.303 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:16:30.303Z,1532988990.303 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-07-30T22:16:30.687Z,1532988990.687 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:16:30.687Z,1532988990.687 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:16:30.687Z,1532988990.687 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:18:24.378Z,1532989104.378 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-07-30T22:19:30.932Z,1532989170.932 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:19:30.936Z,1532989170.936 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2018-07-30T22:19:30.937Z,1532989170.937 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:19:30.963Z,1532989170.963 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:19:31.364Z,1532989171.364 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:19:31.364Z,1532989171.364 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2018-07-30T22:19:31.719Z,1532989171.719 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:19:31.720Z,1532989171.720 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:19:31.720Z,1532989171.720 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:20:19.356Z,1532989219.356 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-30T22:15:19.3Z 2018-07-30T22:20:19.356Z,1532989219.356 [Default:CheckIn:Read_GPS] Stopped 2018-07-30T22:20:19.357Z,1532989219.357 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-30T22:20:23.560Z,1532989223.560 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T215057/Courier0012.lzma 2018-07-30T22:20:24.301Z,1532989224.301 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Courier0012.lzma.bak 2018-07-30T22:20:24.301Z,1532989224.301 [DataOverHttps](INFO): SBD MOMSN=8386988 2018-07-30T22:20:33.910Z,1532989233.910 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20180730T215057/Express0013.lzma 2018-07-30T22:20:34.691Z,1532989234.691 [DataOverHttps](INFO): Moved sent file to Logs/20180730T215057/Express0013.lzma.bak 2018-07-30T22:20:34.691Z,1532989234.691 [DataOverHttps](INFO): SBD MOMSN=8386990 2018-07-30T22:20:35.214Z,1532989235.214 [Default:CheckIn:Read_Iridium] Stopped 2018-07-30T22:20:35.214Z,1532989235.214 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-30T22:20:35.215Z,1532989235.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-30T22:20:55.301Z,1532989255.301 [NAL9602](INFO): Powering down 2018-07-30T22:22:12.901Z,1532989332.901 [CBIT](INFO): Clearing failed state for component DropWeight 2018-07-30T22:22:12.901Z,1532989332.901 [DropWeight] No Fault, FailCount= 1 2018-07-30T22:22:32.097Z,1532989352.097 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:22:32.097Z,1532989352.097 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2018-07-30T22:22:32.097Z,1532989352.097 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:22:32.133Z,1532989352.133 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:22:32.513Z,1532989352.513 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:22:32.513Z,1532989352.513 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2018-07-30T22:22:32.871Z,1532989352.871 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:22:32.871Z,1532989352.871 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:22:32.871Z,1532989352.871 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:25:33.092Z,1532989533.092 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-30T22:25:33.092Z,1532989533.092 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2018-07-30T22:25:33.105Z,1532989533.105 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-30T22:25:33.147Z,1532989533.147 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-30T22:25:33.542Z,1532989533.542 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-30T22:25:33.542Z,1532989533.542 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2018-07-30T22:25:33.880Z,1532989533.880 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-30T22:25:33.880Z,1532989533.880 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-30T22:25:33.881Z,1532989533.881 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-30T22:25:35.927Z,1532989535.927 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-30T22:25:35.927Z,1532989535.927 [Default:CheckIn:C.Wait] Stopped 2018-07-30T22:25:35.927Z,1532989535.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-30T22:25:35.927Z,1532989535.927 [Default:CheckIn:D] Running Loop=1 2018-07-30T22:25:36.294Z,1532989536.294 [Default:CheckIn:D] Stopped 2018-07-30T22:25:36.294Z,1532989536.294 [Default:CheckIn:E] Running Loop=1 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.496663 min 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn:E] Stopped 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn] Stopped 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T22:25:36.751Z,1532989536.751 [Default:CheckIn](INFO): Running loop #10 2018-07-30T22:25:36.752Z,1532989536.752 [Default:CheckIn] Running Loop=10 2018-07-30T22:25:36.752Z,1532989536.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-30T22:25:36.752Z,1532989536.752 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-30T22:25:45.088Z,1532989545.088 [NAL9602](INFO): Powering up 2018-07-30T22:25:56.246Z,1532989556.246 [NAL9602](INFO): NAL9602 initialized 2018-07-30T22:25:57.051Z,1532989557.051 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-07-30T22:25:57.052Z,1532989557.052 [NAL9602] Data Fault, FailCount= 1 2018-07-30T22:25:57.052Z,1532989557.052 [NAL9602](ERROR): Data Fault 2018-07-30T22:25:57.171Z,1532989557.171 [CBIT](ERROR): Data Fault in component: NAL9602 2018-07-30T22:25:57.463Z,1532989557.463 [NAL9602](INFO): Powering down 2018-07-30T22:25:58.399Z,1532989558.399 [CBIT](INFO): Clearing failed state for component NAL9602 2018-07-30T22:25:58.399Z,1532989558.399 [NAL9602] No Fault, FailCount= 1 2018-07-30T22:26:13.132Z,1532989573.132 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,G,0e88,0e88, 16.102, 0.027, 65.535 2018-07-30T22:26:18.646Z,1532989578.646 [CommandLine](IMPORTANT): got command quit 2018-07-30T22:26:19.652Z,1532989579.652 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:19.652Z,1532989579.652 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.785Z,1532989579.785 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-07-30T22:26:19.785Z,1532989579.785 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.785Z,1532989579.785 [CommandLine](INFO): Join timeout helper Thread ID is 2429 2018-07-30T22:26:19.789Z,1532989579.789 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-07-30T22:26:19.789Z,1532989579.789 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.789Z,1532989579.789 [NavChartDb](INFO): Join timeout helper Thread ID is 2430 2018-07-30T22:26:19.797Z,1532989579.797 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:19.797Z,1532989579.797 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.808Z,1532989579.808 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-07-30T22:26:19.808Z,1532989579.808 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.809Z,1532989579.809 [Radio_Surface](INFO): Join timeout helper Thread ID is 2431 2018-07-30T22:26:19.973Z,1532989579.973 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:19.973Z,1532989579.973 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.977Z,1532989579.977 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-07-30T22:26:19.977Z,1532989579.977 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:19.977Z,1532989579.977 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2432 2018-07-30T22:26:20.173Z,1532989580.173 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:20.173Z,1532989580.173 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.189Z,1532989580.189 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-07-30T22:26:20.189Z,1532989580.189 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.190Z,1532989580.190 [logger](INFO): Join timeout helper Thread ID is 2433 2018-07-30T22:26:20.190Z,1532989580.190 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:20.190Z,1532989580.190 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.191Z,1532989580.191 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-07-30T22:26:20.191Z,1532989580.191 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.192Z,1532989580.192 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-07-30T22:26:20.192Z,1532989580.192 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.192Z,1532989580.192 [controlThread](INFO): Join timeout helper Thread ID is 2434 2018-07-30T22:26:20.225Z,1532989580.225 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-30T22:26:20.225Z,1532989580.225 [controlThread](DEBUG): Uninitializing ControlThread 2018-07-30T22:26:20.226Z,1532989580.226 [Aanderaa_O2](INFO): Powering down 2018-07-30T22:26:20.227Z,1532989580.227 [AHRS_M2](INFO): Powering down 2018-07-30T22:26:20.297Z,1532989580.297 [NAL9602](INFO): Powering down 2018-07-30T22:26:20.369Z,1532989580.369 [RDI_Pathfinder](INFO): Powering down 2018-07-30T22:26:20.370Z,1532989580.370 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-30T22:26:20.371Z,1532989580.371 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-07-30T22:26:20.373Z,1532989580.373 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-07-30T22:26:20.373Z,1532989580.373 [MissionManager](INFO): Uninitializing Mission Default 2018-07-30T22:26:20.373Z,1532989580.373 [Default] Stopped 2018-07-30T22:26:20.373Z,1532989580.373 [Default](DEBUG): Aggregate::uninitialize Default 2018-07-30T22:26:20.374Z,1532989580.374 [Default:B.GoToSurface] Stopped 2018-07-30T22:26:20.374Z,1532989580.374 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-30T22:26:20.374Z,1532989580.374 [Default:CheckIn] Stopped 2018-07-30T22:26:20.374Z,1532989580.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-30T22:26:20.374Z,1532989580.374 [Default:CheckIn:Read_GPS] Stopped 2018-07-30T22:26:20.378Z,1532989580.378 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-07-30T22:26:20.378Z,1532989580.378 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-07-30T22:26:20.378Z,1532989580.378 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-07-30T22:26:20.379Z,1532989580.379 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-07-30T22:26:20.379Z,1532989580.379 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-30T22:26:20.379Z,1532989580.379 [BuoyancyServo](INFO): Powering down 2018-07-30T22:26:20.395Z,1532989580.395 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-07-30T22:26:20.395Z,1532989580.395 [ElevatorServo](INFO): Powering down 2018-07-30T22:26:20.396Z,1532989580.396 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-07-30T22:26:20.396Z,1532989580.396 [MassServo](INFO): Powering down 2018-07-30T22:26:20.397Z,1532989580.397 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-07-30T22:26:20.397Z,1532989580.397 [RudderServo](INFO): Powering down 2018-07-30T22:26:20.398Z,1532989580.398 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-07-30T22:26:20.398Z,1532989580.398 [ThrusterServo](INFO): Powering down 2018-07-30T22:26:20.399Z,1532989580.399 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-07-30T22:26:20.400Z,1532989580.400 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-07-30T22:26:20.400Z,1532989580.400 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-07-30T22:26:20.401Z,1532989580.401 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.506Z,1532989580.506 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.642Z,1532989580.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.723Z,1532989580.723 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-30T22:26:20.886Z,1532989580.886 [logger ThreadHandler](INFO): Thread cancelled.