8.7.1.2.2 Synchronization (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

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