8.14.2f Binary Input Change Without Confirm (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

10:59:52.696 Running procedure: 8.14.2f
10:59:52.697 This procedure is enabled because the following options:
10:59:52.697 - supports.binaryInput == true
10:59:52.697 - supports.binaryInputChangeEvents == true
10:59:52.697 - supports.binaryInputEventReads == true
10:59:52.697 step: 1
10:59:52.697 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:59:52.697 - g2v0, All Objects
10:59:52.698 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:59:52.698 g2v0, All Objects
10:59:52.698 --T-> fir: 1 fin: 1 seq: 0
10:59:52.698 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:52.698 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:59:52.698 C0 C1 01 02 00 06 95 76
10:59:52.698 steps: 2 to 3
10:59:52.698 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
10:59:52.698 - If the response carries data, verify that it asks for an application layer confirmation.
10:59:52.698 - Confirm each response that asks for a confirmation
10:59:52.699 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:52.699 C0 C1 81 90 00 73 EE
10:59:52.699 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:52.699 <-T-- fir: 1 fin: 1 seq: 0
10:59:52.699 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:59:52.699 step: 4
10:59:52.699 - Change binary inputs several times, one or more of them, to create events
10:59:52.700 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 1: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 2: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 3: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 4: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 5: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 6: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 7: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 8: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 [PLG] Updated BI 9: value=true, flags=81, timestamp=1793185192700 (UNSYNCHRONIZED_TIME)
10:59:52.700 The plugin reports the DUT's clock unsynchronized
10:59:52.700 step: 5
10:59:52.700 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:59:52.700 - g2v0, All Objects
10:59:52.700 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:59:52.700 g2v0, All Objects
10:59:52.700 --T-> fir: 1 fin: 1 seq: 0
10:59:52.700 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:52.700 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:59:52.700 C0 C2 01 02 00 06 9F 13
10:59:52.700 steps: 6 to 10
10:59:52.700 - Read a single fragment response w/ sequence(0x02)
10:59:52.700 - Verify that the response holds every Binary Input event generated
10:59:52.700 - 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
10:59:52.700 - Verify that an application layer confirmation is requested.
10:59:52.700 - Verify that the events are reported as 3.1r1's rules for event reporting require, for a read of g2v0, by the state of the DUT's clock when it recorded them, where the procedure or the plugin knows it
10:59:52.700 - Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
10:59:52.700 - Keep the response's objects, to compare the next read's with
10:59:52.701 <-P-- 05 64 4B 44 01 00 00 04 FD 93
10:59:52.701 C1 E2 81 90 00 33 02 07 01 FC 9A 2A 82 A1 01 02 6A F3
10:59:52.701 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
10:59:52.701 81 00 00 03 00 81 00 00 04 00 81 00 00 05 00 81 9B 00
10:59:52.701 00 00 06 00 81 00 00 07 00 81 00 00 08 00 81 00 A9 FA
10:59:52.701 00 09 00 81 00 00 AF C5
10:59:52.701 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
10:59:52.701 <-T-- fir: 1 fin: 1 seq: 1
10:59:52.701 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:59:52.701 g51v2, 1-Byte Count, count: 1
10:59:52.701 time: 1793185192700
10:59:52.701 g2v3, 2-Byte Count and Index, count: 10
10:59:52.701 [0] - flags: 0x81 cto: 0
10:59:52.701 [1] - flags: 0x81 cto: 0
10:59:52.701 [2] - flags: 0x81 cto: 0
10:59:52.701 [3] - flags: 0x81 cto: 0
10:59:52.701 [4] - flags: 0x81 cto: 0
10:59:52.701 [5] - flags: 0x81 cto: 0
10:59:52.701 [6] - flags: 0x81 cto: 0
10:59:52.701 [7] - flags: 0x81 cto: 0
10:59:52.701 [8] - flags: 0x81 cto: 0
10:59:52.701 [9] - flags: 0x81 cto: 0
10:59:52.703 [PLG] Verified 10 binary input point event(s)
10:59:52.704 step: 11
10:59:52.704 - Withhold the application layer confirmation for this response
10:59:52.704 steps: 12 to 13
10:59:52.704 - Expect no response within 6000 ms.
10:59:58.709 step: 14
10:59:58.709 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:59:58.709 - g2v0, All Objects
10:59:58.709 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:59:58.709 g2v0, All Objects
10:59:58.709 --T-> fir: 1 fin: 1 seq: 0
10:59:58.710 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:58.710 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:59:58.710 C0 C3 01 02 00 06 99 30
10:59:58.710 step: 15
10:59:58.710 - Read a single fragment response w/ sequence(0x03)
10:59:58.710 - Verify that the response carries the same objects as the previous one
10:59:58.710 <-P-- 05 64 4B 44 01 00 00 04 FD 93
10:59:58.710 C2 E3 81 90 00 33 02 07 01 FC 9A 2A 82 A1 01 02 E6 4B
10:59:58.710 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
10:59:58.710 81 00 00 03 00 81 00 00 04 00 81 00 00 05 00 81 9B 00
10:59:58.710 00 00 06 00 81 00 00 07 00 81 00 00 08 00 81 00 A9 FA
10:59:58.710 00 09 00 81 00 00 AF C5
10:59:58.711 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
10:59:58.711 <-T-- fir: 1 fin: 1 seq: 2
10:59:58.711 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
10:59:58.711 g51v2, 1-Byte Count, count: 1
10:59:58.711 time: 1793185192700
10:59:58.711 g2v3, 2-Byte Count and Index, count: 10
10:59:58.711 [0] - flags: 0x81 cto: 0
10:59:58.711 [1] - flags: 0x81 cto: 0
10:59:58.711 [2] - flags: 0x81 cto: 0
10:59:58.711 [3] - flags: 0x81 cto: 0
10:59:58.711 [4] - flags: 0x81 cto: 0
10:59:58.711 [5] - flags: 0x81 cto: 0
10:59:58.711 [6] - flags: 0x81 cto: 0
10:59:58.711 [7] - flags: 0x81 cto: 0
10:59:58.711 [8] - flags: 0x81 cto: 0
10:59:58.711 [9] - flags: 0x81 cto: 0
10:59:58.711 step: 16
10:59:58.711 - Withhold the application layer confirmation for this response too
10:59:58.711 steps: 17 to 18
10:59:58.711 - Expect no response within 6000 ms.
11:00:04.716 step: 19
11:00:04.716 - Change binary inputs several times, one or more of them, to create events
11:00:04.716 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 1: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 2: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 3: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 4: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 5: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 6: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.716 [PLG] Updated BI 7: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.717 [PLG] Updated BI 8: value=false, flags=1, timestamp=1793185204716 (UNSYNCHRONIZED_TIME)
11:00:04.717 [PLG] Updated BI 9: value=false, flags=1, timestamp=1793185204717 (UNSYNCHRONIZED_TIME)
11:00:04.717 The plugin reports the DUT's clock unsynchronized
11:00:04.717 step: 20
11:00:04.717 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
11:00:04.717 - g2v0, All Objects
11:00:04.717 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
11:00:04.717 g2v0, All Objects
11:00:04.717 --T-> fir: 1 fin: 1 seq: 0
11:00:04.717 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:00:04.717 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:00:04.717 C0 C4 01 02 00 06 8B D9
11:00:04.717 steps: 21 to 23
11:00:04.717 - Read a single fragment response w/ sequence(0x04)
11:00:04.717 - Verify that the response carries every Binary Input event generated and not yet confirmed, in the order they occurred
11:00:04.717 - 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:00:04.717 - Verify that an application layer confirmation is requested.
11:00:04.717 - Verify that the events are reported as 3.1r1's rules for event reporting require, for a read of g2v0, by the state of the DUT's clock when it recorded them, where the procedure or the plugin knows it
11:00:04.717 - 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:00:04.717 - Confirm each response that asks for a confirmation
11:00:04.718 <-P-- 05 64 7D 44 01 00 00 04 67 71
11:00:04.718 C3 E4 81 90 00 33 02 07 01 FC 9A 2A 82 A1 01 02 5B 41
11:00:04.718 03 28 14 00 00 00 81 00 00 01 00 81 00 00 02 00 FE 46
11:00:04.718 81 00 00 03 00 81 00 00 04 00 81 00 00 05 00 81 9B 00
11:00:04.718 00 00 06 00 81 00 00 07 00 81 00 00 08 00 81 00 A9 FA
11:00:04.718 00 09 00 81 00 00 00 00 01 F0 2E 01 00 01 F0 2E 75 47
11:00:04.718 02 00 01 F0 2E 03 00 01 F0 2E 04 00 01 F0 2E 05 F8 99
11:00:04.718 00 01 F0 2E 06 00 01 F0 2E 07 00 01 F0 2E 08 00 3C AB
11:00:04.718 01 F0 2E 09 00 01 F1 2E D5 FF
11:00:04.718 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 125 dest: 1 src: 1024
11:00:04.718 <-T-- fir: 1 fin: 1 seq: 3
11:00:04.718 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(DeviceRestart, NeedTime)
11:00:04.718 g51v2, 1-Byte Count, count: 1
11:00:04.718 time: 1793185192700
11:00:04.718 g2v3, 2-Byte Count and Index, count: 20
11:00:04.718 [0] - flags: 0x81 cto: 0
11:00:04.718 [1] - flags: 0x81 cto: 0
11:00:04.718 [2] - flags: 0x81 cto: 0
11:00:04.718 [3] - flags: 0x81 cto: 0
11:00:04.718 [4] - flags: 0x81 cto: 0
11:00:04.718 [5] - flags: 0x81 cto: 0
11:00:04.718 [6] - flags: 0x81 cto: 0
11:00:04.718 [7] - flags: 0x81 cto: 0
11:00:04.718 [8] - flags: 0x81 cto: 0
11:00:04.718 [9] - flags: 0x81 cto: 0
11:00:04.718 [0] - flags: 0x01 cto: 12016
11:00:04.718 [1] - flags: 0x01 cto: 12016
11:00:04.718 [2] - flags: 0x01 cto: 12016
11:00:04.718 [3] - flags: 0x01 cto: 12016
11:00:04.718 [4] - flags: 0x01 cto: 12016
11:00:04.718 [5] - flags: 0x01 cto: 12016
11:00:04.718 [6] - flags: 0x01 cto: 12016
11:00:04.718 [7] - flags: 0x01 cto: 12016
11:00:04.718 [8] - flags: 0x01 cto: 12016
11:00:04.718 [9] - flags: 0x01 cto: 12017
11:00:04.718 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
11:00:04.718 --T-> fir: 1 fin: 1 seq: 0
11:00:04.718 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:00:04.723 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:00:04.723 C0 C4 00 F9 A1
11:00:04.724 [PLG] Verified 20 binary input point event(s)
11:00:04.724 step: 24
11:00:04.724 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
11:00:04.724 - g2v0, All Objects
11:00:04.724 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
11:00:04.724 g2v0, All Objects
11:00:04.724 --T-> fir: 1 fin: 1 seq: 0
11:00:04.724 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:00:04.724 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:00:04.724 C0 C5 01 02 00 06 8D FA
11:00:04.724 step: 25
11:00:04.724 - Read a single fragment response w/ sequence(0x05)
11:00:04.724 - Verify that the response is empty
11:00:04.724 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
11:00:04.726 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:00:04.726 C4 C5 81 90 00 40 BE
11:00:04.726 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:00:04.726 <-T-- fir: 1 fin: 1 seq: 4
11:00:04.726 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05 IIN(DeviceRestart, NeedTime)
11:00:04.726 Passed procedure: 8.14.2f