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

Device under test: Step Function I/O dnp3 outstation

All results
05:26:38.588 Running procedure: 8.11.2.2
05:26:38.591 This procedure is enabled because the following options:
05:26:38.591 - supports.unsolicitedResponse == true
05:26:38.591 step: 0
05:26:38.591 - Configure the unsolicited response mode to 'true'
05:26:38.591 - Cycle power to the DUT
05:26:38.591 - If an unsolicited NULL response is received, confirm it
05:26:38.591 [PLG] Unsolicited responses were enabled
05:26:38.591 [PLG] Cycling power
05:26:38.593 [PLG] Cycling power
05:26:38.594 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:26:38.594 C0 F0 82 90 00 43 A2
05:26:38.594 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:26:38.594 <-T-- fir: 1 fin: 1 seq: 0
05:26:38.594 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:26:38.594 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:26:38.594 --T-> fir: 1 fin: 1 seq: 0
05:26:38.594 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:26:38.595 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:26:38.595 C0 D0 00 1B 49
05:26:38.595 step: 1
05:26:38.595 - Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:26:38.595 - g60v2, All Objects
05:26:38.595 - g60v3, All Objects
05:26:38.595 - g60v4, All Objects
05:26:38.595 --A-> Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:26:38.595 g60v2, All Objects
05:26:38.595 g60v3, All Objects
05:26:38.595 g60v4, All Objects
05:26:38.595 --T-> fir: 1 fin: 1 seq: 0
05:26:38.595 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:26:38.595 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:26:38.595 C0 C0 15 3C 02 06 3C 03 06 3C 04 06 1A 55
05:26:38.595 step: 2
05:26:38.595 - Read a single fragment response w/ sequence(0x00)
05:26:38.595 - Verify that the response is empty
05:26:38.595 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:26:38.595 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:26:38.595 C1 C0 81 90 00 9D 0F
05:26:38.595 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:26:38.595 <-T-- fir: 1 fin: 1 seq: 1
05:26:38.595 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:26:38.595 step: 3
05:26:38.595 - Generate known Class 1 event data
05:26:38.595 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251598595 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 1: value=true, flags=81, timestamp=1793251598595 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 2: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 3: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 4: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 5: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 6: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 7: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 8: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 [PLG] Updated BI 9: value=true, flags=81, timestamp=1793251598596 (UNSYNCHRONIZED_TIME)
05:26:38.596 step: 4
05:26:38.596 - Expect no unsolicited response within 2500 ms.
05:26:41.099 step: 5
05:26:41.099 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:26:41.099 - g60v2, All Objects
05:26:41.099 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:26:41.099 g60v2, All Objects
05:26:41.099 --T-> fir: 1 fin: 1 seq: 0
05:26:41.099 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:26:41.099 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:26:41.099 C0 C1 14 3C 02 06 B6 7A
05:26:41.099 step: 6
05:26:41.099 - Read a single fragment response w/ sequence(0x01)
05:26:41.099 - Verify that the response is empty
05:26:41.099 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:26:41.100 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:26:41.100 C2 C1 81 92 00 9A B3
05:26:41.100 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:26:41.100 <-T-- fir: 1 fin: 1 seq: 2
05:26:41.100 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime, Class1Events)
05:26:41.100 steps: 7 to 10
05:26:41.100 - Read an unsolicited response
05:26:41.100 - Verify that the response contains events
05:26:41.100 - Verify that the response carries every Class 1 event generated and not yet confirmed, in the order they occurred
05:26:41.100 - Leave the response unconfirmed for now
05:26:41.100 <-P-- 05 64 4B 44 01 00 00 04 FD 93
05:26:41.100 C3 F1 82 90 00 33 02 07 01 03 E1 1F 86 A1 01 02 B9 E8
05:26:41.100 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:26:41.100 81 01 00 03 00 81 01 00 04 00 81 01 00 05 00 81 E7 8E
05:26:41.100 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:26:41.100 00 09 00 81 01 00 E1 6E
05:26:41.100 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
05:26:41.100 <-T-- fir: 1 fin: 1 seq: 3
05:26:41.101 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:26:41.101 g51v2, 1-Byte Count, count: 1
05:26:41.101 time: 1793251598595
05:26:41.101 g2v3, 2-Byte Count and Index, count: 10
05:26:41.101 [0] - flags: 0x81 cto: 0
05:26:41.101 [1] - flags: 0x81 cto: 0
05:26:41.101 [2] - flags: 0x81 cto: 1
05:26:41.101 [3] - flags: 0x81 cto: 1
05:26:41.101 [4] - flags: 0x81 cto: 1
05:26:41.101 [5] - flags: 0x81 cto: 1
05:26:41.101 [6] - flags: 0x81 cto: 1
05:26:41.101 [7] - flags: 0x81 cto: 1
05:26:41.101 [8] - flags: 0x81 cto: 1
05:26:41.101 [9] - flags: 0x81 cto: 1
05:26:41.101 [PLG] Verified 10 Class 1 event(s)
05:26:41.101 steps: 11 to 13
05:26:41.101 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:26:41.101 - Verify that this response contains every event the previous response reported.
05:26:41.101 - Answer a response that asks for a confirmation with a confirmation of the wrong sequence number
05:26:43.101 <-P-- 05 64 4B 44 01 00 00 04 FD 93
05:26:43.101 C4 F1 82 90 00 33 02 07 01 03 E1 1F 86 A1 01 02 64 45
05:26:43.101 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:26:43.101 81 01 00 03 00 81 01 00 04 00 81 01 00 05 00 81 E7 8E
05:26:43.101 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:26:43.101 00 09 00 81 01 00 E1 6E
05:26:43.101 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
05:26:43.101 <-T-- fir: 1 fin: 1 seq: 4
05:26:43.102 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:26:43.102 g51v2, 1-Byte Count, count: 1
05:26:43.102 time: 1793251598595
05:26:43.102 g2v3, 2-Byte Count and Index, count: 10
05:26:43.102 [0] - flags: 0x81 cto: 0
05:26:43.102 [1] - flags: 0x81 cto: 0
05:26:43.102 [2] - flags: 0x81 cto: 1
05:26:43.102 [3] - flags: 0x81 cto: 1
05:26:43.102 [4] - flags: 0x81 cto: 1
05:26:43.102 [5] - flags: 0x81 cto: 1
05:26:43.102 [6] - flags: 0x81 cto: 1
05:26:43.102 [7] - flags: 0x81 cto: 1
05:26:43.102 [8] - flags: 0x81 cto: 1
05:26:43.102 [9] - flags: 0x81 cto: 1
05:26:43.102 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
05:26:43.102 --T-> fir: 1 fin: 1 seq: 0
05:26:43.102 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:26:43.102 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:26:43.102 C0 D2 00 FE 52
05:26:43.106 [PLG] Verified that the response retains the 10 earlier event(s)
05:26:43.106 steps: 14 to 15
05:26:43.106 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:26:43.106 - Verify that this response contains every event the previous response reported.
05:26:43.106 - Confirm each response that asks for a confirmation
05:26:45.102 <-P-- 05 64 4B 44 01 00 00 04 FD 93
05:26:45.102 C5 F1 82 90 00 33 02 07 01 03 E1 1F 86 A1 01 02 E8 08
05:26:45.102 03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
05:26:45.102 81 01 00 03 00 81 01 00 04 00 81 01 00 05 00 81 E7 8E
05:26:45.102 01 00 06 00 81 01 00 07 00 81 01 00 08 00 81 01 12 22
05:26:45.102 00 09 00 81 01 00 E1 6E
05:26:45.102 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
05:26:45.102 <-T-- fir: 1 fin: 1 seq: 5
05:26:45.103 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:26:45.103 g51v2, 1-Byte Count, count: 1
05:26:45.103 time: 1793251598595
05:26:45.103 g2v3, 2-Byte Count and Index, count: 10
05:26:45.103 [0] - flags: 0x81 cto: 0
05:26:45.103 [1] - flags: 0x81 cto: 0
05:26:45.103 [2] - flags: 0x81 cto: 1
05:26:45.103 [3] - flags: 0x81 cto: 1
05:26:45.103 [4] - flags: 0x81 cto: 1
05:26:45.103 [5] - flags: 0x81 cto: 1
05:26:45.103 [6] - flags: 0x81 cto: 1
05:26:45.103 [7] - flags: 0x81 cto: 1
05:26:45.103 [8] - flags: 0x81 cto: 1
05:26:45.103 [9] - flags: 0x81 cto: 1
05:26:45.103 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
05:26:45.103 --T-> fir: 1 fin: 1 seq: 0
05:26:45.103 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:26:45.103 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:26:45.103 C0 D1 00 55 E2
05:26:45.106 [PLG] Verified that the response retains the 10 earlier event(s)
05:26:45.106 step: 16
05:26:45.106 - Generate Class 2, Class 3 events
05:26:45.107 [PLG] Updated Counter 0: value=1, flags=1, timestamp=1793251605106 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 1: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 2: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 3: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 4: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 5: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 6: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 7: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.107 [PLG] Updated Counter 8: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated Counter 9: value=1, flags=1, timestamp=1793251605107 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 0: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 1: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 2: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 3: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 4: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.108 [PLG] Updated AI 5: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.109 [PLG] Updated AI 6: value=100.0, flags=1, timestamp=1793251605108 (UNSYNCHRONIZED_TIME)
05:26:45.109 [PLG] Updated AI 7: value=100.0, flags=1, timestamp=1793251605109 (UNSYNCHRONIZED_TIME)
05:26:45.109 [PLG] Updated AI 8: value=100.0, flags=1, timestamp=1793251605109 (UNSYNCHRONIZED_TIME)
05:26:45.109 [PLG] Updated AI 9: value=100.0, flags=1, timestamp=1793251605109 (UNSYNCHRONIZED_TIME)
05:26:45.109 step: 17
05:26:45.109 - Expect no unsolicited response within 2500 ms.
05:26:47.612 Passed procedure: 8.11.2.2