8.11.2.5 Unsolicited and Polled Responses (revision 2.7, subset level 2)

Device under test: Step Function I/O dnp3 outstation

05:27:06.315 Running procedure: 8.11.2.5
05:27:06.319 This procedure is enabled because the following options:
05:27:06.319 - supports.unsolicitedResponse == true
05:27:06.319 step: 0
05:27:06.319 - Configure the unsolicited response mode to 'true'
05:27:06.319 - Cycle power to the DUT
05:27:06.319 - If an unsolicited NULL response is received, confirm it
05:27:06.319 [PLG] Unsolicited responses were enabled
05:27:06.319 [PLG] Cycling power
05:27:06.320 [PLG] Cycling power
05:27:06.322 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:06.322 C0 F0 82 90 00 43 A2
05:27:06.322 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:06.322 <-T-- fir: 1 fin: 1 seq: 0
05:27:06.322 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:06.322 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:06.322 --T-> fir: 1 fin: 1 seq: 0
05:27:06.322 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:06.322 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:06.322 C0 D0 00 1B 49
05:27:06.323 8.11.2.5.1
05:27:06.323 - Transmits data-filled unsolicited responses
05:27:06.323 step: 1
05:27:06.323 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:27:06.323 - g60v2, All Objects
05:27:06.323 - g60v3, All Objects
05:27:06.323 - g60v4, All Objects
05:27:06.323 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:27:06.323 g60v2, All Objects
05:27:06.323 g60v3, All Objects
05:27:06.323 g60v4, All Objects
05:27:06.323 --T-> fir: 1 fin: 1 seq: 0
05:27:06.323 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:06.323 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:06.323 C0 C0 14 3C 02 06 3C 03 06 3C 04 06 78 96
05:27:06.323 step: 2
05:27:06.323 - Read a single fragment response w/ sequence(0x00)
05:27:06.323 - Verify that the response is empty
05:27:06.323 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:06.324 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:06.324 C1 C0 81 90 00 9D 0F
05:27:06.324 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:06.324 <-T-- fir: 1 fin: 1 seq: 1
05:27:06.324 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:06.324 steps: 3 to 5
05:27:06.324 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:06.324 - Read an unsolicited response
05:27:06.324 - Verify that the response contains events
05:27:06.324 - Confirm each response that asks for a confirmation
05:27:06.324 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251626324 (UNSYNCHRONIZED_TIME)
05:27:06.325 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:06.325 C2 F1 82 90 00 33 02 07 01 54 4D 20 86 A1 01 02 1A 56
05:27:06.325 03 28 01 00 00 00 81 00 00 9A 2A
05:27:06.325 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:06.325 <-T-- fir: 1 fin: 1 seq: 2
05:27:06.325 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:27:06.325 g51v2, 1-Byte Count, count: 1
05:27:06.325 time: 1793251626324
05:27:06.325 g2v3, 2-Byte Count and Index, count: 1
05:27:06.325 [0] - flags: 0x81 cto: 0
05:27:06.325 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
05:27:06.325 --T-> fir: 1 fin: 1 seq: 0
05:27:06.325 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:06.325 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:06.325 C0 D1 00 55 E2
05:27:06.325 8.11.2.5.2
05:27:06.325 - Clears transmitted data upon confirmation
05:27:06.325 step: 1
05:27:06.325 - The response in 8.11.2.5.1 was confirmed when it arrived
05:27:06.325 step: 2
05:27:06.325 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:27:06.325 - g60v2, All Objects
05:27:06.325 - g60v3, All Objects
05:27:06.325 - g60v4, All Objects
05:27:06.325 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:27:06.325 g60v2, All Objects
05:27:06.325 g60v3, All Objects
05:27:06.325 g60v4, All Objects
05:27:06.326 --T-> fir: 1 fin: 1 seq: 0
05:27:06.326 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:06.326 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:06.326 C0 C1 01 3C 02 06 3C 03 06 3C 04 06 10 26
05:27:06.326 step: 3
05:27:06.326 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
05:27:06.326 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
05:27:06.326 - Confirm each response that asks for a confirmation
05:27:06.326 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:06.326 C3 C1 81 90 00 79 8B
05:27:06.326 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:06.326 <-T-- fir: 1 fin: 1 seq: 3
05:27:06.326 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:27:06.326 [PLG] Verified 1 Class 1, 2 and 3 event(s)
05:27:06.327 8.11.2.5.3
05:27:06.327 - Processes non-READ requests immediately
05:27:06.327 step: 1
05:27:06.327 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:06.327 - Read an unsolicited response
05:27:06.327 - Verify that the response contains events
05:27:06.327 - Leave the response unconfirmed for now
05:27:06.327 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251626327 (UNSYNCHRONIZED_TIME)
05:27:06.327 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:06.327 C4 F2 82 90 00 33 02 07 01 57 4D 20 86 A1 01 02 C5 E5
05:27:06.327 03 28 01 00 00 00 01 00 00 3D 7F
05:27:06.327 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:06.327 <-T-- fir: 1 fin: 1 seq: 4
05:27:06.327 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:27:06.327 g51v2, 1-Byte Count, count: 1
05:27:06.327 time: 1793251626327
05:27:06.327 g2v3, 2-Byte Count and Index, count: 1
05:27:06.327 [0] - flags: 0x01 cto: 0
05:27:06.327 step: 2
05:27:06.327 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:27:06.327 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:06.327 - [7] - BooleanValue(false)
05:27:06.328 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:27:06.328 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:06.328 [7] - BooleanValue(false)
05:27:06.328 --T-> fir: 1 fin: 1 seq: 0
05:27:06.328 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:06.328 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:06.328 C0 C2 02 50 01 00 07 07 00 08 65
05:27:06.328 step: 3
05:27:06.328 - Read a single fragment response w/ sequence(0x02)
05:27:06.328 - Verify that the response is empty
05:27:06.328 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:06.328 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:06.328 C5 C2 81 10 00 03 50
05:27:06.328 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:06.328 <-T-- fir: 1 fin: 1 seq: 5
05:27:06.328 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(NeedTime)
05:27:06.328 steps: 4 to 5
05:27:06.328 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:27:06.328 - Verify that this response contains every event the previous response reported.
05:27:08.329 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:08.329 C6 F2 82 90 00 33 02 07 01 57 4D 20 86 A1 01 02 DD 7E
05:27:08.329 03 28 01 00 00 00 01 00 00 3D 7F
05:27:08.329 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:08.329 <-T-- fir: 1 fin: 1 seq: 6
05:27:08.329 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:27:08.329 g51v2, 1-Byte Count, count: 1
05:27:08.329 time: 1793251626327
05:27:08.329 g2v3, 2-Byte Count and Index, count: 1
05:27:08.329 [0] - flags: 0x01 cto: 0
05:27:08.330 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:08.330 8.11.2.5.4
05:27:08.330 - Defers READ requests until after confirmation received
05:27:08.330 step: 1
05:27:08.330 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:27:08.330 - g60v2, All Objects
05:27:08.330 - g60v3, All Objects
05:27:08.330 - g60v4, All Objects
05:27:08.330 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:27:08.330 g60v2, All Objects
05:27:08.330 g60v3, All Objects
05:27:08.330 g60v4, All Objects
05:27:08.330 --T-> fir: 1 fin: 1 seq: 0
05:27:08.330 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:08.330 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:08.330 C0 C3 01 3C 02 06 3C 03 06 3C 04 06 0C 0B
05:27:08.331 step: 2
05:27:08.331 - Expect no message until 1000 ms before the confirm timeout
05:27:09.330 step: 3
05:27:09.330 - Confirm the response
05:27:09.330 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
05:27:09.330 --T-> fir: 1 fin: 1 seq: 0
05:27:09.330 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:09.330 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:09.330 C0 D2 00 FE 52
05:27:09.330 steps: 4 to 6
05:27:09.330 - Read any number of valid responses beginning w/ sequence(0x03) and incrementing by 1 modulo 16
05:27:09.330 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
05:27:09.330 - Confirm each response that asks for a confirmation
05:27:09.331 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:09.331 C7 C3 81 10 00 E7 D4
05:27:09.331 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:09.331 <-T-- fir: 1 fin: 1 seq: 7
05:27:09.331 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(NeedTime)
05:27:09.331 [PLG] Verified 1 Class 1, 2 and 3 event(s)
05:27:09.331 8.11.2.5.5
05:27:09.331 - Defers READ requests until after confirmation timeout
05:27:09.331 step: 1
05:27:09.331 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:09.331 - Read an unsolicited response
05:27:09.331 - Verify that the response contains events
05:27:09.331 - Leave the response unconfirmed for now
05:27:09.331 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251629331 (UNSYNCHRONIZED_TIME)
05:27:09.331 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:09.331 C8 F3 82 10 00 33 02 07 01 13 59 20 86 A1 01 02 0C 0B
05:27:09.331 03 28 01 00 00 00 81 00 00 9A 2A
05:27:09.332 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:09.332 <-T-- fir: 1 fin: 1 seq: 8
05:27:09.332 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x03 IIN(NeedTime)
05:27:09.332 g51v2, 1-Byte Count, count: 1
05:27:09.332 time: 1793251629331
05:27:09.332 g2v3, 2-Byte Count and Index, count: 1
05:27:09.332 [0] - flags: 0x81 cto: 0
05:27:09.332 step: 2
05:27:09.332 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:09.332 - g60v2, All Objects
05:27:09.332 - g60v3, All Objects
05:27:09.332 - g60v4, All Objects
05:27:09.332 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:09.332 g60v2, All Objects
05:27:09.332 g60v3, All Objects
05:27:09.332 g60v4, All Objects
05:27:09.332 --T-> fir: 1 fin: 1 seq: 0
05:27:09.332 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:09.332 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:09.332 C0 C4 01 3C 02 06 3C 03 06 3C 04 06 A6 6A
05:27:09.332 steps: 3 to 7
05:27:09.332 - Expect no message until 500 ms before the confirm timeout
05:27:09.332 - Wait for the confirm timeout to expire
05:27:09.332 - Read any number of valid responses beginning w/ sequence(0x04) and incrementing by 1 modulo 16
05:27:09.332 - Verify that an application layer confirmation is requested.
05:27:09.332 - Verify that this response contains every event the previous response reported.
05:27:09.332 - Confirm each response that asks for a confirmation
05:27:11.333 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:11.333 C9 E4 81 10 00 33 02 07 01 13 59 20 86 A1 01 02 4E 41
05:27:11.333 03 28 01 00 00 00 81 00 00 9A 2A
05:27:11.334 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:11.334 <-T-- fir: 1 fin: 1 seq: 9
05:27:11.334 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(NeedTime)
05:27:11.334 g51v2, 1-Byte Count, count: 1
05:27:11.334 time: 1793251629331
05:27:11.334 g2v3, 2-Byte Count and Index, count: 1
05:27:11.334 [0] - flags: 0x81 cto: 0
05:27:11.334 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:11.334 --T-> fir: 1 fin: 1 seq: 0
05:27:11.334 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:11.334 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:11.334 C0 C4 00 F9 A1
05:27:11.334 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:11.334 step: 8
05:27:11.334 - Expect no unsolicited response within 2500 ms.
05:27:13.837 8.11.2.5.6
05:27:13.837 - Abandons READ requests upon subsequent non-READ requests
05:27:13.837 step: 1
05:27:13.837 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:13.837 - Read an unsolicited response
05:27:13.837 - Verify that the response contains events
05:27:13.837 - Leave the response unconfirmed for now
05:27:13.837 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251633837 (UNSYNCHRONIZED_TIME)
05:27:13.838 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:13.838 CA F4 82 10 00 33 02 07 01 AD 6A 20 86 A1 01 02 9B D7
05:27:13.838 03 28 01 00 00 00 01 00 00 3D 7F
05:27:13.838 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:13.838 <-T-- fir: 1 fin: 1 seq: 10
05:27:13.838 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
05:27:13.838 g51v2, 1-Byte Count, count: 1
05:27:13.838 time: 1793251633837
05:27:13.838 g2v3, 2-Byte Count and Index, count: 1
05:27:13.838 [0] - flags: 0x01 cto: 0
05:27:13.838 step: 2
05:27:13.838 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:27:13.838 - g60v2, All Objects
05:27:13.838 - g60v3, All Objects
05:27:13.838 - g60v4, All Objects
05:27:13.838 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:27:13.838 g60v2, All Objects
05:27:13.838 g60v3, All Objects
05:27:13.838 g60v4, All Objects
05:27:13.838 --T-> fir: 1 fin: 1 seq: 0
05:27:13.838 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:13.838 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:13.838 C0 C5 01 3C 02 06 3C 03 06 3C 04 06 28 7C
05:27:13.839 step: 3
05:27:13.839 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
05:27:13.839 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:13.839 - [7] - BooleanValue(false)
05:27:13.839 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
05:27:13.839 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:13.839 [7] - BooleanValue(false)
05:27:13.839 --T-> fir: 1 fin: 1 seq: 0
05:27:13.839 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:13.839 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:13.839 C0 C6 02 50 01 00 07 07 00 40 3D
05:27:13.839 step: 4
05:27:13.839 - Read a single fragment response w/ sequence(0x06)
05:27:13.839 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:13.839 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:13.839 CB C6 81 10 00 75 13
05:27:13.839 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:13.839 <-T-- fir: 1 fin: 1 seq: 11
05:27:13.839 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06 IIN(NeedTime)
05:27:13.839 steps: 5 to 6
05:27:13.839 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:27:13.839 - Verify that this response contains every event the previous response reported.
05:27:15.839 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:15.839 CC F4 82 10 00 33 02 07 01 AD 6A 20 86 A1 01 02 CA 37
05:27:15.839 03 28 01 00 00 00 01 00 00 3D 7F
05:27:15.839 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:15.839 <-T-- fir: 1 fin: 1 seq: 12
05:27:15.839 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
05:27:15.839 g51v2, 1-Byte Count, count: 1
05:27:15.839 time: 1793251633837
05:27:15.839 g2v3, 2-Byte Count and Index, count: 1
05:27:15.839 [0] - flags: 0x01 cto: 0
05:27:15.839 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:15.839 8.11.2.5.7
05:27:15.839 - Abandons READ requests upon subsequent READ requests
05:27:15.839 step: 1
05:27:15.839 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
05:27:15.839 - g60v1, All Objects
05:27:15.839 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
05:27:15.839 g60v1, All Objects
05:27:15.839 --T-> fir: 1 fin: 1 seq: 0
05:27:15.839 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:27:15.839 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:27:15.839 C0 C7 01 3C 01 06 ED B9
05:27:15.840 step: 2
05:27:15.840 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:15.840 - g60v2, All Objects
05:27:15.840 - g60v3, All Objects
05:27:15.840 - g60v4, All Objects
05:27:15.840 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:15.840 g60v2, All Objects
05:27:15.840 g60v3, All Objects
05:27:15.840 g60v4, All Objects
05:27:15.840 --T-> fir: 1 fin: 1 seq: 0
05:27:15.840 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:15.840 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:15.840 C0 C8 01 3C 02 06 3C 03 06 3C 04 06 EE 84
05:27:15.840 steps: 3 to 8
05:27:15.840 - Expect no message until 500 ms before the confirm timeout
05:27:15.840 - Wait for the confirm timeout to expire
05:27:15.840 - Read any number of valid responses beginning w/ sequence(0x08) and incrementing by 1 modulo 16
05:27:15.840 - Verify that the response only contains objects, variations, and qualifiers specified in table 8-4
05:27:15.840 - Verify that an application layer confirmation is requested.
05:27:15.840 - Verify that this response contains every event the previous response reported.
05:27:15.840 - Confirm each response that asks for a confirmation
05:27:17.839 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:17.839 CD E8 81 10 00 33 02 07 01 AD 6A 20 86 A1 01 02 EB 68
05:27:17.839 03 28 01 00 00 00 01 00 00 3D 7F
05:27:17.839 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:17.840 <-T-- fir: 1 fin: 1 seq: 13
05:27:17.840 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x08 IIN(NeedTime)
05:27:17.840 g51v2, 1-Byte Count, count: 1
05:27:17.840 time: 1793251633837
05:27:17.840 g2v3, 2-Byte Count and Index, count: 1
05:27:17.840 [0] - flags: 0x01 cto: 0
05:27:17.840 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:17.840 --T-> fir: 1 fin: 1 seq: 0
05:27:17.840 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:17.840 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:17.840 C0 C8 00 A7 F9
05:27:17.840 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:17.840 step: 9
05:27:17.840 - Expect no unsolicited response within 2500 ms.
05:27:20.343 8.11.2.5.8
05:27:20.343 - Inhibits Unsolicited responses until after polled confirmation
05:27:20.343 steps: 1 to 11
05:27:20.343 - Skipped because the DUT does not have a minimum number of events before an unsolicited response. A DUT that holds events back for a time instead is not covered by this tool.
05:27:20.343 8.11.2.5.9
05:27:20.343 - Retries unsolicited responses configurable number of times
05:27:20.343 step: 1
05:27:20.343 - Set the DUT's unsolicited retry limit to 5
05:27:20.343 [PLG] Max unsolicited response retries was set to 5
05:27:20.343 [PLG] Cycling power
05:27:20.345 step: 2
05:27:20.345 - Cycle power to the DUT
05:27:20.345 [PLG] Cycling power
05:27:20.346 step: 3
05:27:20.346 - If an unsolicited NULL response is received, confirm it
05:27:20.347 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:20.347 C0 F0 82 90 00 43 A2
05:27:20.347 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:20.347 <-T-- fir: 1 fin: 1 seq: 0
05:27:20.347 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:20.347 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:20.347 --T-> fir: 1 fin: 1 seq: 0
05:27:20.347 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:20.347 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:20.347 C0 D0 00 1B 49
05:27:20.347 step: 4
05:27:20.347 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
05:27:20.347 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:20.347 - [7] - BooleanValue(false)
05:27:20.347 - Read a single fragment response w/ sequence(0x09)
05:27:20.347 - Verify that the response is empty
05:27:20.347 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:20.347 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
05:27:20.347 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:20.347 [7] - BooleanValue(false)
05:27:20.347 --T-> fir: 1 fin: 1 seq: 0
05:27:20.347 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:20.347 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:20.347 C0 C9 02 50 01 00 07 07 00 AE EF
05:27:20.347 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:20.347 C1 C9 81 10 00 75 23
05:27:20.347 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:20.347 <-T-- fir: 1 fin: 1 seq: 1
05:27:20.347 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09 IIN(NeedTime)
05:27:20.348 step: 5
05:27:20.348 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
05:27:20.348 - g60v2, All Objects
05:27:20.348 - g60v3, All Objects
05:27:20.348 - g60v4, All Objects
05:27:20.348 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
05:27:20.348 g60v2, All Objects
05:27:20.348 g60v3, All Objects
05:27:20.348 g60v4, All Objects
05:27:20.348 --T-> fir: 1 fin: 1 seq: 0
05:27:20.348 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:20.348 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:20.348 C0 CA 14 3C 02 06 3C 03 06 3C 04 06 14 0F
05:27:20.348 step: 6
05:27:20.348 - Read a single fragment response w/ sequence(0x0A)
05:27:20.348 - Verify that the response is empty
05:27:20.348 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:20.348 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:20.348 C2 CA 81 10 00 3E 4C
05:27:20.348 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:20.348 <-T-- fir: 1 fin: 1 seq: 2
05:27:20.348 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A IIN(NeedTime)
05:27:20.348 step: 7
05:27:20.348 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:20.348 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251640348 (UNSYNCHRONIZED_TIME)
05:27:20.348 steps: 8 to 9
05:27:20.348 - Read an unsolicited response
05:27:20.348 - Verify that the response contains events
05:27:20.348 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:20.348 C3 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 E5 E2
05:27:20.348 03 28 01 00 00 00 81 00 00 9A 2A
05:27:20.348 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:20.348 <-T-- fir: 1 fin: 1 seq: 3
05:27:20.349 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:20.349 g51v2, 1-Byte Count, count: 1
05:27:20.349 time: 1793251640348
05:27:20.349 g2v3, 2-Byte Count and Index, count: 1
05:27:20.349 [0] - flags: 0x81 cto: 0
05:27:20.349 step: 10
05:27:20.349 - Read an unsolicited response
05:27:20.349 - Repeat these steps 5 times
05:27:20.349 - Expect no unsolicited response within 2500 ms.
05:27:20.349 Repeat 1 of 5
05:27:22.350 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:22.350 C4 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 38 4F
05:27:22.350 03 28 01 00 00 00 81 00 00 9A 2A
05:27:22.350 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:22.350 <-T-- fir: 1 fin: 1 seq: 4
05:27:22.351 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:22.351 g51v2, 1-Byte Count, count: 1
05:27:22.351 time: 1793251640348
05:27:22.351 g2v3, 2-Byte Count and Index, count: 1
05:27:22.351 [0] - flags: 0x81 cto: 0
05:27:22.351 Repeat 2 of 5
05:27:24.352 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:24.352 C5 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 B4 02
05:27:24.352 03 28 01 00 00 00 81 00 00 9A 2A
05:27:24.352 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:24.352 <-T-- fir: 1 fin: 1 seq: 5
05:27:24.352 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:24.352 g51v2, 1-Byte Count, count: 1
05:27:24.352 time: 1793251640348
05:27:24.352 g2v3, 2-Byte Count and Index, count: 1
05:27:24.352 [0] - flags: 0x81 cto: 0
05:27:24.352 Repeat 3 of 5
05:27:26.353 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:26.353 C6 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 20 D4
05:27:26.353 03 28 01 00 00 00 81 00 00 9A 2A
05:27:26.353 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:26.353 <-T-- fir: 1 fin: 1 seq: 6
05:27:26.353 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:26.353 g51v2, 1-Byte Count, count: 1
05:27:26.353 time: 1793251640348
05:27:26.353 g2v3, 2-Byte Count and Index, count: 1
05:27:26.353 [0] - flags: 0x81 cto: 0
05:27:26.353 Repeat 4 of 5
05:27:28.354 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:28.354 C7 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 AC 99
05:27:28.354 03 28 01 00 00 00 81 00 00 9A 2A
05:27:28.354 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:28.354 <-T-- fir: 1 fin: 1 seq: 7
05:27:28.354 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:28.354 g51v2, 1-Byte Count, count: 1
05:27:28.354 time: 1793251640348
05:27:28.354 g2v3, 2-Byte Count and Index, count: 1
05:27:28.354 [0] - flags: 0x81 cto: 0
05:27:28.354 Repeat 5 of 5
05:27:30.355 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:30.355 C8 F1 82 10 00 33 02 07 01 1C 84 20 86 A1 01 02 E3 C2
05:27:30.355 03 28 01 00 00 00 81 00 00 9A 2A
05:27:30.355 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:30.355 <-T-- fir: 1 fin: 1 seq: 8
05:27:30.355 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:30.355 g51v2, 1-Byte Count, count: 1
05:27:30.355 time: 1793251640348
05:27:30.355 g2v3, 2-Byte Count and Index, count: 1
05:27:30.355 [0] - flags: 0x81 cto: 0
05:27:32.858 step: 11
05:27:32.858 - Cycle power to the DUT
05:27:32.858 [PLG] Cycling power
05:27:32.859 step: 12
05:27:32.859 - If an unsolicited NULL response is received, confirm it
05:27:32.860 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:32.860 C0 F0 82 90 00 43 A2
05:27:32.860 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:32.860 <-T-- fir: 1 fin: 1 seq: 0
05:27:32.860 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:32.860 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:32.860 --T-> fir: 1 fin: 1 seq: 0
05:27:32.860 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:32.860 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:32.860 C0 D0 00 1B 49
05:27:32.860 step: 13
05:27:32.860 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
05:27:32.860 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:32.860 - [7] - BooleanValue(false)
05:27:32.860 - Read a single fragment response w/ sequence(0x0B)
05:27:32.860 - Verify that the response is empty
05:27:32.860 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:32.860 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
05:27:32.860 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:32.860 [7] - BooleanValue(false)
05:27:32.861 --T-> fir: 1 fin: 1 seq: 0
05:27:32.861 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:32.861 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:32.861 C0 CB 02 50 01 00 07 07 00 8A C3
05:27:32.861 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:32.861 C1 CB 81 10 00 DC EB
05:27:32.861 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:32.861 <-T-- fir: 1 fin: 1 seq: 1
05:27:32.861 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B IIN(NeedTime)
05:27:32.861 step: 14
05:27:32.861 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
05:27:32.861 - g60v2, All Objects
05:27:32.861 - g60v3, All Objects
05:27:32.861 - g60v4, All Objects
05:27:32.861 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
05:27:32.861 g60v2, All Objects
05:27:32.861 g60v3, All Objects
05:27:32.861 g60v4, All Objects
05:27:32.861 --T-> fir: 1 fin: 1 seq: 0
05:27:32.861 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:32.861 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:32.861 C0 CC 14 3C 02 06 3C 03 06 3C 04 06 30 78
05:27:32.861 step: 15
05:27:32.861 - Read a single fragment response w/ sequence(0x0C)
05:27:32.861 - Verify that the response is empty
05:27:32.861 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:32.861 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:32.861 C2 CC 81 10 00 BC 58
05:27:32.861 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:32.861 <-T-- fir: 1 fin: 1 seq: 2
05:27:32.862 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C IIN(NeedTime)
05:27:32.862 steps: 16 to 17
05:27:32.862 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:32.862 - Read an unsolicited response
05:27:32.862 - Verify that the response contains events
05:27:32.862 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251652862 (UNSYNCHRONIZED_TIME)
05:27:32.862 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:32.862 C3 F1 82 10 00 33 02 07 01 FE B4 20 86 A1 01 02 47 3B
05:27:32.862 03 28 01 00 00 00 81 00 00 9A 2A
05:27:32.862 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:32.862 <-T-- fir: 1 fin: 1 seq: 3
05:27:32.862 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:32.862 g51v2, 1-Byte Count, count: 1
05:27:32.862 time: 1793251652862
05:27:32.862 g2v3, 2-Byte Count and Index, count: 1
05:27:32.862 [0] - flags: 0x81 cto: 0
05:27:32.862 step: 18
05:27:32.862 - Read an unsolicited response
05:27:32.862 - Confirm each response that asks for a confirmation
05:27:32.862 - Expect no unsolicited response within 2500 ms.
05:27:34.863 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:34.863 C4 F1 82 10 00 33 02 07 01 FE B4 20 86 A1 01 02 9A 96
05:27:34.863 03 28 01 00 00 00 81 00 00 9A 2A
05:27:34.863 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:34.863 <-T-- fir: 1 fin: 1 seq: 4
05:27:34.864 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:34.864 g51v2, 1-Byte Count, count: 1
05:27:34.864 time: 1793251652862
05:27:34.864 g2v3, 2-Byte Count and Index, count: 1
05:27:34.864 [0] - flags: 0x81 cto: 0
05:27:34.864 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
05:27:34.864 --T-> fir: 1 fin: 1 seq: 0
05:27:34.864 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:34.864 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:34.864 C0 D1 00 55 E2
05:27:37.367 step: 19
05:27:37.367 - Set the DUT's unsolicited retry limit to indefinite
05:27:37.367 [PLG] Max unsolicited response retries was set to Infinite
05:27:37.367 [PLG] Cycling power
05:27:37.368 step: 20
05:27:37.368 - Cycle power to the DUT
05:27:37.368 [PLG] Cycling power
05:27:37.372 step: 21
05:27:37.372 - If an unsolicited NULL response is received, confirm it
05:27:37.373 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:37.373 C0 F0 82 90 00 43 A2
05:27:37.373 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:37.373 <-T-- fir: 1 fin: 1 seq: 0
05:27:37.373 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:37.373 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:37.373 --T-> fir: 1 fin: 1 seq: 0
05:27:37.373 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:37.373 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:37.373 C0 D0 00 1B 49
05:27:37.373 step: 22
05:27:37.373 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
05:27:37.373 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:37.373 - [7] - BooleanValue(false)
05:27:37.373 - Read a single fragment response w/ sequence(0x0D)
05:27:37.373 - Verify that the response is empty
05:27:37.373 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:37.373 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
05:27:37.373 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:37.373 [7] - BooleanValue(false)
05:27:37.373 --T-> fir: 1 fin: 1 seq: 0
05:27:37.373 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:37.373 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:37.373 C0 CD 02 50 01 00 07 07 00 E6 B7
05:27:37.374 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:37.374 C1 CD 81 10 00 5E FF
05:27:37.374 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:37.374 <-T-- fir: 1 fin: 1 seq: 1
05:27:37.374 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D IIN(NeedTime)
05:27:37.374 step: 23
05:27:37.374 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
05:27:37.374 - g60v2, All Objects
05:27:37.374 - g60v3, All Objects
05:27:37.374 - g60v4, All Objects
05:27:37.374 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
05:27:37.374 g60v2, All Objects
05:27:37.374 g60v3, All Objects
05:27:37.374 g60v4, All Objects
05:27:37.374 --T-> fir: 1 fin: 1 seq: 0
05:27:37.374 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:37.374 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:37.374 C0 CE 14 3C 02 06 3C 03 06 3C 04 06 2C 55
05:27:37.374 step: 24
05:27:37.374 - Read a single fragment response w/ sequence(0x0E)
05:27:37.374 - Verify that the response is empty
05:27:37.374 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:37.374 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:37.374 C2 CE 81 10 00 15 90
05:27:37.374 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:37.374 <-T-- fir: 1 fin: 1 seq: 2
05:27:37.374 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E IIN(NeedTime)
05:27:37.374 step: 25
05:27:37.374 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:37.374 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251657374 (UNSYNCHRONIZED_TIME)
05:27:37.375 steps: 26 to 27
05:27:37.375 - Read an unsolicited response
05:27:37.375 - Verify that the response contains events
05:27:37.375 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:37.375 C3 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 A4 28
05:27:37.375 03 28 01 00 00 00 81 00 00 9A 2A
05:27:37.375 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:37.375 <-T-- fir: 1 fin: 1 seq: 3
05:27:37.375 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:37.375 g51v2, 1-Byte Count, count: 1
05:27:37.375 time: 1793251657374
05:27:37.375 g2v3, 2-Byte Count and Index, count: 1
05:27:37.375 [0] - flags: 0x81 cto: 0
05:27:37.375 step: 28
05:27:37.375 - Read an unsolicited response
05:27:37.375 - Repeat these steps 7 times
05:27:37.375 Repeat 1 of 7
05:27:39.376 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:39.376 C4 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 79 85
05:27:39.376 03 28 01 00 00 00 81 00 00 9A 2A
05:27:39.376 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:39.376 <-T-- fir: 1 fin: 1 seq: 4
05:27:39.376 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:39.376 g51v2, 1-Byte Count, count: 1
05:27:39.376 time: 1793251657374
05:27:39.376 g2v3, 2-Byte Count and Index, count: 1
05:27:39.376 [0] - flags: 0x81 cto: 0
05:27:39.376 Repeat 2 of 7
05:27:41.377 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:41.377 C5 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 F5 C8
05:27:41.377 03 28 01 00 00 00 81 00 00 9A 2A
05:27:41.377 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:41.377 <-T-- fir: 1 fin: 1 seq: 5
05:27:41.378 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:41.378 g51v2, 1-Byte Count, count: 1
05:27:41.378 time: 1793251657374
05:27:41.378 g2v3, 2-Byte Count and Index, count: 1
05:27:41.378 [0] - flags: 0x81 cto: 0
05:27:41.378 Repeat 3 of 7
05:27:43.379 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:43.379 C6 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 61 1E
05:27:43.379 03 28 01 00 00 00 81 00 00 9A 2A
05:27:43.379 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:43.379 <-T-- fir: 1 fin: 1 seq: 6
05:27:43.379 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:43.379 g51v2, 1-Byte Count, count: 1
05:27:43.379 time: 1793251657374
05:27:43.379 g2v3, 2-Byte Count and Index, count: 1
05:27:43.379 [0] - flags: 0x81 cto: 0
05:27:43.379 Repeat 4 of 7
05:27:45.380 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:45.380 C7 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 ED 53
05:27:45.380 03 28 01 00 00 00 81 00 00 9A 2A
05:27:45.380 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:45.380 <-T-- fir: 1 fin: 1 seq: 7
05:27:45.380 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:45.380 g51v2, 1-Byte Count, count: 1
05:27:45.380 time: 1793251657374
05:27:45.380 g2v3, 2-Byte Count and Index, count: 1
05:27:45.380 [0] - flags: 0x81 cto: 0
05:27:45.380 Repeat 5 of 7
05:27:47.381 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:47.381 C8 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 A2 08
05:27:47.381 03 28 01 00 00 00 81 00 00 9A 2A
05:27:47.381 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:47.381 <-T-- fir: 1 fin: 1 seq: 8
05:27:47.381 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:47.381 g51v2, 1-Byte Count, count: 1
05:27:47.381 time: 1793251657374
05:27:47.381 g2v3, 2-Byte Count and Index, count: 1
05:27:47.381 [0] - flags: 0x81 cto: 0
05:27:47.382 Repeat 6 of 7
05:27:49.382 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:49.382 C9 F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 2E 45
05:27:49.382 03 28 01 00 00 00 81 00 00 9A 2A
05:27:49.382 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:49.382 <-T-- fir: 1 fin: 1 seq: 9
05:27:49.383 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:49.383 g51v2, 1-Byte Count, count: 1
05:27:49.383 time: 1793251657374
05:27:49.383 g2v3, 2-Byte Count and Index, count: 1
05:27:49.383 [0] - flags: 0x81 cto: 0
05:27:49.383 Repeat 7 of 7
05:27:51.384 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:51.384 CA F1 82 10 00 33 02 07 01 9E C6 20 86 A1 01 02 BA 93
05:27:51.384 03 28 01 00 00 00 81 00 00 9A 2A
05:27:51.384 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:51.384 <-T-- fir: 1 fin: 1 seq: 10
05:27:51.384 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:51.384 g51v2, 1-Byte Count, count: 1
05:27:51.384 time: 1793251657374
05:27:51.384 g2v3, 2-Byte Count and Index, count: 1
05:27:51.384 [0] - flags: 0x81 cto: 0
05:27:51.384 Passed procedure: 8.11.2.5