8.7.2 LAN Time Sync Method (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

10:57:39.178 Running procedure: 8.7.2
10:57:39.179 This procedure is enabled because the following options:
10:57:39.179 - timesync.supportSynchronizingTimeFromMaster == true
10:57:39.179 - timesync.supportLanTimeSync == true
10:57:39.179 step: 1
10:57:39.179 - Cycle power to the DUT
10:57:39.179 [PLG] Cycling power
10:57:39.180 step: 2
10:57:39.180 - Wait for 5000 milliseconds
10:57:44.180 step: 3
10:57:44.180 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:57:44.180 - g60v2, All Objects
10:57:44.181 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:57:44.181 g60v2, All Objects
10:57:44.181 --T-> fir: 1 fin: 1 seq: 0
10:57:44.181 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:57:44.181 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:57:44.181 C0 C0 01 3C 02 06 54 E0
10:57:44.181 step: 4
10:57:44.181 - Read any number of valid responses beginning w/ sequence(0x00) and incrementing by 1 modulo 16
10:57:44.181 - Verify that the IIN field contains IIN(NeedTime)
10:57:44.181 - Verify that each fragment followed by another, or carrying events, asks for an application layer confirmation.
10:57:44.181 - Confirm each response that asks for a confirmation
10:57:44.181 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:57:44.181 C0 C0 81 90 00 9B 2C
10:57:44.181 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:57:44.181 <-T-- fir: 1 fin: 1 seq: 0
10:57:44.181 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:57:44.181 steps: 5 to 6
10:57:44.181 - Broadcast a Record Current Time request (function code 24) to 0xFFFF, and note when it's been sent
10:57:44.181 --A-> Record Current Time(0x18) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:57:44.182 --T-> fir: 1 fin: 1 seq: 0
10:57:44.182 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 65535 src: 1
10:57:44.182 --P-> 05 64 08 C4 FF FF 01 00 CF 9C
10:57:44.182 C0 C1 18 9F 70
10:57:44.182 step: 7
10:57:44.182 - Verify that no link layer frames are received within 2000 ms
10:57:46.184 frame receive timeout
10:57:46.184 step: 8
10:57:46.184 - Wait for 5000 milliseconds
10:57:51.184 step: 9
10:57:51.184 - Write the time the Record Current Time request was sent (g50v3, qualifier 0x07) w/ sequence(0x02)
10:57:51.185 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:57:51.185 g50v3, 1-Byte Count, count: 1
10:57:51.185 time: 1793185064182
10:57:51.185 --T-> fir: 1 fin: 1 seq: 0
10:57:51.185 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 18 dest: 1024 src: 1
10:57:51.185 --P-> 05 64 12 C4 00 04 01 00 30 DD
10:57:51.185 C0 C2 02 32 03 07 01 F6 A4 28 82 A1 01 FF E8
10:57:51.186 steps: 10 to 11
10:57:51.186 - Read a single fragment response w/ sequence(0x02)
10:57:51.186 - Verify that the response is empty
10:57:51.186 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:57:51.186 - Verify that the IIN field contains IIN(Broadcast)
10:57:51.186 - Verify that the IIN field does not contain bits IIN(NeedTime)
10:57:51.186 - Confirm each response that asks for a confirmation
10:57:51.186 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:57:51.186 C1 C2 81 81 00 52 B3
10:57:51.186 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:57:51.186 <-T-- fir: 1 fin: 1 seq: 1
10:57:51.186 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(DeviceRestart, Broadcast)
10:57:51.186 step: 12
10:57:51.186 - Trigger an event of a supported timestamped type, recording when it occurs for the timestamp check
10:57:51.186 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185071185 (SYNCHRONIZED_TIME)
10:57:51.186 step: 13
10:57:51.186 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:57:51.186 - g60v2, All Objects
10:57:51.186 - g60v3, All Objects
10:57:51.186 - g60v4, All Objects
10:57:51.186 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:57:51.186 g60v2, All Objects
10:57:51.186 g60v3, All Objects
10:57:51.186 g60v4, All Objects
10:57:51.186 --T-> fir: 1 fin: 1 seq: 0
10:57:51.186 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:57:51.186 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:57:51.186 C0 C3 01 3C 02 06 3C 03 06 3C 04 06 0C 0B
10:57:51.186 step: 14
10:57:51.186 - Read any number of valid responses beginning w/ sequence(0x03) and incrementing by 1 modulo 16
10:57:51.186 - Verify that each fragment followed by another, or carrying events, asks for an application layer confirmation.
10:57:51.186 - Verify that the response holds an event that carries its time
10:57:51.186 - 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:51.186 - Confirm each response that asks for a confirmation
10:57:51.187 <-P-- 05 64 18 44 01 00 00 04 11 D6
10:57:51.187 C2 E3 81 80 00 02 02 28 01 00 00 00 81 51 C0 28 31 D4
10:57:51.187 82 A1 01 3E EC
10:57:51.187 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 24 dest: 1 src: 1024
10:57:51.187 <-T-- fir: 1 fin: 1 seq: 2
10:57:51.187 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x03 IIN(DeviceRestart)
10:57:51.187 g2v2, 2-Byte Count and Index, count: 1
10:57:51.187 [0] - flags: 0x81 time: 1793185071185
10:57:51.187 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:57:51.187 --T-> fir: 1 fin: 1 seq: 0
10:57:51.187 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:57:51.187 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:57:51.187 C0 C3 00 98 26
10:57:51.187 [PLG] Verified 1 Class 1, 2 and 3 event(s)
10:57:51.187 Passed procedure: 8.7.2