11:57:29.072 Running procedure: 8.11.2.3
11:57:29.076 This procedure is enabled because the following options:
11:57:29.076 - supports.unsolicitedResponse == true
11:57:29.078 step: 0
11:57:29.078 - Configure the unsolicited response mode to 'true'
11:57:29.078 - Cycle power to the DUT
11:57:29.078 - If an unsolicited NULL response is received, confirm it
11:57:29.087 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:29.087 C0 F0 82 90 00 43 A2
11:57:29.088 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:29.088 <-T-- fir: 1 fin: 1 seq: 0
11:57:29.088 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:29.088 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
11:57:29.089 --T-> fir: 1 fin: 1 seq: 0
11:57:29.089 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:29.090 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:29.090 C0 D0 00 1B 49
11:57:29.091 step: 1
11:57:29.091 - Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:29.091 - g60v2, All Objects
11:57:29.091 - g60v3, All Objects
11:57:29.091 - g60v4, All Objects
11:57:29.092 --A-> Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:29.092 g60v2, All Objects
11:57:29.092 g60v3, All Objects
11:57:29.092 g60v4, All Objects
11:57:29.092 --T-> fir: 1 fin: 1 seq: 0
11:57:29.093 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
11:57:29.093 --P-> 05 64 11 C4 00 04 01 00 60 4E
11:57:29.093 C0 C0 15 3C 02 06 3C 03 06 3C 04 06 1A 55
11:57:29.094 step: 2
11:57:29.094 - Read a single fragment response w/ sequence(0x00)
11:57:29.094 - Verify that the response is empty
11:57:29.095 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:29.095 C1 C0 81 90 00 9D 0F
11:57:29.095 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:29.095 <-T-- fir: 1 fin: 1 seq: 1
11:57:29.095 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:29.096 step: 3
11:57:29.096 - Generate known Class Class 2 event data
11:57:29.096 Updated Counter 0: value=2, flags=1, timestamp=DNPTime(1586534249096)
11:57:29.097 Updated Counter 1024: value=2, flags=1, timestamp=DNPTime(1586534249096)
11:57:29.097 Updated Counter 41: value=2, flags=1, timestamp=DNPTime(1586534249097)
11:57:29.097 step: 4
11:57:29.097 - Expect no unsolicited response.
11:57:34.298 step: 5
11:57:34.298 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:34.298 - g60v3, All Objects
11:57:34.298 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:34.298 g60v3, All Objects
11:57:34.298 --T-> fir: 1 fin: 1 seq: 0
11:57:34.299 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:57:34.299 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:57:34.299 C0 C1 14 3C 03 06 F8 D1
11:57:34.300 step: 6
11:57:34.300 - Read a single fragment response w/ sequence(0x01)
11:57:34.300 - Verify that the response is empty
11:57:34.301 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:34.301 C2 C1 81 94 00 B5 9F
11:57:34.301 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:34.301 <-T-- fir: 1 fin: 1 seq: 2
11:57:34.302 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime, Class2Events)
11:57:34.302 steps: 7 to 10
11:57:34.302 - Read an unsolicited response
11:57:34.303 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:57:34.303 C3 F1 82 90 00 16 01 28 03 00 00 00 01 02 00 00 DC 82
11:57:34.303 00 00 04 01 02 00 00 00 29 00 01 02 00 00 00 4F 10
11:57:34.303 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:57:34.303 <-T-- fir: 1 fin: 1 seq: 3
11:57:34.305 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:34.305 g22v1, 2-Byte Count and Index, count: 3
11:57:34.305 [0] - flags: 0x01 count: 2
11:57:34.305 [1024] - flags: 0x01 count: 2
11:57:34.305 [41] - flags: 0x01 count: 2
11:57:34.305 step: 11
11:57:34.305 - Wait for 5000 milliseconds
11:57:39.306 steps: 12 to 13
11:57:39.306 - Read an unsolicited response
11:57:39.306 - Verify that the device responds with the same Class 2 event data and the additional data generated
11:57:39.307 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:57:39.307 C4 F1 82 90 00 16 01 28 03 00 00 00 01 02 00 00 01 2F
11:57:39.307 00 00 04 01 02 00 00 00 29 00 01 02 00 00 00 4F 10
11:57:39.308 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:57:39.308 <-T-- fir: 1 fin: 1 seq: 4
11:57:39.310 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:39.310 g22v1, 2-Byte Count and Index, count: 3
11:57:39.310 [0] - flags: 0x01 count: 2
11:57:39.310 [1024] - flags: 0x01 count: 2
11:57:39.310 [41] - flags: 0x01 count: 2
11:57:39.311 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
11:57:39.311 --T-> fir: 1 fin: 1 seq: 0
11:57:39.312 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:39.313 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:39.313 C0 D2 00 FE 52
11:57:39.314 steps: 14 to 15
11:57:39.314 - Read an unsolicited response
11:57:44.303 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:57:44.303 C5 F1 82 90 00 16 01 28 03 00 00 00 01 02 00 00 8D 62
11:57:44.303 00 00 04 01 02 00 00 00 29 00 01 02 00 00 00 4F 10
11:57:44.304 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:57:44.304 <-T-- fir: 1 fin: 1 seq: 5
11:57:44.305 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:44.305 g22v1, 2-Byte Count and Index, count: 3
11:57:44.305 [0] - flags: 0x01 count: 2
11:57:44.305 [1024] - flags: 0x01 count: 2
11:57:44.305 [41] - flags: 0x01 count: 2
11:57:44.306 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
11:57:44.306 --T-> fir: 1 fin: 1 seq: 0
11:57:44.307 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:44.307 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:44.307 C0 D1 00 55 E2
11:57:44.308 step: 16
11:57:44.308 - Class 1, Class 3 events
11:57:44.309 Updated BI 65535: value=true, flags=81, timestamp=DNPTime(1586534264308)
11:57:44.309 Updated BI 1024: value=true, flags=81, timestamp=DNPTime(1586534264309)
11:57:44.310 Updated BI 0: value=true, flags=81, timestamp=DNPTime(1586534264309)
11:57:44.310 Updated AI 1024: value=100.0, flags=1, timestamp=DNPTime(1586534264310)
11:57:44.311 Updated AI 0: value=100.0, flags=1, timestamp=DNPTime(1586534264310)
11:57:44.311 Updated AI 65535: value=100.0, flags=1, timestamp=DNPTime(1586534264311)
11:57:44.312 step: 17
11:57:44.312 - Expect no unsolicited response.
11:57:49.512 Passed procedure: 8.11.2.3