the8x3_filename: 03370001 full_filename: salacia-2016-275-1-1 2397 62 03370001.mlg LOG FILE OPENED 2398 behavior surface_7: SUBSTATE 7 ->7 : After data transmission, waiting for control-C to exit/resume 2419 66 Iridium driver received:[[0D]] 2419 Iridium modem matched: NO CARRIER 2419 disabling Iridium console... 2420 DRIVER_ODDITY:iridium:1:retry due to Iridium reporting NO CARRIER 2420 DRIVER_ODDITY:iridium:0:secs; cycling Iridium power 2429 68 Iridium has been powered on for 5.0 secs 2429 Waking up Iridium... sending:ATE1 2429 Iridium, modem making attempt #1 at primary number (881600005168) 2434 69 Iridium driver received:[ATE1[0D][0D]] 2434 Iridium modem matched: OK 2434 Waking up Iridium... sending:AT+cbst=6,0,1 2439 70 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 2439 Iridium modem matched: OK 2439 Waking up Iridium... sending:AT+CSQ 2444 71 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:0[0D][0A][0D]] 2444 Iridium modem matched: OK 2449 72 Iridium waiting for registration... 2459 74 Obtaining Iridium RSSI... 2459 Iridium dialing [ATD00881600005168]........... 2459 Iridium waiting for connection... 2464 75 Iridium driver received:[ATD00881600005168[0D][0D]] 2464 Iridium modem matched: NO CARRIER 2469 75 DRIVER_ODDITY:iridium:2:retry due to Iridium reporting NO CARRIER 2469 DRIVER_ODDITY:iridium:49:secs; cycling Iridium power 2481 77 Iridium has been powered on for 5.0 secs 2481 Waking up Iridium... sending:ATE1 2481 Iridium, modem making attempt #2 at primary number (881600005168) 2486 79 Iridium driver received:[ATE1[0D][0D]] 2486 Iridium modem matched: OK 2486 Waking up Iridium... sending:AT+cbst=6,0,1 2491 79 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 2491 Iridium modem matched: OK 2491 Waking up Iridium... sending:AT+CSQ 2496 81 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:0[0D][0A][0D]] 2496 Iridium modem matched: OK 2501 81 Iridium waiting for registration... 2511 83 Obtaining Iridium RSSI... 2511 Iridium dialing [ATD00881600005168]........... 2511 Iridium waiting for connection... 2516 85 Iridium driver received:[ATD00881600005168[0D][0D]] 2516 Iridium modem matched: NO CARRIER 2521 85 DRIVER_ODDITY:iridium:3:retry due to Iridium reporting NO CARRIER 2521 DRIVER_ODDITY:iridium:101:secs; cycling Iridium power 2531 87 Iridium has been powered on for 5.2 secs 2531 Waking up Iridium... sending:ATE1 2531 Iridium, modem making attempt #3 at primary number (881600005168) 2536 89 Iridium driver received:[ATE1[0D][0D]] 2536 Iridium modem matched: OK 2536 Waking up Iridium... sending:AT+cbst=6,0,1 2540 89 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 2541 Iridium modem matched: OK 2541 Waking up Iridium... sending:AT+CSQ 2550 92 Iridium driver received:[AT+CSQ[0D]] 2550 Iridium driver received:[[0D][0A]+CSQ:0[0D][0A][0D]] 2550 Iridium modem matched: OK 2560 93 Obtaining Iridium RSSI... 2560 Iridium dialing [ATD00881600005168]........... 2560 Iridium waiting for connection... 2565 94 Iridium driver received:[ATD00881600005168[0D][0D]] 2565 Iridium modem matched: NO CARRIER 2570 95 DRIVER_ODDITY:iridium:4:retry due to Iridium reporting NO CARRIER 2570 DRIVER_ODDITY:iridium:149:secs; cycling Iridium power 2579 97 Iridium has been powered on for 5.0 secs 2579 Waking up Iridium... sending:ATE1 2579 Iridium, modem making attempt #4 at primary number (881600005168) 2584 98 Iridium driver received:[ATE1[0D][0D]] 2584 Iridium modem matched: OK 2584 Waking up Iridium... sending:AT+cbst=6,0,1 2589 99 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 2589 Iridium modem matched: OK 2589 Waking up Iridium... sending:AT+CSQ 2599 0 Iridium driver received:[AT+CSQ[0D]] 2599 Iridium driver received:[[0D][0A]+CSQ:4[0D][0A][0D]] 2599 Iridium modem matched: OK 2604 1 Iridium waiting for registration... 2609 3 Obtaining Iridium RSSI... 2609 Iridium dialing [ATD00881600005168]........... 2609 Iridium waiting for connection... 2614 4 Iridium driver received:[ATD00881600005168[0D]] 2629 7 Iridium waiting for connection... 2629 Iridium driver received:[[0D]] 2629 Iridium modem matched: CONNECT 4800 2629 Iridium connected... 2629 Iridium console active and ready... Vehicle Name: salacia Curr Time: Sun Oct 2 18:35:13 2016 MT: 2628 DR Location: 3136.022 N -8041.263 E measured 1007.66 secs ago GPS TooFar: 3136.183 N -8047.733 E measured 1e+308 secs ago GPS Invalid : 3136.138 N -8041.338 E measured 1109.51 secs ago GPS Location: 3136.022 N -8041.263 E measured 1009.2 secs ago sensor:c_wpt_lat(lat)=3138 2439.49 secs ago sensor:c_wpt_lon(lon)=-8044 2439.58 secs ago sensor:m_battery(volts)=13.2821352437157 40.046 secs ago sensor:m_gps_lat(lat)=3136.0218 1009.67 secs ago sensor:m_gps_lon(lon)=-8041.2626 1009.75 secs ago sensor:m_iridium_signal_strength(nodim)=4 20.549 secs ago sensor:m_leakdetect_voltage(volts)=2.48421855921856 40.189 secs ago sensor:m_leakdetect_voltage_forward(volts)=-1 5.795 secs ago sensor:m_mission_start_time(timestamp)=1475430684 1e+308 secs ago sensor:m_present_time(timestamp)=1475433312.38248 1.805 secs ago sensor:m_tot_num_inflections(nodim)=38051 1159.25 secs ago sensor:m_vacuum(inHg)=7.44290012210012 104.136 secs ago sensor:m_water_vx(m/s)=0.175437146778663 1013.76 secs ago sensor:m_water_vy(m/s)=-0.308441458856343 1013.81 secs ago sensor:u_use_current_correction(nodim)=0 1e+308 secs ago sensor:x_last_wpt_lat(lat)=3130.0961 1e+308 secs ago sensor:x_last_wpt_lon(lon)=-8054.4796 1e+308 secs ago ABORT HISTORY: total since reset: 8 ABORT HISTORY: last abort cause: MS_ABORT_OVERTIME ABORT HISTORY: last abort details: ABORT HISTORY: last abort time: 2016-10-02T17:25:54 ABORT HISTORY: last abort segment: salacia-2016-275-0-0 (0336.0000) ABORT HISTORY: last abort mission: SH4B_1H.MI 2630 No login script found for processing. 2630 DRIVER_ODDITY:iridium:1591:xxx_ctrl() ran too long 2716 24 save_and_change_sensors().... Changed u_use_ctd_depth_for_flying from 0 to 0 Changed c_science_on from 1 to 0 2716 save_and_change_sensors().... Changed c_science_printout from 0 to 0 Waiting for motors idle 2754 restore_sensors().... Restored u_use_ctd_depth_for_flying from 0 to 0 Restored c_science_on from 0 to 1 2754 restore_sensors().... 2754 restore_sensors().... Restored c_science_printout from 0 to 0 2755 behavior surface_7: ! succeeded:zr 2755 behavior surface_7: SUBSTATE 7 ->7 : After ! command, waiting for control-C to exit/resume 2775 28 SCI:PROGLET house_elf begin() called 2775 SCI: house_elf: Version 1.2 2776 SCI:PROGLET ctd41cp begin() called 2776 SCI: ctd41cp: Version 0.2 2776 SCI: ctd41cp: Will be sending the following data to glider: 2777 SCI: sci_water_cond(s/m) 2777 SCI: sci_water_temp(degc) 2777 SCI: sci_water_pressure(bar) 2777 SCI: sci_ctd41cp_timestamp(timestamp) 2782 29 SCI:PROGLET house_elf start() called 2782 SCI: house_elf_run(): 0 error(s) in a row. (5 is fatal) 2783 SCI: house_elf_run(): 0 error(s) since house_elf_begin(). (50 is fatal) 2786 29 behavior surface_7: ! succeeded:cd ..\mafiles 2786 behavior surface_7: SUBSTATE 7 ->7 : After ! command, waiting for control-C to exit/resume 2809 33 behavior surface_7: ! succeeded:copy surfac2h.ma surfac99.ma 2809 behavior surface_7: SUBSTATE 7 ->7 : After ! command, waiting for control-C to exit/resume 2826 37 behavior surface_7: User Hit a Control-C, terminating the mission 2826 behavior surface_7: STATE Active -> Mission Complete 2826 behavior ?_-1: layered_control(): Mission completed normally 2826 behavior ?_-1: run_mission(): Mission completed: MS_COMPLETED_NORMALLY(-1) 2830 write_segment_closing_diag_info(): name in ALLCAPS means CRITICAL device (* => SUPERCRITICAL) [I Installed] [- Not_Installed] [u In_use] [- Not_In_use] [X Out_of_Service] name limits stats (#total/#mission/#segment) 0 simdrvr - 1 test_driver - 2 ARGOS* I u -1 20 5 0 3 WATCHDOG I u -1 -1 -1 0 4 DEADMAN I u -1 20 5 0 5 CONSOLE* I u -1 20 5 0 6 GPS I u -1 20 5 0 7 pinger - 8 attitude - 9 attitude_tcm3 I u 3 20 5 1 [ 1 0 0] [ 9 0 0] [ 0 0 0] 10 attitude_rev - 11 ocean_pressure I u 3 20 5 0 12 vacuum I u 3 20 5 0 13 battery I u 3 20 5 0 14lithium_battery - 15 air_pump I u 3 20 5 0 16 pitch_motor I u 3 20 5 0 [ 0 0 0] [ 0 0 0] [ 67 0 0] 17 science_super I u 3 20 5 0 18 roll_motor - 19 fpitch_pump - 20 fin_motor - 21 digifin I u 3 20 5 0 [ 0 0 0] [ 0 0 0] [ 102 0 0] 22 altimeter I u 3 20 5 0 23 ctd - 24 IRIDIUM* I u -1 20 5 0 [ 0 0 0] [ 1 0 0] [ 147 10 10] 25 leakdetect I u 3 20 5 0 26 recovery - 27 coulomb - 28 veh_temp - 29 BUOYANCY_PUMP I u 20 100 10 0 [ 0 0 0] [ 0 0 0] [ 2 0 0] 30THERMAL_ACC_PRE - 31 THERMAL_ENGINE - 32 THERMAL_PUMP - 33 DE_PUMP - 34 thruster_g1 - 35 thruster - devices:(t/m/s) errs: 1/ 0/ 0 warn: 10/ 0/ 0 odd: 318/ 10/ 10 2830 print_device_queue(): 0 argos 2830 print_all_devices(): Time since device sched start this cycle(ms) = 34665 device scheduler total allowable run time(ms)= 962 device drivers called normally = NO call last # again recal run(ms) allow # NAME S -DTms ms +DTms last min avg max Rmax ms 0 simdrvr _ _ _ 2 1 test_driver _ _ _ 2 2 ARGOS I U _ 0 0 2 500 -6759 1 0 1 4791 0 6000 3 WATCHDOG I U _ 0 0 1 7 1 1 13 0 500 4 DEADMAN I U _ 0 0 1 1 0 1 5 0 500 5 CONSOLE I U _ 0 0 1 1 0 1 57 0 500 6 GPS I U _ 0 0 1 3 0 3 249 614 6000 7 pinger _ _ _ 2 8 attitude _ _ _ 2 9 attitude_tcm3 I U _ 0 0 1 1 0 1 309 72 3000 10 attitude_rev _ _ _ 2 11 ocean_pressure I U _ 0 0 2 33 0 1 327 89 2000 12 vacuum I U _ 0 0 1 1 0 1 43 64 500 13 battery I U _ 0 0 2 29 0 1 47 66 500 14lithium_battery _ _ _ 2 15 air_pump I U _ 0 0 1 1 0 1 11 33 500 16 pitch_motor I U _ 0 0 2 17 1 1 1038 218 2000 17 science_super I U _ 0 0 5 1 -7204 3 0 3 552 0 5000 18 roll_motor _ _ _ 2 19 fpitch_pump _ _ _ 2 20 fin_motor _ _ _ 2 21 digifin I U _ 0 0 1 636 591 59912675 864 1000* 22 altimeter I U _ 0 0 1 0 0 0 45 55 500 23 ctd _ _ _ 2 24 IRIDIUM I U _ 0 0 1 12 6 6 1591 0 1500* 25 leakdetect I U _ 0 0 2 34 0 1 48 72 500 26 recovery _ _ _ 2 27 coulomb _ _ _ 2 28 veh_temp _ _ _ 2 29 BUOYANCY_PUMP I U _ 0 0 1 17 2 2 174 0 2000 30THERMAL_ACC_PRE _ _ _ 2 31 THERMAL_ENGINE _ _ _ 2 32 THERMAL_PUMP _ _ _ 2 33 DE_PUMP _ _ _ 2 34 thruster_g1 _ _ _ 2 35 thruster _ _ _ 2 >>>>SOME DEVICES xxx_ctrl() RAN TOO LONG<<<<< 2831 Done:print_all_devices() 2831 Done:write_segment_closing_diag_info(): Vehicle Name: salacia Mission Name: REC4B_20.MI Mission Number: salacia-2016-275-1-1 (0337.0001) post_mission_cleanup(): End of Mission timestamp: Sun Oct 2 18:38:35 2016 2832 03370001.mlg LOG FILE CLOSED