11:57:08.131 Running procedure: 8.11.2.2
11:57:08.137 This procedure is enabled because the following options:
11:57:08.137 - supports.unsolicitedResponse == true
11:57:08.139 step: 0
11:57:08.139 - Configure the unsolicited response mode to 'true'
11:57:08.139 - Cycle power to the DUT
11:57:08.139 - If an unsolicited NULL response is received, confirm it
11:57:08.147 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:08.147 C0 F0 82 90 00 43 A2
11:57:08.148 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:08.148 <-T-- fir: 1 fin: 1 seq: 0
11:57:08.148 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:08.149 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
11:57:08.149 --T-> fir: 1 fin: 1 seq: 0
11:57:08.149 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:08.150 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:08.150 C0 D0 00 1B 49
11:57:08.151 step: 1
11:57:08.151 - Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:08.151 - g60v2, All Objects
11:57:08.151 - g60v3, All Objects
11:57:08.151 - g60v4, All Objects
11:57:08.152 --A-> Disable Unsolicited(0x15) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
11:57:08.152 g60v2, All Objects
11:57:08.152 g60v3, All Objects
11:57:08.152 g60v4, All Objects
11:57:08.152 --T-> fir: 1 fin: 1 seq: 0
11:57:08.152 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
11:57:08.153 --P-> 05 64 11 C4 00 04 01 00 60 4E
11:57:08.153 C0 C0 15 3C 02 06 3C 03 06 3C 04 06 1A 55
11:57:08.154 step: 2
11:57:08.154 - Read a single fragment response w/ sequence(0x00)
11:57:08.154 - Verify that the response is empty
11:57:08.154 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:08.154 C1 C0 81 90 00 9D 0F
11:57:08.155 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:08.155 <-T-- fir: 1 fin: 1 seq: 1
11:57:08.155 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
11:57:08.156 step: 3
11:57:08.156 - Generate known Class Class 1 event data
11:57:08.156 Updated BI 0: value=true, flags=81, timestamp=DNPTime(1586534228156)
11:57:08.156 Updated BI 0: value=false, flags=1, timestamp=DNPTime(1586534228156)
11:57:08.157 Updated BI 65535: value=true, flags=81, timestamp=DNPTime(1586534228156)
11:57:08.157 step: 4
11:57:08.157 - Expect no unsolicited response.
11:57:13.358 step: 5
11:57:13.358 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:13.358 - g60v2, All Objects
11:57:13.358 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
11:57:13.358 g60v2, All Objects
11:57:13.358 --T-> fir: 1 fin: 1 seq: 0
11:57:13.359 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
11:57:13.359 --P-> 05 64 0B C4 00 04 01 00 CA 8A
11:57:13.359 C0 C1 14 3C 02 06 B6 7A
11:57:13.360 step: 6
11:57:13.360 - Read a single fragment response w/ sequence(0x01)
11:57:13.360 - Verify that the response is empty
11:57:13.361 <-P-- 05 64 0A 44 01 00 00 04 67 88
11:57:13.361 C2 C1 81 92 00 9A B3
11:57:13.361 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
11:57:13.361 <-T-- fir: 1 fin: 1 seq: 2
11:57:13.362 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime, Class1Events)
11:57:13.362 steps: 7 to 10
11:57:13.362 - Read an unsolicited response
11:57:13.363 <-P-- 05 64 18 44 01 00 00 04 11 D6
11:57:13.363 C3 F1 82 90 00 02 01 28 03 00 00 00 81 00 00 01 8F F4
11:57:13.363 FF FF 81 BF 75
11:57:13.363 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 24 dest: 1 src: 1024
11:57:13.363 <-T-- fir: 1 fin: 1 seq: 3
11:57:13.365 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:13.365 g2v1, 2-Byte Count and Index, count: 3
11:57:13.365 [0] - flags: 0x81
11:57:13.365 [0] - flags: 0x01
11:57:13.365 [65535] - flags: 0x81
11:57:13.365 step: 11
11:57:13.365 - Wait for 5000 milliseconds
11:57:18.366 steps: 12 to 13
11:57:18.366 - Read an unsolicited response
11:57:18.366 - Verify that the device responds with the same Class 1 event data and the additional data generated
11:57:18.367 <-P-- 05 64 18 44 01 00 00 04 11 D6
11:57:18.367 C4 F1 82 90 00 02 01 28 03 00 00 00 81 00 00 01 52 59
11:57:18.367 FF FF 81 BF 75
11:57:18.367 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 24 dest: 1 src: 1024
11:57:18.367 <-T-- fir: 1 fin: 1 seq: 4
11:57:18.368 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:18.368 g2v1, 2-Byte Count and Index, count: 3
11:57:18.368 [0] - flags: 0x81
11:57:18.368 [0] - flags: 0x01
11:57:18.368 [65535] - flags: 0x81
11:57:18.369 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
11:57:18.369 --T-> fir: 1 fin: 1 seq: 0
11:57:18.369 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:18.370 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:18.370 C0 D2 00 FE 52
11:57:18.370 steps: 14 to 15
11:57:18.370 - Read an unsolicited response
11:57:23.362 <-P-- 05 64 18 44 01 00 00 04 11 D6
11:57:23.362 C5 F1 82 90 00 02 01 28 03 00 00 00 81 00 00 01 DE 14
11:57:23.362 FF FF 81 BF 75
11:57:23.362 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 24 dest: 1 src: 1024
11:57:23.362 <-T-- fir: 1 fin: 1 seq: 5
11:57:23.363 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
11:57:23.363 g2v1, 2-Byte Count and Index, count: 3
11:57:23.363 [0] - flags: 0x81
11:57:23.363 [0] - flags: 0x01
11:57:23.363 [65535] - flags: 0x81
11:57:23.363 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
11:57:23.363 --T-> fir: 1 fin: 1 seq: 0
11:57:23.364 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
11:57:23.364 --P-> 05 64 08 C4 00 04 01 00 9A 19
11:57:23.364 C0 D1 00 55 E2
11:57:23.368 step: 16
11:57:23.368 - Class 2, Class 3 events
11:57:23.368 Updated Counter 1024: value=2, flags=1, timestamp=DNPTime(1586534243368)
11:57:23.369 Updated Counter 41: value=2, flags=1, timestamp=DNPTime(1586534243368)
11:57:23.369 Updated Counter 41: value=3, flags=1, timestamp=DNPTime(1586534243369)
11:57:23.370 Updated AI 65535: value=100.0, flags=1, timestamp=DNPTime(1586534243369)
11:57:23.370 Updated AI 1024: value=100.0, flags=1, timestamp=DNPTime(1586534243370)
11:57:23.370 Updated AI 41: value=100.0, flags=1, timestamp=DNPTime(1586534243370)
11:57:23.371 step: 17
11:57:23.371 - Expect no unsolicited response.
11:57:28.571 Passed procedure: 8.11.2.2