8.11.2.3 Unsolicited Response - Class 2 Data (revision 2.7, subset level 2)

Device under test: Step Function I/O dnp3 outstation

All results
10:58:23.527 Running procedure: 8.11.2.3
10:58:23.527 This procedure is enabled because the following options:
10:58:23.527 - supports.unsolicitedResponse == true
10:58:23.528 step: 0
10:58:23.528 - Configure the unsolicited response mode to 'true'
10:58:23.528 - Cycle power to the DUT
10:58:23.528 - If an unsolicited NULL response is received, confirm it
10:58:23.528 [PLG] Unsolicited responses were enabled
10:58:23.528 [PLG] Cycling power
10:58:23.529 [PLG] Cycling power
10:58:23.530 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:23.530 C0 F0 82 90 00 43 A2
10:58:23.530 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:23.530 <-T-- fir: 1 fin: 1 seq: 0
10:58:23.530 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:23.530 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:58:23.530 --T-> fir: 1 fin: 1 seq: 0
10:58:23.530 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:23.530 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:23.530 C0 D0 00 1B 49
10:58:23.530 step: 1
10:58:23.530 - Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:23.530 - g60v2, All Objects
10:58:23.530 - g60v3, All Objects
10:58:23.530 - g60v4, All Objects
10:58:23.530 --A-> Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:23.530 g60v2, All Objects
10:58:23.530 g60v3, All Objects
10:58:23.530 g60v4, All Objects
10:58:23.530 --T-> fir: 1 fin: 1 seq: 0
10:58:23.530 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:23.530 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:23.530 C0 C0 15 3C 02 06 3C 03 06 3C 04 06 1A 55
10:58:23.530 step: 2
10:58:23.530 - Read a single fragment response w/ sequence(0x00)
10:58:23.530 - Verify that the response is empty
10:58:23.530 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:23.531 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:23.531 C1 C0 81 90 00 9D 0F
10:58:23.531 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:23.531 <-T-- fir: 1 fin: 1 seq: 1
10:58:23.531 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:23.531 step: 3
10:58:23.531 - Generate known Class 2 event data
10:58:23.531 [PLG] Updated Counter 0: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 1: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 2: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 3: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 4: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 5: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 6: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 7: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 8: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 [PLG] Updated Counter 9: value=1, flags=1, timestamp=1793185103531 (UNSYNCHRONIZED_TIME)
10:58:23.531 step: 4
10:58:23.531 - Expect no unsolicited response within 2500 ms.
10:58:26.034 step: 5
10:58:26.034 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:26.034 - g60v3, All Objects
10:58:26.034 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:26.034 g60v3, All Objects
10:58:26.034 --T-> fir: 1 fin: 1 seq: 0
10:58:26.034 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:58:26.034 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:58:26.034 C0 C1 14 3C 03 06 F8 D1
10:58:26.035 step: 6
10:58:26.035 - Read a single fragment response w/ sequence(0x01)
10:58:26.035 - Verify that the response is empty
10:58:26.035 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:26.035 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:26.035 C2 C1 81 94 00 B5 9F
10:58:26.035 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:26.035 <-T-- fir: 1 fin: 1 seq: 2
10:58:26.035 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime, Class2Events)
10:58:26.035 steps: 7 to 10
10:58:26.035 - Read an unsolicited response
10:58:26.035 - Verify that the response contains events
10:58:26.035 - Verify that the response carries every Class 2 event generated and not yet confirmed, in the order they occurred
10:58:26.035 - Leave the response unconfirmed for now
10:58:26.035 <-P-- 05 64 55 44 01 00 00 04 39 1A
10:58:26.035 C3 F1 82 90 00 16 01 28 0A 00 00 00 01 01 00 00 96 0E
10:58:26.035 00 01 00 01 01 00 00 00 02 00 01 01 00 00 00 03 1C C2
10:58:26.035 00 01 01 00 00 00 04 00 01 01 00 00 00 05 00 01 62 75
10:58:26.035 01 00 00 00 06 00 01 01 00 00 00 07 00 01 01 00 94 04
10:58:26.035 00 00 08 00 01 01 00 00 00 09 00 01 01 00 00 00 01 60
10:58:26.035 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 85 dest: 1 src: 1024
10:58:26.035 <-T-- fir: 1 fin: 1 seq: 3
10:58:26.035 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:26.035 g22v1, 2-Byte Count and Index, count: 10
10:58:26.035 [0] - flags: 0x01 count: 1
10:58:26.035 [1] - flags: 0x01 count: 1
10:58:26.035 [2] - flags: 0x01 count: 1
10:58:26.035 [3] - flags: 0x01 count: 1
10:58:26.035 [4] - flags: 0x01 count: 1
10:58:26.035 [5] - flags: 0x01 count: 1
10:58:26.035 [6] - flags: 0x01 count: 1
10:58:26.035 [7] - flags: 0x01 count: 1
10:58:26.035 [8] - flags: 0x01 count: 1
10:58:26.035 [9] - flags: 0x01 count: 1
10:58:26.037 [PLG] Verified 10 Class 2 event(s)
10:58:26.037 steps: 11 to 13
10:58:26.037 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:58:26.037 - Verify that this response contains every event the previous response reported.
10:58:26.037 - Answer a response that asks for a confirmation with a confirmation of the wrong sequence number
10:58:28.036 <-P-- 05 64 55 44 01 00 00 04 39 1A
10:58:28.036 C4 F1 82 90 00 16 01 28 0A 00 00 00 01 01 00 00 4B A3
10:58:28.036 00 01 00 01 01 00 00 00 02 00 01 01 00 00 00 03 1C C2
10:58:28.036 00 01 01 00 00 00 04 00 01 01 00 00 00 05 00 01 62 75
10:58:28.036 01 00 00 00 06 00 01 01 00 00 00 07 00 01 01 00 94 04
10:58:28.036 00 00 08 00 01 01 00 00 00 09 00 01 01 00 00 00 01 60
10:58:28.036 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 85 dest: 1 src: 1024
10:58:28.036 <-T-- fir: 1 fin: 1 seq: 4
10:58:28.037 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:28.037 g22v1, 2-Byte Count and Index, count: 10
10:58:28.037 [0] - flags: 0x01 count: 1
10:58:28.037 [1] - flags: 0x01 count: 1
10:58:28.037 [2] - flags: 0x01 count: 1
10:58:28.037 [3] - flags: 0x01 count: 1
10:58:28.037 [4] - flags: 0x01 count: 1
10:58:28.037 [5] - flags: 0x01 count: 1
10:58:28.037 [6] - flags: 0x01 count: 1
10:58:28.037 [7] - flags: 0x01 count: 1
10:58:28.037 [8] - flags: 0x01 count: 1
10:58:28.037 [9] - flags: 0x01 count: 1
10:58:28.037 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
10:58:28.037 --T-> fir: 1 fin: 1 seq: 0
10:58:28.037 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:28.037 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:28.037 C0 D2 00 FE 52
10:58:28.038 [PLG] Verified that the response retains the 10 earlier event(s)
10:58:28.038 steps: 14 to 15
10:58:28.038 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:58:28.038 - Verify that this response contains every event the previous response reported.
10:58:28.038 - Confirm each response that asks for a confirmation
10:58:30.038 <-P-- 05 64 55 44 01 00 00 04 39 1A
10:58:30.038 C5 F1 82 90 00 16 01 28 0A 00 00 00 01 01 00 00 C7 EE
10:58:30.038 00 01 00 01 01 00 00 00 02 00 01 01 00 00 00 03 1C C2
10:58:30.038 00 01 01 00 00 00 04 00 01 01 00 00 00 05 00 01 62 75
10:58:30.038 01 00 00 00 06 00 01 01 00 00 00 07 00 01 01 00 94 04
10:58:30.038 00 00 08 00 01 01 00 00 00 09 00 01 01 00 00 00 01 60
10:58:30.038 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 85 dest: 1 src: 1024
10:58:30.038 <-T-- fir: 1 fin: 1 seq: 5
10:58:30.039 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:30.039 g22v1, 2-Byte Count and Index, count: 10
10:58:30.039 [0] - flags: 0x01 count: 1
10:58:30.039 [1] - flags: 0x01 count: 1
10:58:30.039 [2] - flags: 0x01 count: 1
10:58:30.039 [3] - flags: 0x01 count: 1
10:58:30.039 [4] - flags: 0x01 count: 1
10:58:30.039 [5] - flags: 0x01 count: 1
10:58:30.039 [6] - flags: 0x01 count: 1
10:58:30.039 [7] - flags: 0x01 count: 1
10:58:30.039 [8] - flags: 0x01 count: 1
10:58:30.039 [9] - flags: 0x01 count: 1
10:58:30.039 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
10:58:30.039 --T-> fir: 1 fin: 1 seq: 0
10:58:30.039 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:30.039 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:30.039 C0 D1 00 55 E2
10:58:30.039 [PLG] Verified that the response retains the 10 earlier event(s)
10:58:30.039 step: 16
10:58:30.039 - Generate Class 1, Class 3 events
10:58:30.040 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185110039 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 1: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 2: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 3: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 4: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 5: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 6: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 7: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 8: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated BI 9: value=true, flags=81, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 0: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 1: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 2: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 3: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 4: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 5: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 6: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 7: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 8: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.040 [PLG] Updated AI 9: value=100.0, flags=1, timestamp=1793185110040 (UNSYNCHRONIZED_TIME)
10:58:30.041 step: 17
10:58:30.041 - Expect no unsolicited response within 2500 ms.
10:58:32.543 Passed procedure: 8.11.2.3