8.7.1.1.2 Delay Measurement (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

05:25:46.428 Running procedure: 8.7.1.1.2
05:25:46.430 This procedure is enabled because the following options:
05:25:46.430 - timesync.supportSynchronizingTimeFromMaster == true
05:25:46.430 step: 1
05:25:46.430 - Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:25:46.430 --A-> Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:25:46.430 --T-> fir: 1 fin: 1 seq: 0
05:25:46.430 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:25:46.430 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:25:46.430 C0 C0 17 C2 A7
05:25:46.430 steps: 2 to 3
05:25:46.430 - Read a single fragment response w/ sequence(0x00)
05:25:46.430 - Verify that the response is one g52v2 object (qualifier 0x07, count 1) carrying the measured delay in milliseconds
05:25:46.430 - Verify that the delay measurement error does not exceed the Device Profile's tolerance
05:25:46.431 <-P-- 05 64 10 44 01 00 00 04 CD 4C
05:25:46.431 C0 C0 81 90 00 34 02 07 01 00 00 CB 3E
05:25:46.431 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 16 dest: 1 src: 1024
05:25:46.431 <-T-- fir: 1 fin: 1 seq: 0
05:25:46.431 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:25:46.431 g52v2, 1-Byte Count, count: 1
05:25:46.431 delay: 0
05:25:46.431 [PLG] Delay measurement is within acceptable range (+/- 0)
05:25:46.431 step: 4
05:25:46.431 - Measure the delay two more times
05:25:46.431 - Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:25:46.431 - Read a single fragment response w/ sequence(0x01)
05:25:46.431 - Verify that the response is one g52v2 object (qualifier 0x07, count 1) carrying the measured delay in milliseconds
05:25:46.431 - Verify that the delay measurement error does not exceed the Device Profile's tolerance
05:25:46.431 - Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:25:46.431 - Read a single fragment response w/ sequence(0x02)
05:25:46.431 - Verify that the response is one g52v2 object (qualifier 0x07, count 1) carrying the measured delay in milliseconds
05:25:46.431 - Verify that the delay measurement error does not exceed the Device Profile's tolerance
05:25:46.431 --A-> Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:25:46.431 --T-> fir: 1 fin: 1 seq: 0
05:25:46.432 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:25:46.432 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:25:46.432 C0 C1 17 8C 0C
05:25:46.432 <-P-- 05 64 10 44 01 00 00 04 CD 4C
05:25:46.432 C1 C1 81 90 00 34 02 07 01 00 00 27 EB
05:25:46.432 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 16 dest: 1 src: 1024
05:25:46.432 <-T-- fir: 1 fin: 1 seq: 1
05:25:46.432 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:25:46.432 g52v2, 1-Byte Count, count: 1
05:25:46.432 delay: 0
05:25:46.432 [PLG] Delay measurement is within acceptable range (+/- 0)
05:25:46.432 --A-> Delay Meas(0x17) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:25:46.432 --T-> fir: 1 fin: 1 seq: 0
05:25:46.432 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:25:46.432 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:25:46.432 C0 C2 17 27 BC
05:25:46.432 <-P-- 05 64 10 44 01 00 00 04 CD 4C
05:25:46.432 C2 C2 81 90 00 34 02 07 01 00 00 6A D8
05:25:46.432 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 16 dest: 1 src: 1024
05:25:46.432 <-T-- fir: 1 fin: 1 seq: 2
05:25:46.433 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:25:46.433 g52v2, 1-Byte Count, count: 1
05:25:46.433 delay: 0
05:25:46.433 [PLG] Delay measurement is within acceptable range (+/- 0)
05:25:46.433 Passed procedure: 8.7.1.1.2