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

Device under test: Step Function I/O dnp3 outstation

05:28:05.535 Running procedure: 8.14.2f
05:28:05.537 This procedure is enabled because the following options:
05:28:05.537 - supports.binaryInput == true
05:28:05.537 - supports.binaryInputChangeEvents == true
05:28:05.537 - supports.binaryInputEventReads == true
05:28:05.537 step: 1
05:28:05.537 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:28:05.537 - g2v0, All Objects
05:28:05.537 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:28:05.537 g2v0, All Objects
05:28:05.537 --T-> fir: 1 fin: 1 seq: 0
05:28:05.537 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:05.538 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:05.538 C0 C1 01 02 00 06 95 76
05:28:05.538 steps: 2 to 3
05:28:05.538 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
05:28:05.538 - If the response carries data, verify that it asks for an application layer confirmation.
05:28:05.538 - Confirm each response that asks for a confirmation
05:28:05.538 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:28:05.538 C0 C1 81 90 00 73 EE
05:28:05.538 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:28:05.538 <-T-- fir: 1 fin: 1 seq: 0
05:28:05.538 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:28:05.538 step: 4
05:28:05.538 - Change binary inputs several times, one or more of them, to create events
05:28:05.538 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251685538 (UNSYNCHRONIZED_TIME)
05:28:05.538 [PLG] Updated BI 1: value=true, flags=81, timestamp=1793251685538 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 2: value=true, flags=81, timestamp=1793251685538 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 3: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 4: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 5: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 6: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 7: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 8: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 [PLG] Updated BI 9: value=true, flags=81, timestamp=1793251685539 (UNSYNCHRONIZED_TIME)
05:28:05.539 The plugin reports the DUT's clock unsynchronized
05:28:05.539 step: 5
05:28:05.539 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:28:05.539 - g2v0, All Objects
05:28:05.539 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:28:05.539 g2v0, All Objects
05:28:05.539 --T-> fir: 1 fin: 1 seq: 0
05:28:05.539 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:05.539 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:05.539 C0 C2 01 02 00 06 9F 13
05:28:05.539 steps: 6 to 10
05:28:05.539 - Read a single fragment response w/ sequence(0x02)
05:28:05.539 - Verify that the response holds every Binary Input event generated
05:28:05.539 - 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
05:28:05.539 - Verify that an application layer confirmation is requested.
05:28:05.539 - 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
05:28:05.539 - Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
05:28:05.539 - Keep the response's objects, to compare the next read's with
05:28:05.540 <-P-- 05 64 4B 44 01 00 00 04 FD 93
05:28:05.540 C1 E2 81 90 00 33 02 07 01 A2 34 21 86 A1 01 02 8E FA
05:28:05.540 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:28:05.540 81 00 00 03 00 81 01 00 04 00 81 01 00 05 00 81 FF E0
05:28:05.540 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:28:05.540 00 09 00 81 01 00 E1 6E
05:28:05.540 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
05:28:05.540 <-T-- fir: 1 fin: 1 seq: 1
05:28:05.540 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:28:05.540 g51v2, 1-Byte Count, count: 1
05:28:05.540 time: 1793251685538
05:28:05.540 g2v3, 2-Byte Count and Index, count: 10
05:28:05.540 [0] - flags: 0x81 cto: 0
05:28:05.540 [1] - flags: 0x81 cto: 0
05:28:05.540 [2] - flags: 0x81 cto: 0
05:28:05.540 [3] - flags: 0x81 cto: 1
05:28:05.540 [4] - flags: 0x81 cto: 1
05:28:05.540 [5] - flags: 0x81 cto: 1
05:28:05.540 [6] - flags: 0x81 cto: 1
05:28:05.540 [7] - flags: 0x81 cto: 1
05:28:05.540 [8] - flags: 0x81 cto: 1
05:28:05.540 [9] - flags: 0x81 cto: 1
05:28:05.541 [PLG] Verified 10 binary input point event(s)
05:28:05.541 step: 11
05:28:05.541 - Withhold the application layer confirmation for this response
05:28:05.541 steps: 12 to 13
05:28:05.541 - Expect no response within 6000 ms.
05:28:11.547 step: 14
05:28:11.547 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:28:11.547 - g2v0, All Objects
05:28:11.547 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:28:11.547 g2v0, All Objects
05:28:11.547 --T-> fir: 1 fin: 1 seq: 0
05:28:11.547 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:11.548 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:11.548 C0 C3 01 02 00 06 99 30
05:28:11.548 step: 15
05:28:11.548 - Read a single fragment response w/ sequence(0x03)
05:28:11.548 - Verify that the response carries the same objects as the previous one
05:28:11.548 <-P-- 05 64 4B 44 01 00 00 04 FD 93
05:28:11.548 C2 E3 81 90 00 33 02 07 01 A2 34 21 86 A1 01 02 02 42
05:28:11.548 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:28:11.548 81 00 00 03 00 81 01 00 04 00 81 01 00 05 00 81 FF E0
05:28:11.548 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:28:11.548 00 09 00 81 01 00 E1 6E
05:28:11.548 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
05:28:11.548 <-T-- fir: 1 fin: 1 seq: 2
05:28:11.549 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
05:28:11.549 g51v2, 1-Byte Count, count: 1
05:28:11.549 time: 1793251685538
05:28:11.549 g2v3, 2-Byte Count and Index, count: 10
05:28:11.549 [0] - flags: 0x81 cto: 0
05:28:11.549 [1] - flags: 0x81 cto: 0
05:28:11.549 [2] - flags: 0x81 cto: 0
05:28:11.549 [3] - flags: 0x81 cto: 1
05:28:11.549 [4] - flags: 0x81 cto: 1
05:28:11.549 [5] - flags: 0x81 cto: 1
05:28:11.549 [6] - flags: 0x81 cto: 1
05:28:11.549 [7] - flags: 0x81 cto: 1
05:28:11.549 [8] - flags: 0x81 cto: 1
05:28:11.549 [9] - flags: 0x81 cto: 1
05:28:11.549 step: 16
05:28:11.549 - Withhold the application layer confirmation for this response too
05:28:11.549 steps: 17 to 18
05:28:11.549 - Expect no response within 6000 ms.
05:28:17.549 step: 19
05:28:17.549 - Change binary inputs several times, one or more of them, to create events
05:28:17.550 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251697549 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 1: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 2: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 3: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 4: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 5: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 6: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 7: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 8: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 [PLG] Updated BI 9: value=false, flags=1, timestamp=1793251697550 (UNSYNCHRONIZED_TIME)
05:28:17.550 The plugin reports the DUT's clock unsynchronized
05:28:17.551 step: 20
05:28:17.551 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:28:17.551 - g2v0, All Objects
05:28:17.551 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:28:17.551 g2v0, All Objects
05:28:17.551 --T-> fir: 1 fin: 1 seq: 0
05:28:17.551 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:17.551 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:17.551 C0 C4 01 02 00 06 8B D9
05:28:17.551 steps: 21 to 23
05:28:17.551 - Read a single fragment response w/ sequence(0x04)
05:28:17.551 - Verify that the response carries every Binary Input event generated and not yet confirmed, in the order they occurred
05:28:17.551 - 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
05:28:17.551 - Verify that an application layer confirmation is requested.
05:28:17.551 - 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
05:28:17.551 - Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
05:28:17.551 - Confirm each response that asks for a confirmation
05:28:17.552 <-P-- 05 64 7D 44 01 00 00 04 67 71
05:28:17.552 C3 E4 81 90 00 33 02 07 01 A2 34 21 86 A1 01 02 BF 48
05:28:17.552 03 28 14 00 00 00 81 00 00 01 00 81 00 00 02 00 FE 46
05:28:17.552 81 00 00 03 00 81 01 00 04 00 81 01 00 05 00 81 FF E0
05:28:17.552 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:28:17.552 00 09 00 81 01 00 00 00 01 EB 2E 01 00 01 EC 2E 64 6F
05:28:17.552 02 00 01 EC 2E 03 00 01 EC 2E 04 00 01 EC 2E 05 4B B4
05:28:17.552 00 01 EC 2E 06 00 01 EC 2E 07 00 01 EC 2E 08 00 8B CC
05:28:17.552 01 EC 2E 09 00 01 EC 2E 3A 41
05:28:17.552 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 125 dest: 1 src: 1024
05:28:17.552 <-T-- fir: 1 fin: 1 seq: 3
05:28:17.552 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(DeviceRestart, NeedTime)
05:28:17.552 g51v2, 1-Byte Count, count: 1
05:28:17.552 time: 1793251685538
05:28:17.552 g2v3, 2-Byte Count and Index, count: 20
05:28:17.552 [0] - flags: 0x81 cto: 0
05:28:17.552 [1] - flags: 0x81 cto: 0
05:28:17.552 [2] - flags: 0x81 cto: 0
05:28:17.552 [3] - flags: 0x81 cto: 1
05:28:17.552 [4] - flags: 0x81 cto: 1
05:28:17.552 [5] - flags: 0x81 cto: 1
05:28:17.552 [6] - flags: 0x81 cto: 1
05:28:17.552 [7] - flags: 0x81 cto: 1
05:28:17.552 [8] - flags: 0x81 cto: 1
05:28:17.552 [9] - flags: 0x81 cto: 1
05:28:17.552 [0] - flags: 0x01 cto: 12011
05:28:17.552 [1] - flags: 0x01 cto: 12012
05:28:17.552 [2] - flags: 0x01 cto: 12012
05:28:17.552 [3] - flags: 0x01 cto: 12012
05:28:17.552 [4] - flags: 0x01 cto: 12012
05:28:17.552 [5] - flags: 0x01 cto: 12012
05:28:17.552 [6] - flags: 0x01 cto: 12012
05:28:17.552 [7] - flags: 0x01 cto: 12012
05:28:17.552 [8] - flags: 0x01 cto: 12012
05:28:17.552 [9] - flags: 0x01 cto: 12012
05:28:17.552 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:28:17.552 --T-> fir: 1 fin: 1 seq: 0
05:28:17.553 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:28:17.553 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:28:17.553 C0 C4 00 F9 A1
05:28:17.555 [PLG] Verified 20 binary input point event(s)
05:28:17.556 step: 24
05:28:17.556 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:28:17.556 - g2v0, All Objects
05:28:17.556 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:28:17.556 g2v0, All Objects
05:28:17.556 --T-> fir: 1 fin: 1 seq: 0
05:28:17.556 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:17.556 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:17.556 C0 C5 01 02 00 06 8D FA
05:28:17.556 step: 25
05:28:17.556 - Read a single fragment response w/ sequence(0x05)
05:28:17.556 - Verify that the response is empty
05:28:17.556 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:28:17.556 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:28:17.556 C4 C5 81 90 00 40 BE
05:28:17.556 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:28:17.556 <-T-- fir: 1 fin: 1 seq: 4
05:28:17.556 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05 IIN(DeviceRestart, NeedTime)
05:28:17.556 Passed procedure: 8.14.2f