11:56:12.441 Running procedure: 8.7.2.2
11:56:12.447 step: 1
11:56:12.447 - Cycle power to the DUT
11:56:12.452 step: 2
11:56:12.452 - Wait for 5000 milliseconds
11:56:17.453 step: 3
11:56:17.453 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:56:17.453 - g60v2, All Objects
11:56:17.454 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:56:17.454 g60v2, All Objects
11:56:17.454 --T-> fir: 1 fin: 1 seq: 0
11:56:17.454 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:56:17.455 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:56:17.455 C0 C0 01 3C 02 06 54 E0
11:56:17.456 step: 4
11:56:17.456 - Read a single fragment response w/ sequence(0x00)
11:56:17.456 - Verify that the IIN field contains IIN(NeedTime)
11:56:17.456 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:56:17.457 C0 C0 81 90 00 9B 2C
11:56:17.457 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:56:17.457 <-T-- fir: 1 fin: 1 seq: 0
11:56:17.457 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:56:17.458 step: 5
11:56:17.458 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:56:17.458 - g50v1, 1-Byte Count, count: 1
11:56:17.458 - time: 1586534172451
11:56:17.459 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:56:17.459 g50v1, 1-Byte Count, count: 1
11:56:17.459 time: 1586534172451
11:56:17.459 --T-> fir: 1 fin: 1 seq: 0
11:56:17.460 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 18 dest: 1024 src: 1
11:56:17.460 --P-> 05 64 12 C4 00 04 01 00 30 DD
11:56:17.460 C0 C1 02 32 01 07 01 23 47 CE 64 71 01 83 6D
11:56:17.461 step: 6
11:56:17.461 - Read a single fragment response w/ sequence(0x01)
11:56:17.461 - Verify that the response is empty
11:56:17.461 - Verify that the IIN field does not contain bits IIN(NeedTime)
11:56:17.462 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:56:17.462 C1 C1 81 80 00 5D 12
11:56:17.462 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:56:17.463 <-T-- fir: 1 fin: 1 seq: 1
11:56:17.463 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart)
11:56:17.463 step: 7
11:56:17.463 - Generate a supported time tagged event at a known time
11:56:17.464 Updated BI 41: value=true, flags=81, timestamp=DNPTime(1586534172454)
11:56:17.465 step: 8
11:56:17.465 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:56:17.465 - g60v2, All Objects
11:56:17.465 - g60v3, All Objects
11:56:17.465 - g60v4, All Objects
11:56:17.465 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:56:17.465 g60v2, All Objects
11:56:17.465 g60v3, All Objects
11:56:17.465 g60v4, All Objects
11:56:17.465 --T-> fir: 1 fin: 1 seq: 0
11:56:17.466 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
11:56:17.466 --P-> 05 64 11 C4 00 04 01 00 60 4E
11:56:17.466 C0 C2 01 3C 02 06 3C 03 06 3C 04 06 82 1D
11:56:17.467 step: 9
11:56:17.467 - Read a single fragment response w/ sequence(0x02)
11:56:17.467 - Verify that the device responds with a valid time tagged event and that the time reported is within the maximum error specified in the Device Profile Document
11:56:17.468 <-P-- 05 64 12 44 01 00 00 04 7A 6A
11:56:17.468 C2 E2 81 80 00 02 01 28 01 00 29 00 81 AD B5
11:56:17.468 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 18 dest: 1 src: 1024
11:56:17.469 <-T-- fir: 1 fin: 1 seq: 2
11:56:17.469 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart)
11:56:17.469 g2v1, 2-Byte Count and Index, count: 1
11:56:17.469 [41] - flags: 0x81
11:56:17.470 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:56:17.470 --T-> fir: 1 fin: 1 seq: 0
11:56:17.470 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:56:17.471 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:56:17.471 C0 C2 00 D6 8D
11:56:17.471 Passed procedure: 8.7.2.2