the8x3_filename: 01950069 full_filename: mote-genie-2016-289-0-69 373143 77 01950069.mlg LOG FILE OPENED Megabytes used on CF file system = 525.562500 Megabytes available on CF file system = 1471.937500 373146 init_gps_input() 373146 behavior surface_8: SUBSTATE 7 ->10 : Waiting for final gps fix 373148 disabling Iridium console... 373160 80 sensor: m_gps_status = 1 enum 373160 sensor: m_gps_ignored_lat = 2657.0718 lat 373160 sensor: m_gps_ignored_lon = -8241.3656 lon 373165 82 sensor: m_gps_status = 1 enum 373165 sensor: m_gps_ignored_lat = 2657.0702 lat 373165 sensor: m_gps_ignored_lon = -8241.3668 lon 373169 83 sensor: m_gps_status = 1 enum 373169 sensor: m_gps_ignored_lat = 2657.0699 lat 373169 sensor: m_gps_ignored_lon = -8241.367 lon 373174 84 sensor: m_gps_status = 1 enum 373174 sensor: m_gps_ignored_lat = 2657.0688 lat 373174 sensor: m_gps_ignored_lon = -8241.3671 lon 373179 85 sensor: m_gps_status = 1 enum 373179 sensor: m_gps_ignored_lat = 2657.0681 lat 373179 sensor: m_gps_ignored_lon = -8241.3673 lon 373184 86 sensor: m_gps_lat = 2657.0667 lat 373184 sensor: m_gps_lon = -8241.3675 lon 373184 sensor: m_gps_status = 0 enum 373188 87 end_gps_input() 373188 behavior surface_8: SUBSTATE 10 ->12 : All done 373188 behavior surface_8: STATE Active -> UnInited 373193 87 behavior surface_8: Reading b_args from surfac51.ma 373193 behavior surface_8: start_when(enum)=13.000000 373193 behavior surface_8: when_utc_min(min)=0.000000 373193 behavior surface_8: when_utc_hour(hour)=10.000000 373193 behavior surface_8: when_utc_day(day)=-1.000000 373193 behavior surface_8: when_utc_month(month)=-1.000000 373193 behavior surface_8: report_all(bool)=0.000000 373193 behavior surface_8: end_action(enum)=1.000000 373193 behavior surface_8: gps_wait_time(s)=600.000000 373193 behavior surface_8: keystroke_wait_time(sec)=300.000000 373193 behavior surface_8: c_use_pitch(enum)=3.000000 373193 behavior surface_8: c_use_bpump(enum)=2.000000 373193 behavior surface_8: c_bpump_value(X)=1000.000000 373193 behavior surface_8: c_pitch_value(X)=0.452800 373193 behavior surface_8: printout_cycle_time(sec)=30.000000 373193 behavior surface_8: force_iridium_use(nodim)=1.000000 373193 behavior surface_8: STATE UnInited -> Waiting for Activation 373193 behavior surface_8: argument: args_from_file = 51.000000 enum 373193 behavior surface_8: argument: start_when = 13.000000 enum 373193 behavior surface_8: argument: when_secs = 1200.000000 sec 373194 behavior surface_8: argument: when_wpt_dist = 10.000000 m 373194 behavior surface_8: argument: end_action = 1.000000 enum 373194 behavior surface_8: argument: report_all = 0.000000 bool 373194 behavior surface_8: argument: gps_wait_time = 600.000000 sec 373194 behavior surface_8: argument: keystroke_wait_time = 300.000000 sec 373194 behavior surface_8: argument: end_wpt_dist = 0.000000 m 373194 behavior surface_8: argument: c_use_bpump = 2.000000 enum 373194 behavior surface_8: argument: c_bpump_value = 1000.000000 X 373194 behavior surface_8: argument: c_use_pitch = 3.000000 enum 373194 behavior surface_8: argument: c_pitch_value = 0.452800 X 373194 behavior surface_8: argument: c_stop_when_air_pump = 0.000000 bool 373194 behavior surface_8: argument: c_use_thruster = 0.000000 enum 373194 behavior surface_8: argument: c_thruster_value = 0.000000 X 373194 behavior surface_8: argument: printout_cycle_time = 30.000000 sec 373194 behavior surface_8: argument: gps_postfix_wait_time = 60.000000 sec 373194 behavior surface_8: argument: force_iridium_use = 1.000000 nodim 373194 behavior surface_8: argument: min_time_between_gps_fixes = 300.000000 sec 373194 behavior surface_8: argument: sensor_input_wait_time = 10.000000 sec 373194 behavior surface_8: argument: when_utc_min = 0.000000 min 373194 behavior surface_8: argument: when_utc_hour = 10.000000 hour 373194 behavior surface_8: argument: when_utc_day = -1.000000 day 373194 behavior surface_8: argument: when_utc_month = -1.000000 month 373194 behavior surface_8: argument: when_utc_on_surface = 1.000000 bool 373194 behavior surface_8: argument: strobe_on = 0.000000 bool 373194 behavior surface_8: argument: thruster_burst = 0.000000 bool 373198 88 behavior sample_18: SUBSTATE 4 ->1 : Diving :OOD:digifin_mgr_ctrl:OUT OF DEADBAND: M_FIN:-0.0569 C_FIN:0.0000 :OOD:digifin_mgr_ctrl:OUT OF DEADBAND: M_FIN:0.0228 C_FIN:0.0000 AB: Inflected early at Dive depth 17 [m] 373372 26 behavior dive_to_1701: SUBSTATE 4 ->5 : Complete, deduced altitude from water depth 373372 behavior dive_to_1701: STATE Active -> Complete 373372 behavior climb_to_1702: STATE UnInited -> Active 373372 behavior climb_to_1702: argument: target_depth = 3.000000 m 373372 behavior climb_to_1702: argument: target_altitude = -1.000000 m 373372 behavior climb_to_1702: argument: use_bpump = 0.000000 enum 373372 behavior climb_to_1702: argument: bpump_value = 1000.000000 X 373372 behavior climb_to_1702: argument: use_pitch = 3.000000 enum 373372 behavior climb_to_1702: argument: pitch_value = 0.453800 X 373372 behavior climb_to_1702: argument: start_when = 0.000000 enum 373372 behavior climb_to_1702: argument: stop_when_hover_for = 180.000000 sec 373372 behavior climb_to_1702: argument: stop_when_stalled_for = 240.000000 sec 373372 behavior climb_to_1702: argument: stop_when_air_pump = 0.000000 bool 373372 behavior climb_to_1702: argument: initial_inflection = 1.000000 bool 373372 behavior climb_to_1702: argument: speed_min = -0.080000 m/s 373372 behavior climb_to_1702: argument: speed_max = -100.000000 m/s 373372 behavior climb_to_1702: argument: use_thruster = 0.000000 enum 373372 behavior climb_to_1702: argument: thruster_value = 0.000000 X 373372 behavior climb_to_1702: SUBSTATE 0 UnInited->1 : waiting for initial depth reading 373372 behavior climb_to_1702: SUBSTATE 1 ->3 : Starting the climb 373372 behavior climb_to_1702: SUBSTATE 3 ->4 : climbing 373376 27 behavior sample_18: SUBSTATE 1 ->3 : Climbing AB: Time to Dive: 122, Climb: 126 [s] AB: Previous ballast: Dive:-150.00, Climb:200.00 AB: Got the timing down! Ratio of Climb/Dive times:1.033. AB: C_AUTOBALLAST_STATE: 2 AB: New ballast: Dive:-150.00, Climb:200.00 373526 61 behavior climb_to_1702: SUBSTATE 4 ->5 : Complete reached depth 373526 behavior climb_to_1702: STATE Active -> Complete 373526 behavior dive_to_1701: STATE UnInited -> Active 373526 behavior dive_to_1701: argument: target_depth = 60.000000 m 373526 behavior dive_to_1701: argument: target_altitude = 3.000000 m 373526 behavior dive_to_1701: argument: use_bpump = 0.000000 enum 373526 behavior dive_to_1701: argument: bpump_value = 350.000000 X 373526 behavior dive_to_1701: argument: use_pitch = 3.000000 enum 373526 behavior dive_to_1701: argument: pitch_value = -0.452800 X 373526 behavior dive_to_1701: argument: start_when = 0.000000 enum 373526 behavior dive_to_1701: argument: stop_when_hover_for = 180.000000 sec 373526 behavior dive_to_1701: argument: stop_when_stalled_for = 240.000000 sec 373526 behavior dive_to_1701: argument: stop_when_air_pump = 0.000000 bool 373526 behavior dive_to_1701: argument: initial_inflection = 1.000000 bool 373526 behavior dive_to_1701: argument: speed_min = 0.080000 m/s 373526 behavior dive_to_1701: argument: speed_max = 100.000000 m/s 373526 behavior dive_to_1701: argument: use_thruster = 0.000000 enum 373526 behavior dive_to_1701: argument: thruster_value = 0.000000 X 373526 behavior dive_to_1701: argument: depth_rate_method = 3.000000 enum 373526 behavior dive_to_1701: argument: wait_for_pitch = 1.000000 bool 373526 behavior dive_to_1701: argument: wait_for_ballast = 100.000000 sec 373526 behavior dive_to_1701: argument: delta_bpump_speed = 50.000000 X 373526 behavior dive_to_1701: argument: delta_bpump_ballast = 25.000000 X 373526 behavior dive_to_1701: argument: time_ratio = 1.100000 X 373526 behavior dive_to_1701: argument: use_sc_model = 0.000000 bool 373526 behavior dive_to_1701: argument: max_thermal_charge_time = 1200.000000 sec 373526 behavior dive_to_1701: argument: max_pumping_charge_time = 300.000000 sec 373526 behavior dive_to_1701: argument: thr_reqd_pres_mul = 1.500000 nodim 373526 behavior dive_to_1701: SUBSTATE 0 UnInited->1 : waiting for initial depth reading 373526 behavior dive_to_1701: SUBSTATE 1 ->3 : Starting the dive 373526 behavior dive_to_1701: SUBSTATE 3 ->4 : diving 373531 62 behavior sample_18: SUBSTATE 3 ->1 : Diving AB: Inflected early at Dive depth 17 [m] 373686 96 behavior dive_to_1701: SUBSTATE 4 ->5 : Complete, deduced altitude from water depth 373686 behavior dive_to_1701: STATE Active -> Complete 373686 behavior climb_to_1702: STATE UnInited -> Active 373686 behavior climb_to_1702: argument: target_depth = 3.000000 m 373686 behavior climb_to_1702: argument: target_altitude = -1.000000 m 373686 behavior climb_to_1702: argument: use_bpump = 0.000000 enum 373686 behavior climb_to_1702: argument: bpump_value = 1000.000000 X 373686 behavior climb_to_1702: argument: use_pitch = 3.000000 enum 373686 behavior climb_to_1702: argument: pitch_value = 0.453800 X 373686 behavior climb_to_1702: argument: start_when = 0.000000 enum 373686 behavior climb_to_1702: argument: stop_when_hover_for = 180.000000 sec 373686 behavior climb_to_1702: argument: stop_when_stalled_for = 240.000000 sec 373686 behavior climb_to_1702: argument: stop_when_air_pump = 0.000000 bool 373687 behavior climb_to_1702: argument: initial_inflection = 1.000000 bool 373687 behavior climb_to_1702: argument: speed_min = -0.080000 m/s 373687 behavior climb_to_1702: argument: speed_max = -100.000000 m/s 373687 behavior climb_to_1702: argument: use_thruster = 0.000000 enum 373687 behavior climb_to_1702: argument: thruster_value = 0.000000 X 373687 behavior climb_to_1702: SUBSTATE 0 UnInited->1 : waiting for initial depth reading 373687 behavior climb_to_1702: SUBSTATE 1 ->3 : Starting the climb 373687 behavior climb_to_1702: SUBSTATE 3 ->4 : climbing 373691 97 behavior sample_18: SUBSTATE 1 ->3 : Climbing 373786 17 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! 373791 19 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! :OOD:digifin_mgr_ctrl:OUT OF DEADBAND: M_FIN:0.4486 C_FIN:-0.4500 373800 21 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! 373804 22 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! :OOD:digifin_mgr_ctrl:OUT OF DEADBAND: M_FIN:0.4486 C_FIN:-0.4500 373813 24 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! 373813 DRIVER_ERROR:digifin_ctrl():4:4:Too many warnings in last minute 373813 call_and_queue_if_reqd(): device digifin TAKEN OUT OF SERVICE 373818 25 ERROR behavior ?_-1: abort_the_mission(0): (16)MS_ABORT_DEVICE_ERROR 373818 behavior ?_-1: abort_the_mission(): Changing U_CYCLE_TIME from 4.000000 to 15.000000 373818 save_and_change_sensors().... Changed u_depth_rate_filter_factor from 4 to -1 Exiting all devices ... init_all_devices(1): Device was OUT OF SERVICE, RETRYING: digifin argos watchdog deadman console gps attitude_rev ocean_pressure vacuum battery air_pump pitch_motor science_super digifin altimeter iridium leakdetect coulomb veh_temp buoyancy_pump start_alldevices() PARSED iridium_phone_data for datum# 0: =========== predial initialization cmds (from c:\config\iridinit.0): ATE AT+cbst=6,0,1 AT+CSQ dial string: ATD00881600005112 from sensors: C_IRIDIUM_LEAD_ZEROS C_IRIDIUM_PHONE_NUM Iridium login script (from c:\config\loginexp.0): empty or non-existent PARSED iridium_phone_data for datum# 1: =========== predial initialization cmds (from c:\config\iridinit.1): ATE AT+cbst=6,0,1 AT+CSQ dial string: ATD0017818711051 from sensors: C_IRIDIUM_LEAD_ZEROS_ALT C_IRIDIUM_PHONE_NUM_ALT Iridium login script (from c:\config\loginexp.1): empty or non-existent 373824 behavior ?_-1: Vehicle Name: mote-genie 373824 behavior ?_-1: abort_the_mission(): (16)MS_ABORT_DEVICE_ERROR 373824 behavior ?_-1: secs since abort started: 6 try num: 0 373824 behavior ?_-1: depths ini: 6 working: 206 at risk: 221 crush: 225 373824 behavior ?_-1: expected time/tries to surface: 391 26 373824 behavior ?_-1: max time/tries to go up: 300 20 373824 behavior ?_-1: too long: 0, not going up: 0, depth sensor busted:0 373824 behavior ?_-1: abort burn time/tries min: 600 40 373824 behavior ?_-1: abort burn time/tries max: 14400 960 373824 behavior ?_-1: ABOVE WORKING DEPTH 373824 behavior ?_-1: drop_the_weight = 0 373835 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! 373835 sensor: m_depth = 3.16908127172984 m 373838 25 behavior ?_-1: Vehicle Name: mote-genie 373838 behavior ?_-1: abort_the_mission(): (16)MS_ABORT_DEVICE_ERROR 373838 behavior ?_-1: secs since abort started: 20 try num: 1 373838 behavior ?_-1: depths ini: 6 working: 206 at risk: 221 crush: 225 373838 behavior ?_-1: expected time/tries to surface: 391 26 373838 behavior ?_-1: max time/tries to go up: 300 20 373839 behavior ?_-1: too long: 0, not going up: 0, depth sensor busted:0 373839 behavior ?_-1: abort burn time/tries min: 600 40 373839 behavior ?_-1: abort burn time/tries max: 14400 960 373839 behavior ?_-1: ABOVE WORKING DEPTH 373839 behavior ?_-1: drop_the_weight = 0 373840 DRIVER_WARNING:digifin:-1:digifin_v2_ctrl() Digifin movement warning. Digifin busy too long!!! 373841 Iridium has been powered on for 5.4 secs 373841 Waking up Iridium... sending:ATE1 373841 Iridium, modem making attempt #1 at primary number (881600005112) 373841 sensor: m_depth = 2.15677492051176 m 373853 26 behavior ?_-1: Vehicle Name: mote-genie 373853 behavior ?_-1: abort_the_mission(): (16)MS_ABORT_DEVICE_ERROR 373853 behavior ?_-1: secs since abort started: 35 try num: 2 373853 behavior ?_-1: depths ini: 6 working: 206 at risk: 221 crush: 225 373853 behavior ?_-1: expected time/tries to surface: 391 26 373853 behavior ?_-1: max time/tries to go up: 300 20 373853 behavior ?_-1: too long: 0, not going up: 0, depth sensor busted:0 373853 behavior ?_-1: abort burn time/tries min: 600 40 373853 behavior ?_-1: abort burn time/tries max: 14400 960 373853 ERROR behavior ?_-1: we_are_done(): At the surface, return (-2)MS_COMPLETED_ABNORMALLY 373853 behavior ?_-1: we_are_done(): Restoring U_CYCLE_TIME from 15.000000 to 4.000000 373853 restore_sensors().... Restored u_depth_rate_filter_factor from -1 to 4 373854 behavior ?_-1: ABOVE WORKING DEPTH 373854 behavior ?_-1: drop_the_weight = 0 373855 behavior ?_-1: run_mission(): Mission completed: MS_COMPLETED_ABNORMALLY(-2) Mission aborted because of device error Re-initing in hopes problem goes away 373857 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 [ 0 0 0] [ 5 0 0] [ 0 0 0] 7 pinger - 8 attitude - 9 attitude_tcm3 - 10 attitude_rev I u 3 20 5 0 [ 0 0 0] [ 1 0 0] [ 0 0 0] 11 ocean_pressure I u 3 20 5 0 [ 0 0 0] [ 0 0 0] [ 9 0 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] [ 106 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 1 [ 1 0 0] [ 41 2 2] [ 95 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] [ 44 0 0] 25 leakdetect I u 3 20 5 0 26 recovery - 27 coulomb I u 3 20 5 0 28 veh_temp I u 3 20 5 0 29 BUOYANCY_PUMP I u 3 20 3 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: 48/ 2/ 2 odd: 254/ 0/ 0 373857 print_device_queue(): 373857 print_all_devices(): Time since device sched start this cycle(ms) = 42822 device scheduler total allowable run time(ms)= 44177 device drivers called normally = yes 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 1 112 0 1 4753 0 6000 3 WATCHDOG I U _ 0 0 1 4 1 1 30 0 500 4 DEADMAN I U _ 0 0 1 1 0 1 3 0 500 5 CONSOLE I U _ 0 0 1 2 0 1 96 0 500 6 GPS I U _ 0 0 1 4 0 1 320 1031 6000 7 pinger _ _ _ 2 8 attitude _ _ _ 2 9 attitude_tcm3 _ _ _ 2 10 attitude_rev I U _ 0 0 2 27 0 3 35 147 1000 11 ocean_pressure I U _ 0 0 2 45 0 1 353 102 2000 12 vacuum I U _ 0 0 1 1 0 1 35 62 500 13 battery I U _ 0 0 1 1 0 1 35 66 500 14lithium_battery _ _ _ 2 15 air_pump I U _ 0 0 1 2 0 1 6 32 500 16 pitch_motor I U _ 0 0 12 185 1 1 1034 61 2000 17 science_super I U _ 0 01872 1 -6627 1 0 1 4576 0 5000 18 roll_motor _ _ _ 2 19 fpitch_pump _ _ _ 2 20 fin_motor _ _ _ 2 21 digifin I U _ 0 0 2 894 287 57812695 674 1000* 22 altimeter I U _ 0 0 1 2 0 2 29 0 500 23 ctd _ _ _ 2 24 IRIDIUM I U _ 0 0 1 60 6 6 1482 0 1500 25 leakdetect I U _ 0 0 2 51 0 1 58 78 500 26 recovery _ _ _ 2 27 coulomb I U _ 0 0 1 38 25 25 51 0 500 28 veh_temp I U _ 0 0 1 27 17 17 42 0 500 29 BUOYANCY_PUMP I U _ 0 0 1 20 2 2 149 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<<<<< 373858 Done:print_all_devices() 373858 Done:write_segment_closing_diag_info(): Vehicle Name: mote-genie Mission Name: STOCKUTC.MI Mission Number: mote-genie-2016-289-0-69 (0195.0069) post_mission_cleanup(): End of Mission timestamp: Thu Oct 20 10:26:00 2016 373859 01950069.mlg LOG FILE CLOSED