2016-12-19T23:50:09.524Z,1482191409.524 [CommandLine](IMPORTANT): got command restart logs
2016-12-19T23:50:10.758Z,1482191410.758 [DataOverHttps](INFO): Moved sent file to Logs/20161219T223117/Courier0036.lzma.bak
2016-12-19T23:50:10.759Z,1482191410.759 [DataOverHttps](INFO): SBD MOMSN=4634516
2016-12-19T23:50:10.891Z,1482191410.891 [BPC1](ERROR): Failed to parse bank A battery data
2016-12-19T23:50:10.891Z,1482191410.891 [BPC1] Data Fault, FailCount= 1
2016-12-19T23:50:10.891Z,1482191410.891 [BPC1](ERROR): Data Fault
2016-12-19T23:50:11.039Z,1482191411.039 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-19T23:50:28.833Z,1482191428.833 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:50:28.833Z,1482191428.833 [BPC1] No Fault, FailCount= 1
2016-12-19T23:50:38.326Z,1482191438.326 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20161219T223117/Courier0040.lzma
2016-12-19T23:50:39.106Z,1482191439.106 [DataOverHttps](INFO): Moved sent file to Logs/20161219T223117/Courier0040.lzma.bak
2016-12-19T23:50:39.106Z,1482191439.106 [DataOverHttps](INFO): SBD MOMSN=4634520
2016-12-19T23:50:51.900Z,1482191451.900 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20161219T235009/Courier0001.lzma
2016-12-19T23:50:52.694Z,1482191452.694 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0001.lzma.bak
2016-12-19T23:50:52.694Z,1482191452.694 [DataOverHttps](INFO): SBD MOMSN=4634522
2016-12-19T23:51:07.018Z,1482191467.018 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20161219T223117/Express0037.lzma
2016-12-19T23:51:07.803Z,1482191467.803 [DataOverHttps](INFO): Moved sent file to Logs/20161219T223117/Express0037.lzma.bak
2016-12-19T23:51:07.803Z,1482191467.803 [DataOverHttps](INFO): SBD MOMSN=4634526
2016-12-19T23:51:23.851Z,1482191483.851 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20161219T223117/Express0041.lzma
2016-12-19T23:51:24.655Z,1482191484.655 [DataOverHttps](INFO): Moved sent file to Logs/20161219T223117/Express0041.lzma.bak
2016-12-19T23:51:24.655Z,1482191484.655 [DataOverHttps](INFO): SBD MOMSN=4634529
2016-12-19T23:51:33.803Z,1482191493.803 [BPC1](ERROR): Failed to receive battery data
2016-12-19T23:51:33.803Z,1482191493.803 [BPC1] Communications Fault, FailCount= 1
2016-12-19T23:51:33.803Z,1482191493.803 [BPC1](ERROR): Communications Fault
2016-12-19T23:51:33.910Z,1482191493.910 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-19T23:51:33.911Z,1482191493.911 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-19T23:51:34.802Z,1482191494.802 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:51:34.802Z,1482191494.802 [BPC1] No Fault, FailCount= 1
2016-12-19T23:51:41.295Z,1482191501.295 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20161219T235009/Express0002.lzma
2016-12-19T23:51:42.026Z,1482191502.026 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0002.lzma.bak
2016-12-19T23:51:42.028Z,1482191502.028 [DataOverHttps](INFO): SBD MOMSN=4634532
2016-12-19T23:51:42.740Z,1482191502.740 [Default:CheckIn:Read_Iridium] Stopped
2016-12-19T23:51:42.744Z,1482191502.744 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-19T23:51:42.744Z,1482191502.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-19T23:52:02.620Z,1482191522.620 [PNI_TCM](ERROR): CRC does not match. Expected:0x36761 got:0x16942
2016-12-19T23:52:35.435Z,1482191555.435 [BPC1](ERROR): Failed to parse bank A battery data
2016-12-19T23:52:35.435Z,1482191555.435 [BPC1] Data Fault, FailCount= 1
2016-12-19T23:52:35.435Z,1482191555.435 [BPC1](ERROR): Data Fault
2016-12-19T23:52:35.549Z,1482191555.549 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-19T23:52:36.642Z,1482191556.642 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:52:36.643Z,1482191556.643 [BPC1] No Fault, FailCount= 1
2016-12-19T23:53:50.853Z,1482191630.853 [BPC1](ERROR): Failed to receive battery data
2016-12-19T23:53:50.853Z,1482191630.853 [BPC1] Communications Fault, FailCount= 1
2016-12-19T23:53:50.853Z,1482191630.853 [BPC1](ERROR): Communications Fault
2016-12-19T23:53:50.901Z,1482191630.901 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-19T23:53:52.082Z,1482191632.082 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:53:52.082Z,1482191632.082 [BPC1] No Fault, FailCount= 1
2016-12-19T23:55:00.761Z,1482191700.761 [BPC1](ERROR): Failed to parse message.
2016-12-19T23:55:00.762Z,1482191700.762 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-19T23:55:00.762Z,1482191700.762 [BPC1] Data Fault, FailCount= 1
2016-12-19T23:55:00.762Z,1482191700.762 [BPC1](ERROR): Data Fault
2016-12-19T23:55:00.868Z,1482191700.868 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-19T23:55:01.979Z,1482191701.979 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:55:01.979Z,1482191701.979 [BPC1] No Fault, FailCount= 1
2016-12-19T23:55:58.542Z,1482191758.542 [BPC1](ERROR): Failed to receive battery data
2016-12-19T23:55:58.542Z,1482191758.542 [BPC1] Communications Fault, FailCount= 1
2016-12-19T23:55:58.542Z,1482191758.542 [BPC1](ERROR): Communications Fault
2016-12-19T23:55:58.621Z,1482191758.621 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-19T23:55:59.769Z,1482191759.769 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:55:59.769Z,1482191759.769 [BPC1] No Fault, FailCount= 1
2016-12-19T23:56:42.986Z,1482191802.986 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-19T23:56:42.994Z,1482191802.994 [Default:CheckIn:C.Wait] Stopped
2016-12-19T23:56:42.994Z,1482191802.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-19T23:56:42.995Z,1482191802.995 [Default:CheckIn:D] Running Loop=1
2016-12-19T23:56:43.367Z,1482191803.367 [Default:CheckIn:D] Stopped
2016-12-19T23:56:43.367Z,1482191803.367 [Default:CheckIn:E] Running Loop=1
2016-12-19T23:56:43.769Z,1482191803.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.030505 min 
2016-12-19T23:56:43.769Z,1482191803.769 [Default:CheckIn:E] Stopped
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn] Stopped
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn](INFO): Running loop #6
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn] Running Loop=6
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-19T23:56:43.770Z,1482191803.770 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-19T23:56:48.404Z,1482191808.404 [NAL9602](INFO): Powering up
2016-12-19T23:56:59.288Z,1482191819.288 [NAL9602](INFO): NAL9602 initialized
2016-12-19T23:57:14.890Z,1482191834.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=18531, MT Status=0, MTMSN=0
2016-12-19T23:57:14.890Z,1482191834.890 [NAL9602](INFO): No messages in MT queue
2016-12-19T23:57:16.118Z,1482191836.118 [NAL9602](INFO): GPS fix at 20161219T235630: (36.807441, -121.785262)
2016-12-19T23:57:16.258Z,1482191836.258 [Default:CheckIn:Read_GPS] Stopped
2016-12-19T23:57:16.259Z,1482191836.259 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-19T23:57:26.351Z,1482191846.351 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20161219T235009/Courier0002.lzma
2016-12-19T23:57:27.971Z,1482191847.971 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0002.lzma.bak
2016-12-19T23:57:27.971Z,1482191847.971 [DataOverHttps](INFO): SBD MOMSN=4634541
2016-12-19T23:57:29.351Z,1482191849.351 [NAL9602](INFO): Powering down
2016-12-19T23:57:42.442Z,1482191862.442 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20161219T235009/Express0003.lzma
2016-12-19T23:57:43.245Z,1482191863.245 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0003.lzma.bak
2016-12-19T23:57:43.246Z,1482191863.246 [DataOverHttps](INFO): SBD MOMSN=4634544
2016-12-19T23:57:43.840Z,1482191863.840 [Default:CheckIn:Read_Iridium] Stopped
2016-12-19T23:57:43.840Z,1482191863.840 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-19T23:57:43.840Z,1482191863.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-19T23:58:53.049Z,1482191933.049 [BPC1](ERROR): Failed to parse bank A battery data
2016-12-19T23:58:53.049Z,1482191933.049 [BPC1] Data Fault, FailCount= 1
2016-12-19T23:58:53.049Z,1482191933.049 [BPC1](ERROR): Data Fault
2016-12-19T23:58:53.150Z,1482191933.150 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-19T23:58:54.347Z,1482191934.347 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-19T23:58:54.347Z,1482191934.347 [BPC1] No Fault, FailCount= 1
2016-12-20T00:01:34.071Z,1482192094.071 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:02:17.538Z,1482192137.538 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:02:17.538Z,1482192137.538 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:02:17.539Z,1482192137.539 [BPC1](ERROR): Data Fault
2016-12-20T00:02:17.665Z,1482192137.665 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:02:18.780Z,1482192138.780 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:02:18.780Z,1482192138.780 [BPC1] No Fault, FailCount= 1
2016-12-20T00:02:44.376Z,1482192164.376 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:02:44.377Z,1482192164.377 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:02:44.377Z,1482192164.377 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:02:44.377Z,1482192164.377 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:02:44.729Z,1482192164.729 [Default:CheckIn:D] Stopped
2016-12-20T00:02:44.729Z,1482192164.729 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:02:45.142Z,1482192165.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.053206 min 
2016-12-20T00:02:45.142Z,1482192165.142 [Default:CheckIn:E] Stopped
2016-12-20T00:02:45.142Z,1482192165.142 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:02:45.143Z,1482192165.143 [Default:CheckIn] Stopped
2016-12-20T00:02:45.143Z,1482192165.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:02:45.143Z,1482192165.143 [Default:CheckIn](INFO): Running loop #7
2016-12-20T00:02:45.147Z,1482192165.147 [Default:CheckIn] Running Loop=7
2016-12-20T00:02:45.147Z,1482192165.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:02:45.148Z,1482192165.148 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:02:50.589Z,1482192170.589 [NAL9602](INFO): Powering up
2016-12-20T00:03:01.770Z,1482192181.770 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:03:23.394Z,1482192203.394 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:03:23.394Z,1482192203.394 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:03:23.394Z,1482192203.394 [BPC1](ERROR): Communications Fault
2016-12-20T00:03:23.417Z,1482192203.417 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:03:24.685Z,1482192204.685 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:03:24.685Z,1482192204.685 [BPC1] No Fault, FailCount= 1
2016-12-20T00:03:26.563Z,1482192206.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=18532, MT Status=2, MTMSN=0
2016-12-20T00:03:26.563Z,1482192206.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:03:37.347Z,1482192217.347 [NAL9602](INFO): SBD MO Status=0, MOMSN=18532, MT Status=0, MTMSN=0
2016-12-20T00:03:37.347Z,1482192217.347 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:03:38.548Z,1482192218.548 [NAL9602](INFO): GPS fix at 20161220T000252: (36.805214, -121.785716)
2016-12-20T00:03:38.653Z,1482192218.653 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:03:38.654Z,1482192218.654 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:03:44.954Z,1482192224.954 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20161219T235009/Courier0008.lzma
2016-12-20T00:03:45.688Z,1482192225.688 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0008.lzma.bak
2016-12-20T00:03:45.689Z,1482192225.689 [DataOverHttps](INFO): SBD MOMSN=4634553
2016-12-20T00:03:52.713Z,1482192232.713 [NAL9602](INFO): Powering down
2016-12-20T00:03:59.093Z,1482192239.093 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20161219T235009/Express0009.lzma
2016-12-20T00:03:59.887Z,1482192239.887 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0009.lzma.bak
2016-12-20T00:03:59.887Z,1482192239.887 [DataOverHttps](INFO): SBD MOMSN=4634556
2016-12-20T00:04:00.592Z,1482192240.592 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:04:00.592Z,1482192240.592 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:04:00.592Z,1482192240.592 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:06:08.965Z,1482192368.965 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:06:08.965Z,1482192368.965 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:06:08.965Z,1482192368.965 [BPC1](ERROR): Communications Fault
2016-12-20T00:06:09.009Z,1482192369.009 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:06:10.248Z,1482192370.248 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:06:10.248Z,1482192370.248 [BPC1] No Fault, FailCount= 1
2016-12-20T00:08:01.033Z,1482192481.033 [PNI_TCM](ERROR): CRC does not match. Expected:0x40296 got:0x36381
2016-12-20T00:09:00.985Z,1482192540.985 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:09:00.985Z,1482192540.985 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:09:00.985Z,1482192540.985 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:09:00.999Z,1482192540.999 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:09:01.397Z,1482192541.397 [Default:CheckIn:D] Stopped
2016-12-20T00:09:01.398Z,1482192541.398 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:09:01.806Z,1482192541.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.331014 min 
2016-12-20T00:09:01.807Z,1482192541.807 [Default:CheckIn:E] Stopped
2016-12-20T00:09:01.815Z,1482192541.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn] Stopped
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn](INFO): Running loop #8
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn] Running Loop=8
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:09:01.816Z,1482192541.816 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:09:06.451Z,1482192546.451 [NAL9602](INFO): Powering up
2016-12-20T00:09:17.621Z,1482192557.621 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:09:34.034Z,1482192574.034 [NAL9602](INFO): SBD MO Status=0, MOMSN=18533, MT Status=0, MTMSN=0
2016-12-20T00:09:34.034Z,1482192574.034 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:09:47.223Z,1482192587.223 [NAL9602](INFO): GPS fix at 20161220T000901: (36.805169, -121.785737)
2016-12-20T00:09:47.330Z,1482192587.330 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:09:47.331Z,1482192587.331 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:09:52.816Z,1482192592.816 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20161219T235009/Courier0012.lzma
2016-12-20T00:09:53.594Z,1482192593.594 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0012.lzma.bak
2016-12-20T00:09:53.595Z,1482192593.595 [DataOverHttps](INFO): SBD MOMSN=4634567
2016-12-20T00:09:59.189Z,1482192599.189 [NAL9602](INFO): Powering down
2016-12-20T00:10:11.887Z,1482192611.887 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20161219T235009/Express0013.lzma
2016-12-20T00:10:12.688Z,1482192612.688 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0013.lzma.bak
2016-12-20T00:10:12.688Z,1482192612.688 [DataOverHttps](INFO): SBD MOMSN=4634574
2016-12-20T00:10:13.327Z,1482192613.327 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:10:13.327Z,1482192613.327 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:10:13.328Z,1482192613.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:11:34.434Z,1482192694.434 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:12:52.832Z,1482192772.832 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:12:52.832Z,1482192772.832 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:12:52.832Z,1482192772.832 [BPC1](ERROR): Communications Fault
2016-12-20T00:12:52.947Z,1482192772.947 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:12:54.037Z,1482192774.037 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:12:54.037Z,1482192774.037 [BPC1] No Fault, FailCount= 1
2016-12-20T00:13:27.839Z,1482192807.839 [PNI_TCM](ERROR): CRC does not match. Expected:0x32267 got:0x7233
2016-12-20T00:15:13.569Z,1482192913.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:15:13.570Z,1482192913.570 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:15:13.570Z,1482192913.570 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:15:13.570Z,1482192913.570 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:15:13.978Z,1482192913.978 [Default:CheckIn:D] Stopped
2016-12-20T00:15:13.978Z,1482192913.978 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:15:14.386Z,1482192914.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.540686 min 
2016-12-20T00:15:14.386Z,1482192914.386 [Default:CheckIn:E] Stopped
2016-12-20T00:15:14.386Z,1482192914.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:15:14.386Z,1482192914.386 [Default:CheckIn] Stopped
2016-12-20T00:15:14.387Z,1482192914.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:15:14.387Z,1482192914.387 [Default:CheckIn](INFO): Running loop #9
2016-12-20T00:15:14.387Z,1482192914.387 [Default:CheckIn] Running Loop=9
2016-12-20T00:15:14.387Z,1482192914.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:15:14.387Z,1482192914.387 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:15:19.885Z,1482192919.885 [NAL9602](INFO): Powering up
2016-12-20T00:15:30.848Z,1482192930.848 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:15:52.026Z,1482192952.026 [NAL9602](INFO): SBD MO Status=0, MOMSN=18534, MT Status=0, MTMSN=0
2016-12-20T00:15:52.026Z,1482192952.026 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:16:05.229Z,1482192965.229 [NAL9602](INFO): GPS fix at 20161220T001519: (36.805117, -121.785727)
2016-12-20T00:16:05.319Z,1482192965.319 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:16:05.320Z,1482192965.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:16:10.342Z,1482192970.342 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20161219T235009/Courier0016.lzma
2016-12-20T00:16:11.088Z,1482192971.088 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0016.lzma.bak
2016-12-20T00:16:11.089Z,1482192971.089 [DataOverHttps](INFO): SBD MOMSN=4634584
2016-12-20T00:16:16.364Z,1482192976.364 [NAL9602](INFO): Powering down
2016-12-20T00:16:23.575Z,1482192983.575 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20161219T235009/Express0017.lzma
2016-12-20T00:16:24.365Z,1482192984.365 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0017.lzma.bak
2016-12-20T00:16:24.365Z,1482192984.365 [DataOverHttps](INFO): SBD MOMSN=4634588
2016-12-20T00:16:25.070Z,1482192985.070 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:16:25.070Z,1482192985.070 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:16:25.070Z,1482192985.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:19:01.616Z,1482193141.616 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:19:01.616Z,1482193141.616 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:19:01.616Z,1482193141.616 [BPC1](ERROR): Data Fault
2016-12-20T00:19:01.663Z,1482193141.663 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:19:02.855Z,1482193142.855 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:19:02.855Z,1482193142.855 [BPC1] No Fault, FailCount= 1
2016-12-20T00:21:25.425Z,1482193285.425 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:21:25.426Z,1482193285.426 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:21:25.426Z,1482193285.426 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:21:25.426Z,1482193285.426 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:21:25.917Z,1482193285.917 [Default:CheckIn:D] Stopped
2016-12-20T00:21:25.937Z,1482193285.937 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:21:26.311Z,1482193286.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.739665 min 
2016-12-20T00:21:26.311Z,1482193286.311 [Default:CheckIn:E] Stopped
2016-12-20T00:21:26.311Z,1482193286.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:21:26.311Z,1482193286.311 [Default:CheckIn] Stopped
2016-12-20T00:21:26.311Z,1482193286.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:21:26.312Z,1482193286.312 [Default:CheckIn](INFO): Running loop #10
2016-12-20T00:21:26.312Z,1482193286.312 [Default:CheckIn] Running Loop=10
2016-12-20T00:21:26.312Z,1482193286.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:21:26.312Z,1482193286.312 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:21:34.671Z,1482193294.671 [NAL9602](INFO): Powering up
2016-12-20T00:21:34.691Z,1482193294.691 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:21:34.691Z,1482193294.691 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:21:34.691Z,1482193294.691 [BPC1](ERROR): Communications Fault
2016-12-20T00:21:34.747Z,1482193294.747 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:21:34.747Z,1482193294.747 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:21:35.679Z,1482193295.679 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:21:35.679Z,1482193295.679 [BPC1] No Fault, FailCount= 1
2016-12-20T00:21:45.567Z,1482193305.567 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:22:31.939Z,1482193351.939 [NAL9602](INFO): SBD MO Status=2, MOMSN=18535, MT Status=2, MTMSN=0
2016-12-20T00:22:31.939Z,1482193351.939 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:22:35.345Z,1482193355.345 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:22:35.346Z,1482193355.346 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:22:35.346Z,1482193355.346 [BPC1](ERROR): Communications Fault
2016-12-20T00:22:35.436Z,1482193355.436 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:22:36.579Z,1482193356.579 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:22:36.579Z,1482193356.579 [BPC1] No Fault, FailCount= 1
2016-12-20T00:22:55.302Z,1482193375.302 [NAL9602](INFO): SBD MO Status=0, MOMSN=18535, MT Status=0, MTMSN=0
2016-12-20T00:22:55.302Z,1482193375.302 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:22:56.507Z,1482193376.507 [NAL9602](INFO): GPS fix at 20161220T002210: (36.802732, -121.786893)
2016-12-20T00:22:56.617Z,1482193376.617 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:22:56.626Z,1482193376.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:23:01.828Z,1482193381.828 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20161219T235009/Courier0020.lzma
2016-12-20T00:23:02.598Z,1482193382.598 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0020.lzma.bak
2016-12-20T00:23:02.598Z,1482193382.598 [DataOverHttps](INFO): SBD MOMSN=4634597
2016-12-20T00:23:10.938Z,1482193390.938 [NAL9602](INFO): Powering down
2016-12-20T00:23:17.620Z,1482193397.620 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20161219T235009/Express0021.lzma
2016-12-20T00:23:18.370Z,1482193398.370 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0021.lzma.bak
2016-12-20T00:23:18.371Z,1482193398.371 [DataOverHttps](INFO): SBD MOMSN=4634601
2016-12-20T00:23:19.075Z,1482193399.075 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:23:19.075Z,1482193399.075 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:23:19.075Z,1482193399.075 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:24:59.538Z,1482193499.538 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:24:59.538Z,1482193499.538 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:24:59.538Z,1482193499.538 [BPC1](ERROR): Communications Fault
2016-12-20T00:24:59.596Z,1482193499.596 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:25:00.812Z,1482193500.812 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:25:00.812Z,1482193500.812 [BPC1] No Fault, FailCount= 1
2016-12-20T00:26:09.900Z,1482193569.900 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:26:09.900Z,1482193569.900 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:26:09.900Z,1482193569.900 [BPC1](ERROR): Data Fault
2016-12-20T00:26:09.924Z,1482193569.924 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:26:11.129Z,1482193571.129 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:26:11.129Z,1482193571.129 [BPC1] No Fault, FailCount= 1
2016-12-20T00:26:27.252Z,1482193587.252 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:26:27.253Z,1482193587.253 [BPC1] Communications Fault, FailCount= 2
2016-12-20T00:26:27.253Z,1482193587.253 [BPC1](ERROR): Communications Fault
2016-12-20T00:26:27.346Z,1482193587.346 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:26:28.473Z,1482193588.473 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:26:28.473Z,1482193588.473 [BPC1] No Fault, FailCount= 2
2016-12-20T00:28:19.518Z,1482193699.518 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:28:19.518Z,1482193699.518 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:28:19.518Z,1482193699.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:28:19.519Z,1482193699.519 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:28:19.917Z,1482193699.917 [Default:CheckIn:D] Stopped
2016-12-20T00:28:19.918Z,1482193699.918 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:28:20.318Z,1482193700.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.639681 min 
2016-12-20T00:28:20.319Z,1482193700.319 [Default:CheckIn:E] Stopped
2016-12-20T00:28:20.319Z,1482193700.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:28:20.319Z,1482193700.319 [Default:CheckIn] Stopped
2016-12-20T00:28:20.319Z,1482193700.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:28:20.319Z,1482193700.319 [Default:CheckIn](INFO): Running loop #11
2016-12-20T00:28:20.320Z,1482193700.320 [Default:CheckIn] Running Loop=11
2016-12-20T00:28:20.320Z,1482193700.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:28:20.320Z,1482193700.320 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:28:25.740Z,1482193705.740 [NAL9602](INFO): Powering up
2016-12-20T00:28:36.726Z,1482193716.726 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:29:09.130Z,1482193749.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=18536, MT Status=2, MTMSN=0
2016-12-20T00:29:09.130Z,1482193749.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:29:35.106Z,1482193775.106 [NAL9602](INFO): SBD MO Status=2, MOMSN=18536, MT Status=2, MTMSN=0
2016-12-20T00:29:35.107Z,1482193775.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:29:53.933Z,1482193793.933 [BPC1](ERROR): Failed to parse bank A battery data
2016-12-20T00:29:53.933Z,1482193793.933 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:29:53.933Z,1482193793.933 [BPC1](ERROR): Data Fault
2016-12-20T00:29:53.956Z,1482193793.956 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:29:55.163Z,1482193795.163 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:29:55.163Z,1482193795.163 [BPC1] No Fault, FailCount= 1
2016-12-20T00:30:26.144Z,1482193826.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=18536, MT Status=2, MTMSN=0
2016-12-20T00:30:26.145Z,1482193826.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:30:56.356Z,1482193856.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=18536, MT Status=2, MTMSN=0
2016-12-20T00:30:56.356Z,1482193856.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:31:15.938Z,1482193875.938 [NAL9602](INFO): SBD MO Status=0, MOMSN=18536, MT Status=0, MTMSN=0
2016-12-20T00:31:15.938Z,1482193875.938 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:31:17.147Z,1482193877.147 [NAL9602](INFO): GPS fix at 20161220T003032: (36.802746, -121.786934)
2016-12-20T00:31:17.232Z,1482193877.232 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:31:17.233Z,1482193877.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:31:23.539Z,1482193883.539 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.539Z,1482193883.539 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.540Z,1482193883.540 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.540Z,1482193883.540 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.541Z,1482193883.541 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.541Z,1482193883.541 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.542Z,1482193883.542 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.542Z,1482193883.542 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.543Z,1482193883.543 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.543Z,1482193883.543 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.543Z,1482193883.543 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.544Z,1482193883.544 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2016-12-20T00:31:23.580Z,1482193883.580 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-12-20T00:31:23.580Z,1482193883.580 [PNI_TCM] Data Fault, FailCount= 1
2016-12-20T00:31:23.580Z,1482193883.580 [PNI_TCM](ERROR): Data Fault
2016-12-20T00:31:23.665Z,1482193883.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.027896 seconds old.
2016-12-20T00:31:23.668Z,1482193883.668 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.028496 seconds old.
2016-12-20T00:31:23.737Z,1482193883.737 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-12-20T00:31:23.796Z,1482193883.796 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20161219T235009/Courier0024.lzma
2016-12-20T00:31:23.820Z,1482193883.820 [PNI_TCM](INFO): Powering down
2016-12-20T00:31:23.941Z,1482193883.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.303924 seconds old.
2016-12-20T00:31:23.943Z,1482193883.943 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.304424 seconds old.
2016-12-20T00:31:24.199Z,1482193884.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.561622 seconds old.
2016-12-20T00:31:24.210Z,1482193884.210 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.570340 seconds old.
2016-12-20T00:31:24.553Z,1482193884.553 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0024.lzma.bak
2016-12-20T00:31:24.553Z,1482193884.553 [DataOverHttps](INFO): SBD MOMSN=4634616
2016-12-20T00:31:24.612Z,1482193884.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.975161 seconds old.
2016-12-20T00:31:24.626Z,1482193884.626 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.986686 seconds old.
2016-12-20T00:31:25.255Z,1482193885.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.618161 seconds old.
2016-12-20T00:31:25.259Z,1482193885.259 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.619468 seconds old.
2016-12-20T00:31:25.298Z,1482193885.298 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-12-20T00:31:25.298Z,1482193885.298 [PNI_TCM] No Fault, FailCount= 1
2016-12-20T00:31:25.472Z,1482193885.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.834797 seconds old.
2016-12-20T00:31:25.483Z,1482193885.483 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.835297 seconds old.
2016-12-20T00:31:32.315Z,1482193892.315 [NAL9602](INFO): Powering down
2016-12-20T00:31:32.358Z,1482193892.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.720974 seconds old.
2016-12-20T00:31:32.361Z,1482193892.361 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.721474 seconds old.
2016-12-20T00:31:32.451Z,1482193892.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.814217 seconds old.
2016-12-20T00:31:32.454Z,1482193892.454 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.814823 seconds old.
2016-12-20T00:31:32.811Z,1482193892.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.173576 seconds old.
2016-12-20T00:31:32.813Z,1482193892.813 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.174076 seconds old.
2016-12-20T00:31:33.252Z,1482193893.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.615159 seconds old.
2016-12-20T00:31:33.255Z,1482193893.255 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.615765 seconds old.
2016-12-20T00:31:33.615Z,1482193893.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.977818 seconds old.
2016-12-20T00:31:33.618Z,1482193893.618 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.978524 seconds old.
2016-12-20T00:31:34.047Z,1482193894.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.409889 seconds old.
2016-12-20T00:31:34.051Z,1482193894.051 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.411395 seconds old.
2016-12-20T00:31:37.533Z,1482193897.533 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:31:37.600Z,1482193897.600 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20161219T235009/Express0025.lzma
2016-12-20T00:31:38.370Z,1482193898.370 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0025.lzma.bak
2016-12-20T00:31:38.370Z,1482193898.370 [DataOverHttps](INFO): SBD MOMSN=4634620
2016-12-20T00:31:38.927Z,1482193898.927 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:31:38.927Z,1482193898.927 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:31:38.928Z,1482193898.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:31:48.726Z,1482193908.726 [BPC1](ERROR): Failed to parse bank A battery data
2016-12-20T00:31:48.726Z,1482193908.726 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:31:48.726Z,1482193908.726 [BPC1](ERROR): Data Fault
2016-12-20T00:31:48.773Z,1482193908.773 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:31:49.962Z,1482193909.962 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:31:49.962Z,1482193909.962 [BPC1] No Fault, FailCount= 1
2016-12-20T00:32:00.727Z,1482193920.727 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347
2016-12-20T00:32:01.125Z,1482193921.125 [PNI_TCM](ERROR): CRC does not match. Expected:0x31754 got:0x16949
2016-12-20T00:32:05.969Z,1482193925.969 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:32:05.970Z,1482193925.970 [BPC1] Communications Fault, FailCount= 2
2016-12-20T00:32:05.970Z,1482193925.970 [BPC1](ERROR): Communications Fault
2016-12-20T00:32:06.096Z,1482193926.096 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:32:07.194Z,1482193927.194 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:32:07.194Z,1482193927.194 [BPC1] No Fault, FailCount= 2
2016-12-20T00:32:21.947Z,1482193941.947 [PNI_TCM](ERROR): CRC does not match. Expected:0x21744 got:0x58529
2016-12-20T00:33:03.870Z,1482193983.870 [BPC1](ERROR): Failed to parse message.
2016-12-20T00:33:03.870Z,1482193983.870 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:33:03.870Z,1482193983.870 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:33:03.870Z,1482193983.870 [BPC1](ERROR): Data Fault
2016-12-20T00:33:03.931Z,1482193983.931 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:33:05.122Z,1482193985.122 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:33:05.122Z,1482193985.122 [BPC1] No Fault, FailCount= 1
2016-12-20T00:33:26.747Z,1482194006.747 [PNI_TCM](ERROR): CRC does not match. Expected:0x63888 got:0x49108
2016-12-20T00:34:39.763Z,1482194079.763 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:34:39.763Z,1482194079.763 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:34:39.763Z,1482194079.763 [BPC1](ERROR): Communications Fault
2016-12-20T00:34:39.881Z,1482194079.881 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:34:44.416Z,1482194084.416 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:34:44.417Z,1482194084.417 [BPC1] No Fault, FailCount= 1
2016-12-20T00:35:39.980Z,1482194139.980 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:35:39.981Z,1482194139.981 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:35:39.981Z,1482194139.981 [BPC1](ERROR): Data Fault
2016-12-20T00:35:40.040Z,1482194140.040 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:35:45.941Z,1482194145.941 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:35:45.941Z,1482194145.941 [BPC1] No Fault, FailCount= 1
2016-12-20T00:36:39.547Z,1482194199.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:36:39.548Z,1482194199.548 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:36:39.548Z,1482194199.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:36:39.548Z,1482194199.548 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:36:39.963Z,1482194199.963 [Default:CheckIn:D] Stopped
2016-12-20T00:36:39.963Z,1482194199.963 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:36:40.294Z,1482194200.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.973763 min 
2016-12-20T00:36:40.303Z,1482194200.303 [Default:CheckIn:E] Stopped
2016-12-20T00:36:40.303Z,1482194200.303 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:36:40.303Z,1482194200.303 [Default:CheckIn] Stopped
2016-12-20T00:36:40.303Z,1482194200.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:36:40.304Z,1482194200.304 [Default:CheckIn](INFO): Running loop #12
2016-12-20T00:36:40.304Z,1482194200.304 [Default:CheckIn] Running Loop=12
2016-12-20T00:36:40.304Z,1482194200.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:36:40.304Z,1482194200.304 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:36:45.737Z,1482194205.737 [NAL9602](INFO): Powering up
2016-12-20T00:36:56.753Z,1482194216.753 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:37:16.758Z,1482194236.758 [NAL9602](INFO): SBD MO Status=2, MOMSN=18537, MT Status=2, MTMSN=0
2016-12-20T00:37:16.758Z,1482194236.758 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:37:35.167Z,1482194255.167 [NAL9602](INFO): SBD MO Status=2, MOMSN=18537, MT Status=2, MTMSN=0
2016-12-20T00:37:35.167Z,1482194255.167 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:37:53.430Z,1482194273.430 [NAL9602](INFO): SBD MO Status=0, MOMSN=18537, MT Status=0, MTMSN=0
2016-12-20T00:37:53.431Z,1482194273.431 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:38:06.237Z,1482194286.237 [NAL9602](INFO): GPS fix at 20161220T003720: (36.802400, -121.787122)
2016-12-20T00:38:06.337Z,1482194286.337 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:38:06.337Z,1482194286.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:38:12.841Z,1482194292.841 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20161219T235009/Courier0028.lzma
2016-12-20T00:38:13.627Z,1482194293.627 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0028.lzma.bak
2016-12-20T00:38:13.627Z,1482194293.627 [DataOverHttps](INFO): SBD MOMSN=4634629
2016-12-20T00:38:20.319Z,1482194300.319 [NAL9602](INFO): Powering down
2016-12-20T00:38:25.657Z,1482194305.657 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20161219T235009/Express0029.lzma
2016-12-20T00:38:26.398Z,1482194306.398 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0029.lzma.bak
2016-12-20T00:38:26.398Z,1482194306.398 [DataOverHttps](INFO): SBD MOMSN=4634632
2016-12-20T00:38:27.109Z,1482194307.109 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:38:27.109Z,1482194307.109 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:38:27.109Z,1482194307.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:41:37.817Z,1482194497.817 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:41:49.787Z,1482194509.787 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:41:49.787Z,1482194509.787 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:41:49.787Z,1482194509.787 [BPC1](ERROR): Communications Fault
2016-12-20T00:41:49.826Z,1482194509.826 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:41:51.011Z,1482194511.011 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:41:51.011Z,1482194511.011 [BPC1] No Fault, FailCount= 1
2016-12-20T00:42:51.701Z,1482194571.701 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:42:51.701Z,1482194571.701 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:42:51.701Z,1482194571.701 [BPC1](ERROR): Communications Fault
2016-12-20T00:42:51.752Z,1482194571.752 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:42:52.942Z,1482194572.942 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:42:52.942Z,1482194572.942 [BPC1] No Fault, FailCount= 1
2016-12-20T00:43:27.367Z,1482194607.367 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:43:27.367Z,1482194607.367 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:43:27.367Z,1482194607.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:43:27.368Z,1482194607.368 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:43:27.746Z,1482194607.746 [Default:CheckIn:D] Stopped
2016-12-20T00:43:27.746Z,1482194607.746 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:43:28.281Z,1482194608.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.770158 min 
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn:E] Stopped
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn] Stopped
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn](INFO): Running loop #13
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn] Running Loop=13
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:43:28.282Z,1482194608.282 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:43:28.925Z,1482194608.925 [NAL9602](INFO): Powering up
2016-12-20T00:43:39.747Z,1482194619.747 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:44:26.609Z,1482194666.609 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:44:26.609Z,1482194666.609 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:44:26.614Z,1482194666.614 [BPC1](ERROR): Data Fault
2016-12-20T00:44:26.687Z,1482194666.687 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:44:27.837Z,1482194667.837 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:44:27.837Z,1482194667.837 [BPC1] No Fault, FailCount= 1
2016-12-20T00:47:38.334Z,1482194858.334 [PNI_TCM](ERROR): CRC does not match. Expected:0x7105 got:0x9628
2016-12-20T00:47:47.995Z,1482194867.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.438783 seconds old.
2016-12-20T00:47:47.997Z,1482194867.997 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.438977 seconds old.
2016-12-20T00:47:48.347Z,1482194868.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.791330 seconds old.
2016-12-20T00:47:48.350Z,1482194868.350 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.791524 seconds old.
2016-12-20T00:47:51.132Z,1482194871.132 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2016-12-20T00:48:13.904Z,1482194893.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=18538, MT Status=2, MTMSN=0
2016-12-20T00:48:13.905Z,1482194893.905 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-12-20T00:48:19.132Z,1482194899.132 [PNI_TCM](ERROR): CRC does not match. Expected:0x39851 got:0x249
2016-12-20T00:48:21.554Z,1482194901.554 [PNI_TCM](ERROR): CRC does not match. Expected:0x52054 got:0x6592
2016-12-20T00:48:28.383Z,1482194908.383 [Default:CheckIn:Read_GPS](INFO): Timed out from 2016-12-20T00:43:28.3Z
2016-12-20T00:48:28.383Z,1482194908.383 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:48:28.383Z,1482194908.383 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:48:34.139Z,1482194914.139 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20161219T235009/Courier0032.lzma
2016-12-20T00:48:34.909Z,1482194914.909 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0032.lzma.bak
2016-12-20T00:48:34.910Z,1482194914.910 [DataOverHttps](INFO): SBD MOMSN=4634647
2016-12-20T00:48:43.265Z,1482194923.265 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2016-12-20T00:48:48.261Z,1482194928.261 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20161219T235009/Express0033.lzma
2016-12-20T00:48:49.043Z,1482194929.043 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0033.lzma.bak
2016-12-20T00:48:49.043Z,1482194929.043 [DataOverHttps](INFO): SBD MOMSN=4634649
2016-12-20T00:48:49.053Z,1482194929.053 [NAL9602](INFO): Powering down
2016-12-20T00:48:49.611Z,1482194929.611 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:48:49.611Z,1482194929.611 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:48:49.611Z,1482194929.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T00:51:27.249Z,1482195087.249 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:51:27.249Z,1482195087.249 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:51:27.250Z,1482195087.250 [BPC1](ERROR): Communications Fault
2016-12-20T00:51:27.315Z,1482195087.315 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:51:28.482Z,1482195088.482 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:51:28.482Z,1482195088.482 [BPC1] No Fault, FailCount= 1
2016-12-20T00:51:38.121Z,1482195098.121 [CBIT](DEBUG): EFC running - data check-sum false
2016-12-20T00:53:30.851Z,1482195210.851 [BPC1](ERROR): Failed to parse message.
2016-12-20T00:53:30.851Z,1482195210.851 [BPC1](ERROR): Failed to parse bank B battery data
2016-12-20T00:53:30.851Z,1482195210.851 [BPC1] Data Fault, FailCount= 1
2016-12-20T00:53:30.851Z,1482195210.851 [BPC1](ERROR): Data Fault
2016-12-20T00:53:30.941Z,1482195210.941 [CBIT](ERROR): Data Fault in component: BPC1
2016-12-20T00:53:32.053Z,1482195212.053 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:53:32.053Z,1482195212.053 [BPC1] No Fault, FailCount= 1
2016-12-20T00:53:50.042Z,1482195230.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-12-20T00:53:50.042Z,1482195230.042 [Default:CheckIn:C.Wait] Stopped
2016-12-20T00:53:50.042Z,1482195230.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T00:53:50.042Z,1482195230.042 [Default:CheckIn:D] Running Loop=1
2016-12-20T00:53:50.442Z,1482195230.442 [Default:CheckIn:D] Stopped
2016-12-20T00:53:50.442Z,1482195230.442 [Default:CheckIn:E] Running Loop=1
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.148421 min 
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn:E] Stopped
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn] Stopped
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T00:53:50.843Z,1482195230.843 [Default:CheckIn](INFO): Running loop #14
2016-12-20T00:53:50.844Z,1482195230.844 [Default:CheckIn] Running Loop=14
2016-12-20T00:53:50.844Z,1482195230.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-12-20T00:53:50.844Z,1482195230.844 [Default:CheckIn:Read_GPS] Running Loop=1
2016-12-20T00:53:54.758Z,1482195234.758 [NAL9602](INFO): Powering up
2016-12-20T00:54:05.632Z,1482195245.632 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:54:06.435Z,1482195246.435 [NAL9602](FAULT): GPS failed to acquire within timeout.
2016-12-20T00:54:06.435Z,1482195246.435 [NAL9602] Data Fault, FailCount= 1
2016-12-20T00:54:06.435Z,1482195246.435 [NAL9602](ERROR): Data Fault
2016-12-20T00:54:06.471Z,1482195246.471 [CBIT](ERROR): Data Fault in component: NAL9602
2016-12-20T00:54:06.836Z,1482195246.836 [NAL9602](INFO): Powering down
2016-12-20T00:54:07.703Z,1482195247.703 [CBIT](INFO): Clearing failed state for component NAL9602
2016-12-20T00:54:07.703Z,1482195247.703 [NAL9602] No Fault, FailCount= 1
2016-12-20T00:54:12.022Z,1482195252.022 [NAL9602](INFO): Powering up NAL9602
2016-12-20T00:54:22.823Z,1482195262.823 [NAL9602](INFO): NAL9602 initialized
2016-12-20T00:55:10.855Z,1482195310.855 [BPC1](ERROR): Failed to receive battery data
2016-12-20T00:55:10.855Z,1482195310.855 [BPC1] Communications Fault, FailCount= 1
2016-12-20T00:55:10.856Z,1482195310.856 [BPC1](ERROR): Communications Fault
2016-12-20T00:55:10.894Z,1482195310.894 [CBIT](ERROR): Communications Fault in component: BPC1
2016-12-20T00:55:12.071Z,1482195312.071 [CBIT](INFO): Clearing failed state for component BPC1
2016-12-20T00:55:12.071Z,1482195312.071 [BPC1] No Fault, FailCount= 1
2016-12-20T00:55:20.826Z,1482195320.826 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2016-12-20T00:57:41.857Z,1482195461.857 [NAL9602](INFO): SBD MO Status=0, MOMSN=18538, MT Status=0, MTMSN=0
2016-12-20T00:57:41.857Z,1482195461.857 [NAL9602](INFO): No messages in MT queue
2016-12-20T00:58:50.956Z,1482195530.956 [Default:CheckIn:Read_GPS](INFO): Timed out from 2016-12-20T00:53:50.8Z
2016-12-20T00:58:50.956Z,1482195530.956 [Default:CheckIn:Read_GPS] Stopped
2016-12-20T00:58:50.956Z,1482195530.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-12-20T00:58:57.098Z,1482195537.098 [NAL9602](INFO): Powering down
2016-12-20T00:58:57.690Z,1482195537.690 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20161219T235009/Courier0036.lzma
2016-12-20T00:58:58.873Z,1482195538.873 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Courier0036.lzma.bak
2016-12-20T00:58:58.873Z,1482195538.873 [DataOverHttps](INFO): SBD MOMSN=4634663
2016-12-20T00:59:15.670Z,1482195555.670 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20161219T235009/Express0037.lzma
2016-12-20T00:59:16.857Z,1482195556.857 [DataOverHttps](INFO): Moved sent file to Logs/20161219T235009/Express0037.lzma.bak
2016-12-20T00:59:16.857Z,1482195556.857 [DataOverHttps](INFO): SBD MOMSN=4634665
2016-12-20T00:59:17.528Z,1482195557.528 [Default:CheckIn:Read_Iridium] Stopped
2016-12-20T00:59:17.528Z,1482195557.528 [Default:CheckIn:C.Wait] Running Loop=1
2016-12-20T00:59:17.528Z,1482195557.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-12-20T01:00:15.762Z,1482195615.762 [CommandLine](IMPORTANT): got command quit
2016-12-20T01:00:16.779Z,1482195616.779 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:16.783Z,1482195616.783 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:16.963Z,1482195616.963 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2016-12-20T01:00:16.963Z,1482195616.963 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:16.964Z,1482195616.964 [CommandLine](INFO): Join timeout helper Thread ID is 2796
2016-12-20T01:00:16.965Z,1482195616.965 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2016-12-20T01:00:16.965Z,1482195616.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:16.966Z,1482195616.966 [NavChartDb](INFO): Join timeout helper Thread ID is 2797
2016-12-20T01:00:17.204Z,1482195617.204 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:17.204Z,1482195617.204 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.205Z,1482195617.205 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2016-12-20T01:00:17.205Z,1482195617.205 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.205Z,1482195617.205 [Radio_Surface](INFO): Join timeout helper Thread ID is 2798
2016-12-20T01:00:17.605Z,1482195617.605 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:17.605Z,1482195617.605 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.620Z,1482195617.620 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2016-12-20T01:00:17.620Z,1482195617.620 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.621Z,1482195617.621 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2800
2016-12-20T01:00:17.893Z,1482195617.893 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:17.893Z,1482195617.893 [WetLabsBB2FL](INFO): Powering down
2016-12-20T01:00:17.894Z,1482195617.894 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.905Z,1482195617.905 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2016-12-20T01:00:17.905Z,1482195617.905 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:17.905Z,1482195617.905 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2803
2016-12-20T01:00:18.009Z,1482195618.009 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:18.009Z,1482195618.009 [CTD_NeilBrown](INFO): Powering down
2016-12-20T01:00:18.010Z,1482195618.010 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.022Z,1482195618.022 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2016-12-20T01:00:18.022Z,1482195618.022 [logger ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.022Z,1482195618.022 [logger](INFO): Join timeout helper Thread ID is 2804
2016-12-20T01:00:18.057Z,1482195618.057 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:18.058Z,1482195618.058 [logger ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.082Z,1482195618.082 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2016-12-20T01:00:18.082Z,1482195618.082 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.082Z,1482195618.082 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2016-12-20T01:00:18.082Z,1482195618.082 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.083Z,1482195618.083 [controlThread](INFO): Join timeout helper Thread ID is 2805
2016-12-20T01:00:18.207Z,1482195618.207 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2016-12-20T01:00:18.207Z,1482195618.207 [controlThread](DEBUG): Uninitializing ControlThread
2016-12-20T01:00:18.207Z,1482195618.207 [Aanderaa_O2](INFO): Powering down
2016-12-20T01:00:18.214Z,1482195618.214 [AcousticModem_Benthos_ATM900](INFO): Powering down
2016-12-20T01:00:18.335Z,1482195618.335 [NAL9602](INFO): Powering down
2016-12-20T01:00:18.336Z,1482195618.336 [PNI_TCM](INFO): Powering down
2016-12-20T01:00:18.427Z,1482195618.427 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-12-20T01:00:18.429Z,1482195618.429 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2016-12-20T01:00:18.429Z,1482195618.429 [Default] Stopped
2016-12-20T01:00:18.429Z,1482195618.429 [Default](DEBUG): Aggregate::uninitialize Default
2016-12-20T01:00:18.429Z,1482195618.429 [Default:B.GoToSurface] Stopped
2016-12-20T01:00:18.429Z,1482195618.429 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2016-12-20T01:00:18.429Z,1482195618.429 [Default:CheckIn] Stopped
2016-12-20T01:00:18.430Z,1482195618.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-12-20T01:00:18.430Z,1482195618.430 [Default:CheckIn:C.Wait] Stopped
2016-12-20T01:00:18.430Z,1482195618.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-12-20T01:00:18.433Z,1482195618.433 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2016-12-20T01:00:18.433Z,1482195618.433 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2016-12-20T01:00:18.433Z,1482195618.433 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2016-12-20T01:00:18.434Z,1482195618.434 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2016-12-20T01:00:18.434Z,1482195618.434 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-12-20T01:00:18.434Z,1482195618.434 [BuoyancyServo](INFO): Powering down
2016-12-20T01:00:18.446Z,1482195618.446 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2016-12-20T01:00:18.446Z,1482195618.446 [ElevatorServo](INFO): Powering down
2016-12-20T01:00:18.447Z,1482195618.447 [MassServo](DEBUG): Uninitialize Mass Servo.
2016-12-20T01:00:18.447Z,1482195618.447 [MassServo](INFO): Powering down
2016-12-20T01:00:18.448Z,1482195618.448 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2016-12-20T01:00:18.448Z,1482195618.448 [RudderServo](INFO): Powering down
2016-12-20T01:00:18.448Z,1482195618.448 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2016-12-20T01:00:18.449Z,1482195618.449 [ThrusterServo](INFO): Powering down
2016-12-20T01:00:18.450Z,1482195618.450 [SBIT](DEBUG): Uninitialize SBIT Component.
2016-12-20T01:00:18.450Z,1482195618.450 [IBIT](DEBUG): Uninitialize IBIT Component.
2016-12-20T01:00:18.450Z,1482195618.450 [CBIT](DEBUG): Uninitialize CBIT Component.
2016-12-20T01:00:18.486Z,1482195618.486 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.555Z,1482195618.555 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.561Z,1482195618.561 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.624Z,1482195618.624 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.670Z,1482195618.670 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-12-20T01:00:18.733Z,1482195618.733 [logger ThreadHandler](INFO): Thread cancelled.