12:02:25.726 Running procedure: 8.22.2.12
12:02:25.730 This procedure is enabled because the following options:
12:02:25.730 - supports.doubleBit == true
12:02:25.731 step: 1
12:02:25.731 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
12:02:25.731 - g4v0, All Objects
12:02:25.732 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
12:02:25.732 g4v0, All Objects
12:02:25.732 --T-> fir: 1 fin: 1 seq: 0
12:02:25.733 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
12:02:25.733 --P-> 05 64 0B C4 00 04 01 00 CA 8A
12:02:25.733 C0 C1 01 04 00 06 05 23
12:02:25.734 steps: 2 to 3
12:02:25.734 - Read a single fragment response w/ sequence(0x01)
12:02:25.734 - If the response is not Null, verify that the device requests an application layer confirmation.
12:02:25.735 <-P-- 05 64 0A 44 01 00 00 04 67 88
12:02:25.735 C0 C1 81 90 00 73 EE
12:02:25.735 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
12:02:25.736 <-T-- fir: 1 fin: 1 seq: 0
12:02:25.736 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
12:02:25.736 step: 4
12:02:25.736 - Generate several Double-bit Binary Input Change Events
12:02:25.737 Updated DBBI 1024: value=DETERMINED_ON, flags=81, timestamp=DNPTime(1586534545737)
12:02:25.737 Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=DNPTime(1586534545737)
12:02:25.738 Updated DBBI 1024: value=DETERMINED_OFF, flags=41, timestamp=DNPTime(1586534545737)
12:02:25.739 step: 5
12:02:25.739 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
12:02:25.739 - g4v3, All Objects
12:02:25.739 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
12:02:25.739 g4v3, All Objects
12:02:25.739 --T-> fir: 1 fin: 1 seq: 0
12:02:25.740 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
12:02:25.740 --P-> 05 64 0B C4 00 04 01 00 CA 8A
12:02:25.740 C0 C2 01 04 03 06 A4 F6
12:02:25.741 step: 6
12:02:25.741 - DUT supports Binary Input Change with Relative Time events.
12:02:25.743 steps: 7 to 12
12:02:25.743 - Read a single fragment response w/ sequence(0x02)
12:02:25.743 - Verify the device responds with all generated Double-bit Binary events
12:02:25.743 - If the variation contains timestamps, verify that they are reasonable according to the resolution specified in the Device Profile
12:02:25.743 - Verify that an application layer confirmation is requested.
12:02:25.743 - Validate that a g51v1 or g51v2 precedes the g4v3 in each fragment of the response
12:02:25.743 - Validation that only g51v1, g51v2 and g4v3 appears in the response
12:02:25.743 - Verify that flag behavior complies with Section 2, Note #5.
12:02:25.744 <-P-- 05 64 28 44 01 00 00 04 52 5F
12:02:25.744 C1 E2 81 90 00 33 02 07 01 49 F9 D3 64 71 01 04 90 97
12:02:25.744 03 28 03 00 00 04 81 00 00 00 00 81 00 00 00 04 62 01
12:02:25.744 41 00 00 28 6A
12:02:25.744 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 40 dest: 1 src: 1024
12:02:25.744 <-T-- fir: 1 fin: 1 seq: 1
12:02:25.746 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
12:02:25.746 g51v2, 1-Byte Count, count: 1
12:02:25.746 time: 1586534545737
12:02:25.746 g4v3, 2-Byte Count and Index, count: 3
12:02:25.746 [1024] - flags: 0x81 cto: 0
12:02:25.746 [0] - flags: 0x81 cto: 0
12:02:25.746 [1024] - flags: 0x41 cto: 0
12:02:25.746 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
12:02:25.747 --T-> fir: 1 fin: 1 seq: 0
12:02:25.747 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
12:02:25.747 --P-> 05 64 08 C4 00 04 01 00 9A 19
12:02:25.747 C0 C2 00 D6 8D
12:02:25.749 step: 13
12:02:25.749 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
12:02:25.749 - g4v0, All Objects
12:02:25.749 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
12:02:25.749 g4v0, All Objects
12:02:25.749 --T-> fir: 1 fin: 1 seq: 0
12:02:25.750 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
12:02:25.750 --P-> 05 64 0B C4 00 04 01 00 CA 8A
12:02:25.750 C0 C3 01 04 00 06 09 65
12:02:25.751 step: 14
12:02:25.751 - Read a single fragment response w/ sequence(0x03)
12:02:25.751 - Verify that the response is empty
12:02:25.752 <-P-- 05 64 0A 44 01 00 00 04 67 88
12:02:25.752 C2 C3 81 90 00 D6 60
12:02:25.752 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
12:02:25.752 <-T-- fir: 1 fin: 1 seq: 2
12:02:25.753 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
12:02:25.753 Passed procedure: 8.22.2.12