2018-08-22T21:10:49.456Z,1534972249.456 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:10:49.456Z,1534972249.456 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:10:49.456Z,1534972249.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:10:49.457Z,1534972249.457 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:10:49.845Z,1534972249.845 [Default:CheckIn:D] Stopped
2018-08-22T21:10:49.845Z,1534972249.845 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.633718 min 
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn:E] Stopped
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn] Stopped
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:10:50.258Z,1534972250.258 [Default:CheckIn](INFO): Running loop #3
2018-08-22T21:10:50.259Z,1534972250.259 [Default:CheckIn] Running Loop=3
2018-08-22T21:10:50.259Z,1534972250.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:10:50.259Z,1534972250.259 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:10:51.003Z,1534972251.003 [NAL9602](INFO): Powering up
2018-08-22T21:11:01.854Z,1534972261.854 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:13:59.558Z,1534972439.558 [NAL9602](INFO): SBD MO Status=0, MOMSN=8444, MT Status=0, MTMSN=0
2018-08-22T21:13:59.558Z,1534972439.558 [NAL9602](INFO): No messages in MT queue
2018-08-22T21:14:00.487Z,1534972440.487 [NAL9602](INFO): GPS fix at 20180822T211357: (36.806715, -121.785457)
2018-08-22T21:14:00.565Z,1534972440.565 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:14:00.566Z,1534972440.566 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-22T21:14:05.953Z,1534972445.953 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180822T193632/Courier0031.lzma
2018-08-22T21:14:06.754Z,1534972446.754 [DataOverHttps](INFO): Moved sent file to Logs/20180822T193632/Courier0031.lzma.bak
2018-08-22T21:14:06.754Z,1534972446.754 [DataOverHttps](INFO): SBD MOMSN=8436395
2018-08-22T21:14:16.460Z,1534972456.460 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20180822T210759/Courier0000.lzma
2018-08-22T21:14:17.323Z,1534972457.323 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0000.lzma.bak
2018-08-22T21:14:17.323Z,1534972457.323 [DataOverHttps](INFO): SBD MOMSN=8436397
2018-08-22T21:14:26.879Z,1534972466.879 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180822T210759/Courier0003.lzma
2018-08-22T21:14:27.667Z,1534972467.667 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0003.lzma.bak
2018-08-22T21:14:27.667Z,1534972467.667 [DataOverHttps](INFO): SBD MOMSN=8436401
2018-08-22T21:14:31.939Z,1534972471.939 [NAL9602](INFO): Powering down
2018-08-22T21:14:37.449Z,1534972477.449 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20180822T193632/Express0032.lzma
2018-08-22T21:14:38.219Z,1534972478.219 [DataOverHttps](INFO): Moved sent file to Logs/20180822T193632/Express0032.lzma.bak
2018-08-22T21:14:38.220Z,1534972478.220 [DataOverHttps](INFO): SBD MOMSN=8436403
2018-08-22T21:14:47.845Z,1534972487.845 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20180822T210759/Express0001.lzma
2018-08-22T21:14:48.611Z,1534972488.611 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0001.lzma.bak
2018-08-22T21:14:48.611Z,1534972488.611 [DataOverHttps](INFO): SBD MOMSN=8436407
2018-08-22T21:14:59.439Z,1534972499.439 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20180822T210759/Express0004.lzma
2018-08-22T21:15:00.231Z,1534972500.231 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0004.lzma.bak
2018-08-22T21:15:00.231Z,1534972500.231 [DataOverHttps](INFO): SBD MOMSN=8436410
2018-08-22T21:15:01.275Z,1534972501.275 [Default:CheckIn:Read_Iridium] Stopped
2018-08-22T21:15:01.275Z,1534972501.275 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-22T21:15:01.275Z,1534972501.275 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-22T21:20:01.572Z,1534972801.572 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:20:01.573Z,1534972801.573 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:20:01.573Z,1534972801.573 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:20:01.574Z,1534972801.574 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:20:01.973Z,1534972801.973 [Default:CheckIn:D] Stopped
2018-08-22T21:20:01.973Z,1534972801.973 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:20:02.384Z,1534972802.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.835854 min 
2018-08-22T21:20:02.384Z,1534972802.384 [Default:CheckIn:E] Stopped
2018-08-22T21:20:02.384Z,1534972802.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:20:02.384Z,1534972802.384 [Default:CheckIn] Stopped
2018-08-22T21:20:02.384Z,1534972802.384 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:20:02.385Z,1534972802.385 [Default:CheckIn](INFO): Running loop #4
2018-08-22T21:20:02.385Z,1534972802.385 [Default:CheckIn] Running Loop=4
2018-08-22T21:20:02.385Z,1534972802.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:20:02.385Z,1534972802.385 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:20:05.982Z,1534972805.982 [NAL9602](INFO): Powering up
2018-08-22T21:20:17.154Z,1534972817.154 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:21:19.596Z,1534972879.596 [NAL9602](INFO): SBD MO Status=0, MOMSN=8445, MT Status=0, MTMSN=0
2018-08-22T21:21:19.596Z,1534972879.596 [NAL9602](INFO): No messages in MT queue
2018-08-22T21:21:20.800Z,1534972880.800 [NAL9602](INFO): GPS fix at 20180822T212118: (36.802496, -121.787049)
2018-08-22T21:21:20.946Z,1534972880.946 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:21:20.946Z,1534972880.946 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-22T21:21:26.167Z,1534972886.167 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20180822T210759/Courier0006.lzma
2018-08-22T21:21:26.970Z,1534972886.970 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0006.lzma.bak
2018-08-22T21:21:26.970Z,1534972886.970 [DataOverHttps](INFO): SBD MOMSN=8436421
2018-08-22T21:21:38.230Z,1534972898.230 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180822T210759/Express0007.lzma
2018-08-22T21:21:39.027Z,1534972899.027 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0007.lzma.bak
2018-08-22T21:21:39.027Z,1534972899.027 [DataOverHttps](INFO): SBD MOMSN=8436424
2018-08-22T21:21:39.926Z,1534972899.926 [Default:CheckIn:Read_Iridium] Stopped
2018-08-22T21:21:39.926Z,1534972899.926 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-22T21:21:39.926Z,1534972899.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-22T21:21:56.474Z,1534972916.474 [NAL9602](INFO): Powering down
2018-08-22T21:26:40.062Z,1534973200.062 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:26:40.062Z,1534973200.062 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:26:40.062Z,1534973200.062 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:26:40.063Z,1534973200.063 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:26:40.463Z,1534973200.463 [Default:CheckIn:D] Stopped
2018-08-22T21:26:40.463Z,1534973200.463 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:26:40.964Z,1534973200.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.477346 min 
2018-08-22T21:26:40.964Z,1534973200.964 [Default:CheckIn:E] Stopped
2018-08-22T21:26:40.970Z,1534973200.970 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:26:40.970Z,1534973200.970 [Default:CheckIn] Stopped
2018-08-22T21:26:40.970Z,1534973200.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:26:40.970Z,1534973200.970 [Default:CheckIn](INFO): Running loop #5
2018-08-22T21:26:40.971Z,1534973200.971 [Default:CheckIn] Running Loop=5
2018-08-22T21:26:40.971Z,1534973200.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:26:40.971Z,1534973200.971 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:26:41.622Z,1534973201.622 [NAL9602](INFO): Powering up
2018-08-22T21:26:52.430Z,1534973212.430 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:27:10.833Z,1534973230.833 [NAL9602](INFO): SBD MO Status=0, MOMSN=8446, MT Status=0, MTMSN=0
2018-08-22T21:27:10.833Z,1534973230.833 [NAL9602](INFO): No messages in MT queue
2018-08-22T21:27:20.866Z,1534973240.866 [NAL9602](INFO): GPS fix at 20180822T212718: (36.802371, -121.787081)
2018-08-22T21:27:20.975Z,1534973240.975 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:27:20.976Z,1534973240.976 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-22T21:27:26.530Z,1534973246.530 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20180822T210759/Courier0009.lzma
2018-08-22T21:27:27.327Z,1534973247.327 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0009.lzma.bak
2018-08-22T21:27:27.327Z,1534973247.327 [DataOverHttps](INFO): SBD MOMSN=8436428
2018-08-22T21:27:37.132Z,1534973257.132 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180822T210759/Express0010.lzma
2018-08-22T21:27:37.931Z,1534973257.931 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0010.lzma.bak
2018-08-22T21:27:37.931Z,1534973257.931 [DataOverHttps](INFO): SBD MOMSN=8436432
2018-08-22T21:27:39.128Z,1534973259.128 [Default:CheckIn:Read_Iridium] Stopped
2018-08-22T21:27:39.129Z,1534973259.129 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-22T21:27:39.129Z,1534973259.129 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-22T21:27:56.263Z,1534973276.263 [NAL9602](INFO): Powering down
2018-08-22T21:32:39.491Z,1534973559.491 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:32:39.491Z,1534973559.491 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:32:39.491Z,1534973559.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:32:39.492Z,1534973559.492 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:32:39.806Z,1534973559.806 [Default:CheckIn:D] Stopped
2018-08-22T21:32:39.806Z,1534973559.806 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:32:40.214Z,1534973560.214 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.466394 min 
2018-08-22T21:32:40.214Z,1534973560.214 [Default:CheckIn:E] Stopped
2018-08-22T21:32:40.214Z,1534973560.214 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:32:40.214Z,1534973560.214 [Default:CheckIn] Stopped
2018-08-22T21:32:40.215Z,1534973560.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:32:40.215Z,1534973560.215 [Default:CheckIn](INFO): Running loop #6
2018-08-22T21:32:40.215Z,1534973560.215 [Default:CheckIn] Running Loop=6
2018-08-22T21:32:40.215Z,1534973560.215 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:32:40.215Z,1534973560.215 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:32:43.814Z,1534973563.814 [NAL9602](INFO): Powering up
2018-08-22T21:32:54.982Z,1534973574.982 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:33:07.388Z,1534973587.388 [NAL9602](INFO): SBD MO Status=0, MOMSN=8447, MT Status=0, MTMSN=0
2018-08-22T21:33:07.389Z,1534973587.389 [NAL9602](INFO): No messages in MT queue
2018-08-22T21:34:58.990Z,1534973698.990 [NAL9602](INFO): GPS fix at 20180822T213456: (36.802850, -121.788036)
2018-08-22T21:34:59.053Z,1534973699.053 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:34:59.054Z,1534973699.054 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-22T21:35:03.916Z,1534973703.916 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180822T210759/Courier0012.lzma
2018-08-22T21:35:04.720Z,1534973704.720 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0012.lzma.bak
2018-08-22T21:35:04.720Z,1534973704.720 [DataOverHttps](INFO): SBD MOMSN=8436445
2018-08-22T21:35:14.890Z,1534973714.890 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180822T210759/Express0013.lzma
2018-08-22T21:35:15.683Z,1534973715.683 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0013.lzma.bak
2018-08-22T21:35:15.683Z,1534973715.683 [DataOverHttps](INFO): SBD MOMSN=8436448
2018-08-22T21:35:16.787Z,1534973716.787 [Default:CheckIn:Read_Iridium] Stopped
2018-08-22T21:35:16.787Z,1534973716.787 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-22T21:35:16.787Z,1534973716.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-22T21:35:34.094Z,1534973734.094 [NAL9602](INFO): Powering down
2018-08-22T21:40:17.231Z,1534974017.231 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:40:17.231Z,1534974017.231 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:40:17.231Z,1534974017.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:40:17.232Z,1534974017.232 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:40:17.581Z,1534974017.581 [Default:CheckIn:D] Stopped
2018-08-22T21:40:17.581Z,1534974017.581 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:40:17.981Z,1534974017.981 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.095984 min 
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn:E] Stopped
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn] Stopped
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn](INFO): Running loop #7
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn] Running Loop=7
2018-08-22T21:40:17.982Z,1534974017.982 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:40:17.983Z,1534974017.983 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:40:21.574Z,1534974021.574 [NAL9602](INFO): Powering up
2018-08-22T21:40:32.734Z,1534974032.734 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:40:49.132Z,1534974049.132 [NAL9602](INFO): SBD MO Status=2, MOMSN=8448, MT Status=2, MTMSN=0
2018-08-22T21:40:49.133Z,1534974049.133 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-08-22T21:41:09.932Z,1534974069.932 [NAL9602](INFO): SBD MO Status=0, MOMSN=8448, MT Status=0, MTMSN=0
2018-08-22T21:41:09.932Z,1534974069.932 [NAL9602](INFO): No messages in MT queue
2018-08-22T21:44:27.755Z,1534974267.755 [NAL9602](INFO): GPS fix at 20180822T214426: (36.802733, -121.788059)
2018-08-22T21:44:27.827Z,1534974267.827 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:44:27.828Z,1534974267.828 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-22T21:44:32.470Z,1534974272.470 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20180822T210759/Courier0015.lzma
2018-08-22T21:44:33.241Z,1534974273.241 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Courier0015.lzma.bak
2018-08-22T21:44:33.242Z,1534974273.242 [DataOverHttps](INFO): SBD MOMSN=8436459
2018-08-22T21:44:43.874Z,1534974283.874 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180822T210759/Express0016.lzma
2018-08-22T21:44:44.671Z,1534974284.671 [DataOverHttps](INFO): Moved sent file to Logs/20180822T210759/Express0016.lzma.bak
2018-08-22T21:44:44.672Z,1534974284.672 [DataOverHttps](INFO): SBD MOMSN=8436462
2018-08-22T21:44:45.713Z,1534974285.713 [Default:CheckIn:Read_Iridium] Stopped
2018-08-22T21:44:45.713Z,1534974285.713 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-22T21:44:45.713Z,1534974285.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-22T21:45:02.642Z,1534974302.642 [NAL9602](INFO): Powering down
2018-08-22T21:49:45.976Z,1534974585.976 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-22T21:49:45.976Z,1534974585.976 [Default:CheckIn:C.Wait] Stopped
2018-08-22T21:49:45.976Z,1534974585.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-22T21:49:45.977Z,1534974585.977 [Default:CheckIn:D] Running Loop=1
2018-08-22T21:49:46.384Z,1534974586.384 [Default:CheckIn:D] Stopped
2018-08-22T21:49:46.384Z,1534974586.384 [Default:CheckIn:E] Running Loop=1
2018-08-22T21:49:46.922Z,1534974586.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.576034 min 
2018-08-22T21:49:46.922Z,1534974586.922 [Default:CheckIn:E] Stopped
2018-08-22T21:49:46.922Z,1534974586.922 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn] Stopped
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn](INFO): Running loop #8
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn] Running Loop=8
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-22T21:49:46.923Z,1534974586.923 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-22T21:49:50.492Z,1534974590.492 [NAL9602](INFO): Powering up
2018-08-22T21:50:01.415Z,1534974601.415 [NAL9602](INFO): NAL9602 initialized
2018-08-22T21:50:11.231Z,1534974611.231 [CommandLine](IMPORTANT): got command exit
2018-08-22T21:50:12.240Z,1534974612.240 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:12.241Z,1534974612.241 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.361Z,1534974612.361 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-22T21:50:12.361Z,1534974612.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.362Z,1534974612.362 [CommandLine](INFO): Join timeout helper Thread ID is 8336
2018-08-22T21:50:12.362Z,1534974612.362 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-22T21:50:12.362Z,1534974612.362 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.363Z,1534974612.363 [NavChartDb](INFO): Join timeout helper Thread ID is 8337
2018-08-22T21:50:12.437Z,1534974612.437 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:12.437Z,1534974612.437 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.445Z,1534974612.445 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-08-22T21:50:12.445Z,1534974612.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.445Z,1534974612.445 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8338
2018-08-22T21:50:12.461Z,1534974612.461 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:12.461Z,1534974612.461 [WetLabsBB2FL](INFO): Powering down
2018-08-22T21:50:12.462Z,1534974612.462 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.465Z,1534974612.465 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-08-22T21:50:12.465Z,1534974612.465 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:12.465Z,1534974612.465 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8339
2018-08-22T21:50:12.674Z,1534974612.674 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:13.120Z,1534974613.120 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-22T21:50:13.120Z,1534974613.120 [CTD_Seabird](INFO): Powering down
2018-08-22T21:50:13.121Z,1534974613.121 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:13.137Z,1534974613.137 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-08-22T21:50:13.137Z,1534974613.137 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:13.138Z,1534974613.138 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8341
2018-08-22T21:50:13.417Z,1534974613.417 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:13.417Z,1534974613.417 [CTD_NeilBrown](INFO): Powering down
2018-08-22T21:50:13.418Z,1534974613.418 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:13.426Z,1534974613.426 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-08-22T21:50:13.426Z,1534974613.426 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:13.427Z,1534974613.427 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 8342
2018-08-22T21:50:14.085Z,1534974614.085 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:14.085Z,1534974614.085 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-22T21:50:14.185Z,1534974614.185 [Rowe_600LCM](INFO): Powering down
2018-08-22T21:50:14.187Z,1534974614.187 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.202Z,1534974614.202 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-22T21:50:14.202Z,1534974614.202 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.202Z,1534974614.202 [Radio_Surface](INFO): Join timeout helper Thread ID is 8344
2018-08-22T21:50:14.597Z,1534974614.597 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:14.597Z,1534974614.597 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.602Z,1534974614.602 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-22T21:50:14.602Z,1534974614.602 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.602Z,1534974614.602 [logger](INFO): Join timeout helper Thread ID is 8345
2018-08-22T21:50:14.633Z,1534974614.633 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:14.633Z,1534974614.633 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.650Z,1534974614.650 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-22T21:50:14.650Z,1534974614.650 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.650Z,1534974614.650 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-22T21:50:14.650Z,1534974614.650 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.650Z,1534974614.650 [controlThread](INFO): Join timeout helper Thread ID is 8346
2018-08-22T21:50:14.667Z,1534974614.667 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-22T21:50:14.667Z,1534974614.667 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-22T21:50:14.668Z,1534974614.668 [NAL9602](INFO): Powering down
2018-08-22T21:50:14.669Z,1534974614.669 [PNI_TCM](INFO): Powering down
2018-08-22T21:50:14.757Z,1534974614.757 [Aanderaa_O2](INFO): Powering down
2018-08-22T21:50:14.759Z,1534974614.759 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-22T21:50:14.760Z,1534974614.760 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-22T21:50:14.761Z,1534974614.761 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-08-22T21:50:14.762Z,1534974614.762 [MissionManager](INFO): Uninitializing Mission Default
2018-08-22T21:50:14.762Z,1534974614.762 [Default] Stopped
2018-08-22T21:50:14.762Z,1534974614.762 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-22T21:50:14.762Z,1534974614.762 [Default:B.GoToSurface] Stopped
2018-08-22T21:50:14.762Z,1534974614.762 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-22T21:50:14.763Z,1534974614.763 [Default:CheckIn] Stopped
2018-08-22T21:50:14.763Z,1534974614.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-22T21:50:14.763Z,1534974614.763 [Default:CheckIn:Read_GPS] Stopped
2018-08-22T21:50:14.767Z,1534974614.767 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-22T21:50:14.767Z,1534974614.767 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-22T21:50:14.768Z,1534974614.768 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-22T21:50:14.768Z,1534974614.768 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-22T21:50:14.769Z,1534974614.769 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-22T21:50:14.769Z,1534974614.769 [BuoyancyServo](INFO): Powering down
2018-08-22T21:50:14.781Z,1534974614.781 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-22T21:50:14.781Z,1534974614.781 [ElevatorServo](INFO): Powering down
2018-08-22T21:50:14.782Z,1534974614.782 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-22T21:50:14.782Z,1534974614.782 [MassServo](INFO): Powering down
2018-08-22T21:50:14.783Z,1534974614.783 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-22T21:50:14.783Z,1534974614.783 [RudderServo](INFO): Powering down
2018-08-22T21:50:14.784Z,1534974614.784 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-22T21:50:14.784Z,1534974614.784 [ThrusterServo](INFO): Powering down
2018-08-22T21:50:14.802Z,1534974614.802 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-22T21:50:14.802Z,1534974614.802 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-22T21:50:14.802Z,1534974614.802 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-22T21:50:14.803Z,1534974614.803 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.926Z,1534974614.926 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.933Z,1534974614.933 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.971Z,1534974614.971 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.975Z,1534974614.975 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:14.978Z,1534974614.978 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:15.005Z,1534974615.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-22T21:50:15.079Z,1534974615.079 [logger ThreadHandler](INFO): Thread cancelled.