Trace collected at: 2010-11-12 10:47:15.464000
----------------------------------------------------------------------
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[17]:     log('========================================', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[18]:     log('CMP_2 - CMD TIME ALLOCATION < 2msec     ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[19]:     log('========================================', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[20]:     PF=("FAIL","PASS")
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[22]:     log('1. Increasing TM Rate, Clear Start ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[23]:     cmd.EFW_TMPKTRATE( 4, 1 )                   #Set APID 267 to 1Hz
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[24]:     SET_TESTVALUE0( 0 )
../../GSEOS_scripts\util.py[112]:     POKE( 0XDDF9, byte )
../../GSEOS_scripts\util.py[102]:     cmd.EFW_ADRH( (addr >> 8  )& 0xff )
../../GSEOS_scripts\util.py[103]:     cmd.EFW_ADRL( addr & 0xff )
../../GSEOS_scripts\util.py[104]:     cmd.EFW_LOAD( byte )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[25]:     SET_TESTVALUE1( 0 )
../../GSEOS_scripts\util.py[115]:     POKE( 0XDDFa, byte )
../../GSEOS_scripts\util.py[102]:     cmd.EFW_ADRH( (addr >> 8  )& 0xff )
../../GSEOS_scripts\util.py[103]:     cmd.EFW_ADRL( addr & 0xff )
../../GSEOS_scripts\util.py[104]:     cmd.EFW_LOAD( byte )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[28]:     log('2.  Measuring BKG ISR (w/No commands)   ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[29]:     POKE( 0x10F, 0xEF )                         #BKG[addr] = RST 5
../../GSEOS_scripts\util.py[102]:     cmd.EFW_ADRH( (addr >> 8  )& 0xff )
../../GSEOS_scripts\util.py[103]:     cmd.EFW_ADRL( addr & 0xff )
../../GSEOS_scripts\util.py[104]:     cmd.EFW_LOAD( byte )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[30]:     sleep(8)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[31]:     wait(APID_267, lambda: True , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[31]:     wait(APID_267, lambda: True , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[32]:     intnum = APID_267.TESTVALUE / 256
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[33]:     inttime= APID_267.TESTVALUE & 255
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[34]:     fmsec = (1000.0/65536.0) * inttime
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[35]:     log('Maximum Interrupt Service Time is %2.3f msec' % fmsec)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[36]:     log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec*100.0/3.90625) )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[37]:     log('Maximum Interrupt Service Routine= %x hex' %intnum)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[38]:     log('Requirement for ISR Duration < 2 msec : %s' % PF[fmsec < 2.0] )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[40]:     log('3.  Commanding CMP   ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[41]:     log('Note: CMP Module no longer has commands', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[42]:     sleep(2)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[44]:     wait(APID_267, lambda: True , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[31]:     wait(APID_267, lambda: True , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[45]:     intnum2 = APID_267.TESTVALUE / 256
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[46]:     inttime2= APID_267.TESTVALUE & 255
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[47]:     fmsec2 = (1000.0/65536.0) * inttime2
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[48]:     log('Maximum Interrupt Service Time is %2.3f msec' % fmsec2)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[49]:     log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec2*100.0/3.90625) )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[50]:     log('Maximum Interrupt Service Routine= %x hex' %intnum2)
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[52]:     log('Change in MAX ISR : %5.3f' % (fmsec2-fmsec) )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[53]:     log('Requirement for DEP CMD Duration < 2 msec : %s' % PF[fmsec2 < 2.0] )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[55]:     log('4.0 Removing Vectors     ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[56]:     POKE( 0x10F, 0 )                         #BKG[addr] = NOP
../../GSEOS_scripts\util.py[102]:     cmd.EFW_ADRH( (addr >> 8  )& 0xff )
../../GSEOS_scripts\util.py[103]:     cmd.EFW_ADRL( addr & 0xff )
../../GSEOS_scripts\util.py[104]:     cmd.EFW_LOAD( byte )
../../GSEOS_scripts/FSW_scripts\TEST_CMP_2.py[58]:     log('================END=====================', color=BLUE, attr=BOLD )