2014-10-10T20:58:05.619Z,1412974685.619 [CommandLine](IMPORTANT): got command restart logs 2014-10-10T20:58:17.716Z,1412974697.716 [NAL9602](INFO): SBD MO Status=1, MOMSN=45248, MT Status=0, MTMSN=0 2014-10-10T20:58:17.770Z,1412974697.770 [NAL9602](INFO): Sent 25 bytes from file Logs/20141010T205746/Courier0000.lzma 2014-10-10T20:58:17.770Z,1412974697.770 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:58:17.772Z,1412974697.772 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205746/Courier0000.lzma.parts/0000.sbd 2014-10-10T20:58:17.772Z,1412974697.772 [NAL9602](INFO): Completed sending Logs/20141010T205746/Courier0000.lzma 2014-10-10T20:58:29.231Z,1412974709.231 [NAL9602](INFO): SBD MO Status=1, MOMSN=45249, MT Status=0, MTMSN=0 2014-10-10T20:58:29.279Z,1412974709.279 [NAL9602](INFO): Sent 167 bytes from file Logs/20141010T044737/Express0241.lzma 2014-10-10T20:58:29.280Z,1412974709.280 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:58:29.282Z,1412974709.282 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T044737/Express0241.lzma.parts/0000.sbd 2014-10-10T20:58:29.282Z,1412974709.282 [NAL9602](INFO): Completed sending Logs/20141010T044737/Express0241.lzma 2014-10-10T20:58:38.677Z,1412974718.677 [NAL9602](INFO): SBD MO Status=1, MOMSN=45250, MT Status=0, MTMSN=0 2014-10-10T20:58:38.734Z,1412974718.734 [NAL9602](INFO): Sent 95 bytes from file Logs/20141010T044737/Express0245.lzma 2014-10-10T20:58:38.734Z,1412974718.734 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:58:38.736Z,1412974718.736 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T044737/Express0245.lzma.parts/0000.sbd 2014-10-10T20:58:38.737Z,1412974718.737 [NAL9602](INFO): Completed sending Logs/20141010T044737/Express0245.lzma 2014-10-10T20:58:47.513Z,1412974727.513 [NAL9602](INFO): SBD MO Status=1, MOMSN=45251, MT Status=0, MTMSN=0 2014-10-10T20:58:47.560Z,1412974727.560 [NAL9602](INFO): Sent 236 bytes from file Logs/20141010T205729/Express0001.lzma 2014-10-10T20:58:47.560Z,1412974727.560 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:58:47.563Z,1412974727.563 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205729/Express0001.lzma.parts/0000.sbd 2014-10-10T20:58:47.571Z,1412974727.571 [NAL9602](INFO): Completed sending Logs/20141010T205729/Express0001.lzma 2014-10-10T20:58:55.327Z,1412974735.327 [NAL9602](INFO): SBD MO Status=1, MOMSN=45252, MT Status=0, MTMSN=0 2014-10-10T20:58:55.380Z,1412974735.380 [NAL9602](INFO): Sent 238 bytes from file Logs/20141010T205746/Express0001.lzma 2014-10-10T20:58:55.380Z,1412974735.380 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:58:55.382Z,1412974735.382 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205746/Express0001.lzma.parts/0000.sbd 2014-10-10T20:58:55.382Z,1412974735.382 [NAL9602](INFO): Completed sending Logs/20141010T205746/Express0001.lzma 2014-10-10T20:59:05.871Z,1412974745.871 [NAL9602](INFO): SBD MO Status=0, MOMSN=45253, MT Status=0, MTMSN=0 2014-10-10T20:59:30.213Z,1412974770.213 [NAL9602](INFO): SBD MO Status=1, MOMSN=45254, MT Status=0, MTMSN=0 2014-10-10T20:59:30.266Z,1412974770.266 [NAL9602](INFO): Sent 25 bytes from file Logs/20141010T205805/Courier0000.lzma 2014-10-10T20:59:30.266Z,1412974770.266 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:59:30.268Z,1412974770.268 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Courier0000.lzma.parts/0000.sbd 2014-10-10T20:59:30.268Z,1412974770.268 [NAL9602](INFO): Completed sending Logs/20141010T205805/Courier0000.lzma 2014-10-10T20:59:41.807Z,1412974781.807 [NAL9602](INFO): SBD MO Status=1, MOMSN=45255, MT Status=0, MTMSN=0 2014-10-10T20:59:41.856Z,1412974781.856 [NAL9602](INFO): Sent 256 bytes from file Logs/20141010T205805/Express0001.lzma 2014-10-10T20:59:41.856Z,1412974781.856 [NAL9602](INFO): Packets left to send: 0 2014-10-10T20:59:41.858Z,1412974781.858 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Express0001.lzma.parts/0000.sbd 2014-10-10T20:59:41.858Z,1412974781.858 [NAL9602](INFO): Completed sending Logs/20141010T205805/Express0001.lzma 2014-10-10T20:59:52.314Z,1412974792.314 [NAL9602](INFO): SBD MO Status=0, MOMSN=45256, MT Status=0, MTMSN=0 2014-10-10T20:59:52.529Z,1412974792.529 [Default:CheckIn:Read_Iridium] Stopped 2014-10-10T20:59:52.530Z,1412974792.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-10-10T20:59:52.530Z,1412974792.530 [Default:CheckIn] Stopped 2014-10-10T20:59:52.530Z,1412974792.530 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-10-10T20:59:52.530Z,1412974792.530 [Default:CheckIn:A.SetSpeed] Stopped 2014-10-10T20:59:52.530Z,1412974792.530 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-10-10T21:00:02.962Z,1412974802.962 [NAL9602](INFO): Powering down 2014-10-10T21:00:03.116Z,1412974803.116 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:00:03.116Z,1412974803.116 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:00:03.116Z,1412974803.116 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:00:03.117Z,1412974803.117 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:00:03.117Z,1412974803.117 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:00:08.113Z,1412974808.113 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 60.459224 min 2014-10-10T21:00:08.113Z,1412974808.113 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:00:08.114Z,1412974808.114 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:00:08.114Z,1412974808.114 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:00:08.114Z,1412974808.114 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:04:53.096Z,1412975093.096 [Default:CheckIn] Running Loop=1 2014-10-10T21:04:53.096Z,1412975093.096 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-10-10T21:04:53.097Z,1412975093.097 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-10-10T21:04:53.097Z,1412975093.097 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-10-10T21:04:53.097Z,1412975093.097 [Default:CheckIn:Read_GPS] Running Loop=1 2014-10-10T21:04:53.484Z,1412975093.484 [NAL9602](INFO): Powering up 2014-10-10T21:05:03.441Z,1412975103.441 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:05:03.441Z,1412975103.441 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:05:03.442Z,1412975103.442 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:05:03.442Z,1412975103.442 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:05:03.442Z,1412975103.442 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:05:03.993Z,1412975103.993 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 65.465514 min 2014-10-10T21:05:03.994Z,1412975103.994 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:05:03.994Z,1412975103.994 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:05:03.994Z,1412975103.994 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:05:03.994Z,1412975103.994 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:05:04.942Z,1412975104.942 [NAL9602](INFO): NAL9602 initialized 2014-10-10T21:05:23.815Z,1412975123.815 [NAL9602](IMPORTANT): GPS fix at: 1412975104.00 2014-10-10T21:05:23.968Z,1412975123.968 [Default:CheckIn:Read_GPS] Stopped 2014-10-10T21:05:23.968Z,1412975123.968 [Default:CheckIn:Read_Iridium] Running Loop=1 2014-10-10T21:05:44.365Z,1412975144.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=45257, MT Status=2, MTMSN=0 2014-10-10T21:05:44.365Z,1412975144.365 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:06:06.737Z,1412975166.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=45257, MT Status=0, MTMSN=0 2014-10-10T21:06:06.785Z,1412975166.785 [NAL9602](INFO): Sent 206 bytes from file Logs/20141010T205805/Courier0004.lzma 2014-10-10T21:06:06.785Z,1412975166.785 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:06:06.787Z,1412975166.787 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Courier0004.lzma.parts/0000.sbd 2014-10-10T21:06:06.787Z,1412975166.787 [NAL9602](INFO): Completed sending Logs/20141010T205805/Courier0004.lzma 2014-10-10T21:06:47.186Z,1412975207.186 [NAL9602](INFO): SBD MO Status=2, MOMSN=45258, MT Status=2, MTMSN=0 2014-10-10T21:06:47.186Z,1412975207.186 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:06:59.762Z,1412975219.762 [NAL9602](INFO): SBD MO Status=1, MOMSN=45258, MT Status=0, MTMSN=0 2014-10-10T21:06:59.811Z,1412975219.811 [NAL9602](INFO): Sent 248 bytes from file Logs/20141010T205805/Express0005.lzma 2014-10-10T21:06:59.811Z,1412975219.811 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:06:59.813Z,1412975219.813 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Express0005.lzma.parts/0000.sbd 2014-10-10T21:06:59.813Z,1412975219.813 [NAL9602](INFO): Completed sending Logs/20141010T205805/Express0005.lzma 2014-10-10T21:07:06.031Z,1412975226.031 [NAL9602](INFO): SBD MO Status=0, MOMSN=45259, MT Status=0, MTMSN=0 2014-10-10T21:07:06.265Z,1412975226.265 [Default:CheckIn:Read_Iridium] Stopped 2014-10-10T21:07:06.266Z,1412975226.266 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-10-10T21:07:06.266Z,1412975226.266 [Default:CheckIn] Stopped 2014-10-10T21:07:06.266Z,1412975226.266 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-10-10T21:07:06.266Z,1412975226.266 [Default:CheckIn:A.SetSpeed] Stopped 2014-10-10T21:07:06.267Z,1412975226.267 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-10-10T21:07:16.694Z,1412975236.694 [NAL9602](INFO): Powering down 2014-10-10T21:10:06.850Z,1412975406.850 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:10:06.851Z,1412975406.851 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:10:06.851Z,1412975406.851 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:10:06.851Z,1412975406.851 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:10:06.852Z,1412975406.852 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:10:11.850Z,1412975411.850 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 70.521490 min 2014-10-10T21:10:11.851Z,1412975411.851 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:10:11.851Z,1412975411.851 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:10:11.851Z,1412975411.851 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:10:11.851Z,1412975411.851 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:11:56.743Z,1412975516.743 [Depth_Keller](ERROR): Pressure reading out of range: 1830.386841 decibar 2014-10-10T21:12:06.836Z,1412975526.836 [Default:CheckIn] Running Loop=1 2014-10-10T21:12:06.836Z,1412975526.836 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-10-10T21:12:06.837Z,1412975526.837 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-10-10T21:12:06.837Z,1412975526.837 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-10-10T21:12:06.837Z,1412975526.837 [Default:CheckIn:Read_GPS] Running Loop=1 2014-10-10T21:12:07.237Z,1412975527.237 [NAL9602](INFO): Powering up 2014-10-10T21:12:18.758Z,1412975538.758 [NAL9602](INFO): NAL9602 initialized 2014-10-10T21:12:52.626Z,1412975572.626 [NAL9602](IMPORTANT): GPS fix at: 1412975553.00 2014-10-10T21:12:52.773Z,1412975572.773 [Default:CheckIn:Read_GPS] Stopped 2014-10-10T21:12:52.774Z,1412975572.774 [Default:CheckIn:Read_Iridium] Running Loop=1 2014-10-10T21:13:56.528Z,1412975636.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=45260, MT Status=2, MTMSN=0 2014-10-10T21:13:56.528Z,1412975636.528 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:14:22.656Z,1412975662.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=45260, MT Status=2, MTMSN=0 2014-10-10T21:14:22.656Z,1412975662.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:14:41.750Z,1412975681.750 [NAL9602](INFO): SBD MO Status=1, MOMSN=45260, MT Status=0, MTMSN=0 2014-10-10T21:14:41.811Z,1412975681.811 [NAL9602](INFO): Sent 57 bytes from file Logs/20141010T205805/Courier0008.lzma 2014-10-10T21:14:41.811Z,1412975681.811 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:14:41.813Z,1412975681.813 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Courier0008.lzma.parts/0000.sbd 2014-10-10T21:14:41.813Z,1412975681.813 [NAL9602](INFO): Completed sending Logs/20141010T205805/Courier0008.lzma 2014-10-10T21:14:51.686Z,1412975691.686 [NAL9602](INFO): SBD MO Status=1, MOMSN=45261, MT Status=0, MTMSN=0 2014-10-10T21:14:51.739Z,1412975691.739 [NAL9602](INFO): Sent 167 bytes from file Logs/20141010T205805/Express0009.lzma 2014-10-10T21:14:51.739Z,1412975691.739 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:14:51.740Z,1412975691.740 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Express0009.lzma.parts/0000.sbd 2014-10-10T21:14:51.741Z,1412975691.741 [NAL9602](INFO): Completed sending Logs/20141010T205805/Express0009.lzma 2014-10-10T21:14:59.549Z,1412975699.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=45262, MT Status=0, MTMSN=0 2014-10-10T21:14:59.777Z,1412975699.777 [Default:CheckIn:Read_Iridium] Stopped 2014-10-10T21:14:59.778Z,1412975699.778 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-10-10T21:14:59.778Z,1412975699.778 [Default:CheckIn] Stopped 2014-10-10T21:14:59.778Z,1412975699.778 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-10-10T21:14:59.778Z,1412975699.778 [Default:CheckIn:A.SetSpeed] Stopped 2014-10-10T21:14:59.778Z,1412975699.778 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-10-10T21:15:10.212Z,1412975710.212 [Depth_Keller](ERROR): Pressure reading out of range: 1830.424316 decibar 2014-10-10T21:15:10.213Z,1412975710.213 [NAL9602](INFO): Powering down 2014-10-10T21:15:10.380Z,1412975710.380 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:15:10.380Z,1412975710.380 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:15:10.380Z,1412975710.380 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:15:10.381Z,1412975710.381 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:15:10.381Z,1412975710.381 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:15:15.355Z,1412975715.355 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 75.579997 min 2014-10-10T21:15:15.356Z,1412975715.356 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:15:15.356Z,1412975715.356 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:15:15.356Z,1412975715.356 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:15:15.356Z,1412975715.356 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:18:20.210Z,1412975900.210 [Depth_Keller](ERROR): Pressure reading out of range: -850.748901 decibar 2014-10-10T21:18:35.209Z,1412975915.210 [Depth_Keller](ERROR): Pressure reading out of range: -850.804810 decibar 2014-10-10T21:18:45.205Z,1412975925.205 [Depth_Keller](ERROR): Pressure reading out of range: 1354.838379 decibar 2014-10-10T21:20:00.364Z,1412976000.364 [Default:CheckIn] Running Loop=1 2014-10-10T21:20:00.378Z,1412976000.378 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-10-10T21:20:00.378Z,1412976000.378 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-10-10T21:20:00.378Z,1412976000.378 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-10-10T21:20:00.379Z,1412976000.379 [Default:CheckIn:Read_GPS] Running Loop=1 2014-10-10T21:20:00.759Z,1412976000.759 [NAL9602](INFO): Powering up 2014-10-10T21:20:10.640Z,1412976010.640 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:20:10.640Z,1412976010.640 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:20:10.640Z,1412976010.640 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:20:10.640Z,1412976010.640 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:20:10.640Z,1412976010.640 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:20:11.180Z,1412976011.180 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 80.585343 min 2014-10-10T21:20:11.180Z,1412976011.180 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:20:11.181Z,1412976011.181 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:20:11.181Z,1412976011.181 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:20:11.181Z,1412976011.181 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:20:12.145Z,1412976012.145 [NAL9602](INFO): NAL9602 initialized 2014-10-10T21:20:53.833Z,1412976053.833 [NAL9602](IMPORTANT): GPS fix at: 1412976034.00 2014-10-10T21:20:53.985Z,1412976053.985 [Default:CheckIn:Read_GPS] Stopped 2014-10-10T21:20:53.986Z,1412976053.986 [Default:CheckIn:Read_Iridium] Running Loop=1 2014-10-10T21:22:00.691Z,1412976120.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=45263, MT Status=2, MTMSN=0 2014-10-10T21:22:00.691Z,1412976120.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:22:18.674Z,1412976138.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=45263, MT Status=0, MTMSN=0 2014-10-10T21:22:18.724Z,1412976138.724 [NAL9602](INFO): Sent 57 bytes from file Logs/20141010T205805/Courier0012.lzma 2014-10-10T21:22:18.724Z,1412976138.724 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:22:18.725Z,1412976138.725 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Courier0012.lzma.parts/0000.sbd 2014-10-10T21:22:18.726Z,1412976138.726 [NAL9602](INFO): Completed sending Logs/20141010T205805/Courier0012.lzma 2014-10-10T21:22:35.188Z,1412976155.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=45264, MT Status=2, MTMSN=0 2014-10-10T21:22:35.188Z,1412976155.188 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2014-10-10T21:23:03.284Z,1412976183.284 [NAL9602](INFO): SBD MO Status=1, MOMSN=45264, MT Status=0, MTMSN=0 2014-10-10T21:23:03.333Z,1412976183.333 [NAL9602](INFO): Sent 295 bytes from file Logs/20141010T205805/Express0013.lzma 2014-10-10T21:23:03.333Z,1412976183.333 [NAL9602](INFO): Packets left to send: 0 2014-10-10T21:23:03.335Z,1412976183.335 [NAL9602](INFO): Stored copy of sent data in Logs/20141010T205805/Express0013.lzma.parts/0000.sbd 2014-10-10T21:23:03.335Z,1412976183.335 [NAL9602](INFO): Completed sending Logs/20141010T205805/Express0013.lzma 2014-10-10T21:23:11.797Z,1412976191.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=45265, MT Status=0, MTMSN=0 2014-10-10T21:23:12.027Z,1412976192.027 [Default:CheckIn:Read_Iridium] Stopped 2014-10-10T21:23:12.028Z,1412976192.028 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-10-10T21:23:12.028Z,1412976192.028 [Default:CheckIn] Stopped 2014-10-10T21:23:12.028Z,1412976192.028 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-10-10T21:23:12.028Z,1412976192.028 [Default:CheckIn:A.SetSpeed] Stopped 2014-10-10T21:23:12.028Z,1412976192.028 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-10-10T21:23:17.463Z,1412976197.463 [Depth_Keller](ERROR): Pressure reading out of range: 1830.411255 decibar 2014-10-10T21:23:22.455Z,1412976202.455 [NAL9602](INFO): Powering down 2014-10-10T21:24:47.459Z,1412976287.459 [Depth_Keller](ERROR): Pressure reading out of range: 1830.412964 decibar 2014-10-10T21:25:02.455Z,1412976302.455 [Depth_Keller](ERROR): Pressure reading out of range: 1830.423462 decibar 2014-10-10T21:25:12.607Z,1412976312.607 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:25:12.607Z,1412976312.607 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:25:12.607Z,1412976312.607 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-10-10T21:25:12.608Z,1412976312.608 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-10-10T21:25:12.608Z,1412976312.608 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-10-10T21:25:17.461Z,1412976317.461 [Depth_Keller](ERROR): Pressure reading out of range: 1354.847168 decibar 2014-10-10T21:25:17.628Z,1412976317.628 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 85.617498 min 2014-10-10T21:25:17.628Z,1412976317.628 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-10-10T21:25:17.628Z,1412976317.628 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:25:17.628Z,1412976317.628 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-10-10T21:25:17.628Z,1412976317.628 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-10-10T21:25:32.460Z,1412976332.460 [Depth_Keller](ERROR): Pressure reading out of range: 1830.410400 decibar 2014-10-10T21:25:47.467Z,1412976347.467 [Depth_Keller](ERROR): Pressure reading out of range: 1830.396484 decibar 2014-10-10T21:26:02.451Z,1412976362.451 [Depth_Keller](ERROR): Pressure reading out of range: 1830.399048 decibar 2014-10-10T21:26:12.454Z,1412976372.454 [Depth_Keller](ERROR): Pressure reading out of range: 1830.407837 decibar 2014-10-10T21:26:22.450Z,1412976382.450 [Depth_Keller](ERROR): Pressure reading out of range: 1830.492432 decibar 2014-10-10T21:26:32.453Z,1412976392.453 [Depth_Keller](ERROR): Pressure reading out of range: 1830.438354 decibar 2014-10-10T21:26:42.453Z,1412976402.453 [Depth_Keller](ERROR): Pressure reading out of range: 1830.429565 decibar 2014-10-10T21:26:57.456Z,1412976417.456 [Depth_Keller](ERROR): Pressure reading out of range: 1830.437500 decibar 2014-10-10T21:27:42.458Z,1412976462.458 [Depth_Keller](ERROR): Pressure reading out of range: 1830.411255 decibar 2014-10-10T21:28:12.629Z,1412976492.629 [Default:CheckIn] Running Loop=1 2014-10-10T21:28:12.629Z,1412976492.629 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-10-10T21:28:12.629Z,1412976492.629 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-10-10T21:28:12.629Z,1412976492.629 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-10-10T21:28:12.629Z,1412976492.629 [Default:CheckIn:Read_GPS] Running Loop=1 2014-10-10T21:28:13.022Z,1412976493.022 [NAL9602](INFO): Powering up 2014-10-10T21:28:24.409Z,1412976504.409 [NAL9602](INFO): NAL9602 initialized 2014-10-10T21:30:12.617Z,1412976612.617 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-10-10T21:30:12.618Z,1412976612.618 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Def