Trace collected at: 2012-12-07 09:24:48.597000
----------------------------------------------------------------------
../GSEOS_scripts/General\AXB_stacer_deploy.py[32]:     log('========================================', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[33]:     log('IAXB_DEPLOY - Deploy Boom%d %d Clicks   ' % (boom,leng), color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[34]:     log('========================================', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[35]:     PF=("FAIL","PASS")
../GSEOS_scripts/General\AXB_stacer_deploy.py[36]:     cmd.EFW_TMPKTRATE( 4, 1 )                   #Set APID 267 to 1Hz
../GSEOS_scripts/General\AXB_stacer_deploy.py[38]:     log('1. Switch to Eng Mode, ARM deploy', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[39]:     cmd.EFW_ENGIN()                     #Engineering Mode
../GSEOS_scripts/General\AXB_stacer_deploy.py[40]:     cmd.EFW_ARM(1)                      #Arm Deployment
../GSEOS_scripts/General\AXB_stacer_deploy.py[41]:     cmd.EFW_DISARM(4)                   #Sweeps OFF
../GSEOS_scripts/General\AXB_stacer_deploy.py[42]:     cmd.EFW_CLEAR()                     #Clear Errors
../GSEOS_scripts/General\AXB_stacer_deploy.py[43]:     cmd.EFW_HSKOPTIONS(1,0,0)           #Fast HSK for Axial Deployment
../GSEOS_scripts/General\AXB_stacer_deploy.py[44]:     cmd.EFW_DEP_OVER(0x33)              #Override cage and STACER bits on AXBs
../GSEOS_scripts/General\AXB_stacer_deploy.py[45]:     cmd.EFW_DUMPTABL(3,0)               #Show the converted analogs
../GSEOS_scripts/General\AXB_stacer_deploy.py[46]:     cmd.EFW_TMDUMPCTR(255)              #Repeatedly dump the analogs
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[47]:     wait(APID_267, lambda: APID_267.ERRCODE==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[49]:     log('2. Selecting Boom Pair', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[50]:     pair = ((boom-1)&6)+1               #Convert to pair 1,3,5
../GSEOS_scripts/General\AXB_stacer_deploy.py[51]:     xmotor = 2**(boom-1)                #Expected Motor Value
../GSEOS_scripts/General\AXB_stacer_deploy.py[52]:     cmd.EFW_DEP_PAIR( pair )            #Select a pair
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[53]:     wait(APID_267, lambda: APID_267.DEPPAIR==pair, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[61]:     log('4. Verifying start counts', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[62]:     wait(APID_267, lambda: APID_267.DLENA==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[62]:     wait(APID_267, lambda: APID_267.DLENA==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[63]:     wait(APID_267, lambda: APID_267.DLENB==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[63]:     wait(APID_267, lambda: APID_267.DLENB==0, flTimeout=30)
../GSEOS_scripts/General\AXB_stacer_deploy.py[65]:     log('5. Deploying Boom', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[66]:     if(boom&1==1):
../GSEOS_scripts/General\AXB_stacer_deploy.py[72]:         cmd.EFW_DEP_MINUS( leng )       #Start Minus Side Going
../GSEOS_scripts/General\AXB_stacer_deploy.py[73]:         xdepstat = 0x30                 #Expected Deploy Status
../GSEOS_scripts/General\AXB_stacer_deploy.py[74]:         xdlena = 0                      #Expected Lengths at end
../GSEOS_scripts/General\AXB_stacer_deploy.py[75]:         xdlenb = leng
../GSEOS_scripts/General\AXB_stacer_deploy.py[77]:     log('6. Monitoring longer deploys', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[78]:     if( leng >= 20 ):
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[79]:         wait(APID_267, lambda: APID_267.DEPSTAT!=00, flTimeout=70)
../GSEOS_scripts/General\AXB_stacer_deploy.py[80]:         if(APID_267.DEPSTAT!=xdepstat):
../GSEOS_scripts/General\AXB_stacer_deploy.py[84]:         if(APID_267.DEPLIMIT!=leng):
../GSEOS_scripts/General\AXB_stacer_deploy.py[88]:         if(APID_267.MTRSTAT!=xmotor):
../GSEOS_scripts/General\AXB_stacer_deploy.py[92]:     log('7. Await Completion ', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[93]:     wait(APID_267, lambda: APID_267.DEPSTAT==0x00, flTimeout=70)    #Stopped
../GSEOS_scripts/General\AXB_stacer_deploy.py[95]:     log('8. Post Deploy Checks ', color=BLUE, attr=BOLD )
../GSEOS_scripts/General\AXB_stacer_deploy.py[96]:     if(APID_267.DLENA!=xdlena):
../GSEOS_scripts/General\AXB_stacer_deploy.py[99]:     if(APID_267.DLENB!=xdlenb):
../GSEOS_scripts/General\AXB_stacer_deploy.py[100]:         stopfail("8.2 DEPLOY LENGTH B 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_NORMAL()                    #Normal Mode
../GSEOS_scripts/General\AXB_stacer_deploy.py[28]:     cmd.EFW_TMPKTRATE( 4, 8 )           #Set APID 267 back to 1/8Hz
../GSEOS_scripts/General\AXB_stacer_deploy.py[101]:         return -1