8.14.2.15 Binary Input Change with Relative Time, Long Interval (revision 2.7, subset level 2)

Device under test: Step Function I/O dnp3 outstation

05:28:11.318 Running procedure: 8.14.2.15
05:28:11.319 This procedure is enabled because the following options:
05:28:11.319 - supports.binaryInput == true
05:28:11.319 - supports.binaryInputChangeEvents == true
05:28:11.319 - timesync.binaryInputChangeWithRelativeTime == true
05:28:11.319 step: 1
05:28:11.319 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:28:11.319 - g2v0, All Objects
05:28:11.320 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:28:11.320 g2v0, All Objects
05:28:11.320 --T-> fir: 1 fin: 1 seq: 0
05:28:11.320 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:28:11.320 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:28:11.320 C0 C1 01 02 00 06 95 76
05:28:11.320 steps: 2 to 3
05:28:11.320 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
05:28:11.320 - If the response carries data, verify that it asks for an application layer confirmation.
05:28:11.320 - Confirm each response that asks for a confirmation
05:28:11.321 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:28:11.321 C0 C1 81 90 00 73 EE
05:28:11.321 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:28:11.321 <-T-- fir: 1 fin: 1 seq: 0
05:28:11.321 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:28:11.321 step: 4
05:28:11.321 - Change binary inputs several times, one or more of them, to create events
05:28:11.321 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251691321 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 1: value=true, flags=81, timestamp=1793251691321 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 2: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 3: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 4: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 5: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 6: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 7: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 8: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 [PLG] Updated BI 9: value=true, flags=81, timestamp=1793251691322 (UNSYNCHRONIZED_TIME)
05:28:11.322 The plugin reports the DUT's clock unsynchronized
05:28:11.322 step: 5
05:28:11.322 - Wait for 70535 milliseconds
05:29:21.858 step: 6
05:29:21.858 - Change binary inputs several times, one or more of them, to create events
05:29:21.858 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.858 [PLG] Updated BI 1: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.858 [PLG] Updated BI 2: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.858 [PLG] Updated BI 3: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.858 [PLG] Updated BI 4: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.858 [PLG] Updated BI 5: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.859 [PLG] Updated BI 6: value=false, flags=1, timestamp=1793251761858 (UNSYNCHRONIZED_TIME)
05:29:21.859 [PLG] Updated BI 7: value=false, flags=1, timestamp=1793251761859 (UNSYNCHRONIZED_TIME)
05:29:21.859 [PLG] Updated BI 8: value=false, flags=1, timestamp=1793251761859 (UNSYNCHRONIZED_TIME)
05:29:21.859 [PLG] Updated BI 9: value=false, flags=1, timestamp=1793251761859 (UNSYNCHRONIZED_TIME)
05:29:21.859 The plugin reports the DUT's clock unsynchronized
05:29:21.859 step: 7
05:29:21.859 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:29:21.859 - g2v3, All Objects
05:29:21.859 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:29:21.859 g2v3, All Objects
05:29:21.859 --T-> fir: 1 fin: 1 seq: 0
05:29:21.859 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:29:21.859 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:29:21.859 C0 C2 01 02 03 06 34 A3
05:29:21.859 steps: 8 to 14
05:29:21.859 - Read a single fragment response w/ sequence(0x02)
05:29:21.859 - Verify that the response carries every Binary Input event generated and not yet confirmed, in the order they occurred
05:29:21.859 - 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:29:21.859 - Verify that an application layer confirmation is requested.
05:29:21.859 - Verify that the response contains events of the variations List(g2v3), and besides them only CTOs (g51v1 or g51v2, qualifier 0x07)
05:29:21.859 - Verify that the event objects are prefixed with 1-octet (qualifier 0x17) or 2-octet (qualifier 0x28) indexes
05:29:21.859 - Verify that each relative-time event is preceded by a CTO object (Table 8-4)
05:29:21.859 - Verify that the response contains at least two CTO headers (g51v1/g51v2), for the event batches generated more than 0xFFFF ms apart
05:29:21.859 - 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:29:21.859 - Confirm each response that asks for a confirmation
05:29:21.860 <-P-- 05 64 8C 44 01 00 00 04 3D F2
05:29:21.860 C1 E2 81 90 00 33 02 07 01 39 4B 21 86 A1 01 02 9D 26
05:29:21.860 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:29:21.860 81 01 00 03 00 81 01 00 04 00 81 01 00 05 00 81 E7 8E
05:29:21.860 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:29:21.860 00 09 00 81 01 00 33 02 07 01 C2 5E 22 86 A1 01 F4 3F
05:29:21.860 02 03 28 0A 00 00 00 01 00 00 01 00 01 00 00 02 0E 05
05:29:21.860 00 01 00 00 03 00 01 00 00 04 00 01 00 00 05 00 A6 A5
05:29:21.860 01 00 00 06 00 01 00 00 07 00 01 01 00 08 00 01 1F 24
05:29:21.860 01 00 09 00 01 01 00 24 64
05:29:21.860 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 140 dest: 1 src: 1024
05:29:21.860 <-T-- fir: 1 fin: 1 seq: 1
05:29:21.860 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:29:21.860 g51v2, 1-Byte Count, count: 1
05:29:21.860 time: 1793251691321
05:29:21.860 g2v3, 2-Byte Count and Index, count: 10
05:29:21.860 [0] - flags: 0x81 cto: 0
05:29:21.860 [1] - flags: 0x81 cto: 0
05:29:21.860 [2] - flags: 0x81 cto: 1
05:29:21.860 [3] - flags: 0x81 cto: 1
05:29:21.860 [4] - flags: 0x81 cto: 1
05:29:21.860 [5] - flags: 0x81 cto: 1
05:29:21.860 [6] - flags: 0x81 cto: 1
05:29:21.860 [7] - flags: 0x81 cto: 1
05:29:21.860 [8] - flags: 0x81 cto: 1
05:29:21.860 [9] - flags: 0x81 cto: 1
05:29:21.860 g51v2, 1-Byte Count, count: 1
05:29:21.860 time: 1793251761858
05:29:21.860 g2v3, 2-Byte Count and Index, count: 10
05:29:21.860 [0] - flags: 0x01 cto: 0
05:29:21.860 [1] - flags: 0x01 cto: 0
05:29:21.860 [2] - flags: 0x01 cto: 0
05:29:21.860 [3] - flags: 0x01 cto: 0
05:29:21.860 [4] - flags: 0x01 cto: 0
05:29:21.860 [5] - flags: 0x01 cto: 0
05:29:21.860 [6] - flags: 0x01 cto: 0
05:29:21.860 [7] - flags: 0x01 cto: 1
05:29:21.860 [8] - flags: 0x01 cto: 1
05:29:21.860 [9] - flags: 0x01 cto: 1
05:29:21.860 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:29:21.860 --T-> fir: 1 fin: 1 seq: 0
05:29:21.860 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:29:21.860 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:29:21.860 C0 C2 00 D6 8D
05:29:21.861 [PLG] Verified 20 binary input point event(s)
05:29:21.862 step: 15
05:29:21.862 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:29:21.862 - g2v3, All Objects
05:29:21.862 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:29:21.862 g2v3, All Objects
05:29:21.862 --T-> fir: 1 fin: 1 seq: 0
05:29:21.862 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:29:21.862 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:29:21.862 C0 C3 01 02 03 06 32 80
05:29:21.862 step: 16
05:29:21.862 - Read a single fragment response w/ sequence(0x03)
05:29:21.862 - Verify that the response is empty
05:29:21.862 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:29:21.862 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:29:21.862 C2 C3 81 90 00 D6 60
05:29:21.862 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:29:21.862 <-T-- fir: 1 fin: 1 seq: 2
05:29:21.862 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
05:29:21.862 Passed procedure: 8.14.2.15