8.11.2.5 Unsolicited and Polled Responses (revision 3.1r1, subset level 3)

Device under test: Step Function I/O dnp3 outstation

05:27:08.833 Running procedure: 8.11.2.5
05:27:08.837 This procedure is enabled because the following options:
05:27:08.837 - supports.unsolicitedResponse == true
05:27:08.837 step: 0
05:27:08.837 - Configure the unsolicited response mode to 'true'
05:27:08.837 - Cycle power to the DUT
05:27:08.837 - If an unsolicited NULL response is received, confirm it
05:27:08.837 [PLG] Unsolicited responses were enabled
05:27:08.837 [PLG] Cycling power
05:27:08.839 [PLG] Cycling power
05:27:08.841 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:08.841 C0 F0 82 90 00 43 A2
05:27:08.841 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:08.841 <-T-- fir: 1 fin: 1 seq: 0
05:27:08.841 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:08.841 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:08.841 --T-> fir: 1 fin: 1 seq: 0
05:27:08.841 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:08.841 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:08.841 C0 D0 00 1B 49
05:27:08.842 8.11.2.5.1
05:27:08.842 - Transmits data-filled unsolicited responses
05:27:08.842 step: 1
05:27:08.842 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:27:08.842 - g60v2, All Objects
05:27:08.842 - g60v3, All Objects
05:27:08.842 - g60v4, All Objects
05:27:08.842 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
05:27:08.842 g60v2, All Objects
05:27:08.842 g60v3, All Objects
05:27:08.842 g60v4, All Objects
05:27:08.842 --T-> fir: 1 fin: 1 seq: 0
05:27:08.842 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:08.842 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:08.842 C0 C0 14 3C 02 06 3C 03 06 3C 04 06 78 96
05:27:08.842 step: 2
05:27:08.842 - Read a single fragment response w/ sequence(0x00)
05:27:08.842 - Verify that the response is empty
05:27:08.842 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:08.843 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:08.843 C1 C0 81 90 00 9D 0F
05:27:08.843 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:08.843 <-T-- fir: 1 fin: 1 seq: 1
05:27:08.843 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:08.843 steps: 3 to 5
05:27:08.843 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:08.843 - Read an unsolicited response
05:27:08.843 - Verify that the response contains events
05:27:08.843 - Confirm each response that asks for a confirmation
05:27:08.844 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251628844 (UNSYNCHRONIZED_TIME)
05:27:08.844 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:08.844 C2 F1 82 90 00 33 02 07 01 2C 57 20 86 A1 01 02 FC 22
05:27:08.844 03 28 01 00 00 00 81 00 00 9A 2A
05:27:08.844 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:08.844 <-T-- fir: 1 fin: 1 seq: 2
05:27:08.844 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:27:08.844 g51v2, 1-Byte Count, count: 1
05:27:08.844 time: 1793251628844
05:27:08.844 g2v3, 2-Byte Count and Index, count: 1
05:27:08.844 [0] - flags: 0x81 cto: 0
05:27:08.844 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
05:27:08.844 --T-> fir: 1 fin: 1 seq: 0
05:27:08.844 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:08.844 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:08.844 C0 D1 00 55 E2
05:27:08.845 8.11.2.5.2
05:27:08.845 - Clears transmitted data upon confirmation
05:27:08.845 step: 1
05:27:08.845 - The response in 8.11.2.5.1 was confirmed when it arrived
05:27:08.845 step: 2
05:27:08.845 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:27:08.845 - g60v2, All Objects
05:27:08.845 - g60v3, All Objects
05:27:08.845 - g60v4, All Objects
05:27:08.845 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
05:27:08.845 g60v2, All Objects
05:27:08.845 g60v3, All Objects
05:27:08.845 g60v4, All Objects
05:27:08.845 --T-> fir: 1 fin: 1 seq: 0
05:27:08.845 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:08.845 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:08.845 C0 C1 01 3C 02 06 3C 03 06 3C 04 06 10 26
05:27:08.845 step: 3
05:27:08.845 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
05:27:08.845 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
05:27:08.845 - Confirm each response that asks for a confirmation
05:27:08.846 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:08.846 C3 C1 81 90 00 79 8B
05:27:08.846 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:08.846 <-T-- fir: 1 fin: 1 seq: 3
05:27:08.846 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
05:27:08.846 [PLG] Verified 1 Class 1, 2 and 3 event(s)
05:27:08.847 8.11.2.5.3
05:27:08.847 - Processes non-READ requests immediately
05:27:08.847 step: 1
05:27:08.847 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:08.847 - Read an unsolicited response
05:27:08.847 - Verify that the response contains events
05:27:08.847 - Leave the response unconfirmed for now
05:27:08.847 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251628847 (UNSYNCHRONIZED_TIME)
05:27:08.847 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:08.847 C4 F2 82 90 00 33 02 07 01 2F 57 20 86 A1 01 02 23 91
05:27:08.847 03 28 01 00 00 00 01 00 00 3D 7F
05:27:08.847 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:08.847 <-T-- fir: 1 fin: 1 seq: 4
05:27:08.848 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:27:08.848 g51v2, 1-Byte Count, count: 1
05:27:08.848 time: 1793251628847
05:27:08.848 g2v3, 2-Byte Count and Index, count: 1
05:27:08.848 [0] - flags: 0x01 cto: 0
05:27:08.848 step: 2
05:27:08.848 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:27:08.848 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:08.848 - [7] - BooleanValue(false)
05:27:08.848 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
05:27:08.848 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:08.848 [7] - BooleanValue(false)
05:27:08.848 --T-> fir: 1 fin: 1 seq: 0
05:27:08.848 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:08.848 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:08.848 C0 C2 02 50 01 00 07 07 00 08 65
05:27:08.848 step: 3
05:27:08.848 - Read a single fragment response w/ sequence(0x02)
05:27:08.848 - Verify that the response is empty
05:27:08.848 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:08.849 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:08.849 C5 C2 81 10 00 03 50
05:27:08.849 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:08.849 <-T-- fir: 1 fin: 1 seq: 5
05:27:08.849 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(NeedTime)
05:27:08.850 steps: 4 to 5
05:27:08.850 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:27:08.850 - Verify that this response contains every event the previous response reported.
05:27:10.848 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:10.848 C6 F2 82 90 00 33 02 07 01 2F 57 20 86 A1 01 02 3B 0A
05:27:10.848 03 28 01 00 00 00 01 00 00 3D 7F
05:27:10.848 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:10.848 <-T-- fir: 1 fin: 1 seq: 6
05:27:10.849 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
05:27:10.849 g51v2, 1-Byte Count, count: 1
05:27:10.849 time: 1793251628847
05:27:10.849 g2v3, 2-Byte Count and Index, count: 1
05:27:10.849 [0] - flags: 0x01 cto: 0
05:27:10.849 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:10.849 8.11.2.5.4
05:27:10.849 - Defers READ requests until after confirmation received
05:27:10.849 step: 1
05:27:10.849 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:27:10.849 - g60v2, All Objects
05:27:10.849 - g60v3, All Objects
05:27:10.849 - g60v4, All Objects
05:27:10.849 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
05:27:10.849 g60v2, All Objects
05:27:10.849 g60v3, All Objects
05:27:10.849 g60v4, All Objects
05:27:10.849 --T-> fir: 1 fin: 1 seq: 0
05:27:10.849 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:10.849 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:10.849 C0 C3 01 3C 02 06 3C 03 06 3C 04 06 0C 0B
05:27:10.850 step: 2
05:27:10.850 - Expect no message until 1000 ms before the confirm timeout
05:27:11.851 step: 3
05:27:11.851 - Confirm the response
05:27:11.851 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
05:27:11.851 --T-> fir: 1 fin: 1 seq: 0
05:27:11.851 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:11.851 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:11.851 C0 D2 00 FE 52
05:27:11.852 steps: 4 to 6
05:27:11.852 - Read any number of valid responses beginning w/ sequence(0x03) and incrementing by 1 modulo 16
05:27:11.852 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
05:27:11.852 - Confirm each response that asks for a confirmation
05:27:11.852 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:11.852 C7 C3 81 10 00 E7 D4
05:27:11.852 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:11.852 <-T-- fir: 1 fin: 1 seq: 7
05:27:11.852 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(NeedTime)
05:27:11.852 [PLG] Verified 1 Class 1, 2 and 3 event(s)
05:27:11.852 8.11.2.5.5
05:27:11.852 - Defers READ requests until after confirmation timeout
05:27:11.852 step: 1
05:27:11.852 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:11.852 - Read an unsolicited response
05:27:11.852 - Verify that the response contains events
05:27:11.852 - Leave the response unconfirmed for now
05:27:11.852 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251631852 (UNSYNCHRONIZED_TIME)
05:27:11.853 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:11.853 C8 F3 82 10 00 33 02 07 01 EC 62 20 86 A1 01 02 97 16
05:27:11.853 03 28 01 00 00 00 81 00 00 9A 2A
05:27:11.853 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:11.853 <-T-- fir: 1 fin: 1 seq: 8
05:27:11.853 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x03 IIN(NeedTime)
05:27:11.853 g51v2, 1-Byte Count, count: 1
05:27:11.853 time: 1793251631852
05:27:11.853 g2v3, 2-Byte Count and Index, count: 1
05:27:11.853 [0] - flags: 0x81 cto: 0
05:27:11.853 step: 2
05:27:11.853 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:11.853 - g60v2, All Objects
05:27:11.853 - g60v3, All Objects
05:27:11.853 - g60v4, All Objects
05:27:11.853 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:11.853 g60v2, All Objects
05:27:11.853 g60v3, All Objects
05:27:11.853 g60v4, All Objects
05:27:11.853 --T-> fir: 1 fin: 1 seq: 0
05:27:11.853 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:11.853 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:11.853 C0 C4 01 3C 02 06 3C 03 06 3C 04 06 A6 6A
05:27:11.853 steps: 3 to 7
05:27:11.853 - Expect no message until 500 ms before the confirm timeout
05:27:11.853 - Wait for the confirm timeout to expire
05:27:11.853 - Read any number of valid responses beginning w/ sequence(0x04) and incrementing by 1 modulo 16
05:27:11.853 - Verify that an application layer confirmation is requested.
05:27:11.853 - Verify that this response contains every event the previous response reported.
05:27:11.853 - Confirm each response that asks for a confirmation
05:27:13.854 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:13.854 C9 E4 81 10 00 33 02 07 01 EC 62 20 86 A1 01 02 D5 5C
05:27:13.854 03 28 01 00 00 00 81 00 00 9A 2A
05:27:13.854 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:13.854 <-T-- fir: 1 fin: 1 seq: 9
05:27:13.854 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(NeedTime)
05:27:13.854 g51v2, 1-Byte Count, count: 1
05:27:13.854 time: 1793251631852
05:27:13.854 g2v3, 2-Byte Count and Index, count: 1
05:27:13.854 [0] - flags: 0x81 cto: 0
05:27:13.854 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
05:27:13.854 --T-> fir: 1 fin: 1 seq: 0
05:27:13.854 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:13.854 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:13.854 C0 C4 00 F9 A1
05:27:13.855 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:13.855 step: 8
05:27:13.855 - Expect no unsolicited response within 2500 ms.
05:27:16.358 8.11.2.5.6
05:27:16.358 - Abandons READ requests upon subsequent non-READ requests
05:27:16.358 step: 1
05:27:16.358 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:16.358 - Read an unsolicited response
05:27:16.358 - Verify that the response contains events
05:27:16.358 - Leave the response unconfirmed for now
05:27:16.358 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793251636358 (UNSYNCHRONIZED_TIME)
05:27:16.358 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:16.358 CA F4 82 10 00 33 02 07 01 86 74 20 86 A1 01 02 EA 14
05:27:16.358 03 28 01 00 00 00 01 00 00 3D 7F
05:27:16.358 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:16.358 <-T-- fir: 1 fin: 1 seq: 10
05:27:16.359 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
05:27:16.359 g51v2, 1-Byte Count, count: 1
05:27:16.359 time: 1793251636358
05:27:16.359 g2v3, 2-Byte Count and Index, count: 1
05:27:16.359 [0] - flags: 0x01 cto: 0
05:27:16.359 step: 2
05:27:16.359 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:27:16.359 - g60v2, All Objects
05:27:16.359 - g60v3, All Objects
05:27:16.359 - g60v4, All Objects
05:27:16.359 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
05:27:16.359 g60v2, All Objects
05:27:16.359 g60v3, All Objects
05:27:16.359 g60v4, All Objects
05:27:16.359 --T-> fir: 1 fin: 1 seq: 0
05:27:16.359 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:16.359 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:16.359 C0 C5 01 3C 02 06 3C 03 06 3C 04 06 28 7C
05:27:16.359 step: 3
05:27:16.359 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
05:27:16.359 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:16.359 - [7] - BooleanValue(false)
05:27:16.359 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
05:27:16.359 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:16.359 [7] - BooleanValue(false)
05:27:16.359 --T-> fir: 1 fin: 1 seq: 0
05:27:16.359 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:16.359 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:16.359 C0 C6 02 50 01 00 07 07 00 40 3D
05:27:16.359 step: 4
05:27:16.359 - Read a single fragment response w/ sequence(0x06)
05:27:16.359 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:16.360 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:16.360 CB C6 81 10 00 75 13
05:27:16.360 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:16.360 <-T-- fir: 1 fin: 1 seq: 11
05:27:16.360 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06 IIN(NeedTime)
05:27:16.360 steps: 5 to 6
05:27:16.360 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
05:27:16.360 - Verify that this response contains every event the previous response reported.
05:27:18.360 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:18.360 CC F4 82 10 00 33 02 07 01 86 74 20 86 A1 01 02 BB F4
05:27:18.360 03 28 01 00 00 00 01 00 00 3D 7F
05:27:18.360 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:18.360 <-T-- fir: 1 fin: 1 seq: 12
05:27:18.361 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
05:27:18.361 g51v2, 1-Byte Count, count: 1
05:27:18.361 time: 1793251636358
05:27:18.361 g2v3, 2-Byte Count and Index, count: 1
05:27:18.361 [0] - flags: 0x01 cto: 0
05:27:18.361 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:18.361 8.11.2.5.7
05:27:18.361 - Abandons READ requests upon subsequent READ requests
05:27:18.361 step: 1
05:27:18.361 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
05:27:18.361 - g60v1, All Objects
05:27:18.361 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
05:27:18.361 g60v1, All Objects
05:27:18.361 --T-> fir: 1 fin: 1 seq: 0
05:27:18.361 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
05:27:18.361 --P-> 05 64 0B C4 00 04 01 00 CA 8A
05:27:18.361 C0 C7 01 3C 01 06 ED B9
05:27:18.361 step: 2
05:27:18.361 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:18.361 - g60v2, All Objects
05:27:18.361 - g60v3, All Objects
05:27:18.361 - g60v4, All Objects
05:27:18.361 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:18.361 g60v2, All Objects
05:27:18.361 g60v3, All Objects
05:27:18.361 g60v4, All Objects
05:27:18.361 --T-> fir: 1 fin: 1 seq: 0
05:27:18.361 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:18.361 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:18.361 C0 C8 01 3C 02 06 3C 03 06 3C 04 06 EE 84
05:27:18.362 steps: 3 to 8
05:27:18.362 - Expect no message until 500 ms before the confirm timeout
05:27:18.362 - Wait for the confirm timeout to expire
05:27:18.362 - Read any number of valid responses beginning w/ sequence(0x08) and incrementing by 1 modulo 16
05:27:18.362 - Verify that the response only contains objects, variations, and qualifiers specified in table 8-4, for subset level 3
05:27:18.362 - Verify that an application layer confirmation is requested.
05:27:18.362 - Verify that this response contains every event the previous response reported.
05:27:18.362 - Confirm each response that asks for a confirmation
05:27:20.363 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:20.363 CD E8 81 10 00 33 02 07 01 86 74 20 86 A1 01 02 9A AB
05:27:20.363 03 28 01 00 00 00 01 00 00 3D 7F
05:27:20.363 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:20.363 <-T-- fir: 1 fin: 1 seq: 13
05:27:20.363 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x08 IIN(NeedTime)
05:27:20.363 g51v2, 1-Byte Count, count: 1
05:27:20.363 time: 1793251636358
05:27:20.363 g2v3, 2-Byte Count and Index, count: 1
05:27:20.363 [0] - flags: 0x01 cto: 0
05:27:20.363 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
05:27:20.363 --T-> fir: 1 fin: 1 seq: 0
05:27:20.363 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:20.364 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:20.364 C0 C8 00 A7 F9
05:27:20.364 [PLG] Verified that the response retains the 1 earlier event(s)
05:27:20.364 step: 9
05:27:20.364 - Expect no unsolicited response within 2500 ms.
05:27:22.867 8.11.2.5.8
05:27:22.867 - Inhibits Unsolicited responses until after polled confirmation
05:27:22.867 steps: 1 to 11
05:27:22.867 - 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:22.867 8.11.2.5.9
05:27:22.867 - Retries unsolicited responses configurable number of times
05:27:22.867 step: 1
05:27:22.867 - Set the DUT's unsolicited retry limit to 5
05:27:22.867 [PLG] Max unsolicited response retries was set to 5
05:27:22.867 [PLG] Cycling power
05:27:22.868 step: 2
05:27:22.868 - Cycle power to the DUT
05:27:22.868 [PLG] Cycling power
05:27:22.869 step: 3
05:27:22.869 - If an unsolicited NULL response is received, confirm it
05:27:22.870 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:22.870 C0 F0 82 90 00 43 A2
05:27:22.870 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:22.870 <-T-- fir: 1 fin: 1 seq: 0
05:27:22.870 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:22.870 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:22.870 --T-> fir: 1 fin: 1 seq: 0
05:27:22.870 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:22.870 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:22.870 C0 D0 00 1B 49
05:27:22.870 step: 4
05:27:22.870 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
05:27:22.870 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:22.870 - [7] - BooleanValue(false)
05:27:22.870 - Read a single fragment response w/ sequence(0x09)
05:27:22.870 - Verify that the response is empty
05:27:22.870 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:22.870 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
05:27:22.870 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:22.870 [7] - BooleanValue(false)
05:27:22.871 --T-> fir: 1 fin: 1 seq: 0
05:27:22.871 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:22.871 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:22.871 C0 C9 02 50 01 00 07 07 00 AE EF
05:27:22.871 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:22.871 C1 C9 81 10 00 75 23
05:27:22.871 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:22.871 <-T-- fir: 1 fin: 1 seq: 1
05:27:22.871 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09 IIN(NeedTime)
05:27:22.871 step: 5
05:27:22.871 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
05:27:22.871 - g60v2, All Objects
05:27:22.871 - g60v3, All Objects
05:27:22.871 - g60v4, All Objects
05:27:22.871 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
05:27:22.871 g60v2, All Objects
05:27:22.871 g60v3, All Objects
05:27:22.871 g60v4, All Objects
05:27:22.871 --T-> fir: 1 fin: 1 seq: 0
05:27:22.871 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:22.871 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:22.871 C0 CA 14 3C 02 06 3C 03 06 3C 04 06 14 0F
05:27:22.871 step: 6
05:27:22.871 - Read a single fragment response w/ sequence(0x0A)
05:27:22.871 - Verify that the response is empty
05:27:22.871 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:22.872 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:22.872 C2 CA 81 10 00 3E 4C
05:27:22.872 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:22.872 <-T-- fir: 1 fin: 1 seq: 2
05:27:22.872 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A IIN(NeedTime)
05:27:22.872 step: 7
05:27:22.872 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:22.872 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251642872 (UNSYNCHRONIZED_TIME)
05:27:22.872 steps: 8 to 9
05:27:22.872 - Read an unsolicited response
05:27:22.872 - Verify that the response contains events
05:27:22.872 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:22.872 C3 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 0A 13
05:27:22.872 03 28 01 00 00 00 81 00 00 9A 2A
05:27:22.872 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:22.872 <-T-- fir: 1 fin: 1 seq: 3
05:27:22.872 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:22.872 g51v2, 1-Byte Count, count: 1
05:27:22.872 time: 1793251642872
05:27:22.872 g2v3, 2-Byte Count and Index, count: 1
05:27:22.872 [0] - flags: 0x81 cto: 0
05:27:22.873 step: 10
05:27:22.873 - Read an unsolicited response
05:27:22.873 - Repeat these steps 5 times
05:27:22.873 - Expect no unsolicited response within 2500 ms.
05:27:22.873 Repeat 1 of 5
05:27:24.873 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:24.873 C4 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 D7 BE
05:27:24.873 03 28 01 00 00 00 81 00 00 9A 2A
05:27:24.873 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:24.873 <-T-- fir: 1 fin: 1 seq: 4
05:27:24.874 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:24.874 g51v2, 1-Byte Count, count: 1
05:27:24.874 time: 1793251642872
05:27:24.874 g2v3, 2-Byte Count and Index, count: 1
05:27:24.874 [0] - flags: 0x81 cto: 0
05:27:24.874 Repeat 2 of 5
05:27:26.875 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:26.875 C5 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 5B F3
05:27:26.875 03 28 01 00 00 00 81 00 00 9A 2A
05:27:26.875 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:26.875 <-T-- fir: 1 fin: 1 seq: 5
05:27:26.875 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:26.875 g51v2, 1-Byte Count, count: 1
05:27:26.875 time: 1793251642872
05:27:26.875 g2v3, 2-Byte Count and Index, count: 1
05:27:26.875 [0] - flags: 0x81 cto: 0
05:27:26.875 Repeat 3 of 5
05:27:28.876 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:28.876 C6 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 CF 25
05:27:28.876 03 28 01 00 00 00 81 00 00 9A 2A
05:27:28.876 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:28.876 <-T-- fir: 1 fin: 1 seq: 6
05:27:28.876 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:28.876 g51v2, 1-Byte Count, count: 1
05:27:28.876 time: 1793251642872
05:27:28.876 g2v3, 2-Byte Count and Index, count: 1
05:27:28.876 [0] - flags: 0x81 cto: 0
05:27:28.876 Repeat 4 of 5
05:27:30.877 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:30.877 C7 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 43 68
05:27:30.877 03 28 01 00 00 00 81 00 00 9A 2A
05:27:30.877 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:30.877 <-T-- fir: 1 fin: 1 seq: 7
05:27:30.878 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:30.878 g51v2, 1-Byte Count, count: 1
05:27:30.878 time: 1793251642872
05:27:30.878 g2v3, 2-Byte Count and Index, count: 1
05:27:30.878 [0] - flags: 0x81 cto: 0
05:27:30.878 Repeat 5 of 5
05:27:32.878 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:32.878 C8 F1 82 10 00 33 02 07 01 F8 8D 20 86 A1 01 02 0C 33
05:27:32.878 03 28 01 00 00 00 81 00 00 9A 2A
05:27:32.878 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:32.878 <-T-- fir: 1 fin: 1 seq: 8
05:27:32.879 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:32.879 g51v2, 1-Byte Count, count: 1
05:27:32.879 time: 1793251642872
05:27:32.879 g2v3, 2-Byte Count and Index, count: 1
05:27:32.879 [0] - flags: 0x81 cto: 0
05:27:35.382 step: 11
05:27:35.382 - Cycle power to the DUT
05:27:35.382 [PLG] Cycling power
05:27:35.383 step: 12
05:27:35.383 - If an unsolicited NULL response is received, confirm it
05:27:35.383 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:35.383 C0 F0 82 90 00 43 A2
05:27:35.383 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:35.383 <-T-- fir: 1 fin: 1 seq: 0
05:27:35.383 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:35.384 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:35.384 --T-> fir: 1 fin: 1 seq: 0
05:27:35.384 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:35.384 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:35.384 C0 D0 00 1B 49
05:27:35.384 step: 13
05:27:35.384 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
05:27:35.384 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:35.384 - [7] - BooleanValue(false)
05:27:35.384 - Read a single fragment response w/ sequence(0x0B)
05:27:35.384 - Verify that the response is empty
05:27:35.384 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:35.384 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
05:27:35.384 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:35.384 [7] - BooleanValue(false)
05:27:35.384 --T-> fir: 1 fin: 1 seq: 0
05:27:35.384 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:35.384 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:35.384 C0 CB 02 50 01 00 07 07 00 8A C3
05:27:35.384 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:35.384 C1 CB 81 10 00 DC EB
05:27:35.384 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:35.384 <-T-- fir: 1 fin: 1 seq: 1
05:27:35.384 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B IIN(NeedTime)
05:27:35.385 step: 14
05:27:35.385 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
05:27:35.385 - g60v2, All Objects
05:27:35.385 - g60v3, All Objects
05:27:35.385 - g60v4, All Objects
05:27:35.385 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
05:27:35.385 g60v2, All Objects
05:27:35.385 g60v3, All Objects
05:27:35.385 g60v4, All Objects
05:27:35.385 --T-> fir: 1 fin: 1 seq: 0
05:27:35.385 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:35.385 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:35.385 C0 CC 14 3C 02 06 3C 03 06 3C 04 06 30 78
05:27:35.385 step: 15
05:27:35.385 - Read a single fragment response w/ sequence(0x0C)
05:27:35.385 - Verify that the response is empty
05:27:35.385 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:35.385 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:35.385 C2 CC 81 10 00 BC 58
05:27:35.385 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:35.385 <-T-- fir: 1 fin: 1 seq: 2
05:27:35.385 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C IIN(NeedTime)
05:27:35.385 steps: 16 to 17
05:27:35.385 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:35.385 - Read an unsolicited response
05:27:35.385 - Verify that the response contains events
05:27:35.386 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251655385 (UNSYNCHRONIZED_TIME)
05:27:35.387 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:35.387 C3 F1 82 10 00 33 02 07 01 D9 BE 20 86 A1 01 02 8A 9C
05:27:35.387 03 28 01 00 00 00 81 00 00 9A 2A
05:27:35.387 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:35.387 <-T-- fir: 1 fin: 1 seq: 3
05:27:35.387 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:35.387 g51v2, 1-Byte Count, count: 1
05:27:35.387 time: 1793251655385
05:27:35.387 g2v3, 2-Byte Count and Index, count: 1
05:27:35.387 [0] - flags: 0x81 cto: 0
05:27:35.387 step: 18
05:27:35.387 - Read an unsolicited response
05:27:35.387 - Confirm each response that asks for a confirmation
05:27:35.387 - Expect no unsolicited response within 2500 ms.
05:27:37.387 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:37.387 C4 F1 82 10 00 33 02 07 01 D9 BE 20 86 A1 01 02 57 31
05:27:37.387 03 28 01 00 00 00 81 00 00 9A 2A
05:27:37.387 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:37.387 <-T-- fir: 1 fin: 1 seq: 4
05:27:37.387 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:37.387 g51v2, 1-Byte Count, count: 1
05:27:37.387 time: 1793251655385
05:27:37.387 g2v3, 2-Byte Count and Index, count: 1
05:27:37.387 [0] - flags: 0x81 cto: 0
05:27:37.387 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
05:27:37.387 --T-> fir: 1 fin: 1 seq: 0
05:27:37.387 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:37.387 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:37.387 C0 D1 00 55 E2
05:27:39.890 step: 19
05:27:39.890 - Set the DUT's unsolicited retry limit to indefinite
05:27:39.890 [PLG] Max unsolicited response retries was set to Infinite
05:27:39.890 [PLG] Cycling power
05:27:39.891 step: 20
05:27:39.891 - Cycle power to the DUT
05:27:39.891 [PLG] Cycling power
05:27:39.892 step: 21
05:27:39.892 - If an unsolicited NULL response is received, confirm it
05:27:39.893 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:39.893 C0 F0 82 90 00 43 A2
05:27:39.893 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:39.893 <-T-- fir: 1 fin: 1 seq: 0
05:27:39.893 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
05:27:39.893 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
05:27:39.893 --T-> fir: 1 fin: 1 seq: 0
05:27:39.893 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
05:27:39.893 --P-> 05 64 08 C4 00 04 01 00 9A 19
05:27:39.893 C0 D0 00 1B 49
05:27:39.893 step: 22
05:27:39.893 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
05:27:39.893 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:39.893 - [7] - BooleanValue(false)
05:27:39.893 - Read a single fragment response w/ sequence(0x0D)
05:27:39.893 - Verify that the response is empty
05:27:39.893 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:39.893 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
05:27:39.893 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
05:27:39.893 [7] - BooleanValue(false)
05:27:39.893 --T-> fir: 1 fin: 1 seq: 0
05:27:39.893 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
05:27:39.893 --P-> 05 64 0E C4 00 04 01 00 43 72
05:27:39.893 C0 CD 02 50 01 00 07 07 00 E6 B7
05:27:39.894 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:39.894 C1 CD 81 10 00 5E FF
05:27:39.894 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:39.894 <-T-- fir: 1 fin: 1 seq: 1
05:27:39.894 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D IIN(NeedTime)
05:27:39.894 step: 23
05:27:39.894 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
05:27:39.894 - g60v2, All Objects
05:27:39.894 - g60v3, All Objects
05:27:39.894 - g60v4, All Objects
05:27:39.894 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
05:27:39.894 g60v2, All Objects
05:27:39.894 g60v3, All Objects
05:27:39.894 g60v4, All Objects
05:27:39.894 --T-> fir: 1 fin: 1 seq: 0
05:27:39.894 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
05:27:39.894 --P-> 05 64 11 C4 00 04 01 00 60 4E
05:27:39.894 C0 CE 14 3C 02 06 3C 03 06 3C 04 06 2C 55
05:27:39.894 step: 24
05:27:39.894 - Read a single fragment response w/ sequence(0x0E)
05:27:39.894 - Verify that the response is empty
05:27:39.894 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
05:27:39.894 <-P-- 05 64 0A 44 01 00 00 04 67 88
05:27:39.894 C2 CE 81 10 00 15 90
05:27:39.894 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
05:27:39.894 <-T-- fir: 1 fin: 1 seq: 2
05:27:39.895 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E IIN(NeedTime)
05:27:39.895 step: 25
05:27:39.895 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
05:27:39.895 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793251659895 (UNSYNCHRONIZED_TIME)
05:27:39.895 steps: 26 to 27
05:27:39.895 - Read an unsolicited response
05:27:39.895 - Verify that the response contains events
05:27:39.895 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:39.895 C3 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 19 EB
05:27:39.895 03 28 01 00 00 00 81 00 00 9A 2A
05:27:39.895 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:39.895 <-T-- fir: 1 fin: 1 seq: 3
05:27:39.895 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:39.895 g51v2, 1-Byte Count, count: 1
05:27:39.895 time: 1793251659895
05:27:39.895 g2v3, 2-Byte Count and Index, count: 1
05:27:39.895 [0] - flags: 0x81 cto: 0
05:27:39.895 step: 28
05:27:39.895 - Read an unsolicited response
05:27:39.895 - Repeat these steps 7 times
05:27:39.895 Repeat 1 of 7
05:27:41.896 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:41.896 C4 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 C4 46
05:27:41.896 03 28 01 00 00 00 81 00 00 9A 2A
05:27:41.896 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:41.896 <-T-- fir: 1 fin: 1 seq: 4
05:27:41.897 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:41.897 g51v2, 1-Byte Count, count: 1
05:27:41.897 time: 1793251659895
05:27:41.897 g2v3, 2-Byte Count and Index, count: 1
05:27:41.897 [0] - flags: 0x81 cto: 0
05:27:41.897 Repeat 2 of 7
05:27:43.898 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:43.898 C5 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 48 0B
05:27:43.898 03 28 01 00 00 00 81 00 00 9A 2A
05:27:43.898 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:43.898 <-T-- fir: 1 fin: 1 seq: 5
05:27:43.898 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:43.898 g51v2, 1-Byte Count, count: 1
05:27:43.898 time: 1793251659895
05:27:43.898 g2v3, 2-Byte Count and Index, count: 1
05:27:43.898 [0] - flags: 0x81 cto: 0
05:27:43.898 Repeat 3 of 7
05:27:45.899 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:45.899 C6 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 DC DD
05:27:45.899 03 28 01 00 00 00 81 00 00 9A 2A
05:27:45.899 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:45.899 <-T-- fir: 1 fin: 1 seq: 6
05:27:45.899 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:45.899 g51v2, 1-Byte Count, count: 1
05:27:45.899 time: 1793251659895
05:27:45.899 g2v3, 2-Byte Count and Index, count: 1
05:27:45.899 [0] - flags: 0x81 cto: 0
05:27:45.899 Repeat 4 of 7
05:27:47.899 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:47.899 C7 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 50 90
05:27:47.899 03 28 01 00 00 00 81 00 00 9A 2A
05:27:47.899 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:47.899 <-T-- fir: 1 fin: 1 seq: 7
05:27:47.899 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:47.899 g51v2, 1-Byte Count, count: 1
05:27:47.899 time: 1793251659895
05:27:47.899 g2v3, 2-Byte Count and Index, count: 1
05:27:47.899 [0] - flags: 0x81 cto: 0
05:27:47.900 Repeat 5 of 7
05:27:49.900 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:49.900 C8 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 1F CB
05:27:49.900 03 28 01 00 00 00 81 00 00 9A 2A
05:27:49.901 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:49.901 <-T-- fir: 1 fin: 1 seq: 8
05:27:49.901 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:49.901 g51v2, 1-Byte Count, count: 1
05:27:49.901 time: 1793251659895
05:27:49.901 g2v3, 2-Byte Count and Index, count: 1
05:27:49.901 [0] - flags: 0x81 cto: 0
05:27:49.901 Repeat 6 of 7
05:27:51.902 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:51.902 C9 F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 93 86
05:27:51.902 03 28 01 00 00 00 81 00 00 9A 2A
05:27:51.902 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:51.902 <-T-- fir: 1 fin: 1 seq: 9
05:27:51.902 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:51.902 g51v2, 1-Byte Count, count: 1
05:27:51.902 time: 1793251659895
05:27:51.902 g2v3, 2-Byte Count and Index, count: 1
05:27:51.902 [0] - flags: 0x81 cto: 0
05:27:51.902 Repeat 7 of 7
05:27:53.903 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
05:27:53.903 CA F1 82 10 00 33 02 07 01 77 D0 20 86 A1 01 02 07 50
05:27:53.903 03 28 01 00 00 00 81 00 00 9A 2A
05:27:53.903 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
05:27:53.903 <-T-- fir: 1 fin: 1 seq: 10
05:27:53.903 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
05:27:53.903 g51v2, 1-Byte Count, count: 1
05:27:53.903 time: 1793251659895
05:27:53.903 g2v3, 2-Byte Count and Index, count: 1
05:27:53.903 [0] - flags: 0x81 cto: 0
05:27:53.903 Passed procedure: 8.11.2.5