8.22p Double-bit Input Change with Relative Time, Long Interval (as 8.14.2p) (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

All results
05:30:42.601 Running procedure: 8.22p
05:30:42.602 This procedure is enabled because the following options:
05:30:42.602 - supports.doubleBitInput == true
05:30:42.602 - supports.doubleBitInputChangeEvents == true
05:30:42.602 - supports.doubleBitInputEventReads == true
05:30:42.602 - timesync.doubleBitInputChangeWithRelativeTimeRejected == false
05:30:42.602 - timesync.doubleBitInputChangeWithRelativeTime == true
05:30:42.602 step: 1
05:30:42.602 - For a DUT that reports events with relative time
05:30:42.602 step: 2
05:30:42.602 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:30:42.602 - g4v0, All Objects
05:30:42.603 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:30:42.603 g4v0, All Objects
05:30:42.603 --T-> fir: 1 fin: 1 seq: 0
05:30:42.603 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:30:42.603 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:30:42.603 C0 C1 01 04 00 06 05 23
05:30:42.603 steps: 3 to 4
05:30:42.603 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
05:30:42.603 - If the response carries data, verify that it asks for an application layer confirmation.
05:30:42.603 - Confirm each response that asks for a confirmation
05:30:42.603 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:30:42.603 C0 C1 81 90 00 73 EE
05:30:42.603 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:30:42.603 <-T-- fir: 1 fin: 1 seq: 0
05:30:42.603 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:30:42.603 step: 5
05:30:42.603 - Change double-bit binary inputs several times, one or more of them, to create events
05:30:42.603 [PLG] Updated DBBI 0: value=DETERMINED_ON, flags=81, timestamp=1793251842603 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 1: value=DETERMINED_ON, flags=81, timestamp=1793251842603 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 2: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 3: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 4: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 5: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 6: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 7: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 8: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 [PLG] Updated DBBI 9: value=DETERMINED_ON, flags=81, timestamp=1793251842604 (UNSYNCHRONIZED_TIME)
05:30:42.604 The plugin reports the DUT's clock unsynchronized
05:30:42.604 step: 6
05:30:42.604 - Wait for 70535 milliseconds
05:31:53.139 step: 7
05:31:53.139 - Change double-bit binary inputs several times, one or more of them, to create events
05:31:53.140 [PLG] Updated DBBI 0: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 1: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 2: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 3: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 4: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 5: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 6: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 7: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 8: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 [PLG] Updated DBBI 9: value=DETERMINED_OFF, flags=41, timestamp=1793251913140 (UNSYNCHRONIZED_TIME)
05:31:53.140 The plugin reports the DUT's clock unsynchronized
05:31:53.141 step: 8
05:31:53.141 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:31:53.141 - g4v3, All Objects
05:31:53.141 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:31:53.141 g4v3, All Objects
05:31:53.141 --T-> fir: 1 fin: 1 seq: 0
05:31:53.141 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:31:53.141 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:31:53.141 C0 C2 01 04 03 06 A4 F6
05:31:53.141 steps: 9 to 16
05:31:53.141 - Read a single fragment response w/ sequence(0x02)
05:31:53.141 - Verify that the response carries every Double-bit Binary event generated and not yet confirmed, in the order they occurred
05:31:53.141 - 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:31:53.141 - Verify that an application layer confirmation is requested.
05:31:53.141 - Verify that the response contains events of the variations List(g4v3), and besides them only CTOs (g51v1 or g51v2, qualifier 0x07)
05:31:53.141 - Verify that the event objects are prefixed with 1-octet (qualifier 0x17) or 2-octet (qualifier 0x28) indexes
05:31:53.141 - Verify that each relative-time event is preceded by a CTO object (Table 8-4)
05:31:53.141 - Verify that a CTO (g51v1 or g51v2) precedes each group of events more than 0xFFFF ms apart: at least two
05:31:53.141 - 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:31:53.141 - Confirm each response that asks for a confirmation
05:31:53.141 <-P-- 05 64 8C 44 01 00 00 04 3D F2
05:31:53.141 C1 E2 81 90 00 33 02 07 01 2B 9A 23 86 A1 01 04 8D DA
05:31:53.141 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:31:53.141 81 01 00 03 00 81 01 00 04 00 81 01 00 05 00 81 E7 8E
05:31:53.141 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:31:53.141 00 09 00 81 01 00 33 02 07 01 B4 AD 24 86 A1 01 9A E1
05:31:53.141 04 03 28 0A 00 00 00 41 00 00 01 00 41 00 00 02 09 40
05:31:53.141 00 41 00 00 03 00 41 00 00 04 00 41 00 00 05 00 4E FE
05:31:53.141 41 00 00 06 00 41 00 00 07 00 41 00 00 08 00 41 D8 77
05:31:53.141 00 00 09 00 41 00 00 67 1C
05:31:53.141 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 140 dest: 1 src: 1024
05:31:53.141 <-T-- fir: 1 fin: 1 seq: 1
05:31:53.142 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:31:53.142 g51v2, 1-Byte Count, count: 1
05:31:53.142 time: 1793251842603
05:31:53.142 g4v3, 2-Byte Count and Index, count: 10
05:31:53.142 [0] - flags: 0x81 cto: 0
05:31:53.142 [1] - flags: 0x81 cto: 0
05:31:53.142 [2] - flags: 0x81 cto: 1
05:31:53.142 [3] - flags: 0x81 cto: 1
05:31:53.142 [4] - flags: 0x81 cto: 1
05:31:53.142 [5] - flags: 0x81 cto: 1
05:31:53.142 [6] - flags: 0x81 cto: 1
05:31:53.142 [7] - flags: 0x81 cto: 1
05:31:53.142 [8] - flags: 0x81 cto: 1
05:31:53.142 [9] - flags: 0x81 cto: 1
05:31:53.142 g51v2, 1-Byte Count, count: 1
05:31:53.142 time: 1793251913140
05:31:53.142 g4v3, 2-Byte Count and Index, count: 10
05:31:53.142 [0] - flags: 0x41 cto: 0
05:31:53.142 [1] - flags: 0x41 cto: 0
05:31:53.142 [2] - flags: 0x41 cto: 0
05:31:53.142 [3] - flags: 0x41 cto: 0
05:31:53.142 [4] - flags: 0x41 cto: 0
05:31:53.142 [5] - flags: 0x41 cto: 0
05:31:53.142 [6] - flags: 0x41 cto: 0
05:31:53.142 [7] - flags: 0x41 cto: 0
05:31:53.142 [8] - flags: 0x41 cto: 0
05:31:53.142 [9] - flags: 0x41 cto: 0
05:31:53.142 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:31:53.142 --T-> fir: 1 fin: 1 seq: 0
05:31:53.142 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:31:53.142 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:31:53.142 C0 C2 00 D6 8D
05:31:53.142 [PLG] Verified 20 double-bit binary input point event(s)
05:31:53.142 step: 17
05:31:53.142 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:31:53.142 - g4v3, All Objects
05:31:53.142 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:31:53.142 g4v3, All Objects
05:31:53.142 --T-> fir: 1 fin: 1 seq: 0
05:31:53.142 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:31:53.143 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:31:53.143 C0 C3 01 04 03 06 A2 D5
05:31:53.143 step: 18
05:31:53.143 - Read a single fragment response w/ sequence(0x03)
05:31:53.143 - Verify that the response is empty
05:31:53.143 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:31:53.143 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:31:53.143 C2 C3 81 90 00 D6 60
05:31:53.143 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:31:53.143 <-T-- fir: 1 fin: 1 seq: 2
05:31:53.143 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
05:31:53.143 Passed procedure: 8.22p