the8x3_filename: 01210025 full_filename: pelagia-2012-039-1-25 202943 01210025.mlg LOG FILE OPENED 202944 ERROR behavior surface_5: S COMMAND FAILED: s *.sbd *.tbd 202946 DRIVER_ODDITY:iridium:2:retry due to Iridium reporting NO CARRIER 202946 DRIVER_ODDITY:iridium:97:secs; cycling Iridium power 202954 74 Iridium has been powered on for 4.7 secs 202954 Waking up Iridium... sending:ATE1 202954 Iridium, modem making attempt #2 at primary number (881600005143) 202959 75 Iridium driver received:[ATE1[0D][0D]] 202959 Iridium modem matched: OK 202959 Waking up Iridium... sending:AT+cbst=6,0,1 202964 75 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 202964 Iridium modem matched: OK 202964 Waking up Iridium... sending:AT+CSQ 202973 77 Iridium driver received:[AT+CSQ[0D]] 202973 Iridium driver received:[[0D][0A]+CSQ:0[0D][0A][0D]] 202973 Iridium modem matched: OK 202978 79 Iridium waiting for registration... 202983 80 Obtaining Iridium RSSI... 202983 Iridium dialing [ATD00881600005143]........... 202983 Iridium waiting for connection... 202988 81 Iridium driver received:[ATD00881600005143[0D][0D]] 202988 Iridium modem matched: NO CARRIER 202993 81 DRIVER_ODDITY:iridium:3:retry due to Iridium reporting NO CARRIER 202993 DRIVER_ODDITY:iridium:144:secs; cycling Iridium power 203002 83 Iridium has been powered on for 4.8 secs 203002 Waking up Iridium... sending:ATE1 203002 Iridium, modem making attempt #3 at primary number (881600005143) 203007 85 Iridium driver received:[ATE1[0D][0D]] 203007 Iridium modem matched: OK 203007 Waking up Iridium... sending:AT+cbst=6,0,1 203012 86 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 203012 Iridium modem matched: OK 203012 Waking up Iridium... sending:AT+CSQ 203017 87 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:0[0D][0A][0D]] 203017 Iridium modem matched: OK 203021 87 Iridium waiting for registration... 203029 88 Obtaining Iridium RSSI... 203029 Iridium dialing [ATD00881600005143]........... 203029 Iridium waiting for connection... 203034 89 Iridium driver received:[ATD00881600005143[0D][0D]] 203034 Iridium modem matched: NO CARRIER 203038 91 DRIVER_ODDITY:iridium:4:retry due to Iridium reporting NO CARRIER 203038 DRIVER_ODDITY:iridium:189:secs; cycling Iridium power 203048 93 Iridium has been powered on for 5.0 secs 203048 Waking up Iridium... sending:ATE1 203048 Iridium, modem making attempt #4 at primary number (881600005143) 203053 93 Iridium driver received:[ATE1[0D][0D]] 203053 Iridium modem matched: OK 203053 Waking up Iridium... sending:AT+cbst=6,0,1 203057 94 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 203057 Iridium modem matched: OK 203057 Waking up Iridium... sending:AT+CSQ 203062 95 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:0[0D][0A][0D]] 203062 Iridium modem matched: OK 203067 97 Iridium waiting for registration... 203077 99 Obtaining Iridium RSSI... 203077 Iridium dialing [ATD00881600005143]........... 203077 Iridium waiting for connection... 203082 0 Iridium driver received:[ATD00881600005143[0D][0D]] 203082 Iridium modem matched: NO CARRIER 203087 0 DRIVER_ODDITY:iridium:5:retry due to Iridium reporting NO CARRIER 203087 DRIVER_ODDITY:iridium:237:secs; cycling Iridium power 203096 2 Iridium has been powered on for 4.8 secs 203096 Waking up Iridium... sending:ATE1 203096 Iridium, modem making attempt #5 at primary number (881600005143) 203101 4 Iridium driver received:[ATE1[0D][0D]] 203101 Iridium modem matched: OK 203101 Waking up Iridium... sending:AT+cbst=6,0,1 203106 5 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 203106 Iridium modem matched: OK 203106 Waking up Iridium... sending:AT+CSQ 203110 6 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:0[0D][0A][0D]] 203110 Iridium modem matched: OK 203118 6 Iridium waiting for registration... 203123 7 Obtaining Iridium RSSI... 203123 Iridium dialing [ATD00881600005143]........... 203123 Iridium waiting for connection... 203128 8 Iridium driver received:[ATD00881600005143[0D][0D]] 203128 Iridium modem matched: NO CARRIER 203133 10 DRIVER_ODDITY:iridium:6:retry due to Iridium reporting NO CARRIER 203133 DRIVER_ODDITY:iridium:284:secs; cycling Iridium power 203142 12 Iridium has been powered on for 5.0 secs 203142 Waking up Iridium... sending:ATE1 203142 Iridium, modem making attempt #1 at secondary number (15085482446) 203147 12 Iridium driver received:[ATE1[0D][0D]] 203147 Iridium modem matched: OK 203147 Waking up Iridium... sending:AT+cbst=6,0,1 203152 13 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 203152 Iridium modem matched: OK 203152 Waking up Iridium... sending:AT+CSQ 203157 14 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:5[0D][0A][0D]] 203157 Iridium modem matched: OK 203161 16 Iridium waiting for registration... 203171 18 Obtaining Iridium RSSI... 203171 Iridium dialing [ATD0015085482446]........... 203171 Iridium waiting for connection... 203176 18 Iridium driver received:[ATD0015085482446[0D]] 203190 22 Iridium waiting for connection... 203190 Iridium driver received:[[0D]] 203190 Iridium modem matched: CONNECT 4800 203190 Iridium connected... 203190 Iridium console active and ready... Vehicle Name: pelagia Curr Time: Sat Feb 11 19:18:36 2012 MT: 203189 DR Location: 3253.373 N -7820.129 E measured 939.274 secs ago GPS TooFar: 3252.629 N -7823.876 E measured 44202 secs ago GPS Invalid : 3253.337 N -7821.954 E measured 1043.53 secs ago GPS Location: 3253.373 N -7820.129 E measured 941.686 secs ago sensor:m_battery(volts)=12.1756461197979 53.596 secs ago sensor:m_dr_time(sec)=-1 3.457 secs ago sensor:m_gps_lat(lat)=3253.3732 942.058 secs ago sensor:m_gps_lon(lon)=-7820.1288 942.116 secs ago sensor:m_iridium_signal_strength(nodim)=5 20.01 secs ago sensor:m_lat(lat)=3253.37320003865 939.875 secs ago sensor:m_leakdetect_voltage(volts)=2.4982905982906 57.974 secs ago sensor:m_lon(lon)=-7820.12879991507 939.994 secs ago sensor:m_mission_start_time(timestamp)=1328784725 1e+308 secs ago sensor:m_present_time(timestamp)=1328987915.00381 1.841 secs ago sensor:m_vacuum(inHg)=8.35046910866911 124.209 secs ago sensor:m_water_vx(m/s)=0.281443743227164 950.416 secs ago sensor:m_water_vy(m/s)=-0.152838941881131 950.466 secs ago ABORT HISTORY: total since reset: 4 ABORT HISTORY: last abort cause: MS_ABORT_USER_INTERRUPT ABORT HISTORY: last abort details: ABORT HISTORY: last abort time: 2012-02-09T10:49:18 ABORT HISTORY: last abort segment: pelagia-2012-039-0-6 (0120.0006) ABORT HISTORY: last abort mission: LB2025P3.MI 203192 No login script found for processing. I am going to hangup the Iridium I will call you back in 0 minutes 203223 27 disabling Iridium console... 203232 30 Iridium has been powered on for 4.8 secs 203232 Waking up Iridium... sending:ATE1 203232 Iridium, modem making attempt #1 at primary number (881600005143) 203237 31 Iridium driver received:[ATE1[0D][0D]] 203237 Iridium modem matched: OK 203237 Waking up Iridium... sending:AT+cbst=6,0,1 203242 31 Iridium driver received:[AT+cbst=6,0,1[0D][0D]] 203242 Iridium modem matched: OK 203242 Waking up Iridium... sending:AT+CSQ 203246 32 Iridium driver received:[AT+CSQ[0D][0D][0A]+CSQ:5[0D][0A][0D]] 203247 Iridium modem matched: OK 203251 33 Iridium waiting for registration... 203261 36 Obtaining Iridium RSSI... 203261 Iridium dialing [ATD00881600005143]........... 203261 Iridium waiting for connection... 203266 37 Iridium driver received:[ATD00881600005143[0D]] 203271 37 Iridium driver received:[[0D]] 203271 Iridium modem matched: CONNECT 4800 203271 Iridium connected... 203271 Iridium console active and ready... Vehicle Name: pelagia Curr Time: Sat Feb 11 19:19:56 2012 MT: 203269 DR Location: 3253.373 N -7820.129 E measured 1019.5 secs ago GPS TooFar: 3252.629 N -7823.876 E measured 44282.2 secs ago GPS Invalid : 3253.337 N -7821.954 E measured 1123.75 secs ago GPS Location: 3253.373 N -7820.129 E measured 1021.91 secs ago sensor:m_battery(volts)=12.1807671299301 5.052 secs ago sensor:m_dr_time(sec)=-1 3.426 secs ago sensor:m_gps_lat(lat)=3253.3732 1022.28 secs ago sensor:m_gps_lon(lon)=-7820.1288 1022.34 secs ago sensor:m_iridium_signal_strength(nodim)=5 10.265 secs ago sensor:m_lat(lat)=3253.37320003865 1020.1 secs ago sensor:m_leakdetect_voltage(volts)=2.49969474969475 10.208 secs ago sensor:m_lon(lon)=-7820.12879991507 1020.22 secs ago sensor:m_mission_start_time(timestamp)=1328784725 1e+308 secs ago sensor:m_present_time(timestamp)=1328987995.25891 1.815 secs ago sensor:m_vacuum(inHg)=8.18338192918193 79.84 secs ago sensor:m_water_vx(m/s)=0.281443743227164 1030.65 secs ago sensor:m_water_vy(m/s)=-0.152838941881131 1030.7 secs ago ABORT HISTORY: total since reset: 4 ABORT HISTORY: last abort cause: MS_ABORT_USER_INTERRUPT ABORT HISTORY: last abort details: ABORT HISTORY: last abort time: 2012-02-09T10:49:18 ABORT HISTORY: last abort segment: pelagia-2012-039-0-6 (0120.0006) ABORT HISTORY: last abort mission: LB2025P3.MI 203272 No login script found for processing. Megabytes used on CF file system = 234.609375 Megabytes available on CF file system = 764.234375 203323 48 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 [ 0 0 0] [ 98 34 0] [ 0 0 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] [ 0 0 0] [ 8 0 0] 7 pinger - 8 attitude I u 3 20 5 0 9 attitude_tcm3 - 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] [ 1 0 0] 17 science_super I u 3 20 5 0 [ 0 0 0] [ 2 0 0] [1784 511 35] 18 roll_motor - 19 fpitch_pump - 20 fin_motor - 21 digifin I u 3 20 5 0 [ 0 0 0] [ 0 0 0] [ 210 47 2] 22 altimeter I u 3 20 5 0 23 ctd - 24 IRIDIUM* I u -1 20 5 0 [ 0 0 0] [ 0 0 0] [ 236 44 12] 25 leakdetect I u 3 20 5 0 26 recovery - 27 coulomb - 28 veh_temp - 29 avbot - 30 BUOYANCY_PUMP I u 3 20 3 0 [ 0 0 0] [ 0 0 0] [6746 1067 10] 31THERMAL_ACC_PRE - 32 THERMAL_ENGINE - 33 THERMAL_PUMP - 34 DE_PUMP - devices:(t/m/s) errs: 0/ 0/ 0 warn: 100/ 34/ 0 odd:8985/1669/ 59 203324 print_device_queue(): 0 argos 203324 print_all_devices(): Time since device sched start this cycle(ms) = 55675 device scheduler total allowable run time(ms)= 23697 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 2 500 -5008 4 0 1 5903 0 6000 3 WATCHDOG I U _ 0 0 1 7 1 1 12 0 500 4 DEADMAN I U _ 0 0 1 2 0 1 5 0 500 5 CONSOLE I U _ 0 0 1 1 0 1 103 0 500 6 GPS I U _ 0 0 1 1 0 1 688 1141 6000 7 pinger _ _ _ 2 8 attitude I U _ 0 0 1 0 0 0 36 165 1000 9 attitude_tcm3 _ _ _ 2 10 attitude_rev _ _ _ 2 11 ocean_pressure I U _ 0 0 2 36 0 1 344 128 2000 12 vacuum I U _ 0 0 2 29 0 1 43 597 500? 13 battery I U _ 0 0 1 1 0 1 45 86 500 14lithium_battery _ _ _ 2 15 air_pump I U _ 0 0 1 3 0 1 10 48 500 16 pitch_motor I U _ 0 0 2 15 1 1 1034 78 2000 17 science_super I U _ 0 0 34 1 -5315 0 0 0 6411 0 5000* 18 roll_motor _ _ _ 2 19 fpitch_pump _ _ _ 2 20 fin_motor _ _ _ 2 21 digifin I U _ 0 0 1 635 586 60212701 875 1000* 22 altimeter I U _ 0 0 1 3 0 3 42 69 500 23 ctd _ _ _ 2 24 IRIDIUM I U _ 0 0 1 11 6 6 1393 0 1500 25 leakdetect I U _ 0 0 1 1 0 1 47 648 500? 26 recovery _ _ _ 2 27 coulomb _ _ _ 2 28 veh_temp _ _ _ 2 29 avbot _ _ _ 2 30 BUOYANCY_PUMP I U _ 0 0 1 18 2 2 513 0 2000 31THERMAL_ACC_PRE _ _ _ 2 32 THERMAL_ENGINE _ _ _ 2 33 THERMAL_PUMP _ _ _ 2 34 DE_PUMP _ _ _ 2 >>>>SOME DEVICES xxx_ctrl() RAN TOO LONG<<<<< 203324 Done:print_all_devices() 203324 Done:write_segment_closing_diag_info(): 203326 01210025.mlg LOG FILE CLOSED