Trace collected at: 2011-07-08 12:20:13.953000
----------------------------------------------------------------------
../GSEOS_scripts/General\axb_stacer_deploy.py[31]:     log('========================================', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[32]:     log('IAXB_DEPLOY - Deploy Boom%d %d Clicks   ' % (boom,leng), color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[33]:     log('========================================', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[34]:     PF=("FAIL","PASS")
../GSEOS_scripts/General\axb_stacer_deploy.py[35]:     cmd.EFW_TMPKTRATE( 4, 1 )                   #Set APID 267 to 1Hz
../GSEOS_scripts/General\axb_stacer_deploy.py[37]:     log('1. Switch to Eng Mode, ARM deploy', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[38]:     cmd.EFW_ENGIN()                     #Engineering Mode
../GSEOS_scripts/General\axb_stacer_deploy.py[39]:     cmd.EFW_ARM(1)                      #Arm Deployment
../GSEOS_scripts/General\axb_stacer_deploy.py[40]:     cmd.EFW_DISARM(4)                   #Sweeps OFF
../GSEOS_scripts/General\axb_stacer_deploy.py[41]:     cmd.EFW_CLEAR()                     #Clear Errors
../GSEOS_scripts/General\axb_stacer_deploy.py[42]:     cmd.EFW_HSKOPTIONS(1,0,0)           #Fast HSK for Axial Deployment
../GSEOS_scripts/General\axb_stacer_deploy.py[43]:     cmd.EFW_DEP_OVER(0x33)              #Override cage and STACER bits on AXBs
../GSEOS_scripts/General\axb_stacer_deploy.py[44]:     cmd.EFW_DUMPTABL(3,0)               #Show the converted analogs
../GSEOS_scripts/General\axb_stacer_deploy.py[45]:     cmd.EFW_TMDUMPCTR(255)              #Repeatedly dump the analogs
../GSEOS_scripts/General\axb_stacer_deploy.py[46]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[46]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[48]:     log('2. Selecting Boom Pair', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[49]:     pair = ((boom-1)&6)+1               #Convert to pair 1,3,5
../GSEOS_scripts/General\axb_stacer_deploy.py[50]:     xmotor = 2**(boom-1)                #Expected Motor Value
../GSEOS_scripts/General\axb_stacer_deploy.py[51]:     cmd.EFW_DEP_PAIR( pair )            #Select a pair
../GSEOS_scripts/General\axb_stacer_deploy.py[52]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[52]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[54]:     log('3. Checking Door Status', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[55]:     wait(APID_267, lambda:True , flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[55]:     wait(APID_267, lambda:True , flTimeout=30)
../GSEOS_scripts/General\axb_stacer_deploy.py[56]:     if(APID_267.BOOMBITS&0x22!=0):
../GSEOS_scripts/General\axb_stacer_deploy.py[60]:     log('4. Verifying start counts', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[64]:     log('5. Deploying Boom', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[65]:     if(boom&1==1):
../GSEOS_scripts/General\axb_stacer_deploy.py[66]:         cmd.EFW_DEP_PLUS( leng )        #Start Plus Side Going
../GSEOS_scripts/General\axb_stacer_deploy.py[67]:         xdepstat = 0x03                 #Expected Deploy Status
../GSEOS_scripts/General\axb_stacer_deploy.py[68]:         xdlena = leng                   #Expected Lengths at end
../GSEOS_scripts/General\axb_stacer_deploy.py[69]:         xdlenb = 0
../GSEOS_scripts/General\axb_stacer_deploy.py[76]:     log('6. Monitoring longer deploys', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[77]:     if( leng >= 20 ):
../GSEOS_scripts/General\axb_stacer_deploy.py[91]:     log('7. Await Completion ', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[92]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\axb_stacer_deploy.py[92]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\axb_stacer_deploy.py[94]:     log('8. Post Deploy Checks ', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\axb_stacer_deploy.py[95]:     if(APID_267.DLENA!=xdlena):
../GSEOS_scripts/General\axb_stacer_deploy.py[96]:         stopfail("8.1 DEPLOY LENGTH A Incorrect")
../GSEOS_scripts/General\axb_stacer_deploy.py[21]:     log(reason,color=RED, attr=BOLD)
../GSEOS_scripts/General\axb_stacer_deploy.py[22]:     cmd.EFW_DEP_STOP()                  #Clean Up
../GSEOS_scripts/General\axb_stacer_deploy.py[23]:     sleep(2)
../GSEOS_scripts/General\axb_stacer_deploy.py[24]:     cmd.EFW_DEP_PAIR(0)                 #Clear the Pair before disable
../GSEOS_scripts/General\axb_stacer_deploy.py[25]:     cmd.EFW_HSKOPTIONS(0,0,0)           #Enable HSK
../GSEOS_scripts/General\axb_stacer_deploy.py[26]:     cmd.EFW_DISARM(1)                   #Deployment disable
../GSEOS_scripts/General\axb_stacer_deploy.py[27]:     cmd.EFW_ARM(4)                      #Sweeps back on
../GSEOS_scripts/General\axb_stacer_deploy.py[28]:     cmd.EFW_NORMAL()                    #Normal Mode
../GSEOS_scripts/General\axb_stacer_deploy.py[97]:         return -1