2021-02-18T17:58:38.936Z,1613671118.936 [CommandLine](IMPORTANT): got command restart logs 2021-02-18T17:59:24.598Z,1613671164.598 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T17:59:24.598Z,1613671164.598 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T17:59:24.598Z,1613671164.598 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T17:59:24.598Z,1613671164.598 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T17:59:25.031Z,1613671165.031 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T17:59:25.031Z,1613671165.031 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 10.462976 min 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2021-02-18T17:59:25.424Z,1613671165.424 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T17:59:25.425Z,1613671165.425 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T17:59:27.423Z,1613671167.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175927.00,A,4133.11773,N,07042.60907,W,4.840,104.89,180221,,,D*7D 2021-02-18T17:59:27.426Z,1613671167.426 [NAL9602](INFO): GPS fix at 20210218T175927: (41.551962, -70.710151) 2021-02-18T17:59:27.477Z,1613671167.477 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T17:59:27.478Z,1613671167.478 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T17:59:31.046Z,1613671171.046 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210218T061751/Courier0141.lzma 2021-02-18T17:59:32.051Z,1613671172.051 [DataOverHttps](INFO): Moved sent file to Logs/20210218T061751/Courier0141.lzma.bak 2021-02-18T17:59:32.051Z,1613671172.051 [DataOverHttps](INFO): SBD MOMSN=83671 2021-02-18T17:59:42.775Z,1613671182.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=13773, MT Status=0, MTMSN=0 2021-02-18T17:59:42.775Z,1613671182.775 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:00:13.485Z,1613671213.485 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:00:26.267Z,1613671226.267 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20210218T175838/Courier0000.lzma 2021-02-18T18:00:27.269Z,1613671227.269 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0000.lzma.bak 2021-02-18T18:00:27.269Z,1613671227.269 [DataOverHttps](INFO): SBD MOMSN=83674 2021-02-18T18:00:36.306Z,1613671236.306 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210218T175838/Courier0003.lzma 2021-02-18T18:00:37.309Z,1613671237.309 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0003.lzma.bak 2021-02-18T18:00:37.309Z,1613671237.309 [DataOverHttps](INFO): SBD MOMSN=83678 2021-02-18T18:00:46.359Z,1613671246.359 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20210218T061751/Express0142.lzma 2021-02-18T18:00:47.363Z,1613671247.363 [DataOverHttps](INFO): Moved sent file to Logs/20210218T061751/Express0142.lzma.bak 2021-02-18T18:00:47.363Z,1613671247.363 [DataOverHttps](INFO): SBD MOMSN=83680 2021-02-18T18:01:48.615Z,1613671308.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:01:57.655Z,1613671317.655 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20210218T175838/Express0001.lzma 2021-02-18T18:01:58.657Z,1613671318.657 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0001.lzma.bak 2021-02-18T18:01:58.657Z,1613671318.657 [DataOverHttps](INFO): SBD MOMSN=83683 2021-02-18T18:02:34.800Z,1613671354.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:02:38.819Z,1613671358.819 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210218T175838/Express0002.lzma 2021-02-18T18:02:39.821Z,1613671359.821 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0002.lzma.bak 2021-02-18T18:02:39.821Z,1613671359.821 [DataOverHttps](INFO): SBD MOMSN=83687 2021-02-18T18:02:41.359Z,1613671361.359 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:02:41.360Z,1613671361.360 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:02:41.360Z,1613671361.360 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:04:50.640Z,1613671490.640 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:05:00.734Z,1613671500.734 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-02-18T18:07:16.960Z,1613671636.960 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247440 2021-02-18T18:07:27.004Z,1613671647.004 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003327 2021-02-18T18:07:41.966Z,1613671661.966 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:07:41.966Z,1613671661.966 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:07:41.966Z,1613671661.966 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:07:41.966Z,1613671661.966 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:07:42.468Z,1613671662.468 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:07:42.468Z,1613671662.468 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 18.753595 min 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:07:42.968Z,1613671662.968 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2021-02-18T18:07:42.969Z,1613671662.969 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2021-02-18T18:07:42.969Z,1613671662.969 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:07:42.969Z,1613671662.969 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:07:44.762Z,1613671664.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180745.00,A,4132.48428,N,07041.78159,W,8.320,160.45,180221,,,D*77 2021-02-18T18:07:44.782Z,1613671664.782 [NAL9602](INFO): GPS fix at 20210218T180745: (41.541405, -70.696360) 2021-02-18T18:07:44.804Z,1613671664.804 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:07:44.804Z,1613671664.804 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:07:49.103Z,1613671669.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210218T175838/Courier0005.lzma 2021-02-18T18:07:50.105Z,1613671670.105 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0005.lzma.bak 2021-02-18T18:07:50.105Z,1613671670.105 [DataOverHttps](INFO): SBD MOMSN=83689 2021-02-18T18:07:59.151Z,1613671679.151 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20210218T175838/Express0007.lzma 2021-02-18T18:08:00.221Z,1613671680.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=13774, MT Status=0, MTMSN=0 2021-02-18T18:08:00.221Z,1613671680.221 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:08:00.452Z,1613671680.452 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0007.lzma.bak 2021-02-18T18:08:00.453Z,1613671680.453 [DataOverHttps](INFO): SBD MOMSN=83693 2021-02-18T18:08:01.443Z,1613671681.443 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:08:01.443Z,1613671681.443 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:08:01.443Z,1613671681.443 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:08:28.944Z,1613671708.944 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-02-18T18:08:28.946Z,1613671708.946 [BPC1](INFO): Received data from all battery sticks. 2021-02-18T18:08:30.921Z,1613671710.921 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:09:41.576Z,1613671781.576 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251705 2021-02-18T18:09:51.620Z,1613671791.620 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003321 2021-02-18T18:10:51.856Z,1613671851.856 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239388 2021-02-18T18:11:01.906Z,1613671861.906 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007780 2021-02-18T18:12:36.156Z,1613671956.156 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-02-18T18:13:02.026Z,1613671982.026 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:13:02.026Z,1613671982.026 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:13:02.026Z,1613671982.026 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:13:02.027Z,1613671982.027 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:13:02.431Z,1613671982.431 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:13:02.431Z,1613671982.431 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:13:02.854Z,1613671982.854 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 24.086310 min 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4 2021-02-18T18:13:02.855Z,1613671982.855 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4 2021-02-18T18:13:02.856Z,1613671982.856 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:13:02.856Z,1613671982.856 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:13:04.856Z,1613671984.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181305.00,A,4131.57330,N,07041.62022,W,11.585,156.27,180221,,,A*46 2021-02-18T18:13:04.858Z,1613671984.858 [NAL9602](INFO): GPS fix at 20210218T181305: (41.526222, -70.693670) 2021-02-18T18:13:04.898Z,1613671984.898 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:13:04.898Z,1613671984.898 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:13:08.423Z,1613671988.423 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20210218T175838/Courier0009.lzma 2021-02-18T18:13:09.425Z,1613671989.425 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0009.lzma.bak 2021-02-18T18:13:09.425Z,1613671989.425 [DataOverHttps](INFO): SBD MOMSN=83697 2021-02-18T18:13:18.171Z,1613671998.171 [NAL9602](INFO): SBD MO Status=0, MOMSN=13775, MT Status=0, MTMSN=0 2021-02-18T18:13:18.171Z,1613671998.171 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:13:18.469Z,1613671998.469 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20210218T175838/Express0010.lzma 2021-02-18T18:13:19.469Z,1613671999.469 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0010.lzma.bak 2021-02-18T18:13:19.469Z,1613671999.469 [DataOverHttps](INFO): SBD MOMSN=83700 2021-02-18T18:13:21.013Z,1613672001.013 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:13:21.013Z,1613672001.013 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:13:21.013Z,1613672001.013 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:13:48.902Z,1613672028.902 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:16:56.360Z,1613672216.360 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items 2021-02-18T18:17:21.468Z,1613672241.468 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255367 2021-02-18T18:17:31.518Z,1613672251.518 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007726 2021-02-18T18:18:21.618Z,1613672301.618 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:18:21.618Z,1613672301.618 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:18:21.618Z,1613672301.618 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:18:21.618Z,1613672301.618 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:18:22.021Z,1613672302.021 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:18:22.021Z,1613672302.021 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:18:22.411Z,1613672302.411 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 29.412809 min 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:18:22.412Z,1613672302.412 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:18:24.423Z,1613672304.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181824.00,A,4131.24186,N,07040.53607,W,12.849,73.63,180221,,,D*7A 2021-02-18T18:18:24.425Z,1613672304.425 [NAL9602](INFO): GPS fix at 20210218T181824: (41.520698, -70.675601) 2021-02-18T18:18:24.436Z,1613672304.436 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:18:24.436Z,1613672304.436 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:18:28.763Z,1613672308.763 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210218T175838/Courier0012.lzma 2021-02-18T18:18:29.761Z,1613672309.761 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0012.lzma.bak 2021-02-18T18:18:29.761Z,1613672309.761 [DataOverHttps](INFO): SBD MOMSN=83704 2021-02-18T18:18:38.799Z,1613672318.799 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20210218T175838/Express0013.lzma 2021-02-18T18:18:39.801Z,1613672319.801 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0013.lzma.bak 2021-02-18T18:18:39.801Z,1613672319.801 [DataOverHttps](INFO): SBD MOMSN=83707 2021-02-18T18:18:40.583Z,1613672320.583 [NAL9602](INFO): SBD MO Status=0, MOMSN=13776, MT Status=0, MTMSN=0 2021-02-18T18:18:40.583Z,1613672320.583 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:18:41.402Z,1613672321.402 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:18:41.403Z,1613672321.403 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:18:41.403Z,1613672321.403 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:19:11.283Z,1613672351.283 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:23:41.997Z,1613672621.997 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:23:41.997Z,1613672621.997 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:23:41.998Z,1613672621.998 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:23:41.998Z,1613672621.998 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:23:42.411Z,1613672622.411 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:23:42.411Z,1613672622.411 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:23:42.839Z,1613672622.839 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 34.752645 min 2021-02-18T18:23:42.839Z,1613672622.839 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:23:42.840Z,1613672622.840 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:23:44.801Z,1613672624.801 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182345.00,A,4131.45759,N,07040.31810,W,0.019,79.75,180221,,,D*49 2021-02-18T18:23:44.807Z,1613672624.807 [NAL9602](INFO): GPS fix at 20210218T182345: (41.524293, -70.671968) 2021-02-18T18:23:44.872Z,1613672624.872 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:23:44.872Z,1613672624.872 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:23:59.792Z,1613672639.792 [NAL9602](INFO): SBD MO Status=0, MOMSN=13777, MT Status=0, MTMSN=0 2021-02-18T18:23:59.793Z,1613672639.793 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:24:17.191Z,1613672657.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:24:27.245Z,1613672667.245 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.272572 2021-02-18T18:24:46.800Z,1613672686.800 [NAL9602](INFO): SBD MO Status=1, MOMSN=13778, MT Status=0, MTMSN=0 2021-02-18T18:24:46.861Z,1613672686.861 [NAL9602](INFO): Sent 72 bytes from file Logs/20210218T175838/Courier0015.lzma 2021-02-18T18:24:46.861Z,1613672686.861 [NAL9602](INFO): Packets left to send: 0 2021-02-18T18:24:53.797Z,1613672693.797 [NAL9602](INFO): SBD MO Status=1, MOMSN=13779, MT Status=0, MTMSN=0 2021-02-18T18:24:53.847Z,1613672693.847 [NAL9602](INFO): Sent 120 bytes from file Logs/20210218T175838/Express0016.lzma 2021-02-18T18:24:53.847Z,1613672693.847 [NAL9602](INFO): Packets left to send: 0 2021-02-18T18:24:56.622Z,1613672696.622 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-02-18T18:24:58.376Z,1613672698.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:25:05.119Z,1613672705.119 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:25:06.713Z,1613672706.713 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:25:09.953Z,1613672709.953 [NAL9602](INFO): SBD MO Status=0, MOMSN=13780, MT Status=0, MTMSN=0 2021-02-18T18:25:18.034Z,1613672718.034 [NAL9602](INFO): SBD MO Status=0, MOMSN=13781, MT Status=0, MTMSN=0 2021-02-18T18:25:28.127Z,1613672728.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=13782, MT Status=0, MTMSN=0 2021-02-18T18:25:33.520Z,1613672733.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:25:45.495Z,1613672745.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=13783, MT Status=0, MTMSN=0 2021-02-18T18:25:53.415Z,1613672753.415 [NAL9602](INFO): SBD MO Status=0, MOMSN=13784, MT Status=0, MTMSN=0 2021-02-18T18:26:01.547Z,1613672761.547 [NAL9602](INFO): SBD MO Status=0, MOMSN=13785, MT Status=0, MTMSN=0 2021-02-18T18:26:08.667Z,1613672768.667 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:26:12.412Z,1613672772.412 [NAL9602](INFO): SBD MO Status=0, MOMSN=13786, MT Status=0, MTMSN=0 2021-02-18T18:26:26.945Z,1613672786.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=13787, MT Status=0, MTMSN=0 2021-02-18T18:26:36.243Z,1613672796.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=13788, MT Status=0, MTMSN=0 2021-02-18T18:26:43.807Z,1613672803.807 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:26:45.131Z,1613672805.131 [NAL9602](INFO): SBD MO Status=0, MOMSN=13789, MT Status=0, MTMSN=0 2021-02-18T18:26:52.403Z,1613672812.403 [NAL9602](INFO): SBD MO Status=0, MOMSN=13790, MT Status=0, MTMSN=0 2021-02-18T18:27:00.500Z,1613672820.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=13791, MT Status=0, MTMSN=0 2021-02-18T18:27:05.558Z,1613672825.558 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items 2021-02-18T18:27:08.967Z,1613672828.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=13792, MT Status=0, MTMSN=0 2021-02-18T18:27:18.955Z,1613672838.955 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:27:19.495Z,1613672839.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=13793, MT Status=0, MTMSN=0 2021-02-18T18:27:21.094Z,1613672841.094 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:27:26.738Z,1613672846.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=13794, MT Status=0, MTMSN=0 2021-02-18T18:27:36.351Z,1613672856.351 [NAL9602](INFO): SBD MO Status=0, MOMSN=13795, MT Status=0, MTMSN=0 2021-02-18T18:27:45.299Z,1613672865.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=13796, MT Status=0, MTMSN=0 2021-02-18T18:27:54.109Z,1613672874.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:27:54.311Z,1613672874.311 [NAL9602](INFO): SBD MO Status=0, MOMSN=13797, MT Status=0, MTMSN=0 2021-02-18T18:28:12.495Z,1613672892.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=13798, MT Status=0, MTMSN=0 2021-02-18T18:28:20.975Z,1613672900.975 [NAL9602](INFO): SBD MO Status=0, MOMSN=13799, MT Status=0, MTMSN=0 2021-02-18T18:28:28.646Z,1613672908.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=13800, MT Status=0, MTMSN=0 2021-02-18T18:28:29.264Z,1613672909.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:28:35.919Z,1613672915.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=13801, MT Status=0, MTMSN=0 2021-02-18T18:28:41.320Z,1613672921.320 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003799 2021-02-18T18:28:42.813Z,1613672922.813 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:28:42.814Z,1613672922.814 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:28:42.814Z,1613672922.814 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:28:44.824Z,1613672924.824 [NAL9602](INFO): SBD MO Status=0, MOMSN=13802, MT Status=0, MTMSN=0 2021-02-18T18:29:15.509Z,1613672955.509 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:30:22.744Z,1613673022.744 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263447 2021-02-18T18:30:32.788Z,1613673032.788 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003846 2021-02-18T18:33:43.448Z,1613673223.448 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:33:43.448Z,1613673223.448 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:33:43.448Z,1613673223.448 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:33:43.448Z,1613673223.448 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:33:43.885Z,1613673223.885 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:33:43.886Z,1613673223.886 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 44.777214 min 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7 2021-02-18T18:33:44.305Z,1613673224.305 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7 2021-02-18T18:33:44.306Z,1613673224.306 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:33:44.306Z,1613673224.306 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:33:46.272Z,1613673226.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183346.00,A,4131.46151,N,07040.31058,W,1.924,23.93,180221,,,D*43 2021-02-18T18:33:46.274Z,1613673226.274 [NAL9602](INFO): GPS fix at 20210218T183346: (41.524358, -70.671843) 2021-02-18T18:33:46.331Z,1613673226.331 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:33:46.331Z,1613673226.331 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:33:50.610Z,1613673230.610 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210218T175838/Courier0018.lzma 2021-02-18T18:33:51.613Z,1613673231.613 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0018.lzma.bak 2021-02-18T18:33:51.613Z,1613673231.613 [DataOverHttps](INFO): SBD MOMSN=83728 2021-02-18T18:34:00.651Z,1613673240.651 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210218T175838/Express0019.lzma 2021-02-18T18:34:01.653Z,1613673241.653 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0019.lzma.bak 2021-02-18T18:34:01.653Z,1613673241.653 [DataOverHttps](INFO): SBD MOMSN=83732 2021-02-18T18:34:02.941Z,1613673242.941 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:34:02.941Z,1613673242.941 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:34:02.941Z,1613673242.941 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:34:04.898Z,1613673244.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=13803, MT Status=2, MTMSN=0 2021-02-18T18:34:04.898Z,1613673244.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-02-18T18:34:33.779Z,1613673273.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-02-18T18:34:42.902Z,1613673282.902 [NAL9602](INFO): SBD MO Status=2, MOMSN=13803, MT Status=2, MTMSN=0 2021-02-18T18:34:42.902Z,1613673282.902 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-02-18T18:35:15.198Z,1613673315.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=13803, MT Status=0, MTMSN=0 2021-02-18T18:35:15.198Z,1613673315.198 [NAL9602](INFO): No messages in MT queue 2021-02-18T18:35:45.900Z,1613673345.900 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:39:03.469Z,1613673543.469 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:39:03.470Z,1613673543.470 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:39:03.470Z,1613673543.470 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:39:03.470Z,1613673543.470 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:39:03.893Z,1613673543.893 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:39:03.893Z,1613673543.893 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:39:04.304Z,1613673544.304 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 50.110677 min 2021-02-18T18:39:04.304Z,1613673544.304 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:39:04.304Z,1613673544.304 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:39:04.304Z,1613673544.304 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:39:04.304Z,1613673544.304 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:39:04.305Z,1613673544.305 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #8 2021-02-18T18:39:04.305Z,1613673544.305 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=8 2021-02-18T18:39:04.305Z,1613673544.305 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:39:04.305Z,1613673544.305 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:39:06.295Z,1613673546.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183906.00,A,4131.46613,N,07040.27900,W,0.039,112.97,180221,,,D*7C 2021-02-18T18:39:06.297Z,1613673546.297 [NAL9602](INFO): GPS fix at 20210218T183906: (41.524436, -70.671317) 2021-02-18T18:39:06.337Z,1613673546.337 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:39:06.337Z,1613673546.337 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:39:09.951Z,1613673549.951 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20210218T175838/Courier0021.lzma 2021-02-18T18:39:10.953Z,1613673550.953 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0021.lzma.bak 2021-02-18T18:39:10.953Z,1613673550.953 [DataOverHttps](INFO): SBD MOMSN=83736 2021-02-18T18:39:17.634Z,1613673557.634 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:39:19.991Z,1613673559.991 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20210218T175838/Express0022.lzma 2021-02-18T18:39:20.993Z,1613673560.993 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0022.lzma.bak 2021-02-18T18:39:20.993Z,1613673560.993 [DataOverHttps](INFO): SBD MOMSN=83739 2021-02-18T18:39:22.467Z,1613673562.467 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:39:22.467Z,1613673562.467 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:39:22.467Z,1613673562.467 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:39:27.302Z,1613673567.302 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-02-18T18:42:30.733Z,1613673750.733 [NAL9602](INFO): SBD MO Status=2, MOMSN=13804, MT Status=2, MTMSN=0 2021-02-18T18:42:30.733Z,1613673750.733 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-02-18T18:43:22.992Z,1613673802.992 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239353 2021-02-18T18:43:33.036Z,1613673813.036 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003826 2021-02-18T18:44:07.721Z,1613673847.721 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-02-18T18:44:08.510Z,1613673848.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-02-18T18:44:23.082Z,1613673863.082 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:44:23.082Z,1613673863.082 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:44:23.082Z,1613673863.082 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:44:23.083Z,1613673863.083 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:44:23.476Z,1613673863.476 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:44:23.476Z,1613673863.476 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 55.437048 min 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #9 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=9 2021-02-18T18:44:23.896Z,1613673863.896 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:44:23.897Z,1613673863.897 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:44:25.887Z,1613673865.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184426.00,A,4131.46480,N,07040.27823,W,0.058,112.97,180221,,,D*7B 2021-02-18T18:44:25.889Z,1613673865.889 [NAL9602](INFO): GPS fix at 20210218T184426: (41.524413, -70.671304) 2021-02-18T18:44:25.928Z,1613673865.928 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:44:25.928Z,1613673865.928 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:44:30.274Z,1613673870.274 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210218T175838/Courier0024.lzma 2021-02-18T18:44:31.279Z,1613673871.279 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0024.lzma.bak 2021-02-18T18:44:31.279Z,1613673871.279 [DataOverHttps](INFO): SBD MOMSN=83742 2021-02-18T18:44:40.621Z,1613673880.621 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20210218T175838/Express0025.lzma 2021-02-18T18:44:41.317Z,1613673881.317 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0025.lzma.bak 2021-02-18T18:44:41.317Z,1613673881.317 [DataOverHttps](INFO): SBD MOMSN=83745 2021-02-18T18:44:42.912Z,1613673882.912 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:44:42.913Z,1613673882.913 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:44:42.913Z,1613673882.913 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:44:58.613Z,1613673898.613 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:45:22.924Z,1613673922.924 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-02-18T18:47:24.092Z,1613674044.092 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T18:49:43.500Z,1613674183.500 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:49:43.501Z,1613674183.501 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:49:43.501Z,1613674183.501 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:49:43.501Z,1613674183.501 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:49:43.964Z,1613674183.964 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:49:43.965Z,1613674183.965 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:49:44.301Z,1613674184.301 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 60.778528 min 2021-02-18T18:49:44.301Z,1613674184.301 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #10 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=10 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:49:44.302Z,1613674184.302 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:49:46.295Z,1613674186.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184946.00,A,4131.46118,N,07040.28122,W,1.419,112.97,180221,,,D*73 2021-02-18T18:49:46.297Z,1613674186.297 [NAL9602](INFO): GPS fix at 20210218T184946: (41.524353, -70.671354) 2021-02-18T18:49:46.368Z,1613674186.368 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T18:49:46.368Z,1613674186.368 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T18:49:50.622Z,1613674190.622 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210218T175838/Courier0027.lzma 2021-02-18T18:49:51.625Z,1613674191.625 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0027.lzma.bak 2021-02-18T18:49:51.625Z,1613674191.625 [DataOverHttps](INFO): SBD MOMSN=83749 2021-02-18T18:50:00.663Z,1613674200.663 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20210218T175838/Express0028.lzma 2021-02-18T18:50:01.665Z,1613674201.665 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0028.lzma.bak 2021-02-18T18:50:01.665Z,1613674201.665 [DataOverHttps](INFO): SBD MOMSN=83753 2021-02-18T18:50:02.951Z,1613674202.951 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T18:50:02.951Z,1613674202.951 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T18:50:02.951Z,1613674202.951 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T18:50:17.016Z,1613674217.016 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-02-18T18:50:17.099Z,1613674217.099 [NAL9602](FAULT): received: +CSQ:0 OK804, 2, 0, 0, 0 OK 2021-02-18T18:50:17.099Z,1613674217.099 [NAL9602] Data Fault, FailCount= 1 2021-02-18T18:50:17.099Z,1613674217.099 [NAL9602](ERROR): Data Fault 2021-02-18T18:50:17.124Z,1613674217.124 [CBIT](ERROR): Data Fault in component: NAL9602 2021-02-18T18:50:17.428Z,1613674217.428 [NAL9602](INFO): Powering down 2021-02-18T18:50:18.303Z,1613674218.303 [CBIT](INFO): Clearing failed state for component NAL9602 2021-02-18T18:50:18.303Z,1613674218.303 [NAL9602] No Fault, FailCount= 1 2021-02-18T18:50:47.725Z,1613674247.725 [NAL9602](INFO): Powering up NAL9602 2021-02-18T18:50:58.633Z,1613674258.633 [NAL9602](INFO): NAL9602 initialized 2021-02-18T18:51:29.738Z,1613674289.738 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T18:52:03.011Z,1613674323.011 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2021-02-18T18:52:03.011Z,1613674323.011 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2021-02-18T18:52:03.011Z,1613674323.011 [RDI_PathfinderUp](ERROR): Communications Fault 2021-02-18T18:52:03.066Z,1613674323.066 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2021-02-18T18:52:03.343Z,1613674323.343 [RDI_PathfinderUp](INFO): Powering down 2021-02-18T18:52:04.096Z,1613674324.096 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2021-02-18T18:52:04.096Z,1613674324.096 [RDI_PathfinderUp] No Fault, FailCount= 1 2021-02-18T18:52:56.949Z,1613674376.949 [CommandLine](IMPORTANT): got command DDM armed 2021-02-18T18:52:56.949Z,1613674376.949 [CommandLine](IMPORTANT): Commanding DDM Armed 2021-02-18T18:53:07.520Z,1613674387.520 [CommandLine](IMPORTANT): got command DDM armed 2021-02-18T18:53:07.520Z,1613674387.520 [CommandLine](IMPORTANT): Commanding DDM Armed 2021-02-18T18:55:03.485Z,1613674503.485 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T18:55:03.485Z,1613674503.485 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T18:55:03.485Z,1613674503.485 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T18:55:03.486Z,1613674503.486 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T18:55:03.892Z,1613674503.892 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T18:55:03.892Z,1613674503.892 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T18:55:04.282Z,1613674504.282 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 66.110649 min 2021-02-18T18:55:04.282Z,1613674504.282 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #11 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=11 2021-02-18T18:55:04.283Z,1613674504.283 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T18:55:04.284Z,1613674504.284 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T18:55:07.898Z,1613674507.898 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-02-18T18:55:13.952Z,1613674513.952 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239426 2021-02-18T18:55:23.996Z,1613674523.996 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003770 2021-02-18T19:00:04.528Z,1613674804.528 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2021-02-18T18:55:04.3Z 2021-02-18T19:00:04.528Z,1613674804.528 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T19:00:04.529Z,1613674804.529 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T19:00:09.194Z,1613674809.194 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210218T175838/Courier0030.lzma 2021-02-18T19:00:10.197Z,1613674810.197 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0030.lzma.bak 2021-02-18T19:00:10.197Z,1613674810.197 [DataOverHttps](INFO): SBD MOMSN=83757 2021-02-18T19:00:19.235Z,1613674819.235 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20210218T175838/Express0031.lzma 2021-02-18T19:00:20.237Z,1613674820.237 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0031.lzma.bak 2021-02-18T19:00:20.237Z,1613674820.237 [DataOverHttps](INFO): SBD MOMSN=83759 2021-02-18T19:00:21.840Z,1613674821.840 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T19:00:21.840Z,1613674821.840 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T19:00:21.841Z,1613674821.841 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T19:00:37.159Z,1613674837.159 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T19:01:08.689Z,1613674868.689 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-02-18T19:01:28.603Z,1613674888.603 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2021-02-18T19:01:28.603Z,1613674888.603 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2021-02-18T19:01:28.603Z,1613674888.603 [RDI_PathfinderUp](ERROR): Communications Fault 2021-02-18T19:01:28.644Z,1613674888.644 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2021-02-18T19:01:28.947Z,1613674888.947 [RDI_PathfinderUp](INFO): Powering down 2021-02-18T19:01:29.715Z,1613674889.715 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2021-02-18T19:01:29.715Z,1613674889.715 [RDI_PathfinderUp] No Fault, FailCount= 1 2021-02-18T19:04:08.541Z,1613675048.541 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-02-18T19:05:22.452Z,1613675122.452 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T19:05:22.452Z,1613675122.452 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T19:05:22.452Z,1613675122.452 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T19:05:22.452Z,1613675122.452 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T19:05:22.850Z,1613675122.850 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T19:05:22.850Z,1613675122.850 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T19:05:23.253Z,1613675123.253 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 76.426628 min 2021-02-18T19:05:23.253Z,1613675123.253 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T19:05:23.253Z,1613675123.253 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:05:23.253Z,1613675123.253 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T19:05:23.254Z,1613675123.254 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:05:23.254Z,1613675123.254 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #12 2021-02-18T19:05:23.254Z,1613675123.254 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=12 2021-02-18T19:05:23.254Z,1613675123.254 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:05:23.254Z,1613675123.254 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T19:05:24.865Z,1613675124.865 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-02-18T19:05:24.865Z,1613675124.865 [NAL9602] Data Fault, FailCount= 1 2021-02-18T19:05:24.865Z,1613675124.865 [NAL9602](ERROR): Data Fault 2021-02-18T19:05:24.960Z,1613675124.960 [CBIT](ERROR): Data Fault in component: NAL9602 2021-02-18T19:05:25.253Z,1613675125.253 [NAL9602](INFO): Powering down 2021-02-18T19:05:26.107Z,1613675126.107 [CBIT](INFO): Clearing failed state for component NAL9602 2021-02-18T19:05:26.107Z,1613675126.107 [NAL9602] No Fault, FailCount= 1 2021-02-18T19:05:55.554Z,1613675155.554 [NAL9602](INFO): Powering up NAL9602 2021-02-18T19:06:06.514Z,1613675166.514 [NAL9602](INFO): NAL9602 initialized 2021-02-18T19:07:03.841Z,1613675223.841 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-02-18T19:07:54.335Z,1613675274.335 [NAL9602](INFO): SBD MO Status=0, MOMSN=13804, MT Status=0, MTMSN=0 2021-02-18T19:07:54.335Z,1613675274.335 [NAL9602](INFO): No messages in MT queue 2021-02-18T19:09:27.668Z,1613675367.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190927.00,A,4131.46474,N,07040.27018,W,0.389,0.00,180221,,,A*7F 2021-02-18T19:09:27.670Z,1613675367.670 [NAL9602](INFO): GPS fix at 20210218T190927: (41.524412, -70.671170) 2021-02-18T19:09:27.719Z,1613675367.719 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T19:09:27.720Z,1613675367.720 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-02-18T19:09:31.514Z,1613675371.514 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210218T175838/Courier0033.lzma 2021-02-18T19:09:32.509Z,1613675372.509 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Courier0033.lzma.bak 2021-02-18T19:09:32.509Z,1613675372.509 [DataOverHttps](INFO): SBD MOMSN=83769 2021-02-18T19:09:42.563Z,1613675382.563 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20210218T175838/Express0034.lzma 2021-02-18T19:09:43.557Z,1613675383.557 [DataOverHttps](INFO): Moved sent file to Logs/20210218T175838/Express0034.lzma.bak 2021-02-18T19:09:43.557Z,1613675383.557 [DataOverHttps](INFO): SBD MOMSN=83772 2021-02-18T19:09:45.151Z,1613675385.151 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-02-18T19:09:45.151Z,1613675385.151 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-02-18T19:09:45.151Z,1613675385.151 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-02-18T19:10:00.424Z,1613675400.424 [NAL9602](INFO): Not Powering down - fast GPS 2021-02-18T19:10:02.980Z,1613675402.980 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T19:12:19.012Z,1613675539.012 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-02-18T19:13:09.097Z,1613675589.097 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-02-18T19:13:29.300Z,1613675609.300 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2021-02-18T19:14:45.670Z,1613675685.670 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-02-18T19:14:45.670Z,1613675685.670 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-02-18T19:14:45.670Z,1613675685.670 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-02-18T19:14:45.670Z,1613675685.670 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-02-18T19:14:46.101Z,1613675686.101 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-02-18T19:14:46.101Z,1613675686.101 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-02-18T19:14:46.607Z,1613675686.607 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 85.814144 min 2021-02-18T19:14:46.607Z,1613675686.607 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-02-18T19:14:46.607Z,1613675686.607 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:14:46.607Z,1613675686.607 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T19:14:46.607Z,1613675686.607 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:14:46.608Z,1613675686.608 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #13 2021-02-18T19:14:46.608Z,1613675686.608 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=13 2021-02-18T19:14:46.608Z,1613675686.608 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:14:46.608Z,1613675686.608 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-02-18T19:15:55.962Z,1613675755.962 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-02-18T19:15:55.964Z,1613675755.964 [BPC1](INFO): Received data from all battery sticks. 2021-02-18T19:17:07.305Z,1613675827.305 [CommandLine](IMPORTANT): got command quit 2021-02-18T19:17:08.323Z,1613675828.323 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2021-02-18T19:17:08.324Z,1613675828.324 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:08.325Z,1613675828.325 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.407Z,1613675828.407 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-02-18T19:17:08.407Z,1613675828.407 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.408Z,1613675828.408 [CommandLine](INFO): Join timeout helper Thread ID is 1677 2021-02-18T19:17:08.408Z,1613675828.408 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-02-18T19:17:08.408Z,1613675828.408 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.409Z,1613675828.409 [NavChartDb](INFO): Join timeout helper Thread ID is 1678 2021-02-18T19:17:08.503Z,1613675828.503 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:08.503Z,1613675828.503 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.507Z,1613675828.507 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2021-02-18T19:17:08.507Z,1613675828.507 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.507Z,1613675828.507 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1679 2021-02-18T19:17:08.627Z,1613675828.627 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:08.627Z,1613675828.627 [WetLabsSeaOWL_UV_A](INFO): Powering down 2021-02-18T19:17:08.628Z,1613675828.628 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.647Z,1613675828.647 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-02-18T19:17:08.647Z,1613675828.647 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.647Z,1613675828.647 [Radio_Surface](INFO): Join timeout helper Thread ID is 1680 2021-02-18T19:17:08.855Z,1613675828.855 [Radio_Surface](INFO): Powering down 2021-02-18T19:17:08.856Z,1613675828.856 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:08.856Z,1613675828.856 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.859Z,1613675828.859 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-02-18T19:17:08.859Z,1613675828.859 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:08.859Z,1613675828.859 [Onboard](INFO): Join timeout helper Thread ID is 1681 2021-02-18T19:17:09.135Z,1613675829.135 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:09.136Z,1613675829.136 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.144Z,1613675829.144 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-02-18T19:17:09.144Z,1613675829.144 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.144Z,1613675829.144 [DataOverHttps](INFO): Join timeout helper Thread ID is 1682 2021-02-18T19:17:09.400Z,1613675829.400 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:09.401Z,1613675829.401 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.412Z,1613675829.412 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-02-18T19:17:09.412Z,1613675829.412 [logger ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.412Z,1613675829.412 [logger](INFO): Join timeout helper Thread ID is 1683 2021-02-18T19:17:09.419Z,1613675829.419 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:09.419Z,1613675829.419 [logger ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.433Z,1613675829.433 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-02-18T19:17:09.433Z,1613675829.433 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.434Z,1613675829.434 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-02-18T19:17:09.434Z,1613675829.434 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.435Z,1613675829.435 [controlThread](INFO): Join timeout helper Thread ID is 1684 2021-02-18T19:17:09.468Z,1613675829.468 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-02-18T19:17:09.469Z,1613675829.469 [controlThread](DEBUG): Uninitializing ControlThread 2021-02-18T19:17:09.470Z,1613675829.470 [AHRS_M2](INFO): Powering down 2021-02-18T19:17:09.544Z,1613675829.544 [DUSBL_Hydroid](INFO): Powering down 2021-02-18T19:17:09.615Z,1613675829.615 [Micromodem](INFO): Powering down 2021-02-18T19:17:09.711Z,1613675829.711 [NAL9602](INFO): Powering down 2021-02-18T19:17:09.783Z,1613675829.783 [RDI_Pathfinder](INFO): Powering down 2021-02-18T19:17:09.855Z,1613675829.855 [RDI_PathfinderUp](INFO): Powering down 2021-02-18T19:17:09.856Z,1613675829.856 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-02-18T19:17:09.857Z,1613675829.857 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-02-18T19:17:09.858Z,1613675829.858 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-02-18T19:17:09.859Z,1613675829.859 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock] Stopped 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault] Stopped 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-02-18T19:17:09.859Z,1613675829.859 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-02-18T19:17:09.866Z,1613675829.866 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-02-18T19:17:09.866Z,1613675829.866 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-02-18T19:17:09.867Z,1613675829.867 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-02-18T19:17:09.867Z,1613675829.867 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-02-18T19:17:09.868Z,1613675829.868 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-02-18T19:17:09.868Z,1613675829.868 [BuoyancyServo](INFO): Powering down 2021-02-18T19:17:09.879Z,1613675829.879 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-02-18T19:17:09.879Z,1613675829.879 [ElevatorServo](INFO): Powering down 2021-02-18T19:17:09.880Z,1613675829.880 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-02-18T19:17:09.880Z,1613675829.880 [MassServo](INFO): Powering down 2021-02-18T19:17:09.881Z,1613675829.881 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-02-18T19:17:09.881Z,1613675829.881 [RudderServo](INFO): Powering down 2021-02-18T19:17:09.882Z,1613675829.882 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-02-18T19:17:09.882Z,1613675829.882 [ThrusterServo](INFO): Powering down 2021-02-18T19:17:09.882Z,1613675829.882 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-02-18T19:17:09.883Z,1613675829.883 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-02-18T19:17:09.883Z,1613675829.883 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-02-18T19:17:09.883Z,1613675829.883 [CBIT](DEBUG): Powering off loads. 2021-02-18T19:17:09.895Z,1613675829.895 [CBIT](DEBUG): Disabling WDT. 2021-02-18T19:17:09.907Z,1613675829.907 [CBIT](DEBUG): Opening all GF detection circuits. 2021-02-18T19:17:09.907Z,1613675829.907 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.918Z,1613675829.918 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.922Z,1613675829.922 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:09.931Z,1613675829.931 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:10.016Z,1613675830.016 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:10.077Z,1613675830.077 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-02-18T19:17:10.147Z,1613675830.147 [logger ThreadHandler](INFO): Thread cancelled.