8.22.2.10 Double-bit Input Change With Time, 0x07 (revision 2.7, subset level 2)

Device under test: Step Function I/O dnp3 outstation

11:01:42.068 Running procedure: 8.22.2.10
11:01:42.069 This procedure is enabled because the following options:
11:01:42.069 - supports.doubleBitInput == true
11:01:42.069 - supports.doubleBitInputChangeEvents == true
11:01:42.069 - timesync.doubleBitInputChangeWithTime == true
11:01:42.069 step: 1
11:01:42.069 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:01:42.069 - g4v0, All Objects
11:01:42.069 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:01:42.069 g4v0, All Objects
11:01:42.069 --T-> fir: 1 fin: 1 seq: 0
11:01:42.069 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:01:42.069 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:01:42.069 C0 C1 01 04 00 06 05 23
11:01:42.069 steps: 2 to 3
11:01:42.069 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
11:01:42.069 - If the response carries data, verify that it asks for an application layer confirmation.
11:01:42.069 - Confirm each response that asks for a confirmation
11:01:42.072 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:01:42.072 C0 C1 81 90 00 73 EE
11:01:42.072 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:01:42.072 <-T-- fir: 1 fin: 1 seq: 0
11:01:42.072 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:01:42.072 step: 4
11:01:42.072 - Generate 10 Double-bit Binary Input Change Event(s)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793185302072 (UNSYNCHRONIZED_TIME)
11:01:42.072 The plugin reports the DUT's clock unsynchronized
11:01:42.072 step: 5
11:01:42.072 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:01:42.072 - g4v2, 1-Byte Count, count: 1
11:01:42.072 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:01:42.072 g4v2, 1-Byte Count, count: 1
11:01:42.072 --T-> fir: 1 fin: 1 seq: 0
11:01:42.072 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 12 dest: 1024 src: 1
11:01:42.072 --P-> 05 64 0C C4 00 04 01 00 F4 54
11:01:42.072 C0 C2 01 04 02 07 01 00 3F
11:01:42.073 steps: 6 to 11
11:01:42.073 - Read a single fragment response w/ sequence(0x02)
11:01:42.073 - Verify the device responds with the first Double-bit Binary event
11:01:42.073 - If the events carry timestamps, verify that the gaps between them match the gaps between when the events were generated, to the Device Profile's resolution
11:01:42.073 - Verify that an application layer confirmation is requested.
11:01:42.073 - Verify that the events are reported as TB2017-003a 5.1.3.1.2 requires, which the DUT implements: g4v2 for events recorded while the clock was synchronized, g4v3 after g51v2 for those recorded while it was unsynchronized, by the clock's state as the plugin reports it
11:01:42.073 - Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
11:01:42.073 - Confirm each response that asks for a confirmation
11:01:42.073 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
11:01:42.073 C1 E2 81 92 00 33 02 07 01 38 46 2C 82 A1 01 04 64 36
11:01:42.073 03 28 01 00 00 00 81 00 00 9A 2A
11:01:42.073 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
11:01:42.073 <-T-- fir: 1 fin: 1 seq: 1
11:01:42.073 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime, Class1Events)
11:01:42.073 g51v2, 1-Byte Count, count: 1
11:01:42.073 time: 1793185302072
11:01:42.073 g4v3, 2-Byte Count and Index, count: 1
11:01:42.073 [0] - flags: 0x81 cto: 0
11:01:42.073 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
11:01:42.073 --T-> fir: 1 fin: 1 seq: 0
11:01:42.073 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:01:42.073 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:01:42.073 C0 C2 00 D6 8D
11:01:42.073 [PLG] Verified first double-bit binary input point event
11:01:42.073 Note on the result: The events were checked as TB2017-003a 5.1.3.1.2 requires, which the DUT implements, in place of the steps' g4v2 only
11:01:42.073 step: 12
11:01:42.073 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
11:01:42.073 - g4v2, 1-Byte Count, count: 9
11:01:42.073 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
11:01:42.073 g4v2, 1-Byte Count, count: 9
11:01:42.073 --T-> fir: 1 fin: 1 seq: 0
11:01:42.073 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 12 dest: 1024 src: 1
11:01:42.073 --P-> 05 64 0C C4 00 04 01 00 F4 54
11:01:42.073 C0 C3 01 04 02 07 09 6E 75
11:01:42.073 steps: 13 to 15
11:01:42.073 - Read a single fragment response w/ sequence(0x03)
11:01:42.073 - Verify that the response holds the Double-bit Binary events generated that weren't reported before
11:01:42.073 - If the events carry timestamps, verify that the gaps between them match the gaps between when the events were generated, to the Device Profile's resolution
11:01:42.073 - Verify that an application layer confirmation is requested.
11:01:42.073 - Verify that the events are reported as TB2017-003a 5.1.3.1.2 requires, which the DUT implements: g4v2 for events recorded while the clock was synchronized, g4v3 after g51v2 for those recorded while it was unsynchronized, by the clock's state as the plugin reports it
11:01:42.073 - Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
11:01:42.073 - Confirm each response that asks for a confirmation
11:01:42.074 <-P-- 05 64 46 44 01 00 00 04 A8 F1
11:01:42.074 C2 E3 81 90 00 33 02 07 01 38 46 2C 82 A1 01 04 9E 30
11:01:42.074 03 28 09 00 00 00 41 00 00 00 00 81 00 00 00 00 09 E8
11:01:42.074 41 00 00 00 00 81 00 00 00 00 41 00 00 00 00 81 B2 55
11:01:42.074 00 00 00 00 41 00 00 00 00 81 00 00 00 00 41 00 4F 13
11:01:42.074 00 FF FF
11:01:42.074 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 70 dest: 1 src: 1024
11:01:42.074 <-T-- fir: 1 fin: 1 seq: 2
11:01:42.074 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
11:01:42.074 g51v2, 1-Byte Count, count: 1
11:01:42.074 time: 1793185302072
11:01:42.074 g4v3, 2-Byte Count and Index, count: 9
11:01:42.074 [0] - flags: 0x41 cto: 0
11:01:42.074 [0] - flags: 0x81 cto: 0
11:01:42.074 [0] - flags: 0x41 cto: 0
11:01:42.074 [0] - flags: 0x81 cto: 0
11:01:42.074 [0] - flags: 0x41 cto: 0
11:01:42.074 [0] - flags: 0x81 cto: 0
11:01:42.074 [0] - flags: 0x41 cto: 0
11:01:42.074 [0] - flags: 0x81 cto: 0
11:01:42.074 [0] - flags: 0x41 cto: 0
11:01:42.074 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
11:01:42.074 --T-> fir: 1 fin: 1 seq: 0
11:01:42.074 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:01:42.074 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:01:42.074 C0 C3 00 98 26
11:01:42.074 [PLG] Verified 9 double-bit binary input point event(s)
11:01:42.074 step: 16
11:01:42.074 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
11:01:42.074 - g4v0, All Objects
11:01:42.074 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
11:01:42.074 g4v0, All Objects
11:01:42.074 --T-> fir: 1 fin: 1 seq: 0
11:01:42.074 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:01:42.074 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:01:42.074 C0 C4 01 04 00 06 1B 8C
11:01:42.074 step: 17
11:01:42.074 - Read a single fragment response w/ sequence(0x04)
11:01:42.074 - Verify that the response is empty
11:01:42.074 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
11:01:42.075 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:01:42.075 C3 C4 81 90 00 BA 95
11:01:42.075 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:01:42.075 <-T-- fir: 1 fin: 1 seq: 3
11:01:42.075 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04 IIN(DeviceRestart, NeedTime)
11:01:42.075 Passed procedure: 8.22.2.10