8.7.1.2.2 Synchronization (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

05:25:46.655 Running procedure: 8.7.1.2.2
05:25:46.657 This procedure is enabled because the following options:
05:25:46.657 - timesync.supportSynchronizingTimeFromMaster == true
05:25:46.657 step: 1
05:25:46.657 - Cycle power to the DUT
05:25:46.657 [PLG] Cycling power
05:25:46.658 step: 2
05:25:46.658 - Wait for 5000 milliseconds
05:25:51.658 step: 3
05:25:51.658 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:25:51.658 - g60v2, All Objects
05:25:51.659 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:25:51.659 g60v2, All Objects
05:25:51.659 --T-> fir: 1 fin: 1 seq: 0
05:25:51.659 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:25:51.659 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:25:51.659 C0 C0 01 3C 02 06 54 E0
05:25:51.659 step: 4
05:25:51.659 - Read any number of valid responses beginning w/ sequence(0x00) and incrementing by 1 modulo 16
05:25:51.659 - Verify that the IIN field contains IIN(NeedTime)
05:25:51.659 - Verify that each fragment followed by another, or carrying events, asks for an application layer confirmation.
05:25:51.659 - Confirm each response that asks for a confirmation
05:25:51.660 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:25:51.660 C0 C0 81 90 00 9B 2C
05:25:51.660 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:25:51.660 <-T-- fir: 1 fin: 1 seq: 0
05:25:51.660 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:25:51.660 step: 5
05:25:51.660 - Write the current time + 5 ms w/ sequence(0x01)
05:25:51.660 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:25:51.660 g50v1, 1-Byte Count, count: 1
05:25:51.660 time: 1793251551665
05:25:51.660 --T-> fir: 1 fin: 1 seq: 0
05:25:51.660 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 18 dest: 1024 src: 1
05:25:51.660 --P-> 05 64 12 C4 00 04 01 00 30 DD
05:25:51.660 C0 C1 02 32 01 07 01 B1 29 1F 86 A1 01 AE 9F
05:25:51.660 step: 6
05:25:51.660 - Read a single fragment response w/ sequence(0x01)
05:25:51.660 - Verify that the response is empty
05:25:51.660 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:25:51.660 - Verify that the IIN field does not contain bits IIN(NeedTime)
05:25:51.660 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:25:51.660 C1 C1 81 80 00 5D 12
05:25:51.660 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:25:51.660 <-T-- fir: 1 fin: 1 seq: 1
05:25:51.661 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart)
05:25:51.661 step: 7
05:25:51.661 - Trigger an event of a supported timestamped type, recording when it occurs for the timestamp check
05:25:51.661 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251551665 (SYNCHRONIZED_TIME)
05:25:51.661 step: 8
05:25:51.661 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:25:51.661 - g60v2, All Objects
05:25:51.661 - g60v3, All Objects
05:25:51.661 - g60v4, All Objects
05:25:51.661 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:25:51.661 g60v2, All Objects
05:25:51.661 g60v3, All Objects
05:25:51.661 g60v4, All Objects
05:25:51.661 --T-> fir: 1 fin: 1 seq: 0
05:25:51.661 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:25:51.661 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:25:51.661 C0 C2 01 3C 02 06 3C 03 06 3C 04 06 82 1D
05:25:51.661 step: 9
05:25:51.661 - Read any number of valid responses beginning w/ sequence(0x02) and incrementing by 1 modulo 16
05:25:51.661 - Verify that the response only contains objects, variations, and qualifiers specified in table 8-4, for subset level 3
05:25:51.661 - Verify that each fragment followed by another, or carrying events, asks for an application layer confirmation.
05:25:51.661 - Verify that the response holds an event that carries its time
05:25:51.661 - Verify that the generated event is in the response and that its timestamp's error from the known event time does not exceed the Device Profile's tolerance
05:25:51.661 - Confirm each response that asks for a confirmation
05:25:51.661 <-P-- 05 64 18 44 01 00 00 04 11 D6
05:25:51.661 C2 E2 81 80 00 02 02 28 01 00 00 00 81 B1 29 1F C3 1C
05:25:51.661 86 A1 01 DE 8A
05:25:51.661 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 24 dest: 1 src: 1024
05:25:51.661 <-T-- fir: 1 fin: 1 seq: 2
05:25:51.661 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart)
05:25:51.661 g2v2, 2-Byte Count and Index, count: 1
05:25:51.661 [0] - flags: 0x81 time: 1793251551665
05:25:51.661 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:25:51.661 --T-> fir: 1 fin: 1 seq: 0
05:25:51.661 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:25:51.661 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:25:51.661 C0 C2 00 D6 8D
05:25:51.662 [PLG] Verified 1 Class 1, 2 and 3 event(s)
05:25:51.662 Passed procedure: 8.7.1.2.2