11:57:50.231 Running procedure: 8.11.2.4
11:57:50.236 This procedure is enabled because the following options:
11:57:50.236 - supports.unsolicitedResponse == true
11:57:50.238 step: 0
11:57:50.238 - Configure the unsolicited response mode to 'true'
11:57:50.238 - Cycle power to the DUT
11:57:50.238 - If an unsolicited NULL response is received, confirm it
11:57:50.252 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:50.252 C0 F0 82 90 00 43 A2
11:57:50.252 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:50.253 <-T-- fir: 1 fin: 1 seq: 0
11:57:50.253 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:50.253 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
11:57:50.254 --T-> fir: 1 fin: 1 seq: 0
11:57:50.254 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:50.255 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:50.255 C0 D0 00 1B 49
11:57:50.257 step: 1
11:57:50.257 - Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:50.257 - g60v2, All Objects
11:57:50.257 - g60v3, All Objects
11:57:50.257 - g60v4, All Objects
11:57:50.259 --A-> Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:50.259 g60v2, All Objects
11:57:50.259 g60v3, All Objects
11:57:50.259 g60v4, All Objects
11:57:50.259 --T-> fir: 1 fin: 1 seq: 0
11:57:50.259 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
11:57:50.260 --P-> 05 64 11 C4 00 04 01 00 60 4E
11:57:50.260 C0 C0 15 3C 02 06 3C 03 06 3C 04 06 1A 55
11:57:50.261 step: 2
11:57:50.261 - Read a single fragment response w/ sequence(0x00)
11:57:50.261 - Verify that the response is empty
11:57:50.262 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:50.262 C1 C0 81 90 00 9D 0F
11:57:50.262 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:50.263 <-T-- fir: 1 fin: 1 seq: 1
11:57:50.263 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:50.263 step: 3
11:57:50.263 - Generate known Class Class 3 event data
11:57:50.264 Updated AI 1024: value=100.0, flags=1, timestamp=DNPTime(1586534270263)
11:57:50.264 Updated AI 0: value=100.0, flags=1, timestamp=DNPTime(1586534270264)
11:57:50.265 Updated AI 65535: value=100.0, flags=1, timestamp=DNPTime(1586534270264)
11:57:50.266 step: 4
11:57:50.266 - Expect no unsolicited response.
11:57:55.468 step: 5
11:57:55.468 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:55.468 - g60v4, All Objects
11:57:55.469 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:55.469 g60v4, All Objects
11:57:55.469 --T-> fir: 1 fin: 1 seq: 0
11:57:55.469 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:57:55.470 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:57:55.470 C0 C1 14 3C 04 06 99 56
11:57:55.472 step: 6
11:57:55.472 - Read a single fragment response w/ sequence(0x01)
11:57:55.472 - Verify that the response is empty
11:57:55.472 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:55.473 C2 C1 81 98 00 EB C7
11:57:55.473 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:55.473 <-T-- fir: 1 fin: 1 seq: 2
11:57:55.474 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime, Class3Events)
11:57:55.474 steps: 7 to 10
11:57:55.474 - Read an unsolicited response
11:57:55.475 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:57:55.475 C3 F1 82 90 00 20 01 28 03 00 00 04 01 64 00 00 3F 2E
11:57:55.475 00 00 00 01 64 00 00 00 FF FF 01 64 00 00 00 09 81
11:57:55.475 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:57:55.476 <-T-- fir: 1 fin: 1 seq: 3
11:57:55.478 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:55.478 g32v1, 2-Byte Count and Index, count: 3
11:57:55.478 [1024] - flags: 0x01 value: 100
11:57:55.478 [0] - flags: 0x01 value: 100
11:57:55.478 [65535] - flags: 0x01 value: 100
11:57:55.478 step: 11
11:57:55.478 - Wait for 5000 milliseconds
11:58:00.479 steps: 12 to 13
11:58:00.479 - Read an unsolicited response
11:58:00.479 - Verify that the device responds with the same Class 3 event data and the additional data generated
11:58:00.481 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:58:00.481 C4 F1 82 90 00 20 01 28 03 00 00 04 01 64 00 00 E2 83
11:58:00.481 00 00 00 01 64 00 00 00 FF FF 01 64 00 00 00 09 81
11:58:00.481 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:58:00.481 <-T-- fir: 1 fin: 1 seq: 4
11:58:00.483 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:58:00.483 g32v1, 2-Byte Count and Index, count: 3
11:58:00.483 [1024] - flags: 0x01 value: 100
11:58:00.483 [0] - flags: 0x01 value: 100
11:58:00.483 [65535] - flags: 0x01 value: 100
11:58:00.483 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
11:58:00.483 --T-> fir: 1 fin: 1 seq: 0
11:58:00.484 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:58:00.484 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:58:00.484 C0 D2 00 FE 52
11:58:00.485 steps: 14 to 15
11:58:00.485 - Read an unsolicited response
11:58:05.473 <-P-- 05 64 24 44 01 00 00 04 E0 88
11:58:05.473 C5 F1 82 90 00 20 01 28 03 00 00 04 01 64 00 00 6E CE
11:58:05.473 00 00 00 01 64 00 00 00 FF FF 01 64 00 00 00 09 81
11:58:05.473 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 36 dest: 1 src: 1024
11:58:05.473 <-T-- fir: 1 fin: 1 seq: 5
11:58:05.475 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:58:05.475 g32v1, 2-Byte Count and Index, count: 3
11:58:05.475 [1024] - flags: 0x01 value: 100
11:58:05.475 [0] - flags: 0x01 value: 100
11:58:05.475 [65535] - flags: 0x01 value: 100
11:58:05.476 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
11:58:05.476 --T-> fir: 1 fin: 1 seq: 0
11:58:05.476 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:58:05.477 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:58:05.477 C0 D1 00 55 E2
11:58:05.478 step: 16
11:58:05.478 - Class 1, Class 2 events
11:58:05.478 Updated BI 65535: value=true, flags=81, timestamp=DNPTime(1586534285478)
11:58:05.479 Updated BI 1024: value=true, flags=81, timestamp=DNPTime(1586534285478)
11:58:05.479 Updated BI 0: value=true, flags=81, timestamp=DNPTime(1586534285479)
11:58:05.480 Updated Counter 0: value=2, flags=1, timestamp=DNPTime(1586534285479)
11:58:05.480 Updated Counter 1024: value=2, flags=1, timestamp=DNPTime(1586534285480)
11:58:05.481 Updated Counter 0: value=3, flags=1, timestamp=DNPTime(1586534285480)
11:58:05.481 step: 17
11:58:05.481 - Expect no unsolicited response.
11:58:10.683 Passed procedure: 8.11.2.4