Trace collected at: 2010-10-13 12:32:39.717000
----------------------------------------------------------------------
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[15]:     log('========================================', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[16]:     log('BKG_3 - VERIFY MET OFFSET MEASUREMENT   ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[17]:     log('========================================', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[18]:     log('1. Initializing    ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[19]:     PF=("FAIL","PASS")
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[21]:     log('1.0 Start with TESTVALUE = 0, No Errors', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[22]:     cmd.EFW_CLEAR()                             #Clear Errors
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[23]:     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_BKG_3.py[24]:     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_BKG_3.py[25]:     cmd.EFW_TMPKTRATE( 4, 1 )                   #Set APID 267 to 1Hz
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[26]:     wait(APID_267, lambda: APID_267.TESTVALUE==0 , flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[28]:     log('1.1 Disabling SRAM Protection/Checksums ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[29]:     cmd.EFW_DISARM(3)               #SRAM scanner off
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[30]:     cmd.EFW_SRMWRTCTL(0)            #SRAM Protection off
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[31]:     sleep(1)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[33]:     log('1.2 Loading TEST1PPS program ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[34]:     efw.idpu_load("00007000", " aa 3e 00 21 09 70 c3 03 00 db 1b e6 01 21 1d 70 be c8 77 b7 c8 db 23 d3 18 32 f8 dd c9 00")
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[36]:     log('1.3 Starting program ', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[37]:     cmd.EFW_CMDS(1)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[38]:     cmd.EFW_EXEC(0x70)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[39]:     wait(APID_263, lambda: APID_263.PROGENA0==1, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[39]:     wait(APID_263, lambda: APID_263.PROGENA0==1, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[40]:     sleep(5)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[42]:     log('2.0 Comparing SC-IDPU 1PPS Time Measures', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[43]:     wait(APID_267, lambda: True, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[43]:     wait(APID_267, lambda: True, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[44]:     fpga = APID_267.IO_DELTAMET / 65536.0
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[45]:     log('    FPGA Measurement = %5.3f seconds' % fpga, color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[46]:     tsw = (APID_267.IO_LEDS+0.5) / 256.0
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[47]:     log('     S/W Measurement = %5.3f seconds' % tsw, color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[48]:     diff=abs(fpga-tsw)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[49]:     log('    Difference of %5.3f : %s' %(diff,PF[diff<.010]), color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[52]:     log('3.0 Cleaning up, Stopping Program', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[53]:     cmd.EFW_STOPUSER(0)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[54]:     wait(APID_263, lambda: APID_263.PROGENA0==0, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[54]:     wait(APID_263, lambda: APID_263.PROGENA0==0, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[54]:     wait(APID_263, lambda: APID_263.PROGENA0==0, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[54]:     wait(APID_263, lambda: APID_263.PROGENA0==0, flTimeout=10)
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[55]:     log('3.1 ReArming SRAM Protections', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[56]:     cmd.EFW_ARM(3)                  #Scanner on
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[57]:     cmd.EFW_SRMWRTCTL(1)            #SRAM Protection on
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[59]:     log('================END=====================', color=BLUE, attr=BOLD )
../../GSEOS_scripts/FSW_scripts\TEST_BKG_3.py[60]:     return(0)