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

Device under test: Step Function I/O dnp3 outstation

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