Trace collected at: 2010-11-10 09:24:50.633000 ---------------------------------------------------------------------- ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[17]: log('========================================', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[18]: log('DFB_3 - CMD TIME ALLOCATION < 2msec ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[19]: log('========================================', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[20]: PF=("FAIL","PASS") ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[22]: log('1. Increasing TM Rate, Clear Start ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[23]: cmd.EFW_TMPKTRATE( 4, 1 ) #Set APID 267 to 1Hz ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.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_DFB_3.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_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[28]: log('2. Measuring BKG ISR (w/No commands) ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.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_DFB_3.py[30]: sleep(8) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[31]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[31]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[32]: intnum = APID_267.TESTVALUE / 256 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[33]: inttime= APID_267.TESTVALUE & 255 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[34]: fmsec = (1000.0/65536.0) * inttime ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[35]: log('Maximum Interrupt Service Time is %2.3f msec' % fmsec) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[36]: log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec*100.0/3.90625) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[37]: log('Maximum Interrupt Service Routine= %x hex' %intnum) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[38]: log('Requirement for ISR Duration < 2 msec : %s' % PF[fmsec < 2.0] ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[40]: log('3. Commanding DFB ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[42]: cmd.EFW_CONFIG0( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[43]: cmd.EFW_CONFIG1( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[44]: cmd.EFW_DFBINIT( ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[45]: cmd.EFW_DFBCONFIG( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[46]: cmd.EFW_DFBDMA( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[47]: cmd.EFW_DFBCHNRST( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[48]: cmd.EFW_DFBHSKREAD( 0 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[50]: sleep(2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[52]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[31]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[53]: intnum2 = APID_267.TESTVALUE / 256 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[54]: inttime2= APID_267.TESTVALUE & 255 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[55]: fmsec2 = (1000.0/65536.0) * inttime2 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[56]: log('Maximum Interrupt Service Time is %2.3f msec' % fmsec2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[57]: log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec2*100.0/3.90625) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[58]: log('Maximum Interrupt Service Routine= %x hex' %intnum2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[60]: log('Change in MAX ISR : %5.3f' % (fmsec2-fmsec) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[61]: log('Requirement for DEP CMD Duration < 2 msec : %s' % PF[fmsec2 < 2.0] ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[63]: log('4.0 Removing Vectors ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[64]: 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_DFB_3.py[66]: log('================END=====================', color=BLUE, attr=BOLD ) Trace collected at: 2010-11-10 09:41:54.742000 ---------------------------------------------------------------------- ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[21]: log('========================================', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[22]: log('DFB_3 - CMD TIME ALLOCATION < 2msec ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[23]: log('========================================', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[24]: PF=("FAIL","PASS") ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[25]: DVP = 0x111 # Duration Verification Point ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[26]: RST5= 0xEF # Restart 5 Instruction ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[27]: NOP = 0x00 # No Operation ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[29]: log('1. Increasing TM Rate, Clear Start ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[30]: cmd.EFW_TMPKTRATE( 4, 1 ) #Set APID 267 to 1Hz ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[31]: 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_DFB_3.py[32]: 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_DFB_3.py[33]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[33]: wait(APID_267, lambda: APID_267.TESTVALUE == 0 , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[35]: log('1.1 Enabling SRAM loading, Disable Scan ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[36]: cmd.EFW_SRMWRTCTL( 0 ) # Enable Writing to the SRAM ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[37]: cmd.EFW_DISARM(3) # Disable SRAM Scanner ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[39]: log('2.1 Verifying BKG Duration Point ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[40]: DUMPADR( DVP ) ../../GSEOS_scripts\util.py[65]: cmd.EFW_DUMPADRX( (addr >> 24 )& 0xff ) ../../GSEOS_scripts\util.py[66]: cmd.EFW_DUMPADRE( (addr >> 16 )& 0xff ) ../../GSEOS_scripts\util.py[67]: cmd.EFW_DUMPADRH( (addr >> 8 )& 0xff ) ../../GSEOS_scripts\util.py[68]: cmd.EFW_DUMPADRL( addr & 0xff ) ../../GSEOS_scripts\util.py[69]: cmd.EFW_TMDUMPCTR(255) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[41]: wait(APID_266, lambda: APID_266.DUMPADR == DVP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[42]: wait(APID_266, lambda: APID_266.Data[0] == NOP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[42]: wait(APID_266, lambda: APID_266.Data[0] == NOP, flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[44]: log('2.2 Installing BKG ISR Timing ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[45]: POKE( DVP, RST5 ) #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_DFB_3.py[46]: sleep(8) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[48]: log('2.3 Measuring BKG ISR (w/No commands) ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[49]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[49]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[50]: intnum = APID_267.TESTVALUE / 256 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[51]: inttime= APID_267.TESTVALUE & 255 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[52]: fmsec = (1000.0/65536.0) * inttime ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[53]: log('Maximum Interrupt Service Time is %2.3f msec' % fmsec) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[54]: log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec*100.0/3.90625) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[55]: log('Maximum Interrupt Service Routine= %x hex' %intnum) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[56]: log('Requirement for ISR Duration < 2 msec : %s' % PF[fmsec < 2.0] ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[58]: log('3. Commanding DFB ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[60]: cmd.EFW_CONFIG0( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[61]: cmd.EFW_CONFIG1( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[62]: cmd.EFW_DFBINIT( ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[63]: cmd.EFW_DFBCONFIG( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[64]: cmd.EFW_DFBDMA( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[65]: cmd.EFW_DFBCHNRST( 1 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[66]: cmd.EFW_DFBHSKREAD( 0 ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[68]: sleep(2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[70]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[49]: wait(APID_267, lambda: True , flTimeout=10) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[71]: intnum2 = APID_267.TESTVALUE / 256 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[72]: inttime2= APID_267.TESTVALUE & 255 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[73]: fmsec2 = (1000.0/65536.0) * inttime2 ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[74]: log('Maximum Interrupt Service Time is %2.3f msec' % fmsec2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[75]: log('Percent of Interrupt at 256 Hz is %2.3f percent' % (fmsec2*100.0/3.90625) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[76]: log('Maximum Interrupt Service Routine= %x hex' %intnum2) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[78]: log('Change in MAX ISR : %5.3f' % (fmsec2-fmsec) ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[79]: log('Requirement for DEP CMD Duration < 2 msec : %s' % PF[fmsec2 < 2.0] ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[81]: log('4.0 Removing Diagnostics ', color=BLUE, attr=BOLD ) ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[82]: POKE( DVP, NOP ) #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_DFB_3.py[83]: cmd.EFW_SRMWRTCTL( 1 ) # Disable Writing to the SRAM ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[84]: cmd.EFW_DISARM(3) # Enable SRAM Scanner ../../GSEOS_scripts/FSW_scripts\TEST_DFB_3.py[85]: log('================END=====================', color=BLUE, attr=BOLD )