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

Device under test: Step Function I/O dnp3 outstation

10:58:42.053 Running procedure: 8.11.2.5
10:58:42.056 This procedure is enabled because the following options:
10:58:42.056 - supports.unsolicitedResponse == true
10:58:42.056 step: 0
10:58:42.056 - Configure the unsolicited response mode to 'true'
10:58:42.056 - Cycle power to the DUT
10:58:42.056 - If an unsolicited NULL response is received, confirm it
10:58:42.056 [PLG] Unsolicited responses were enabled
10:58:42.056 [PLG] Cycling power
10:58:42.057 [PLG] Cycling power
10:58:42.059 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:42.059 C0 F0 82 90 00 43 A2
10:58:42.059 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:42.059 <-T-- fir: 1 fin: 1 seq: 0
10:58:42.060 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:42.060 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:58:42.060 --T-> fir: 1 fin: 1 seq: 0
10:58:42.060 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:42.060 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:42.060 C0 D0 00 1B 49
10:58:42.060 8.11.2.5.1
10:58:42.060 - Transmits data-filled unsolicited responses
10:58:42.060 step: 1
10:58:42.060 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:42.060 - g60v2, All Objects
10:58:42.060 - g60v3, All Objects
10:58:42.060 - g60v4, All Objects
10:58:42.060 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:42.060 g60v2, All Objects
10:58:42.060 g60v3, All Objects
10:58:42.060 g60v4, All Objects
10:58:42.060 --T-> fir: 1 fin: 1 seq: 0
10:58:42.060 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:42.061 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:42.061 C0 C0 14 3C 02 06 3C 03 06 3C 04 06 78 96
10:58:42.061 step: 2
10:58:42.061 - Read a single fragment response w/ sequence(0x00)
10:58:42.061 - Verify that the response is empty
10:58:42.061 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:42.061 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:42.061 C1 C0 81 90 00 9D 0F
10:58:42.061 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:42.061 <-T-- fir: 1 fin: 1 seq: 1
10:58:42.061 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:42.061 steps: 3 to 5
10:58:42.061 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:42.061 - Read an unsolicited response
10:58:42.061 - Verify that the response contains events
10:58:42.061 - Confirm each response that asks for a confirmation
10:58:42.061 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185122061 (UNSYNCHRONIZED_TIME)
10:58:42.062 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:42.062 C2 F1 82 90 00 33 02 07 01 0D 87 29 82 A1 01 02 34 DA
10:58:42.062 03 28 01 00 00 00 81 00 00 9A 2A
10:58:42.062 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:42.062 <-T-- fir: 1 fin: 1 seq: 2
10:58:42.062 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:42.062 g51v2, 1-Byte Count, count: 1
10:58:42.062 time: 1793185122061
10:58:42.062 g2v3, 2-Byte Count and Index, count: 1
10:58:42.062 [0] - flags: 0x81 cto: 0
10:58:42.062 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
10:58:42.062 --T-> fir: 1 fin: 1 seq: 0
10:58:42.062 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:42.062 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:42.062 C0 D1 00 55 E2
10:58:42.062 8.11.2.5.2
10:58:42.062 - Clears transmitted data upon confirmation
10:58:42.063 step: 1
10:58:42.063 - The response in 8.11.2.5.1 was confirmed when it arrived
10:58:42.063 step: 2
10:58:42.063 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:42.063 - g60v2, All Objects
10:58:42.063 - g60v3, All Objects
10:58:42.063 - g60v4, All Objects
10:58:42.063 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:42.063 g60v2, All Objects
10:58:42.063 g60v3, All Objects
10:58:42.063 g60v4, All Objects
10:58:42.063 --T-> fir: 1 fin: 1 seq: 0
10:58:42.063 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:42.063 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:42.063 C0 C1 01 3C 02 06 3C 03 06 3C 04 06 10 26
10:58:42.063 step: 3
10:58:42.063 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
10:58:42.063 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
10:58:42.063 - Confirm each response that asks for a confirmation
10:58:42.063 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:42.063 C3 C1 81 90 00 79 8B
10:58:42.063 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:42.063 <-T-- fir: 1 fin: 1 seq: 3
10:58:42.063 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:42.064 [PLG] Verified 1 Class 1, 2 and 3 event(s)
10:58:42.064 8.11.2.5.3
10:58:42.064 - Processes non-READ requests immediately
10:58:42.064 step: 1
10:58:42.064 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:42.064 - Read an unsolicited response
10:58:42.064 - Verify that the response contains events
10:58:42.064 - Leave the response unconfirmed for now
10:58:42.064 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793185122064 (UNSYNCHRONIZED_TIME)
10:58:42.065 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:42.065 C4 F2 82 90 00 33 02 07 01 10 87 29 82 A1 01 02 F8 45
10:58:42.065 03 28 01 00 00 00 01 00 00 3D 7F
10:58:42.065 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:42.065 <-T-- fir: 1 fin: 1 seq: 4
10:58:42.065 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:58:42.065 g51v2, 1-Byte Count, count: 1
10:58:42.065 time: 1793185122064
10:58:42.065 g2v3, 2-Byte Count and Index, count: 1
10:58:42.065 [0] - flags: 0x01 cto: 0
10:58:42.065 step: 2
10:58:42.065 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:58:42.065 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:42.065 - [7] - BooleanValue(false)
10:58:42.065 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:58:42.065 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:42.065 [7] - BooleanValue(false)
10:58:42.065 --T-> fir: 1 fin: 1 seq: 0
10:58:42.065 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:58:42.065 --P-> 05 64 0E C4 00 04 01 00 43 72
10:58:42.065 C0 C2 02 50 01 00 07 07 00 08 65
10:58:42.065 step: 3
10:58:42.065 - Read a single fragment response w/ sequence(0x02)
10:58:42.065 - Verify that the response is empty
10:58:42.065 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:42.065 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:42.065 C5 C2 81 10 00 03 50
10:58:42.065 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:42.065 <-T-- fir: 1 fin: 1 seq: 5
10:58:42.065 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(NeedTime)
10:58:42.065 steps: 4 to 5
10:58:42.065 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:58:42.065 - Verify that this response contains every event the previous response reported.
10:58:44.067 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:44.067 C6 F2 82 90 00 33 02 07 01 10 87 29 82 A1 01 02 E0 DE
10:58:44.067 03 28 01 00 00 00 01 00 00 3D 7F
10:58:44.067 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:44.067 <-T-- fir: 1 fin: 1 seq: 6
10:58:44.067 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:58:44.067 g51v2, 1-Byte Count, count: 1
10:58:44.067 time: 1793185122064
10:58:44.067 g2v3, 2-Byte Count and Index, count: 1
10:58:44.067 [0] - flags: 0x01 cto: 0
10:58:44.067 [PLG] Verified that the response retains the 1 earlier event(s)
10:58:44.067 8.11.2.5.4
10:58:44.067 - Defers READ requests until after confirmation received
10:58:44.067 step: 1
10:58:44.067 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:58:44.067 - g60v2, All Objects
10:58:44.067 - g60v3, All Objects
10:58:44.067 - g60v4, All Objects
10:58:44.067 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:58:44.067 g60v2, All Objects
10:58:44.067 g60v3, All Objects
10:58:44.067 g60v4, All Objects
10:58:44.067 --T-> fir: 1 fin: 1 seq: 0
10:58:44.068 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:44.068 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:44.068 C0 C3 01 3C 02 06 3C 03 06 3C 04 06 0C 0B
10:58:44.068 step: 2
10:58:44.068 - Expect no message until 1000 ms before the confirm timeout
10:58:45.068 step: 3
10:58:45.068 - Confirm the response
10:58:45.068 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
10:58:45.068 --T-> fir: 1 fin: 1 seq: 0
10:58:45.068 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:45.068 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:45.068 C0 D2 00 FE 52
10:58:45.069 steps: 4 to 6
10:58:45.069 - Read any number of valid responses beginning w/ sequence(0x03) and incrementing by 1 modulo 16
10:58:45.069 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
10:58:45.069 - Confirm each response that asks for a confirmation
10:58:45.069 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:45.069 C7 C3 81 10 00 E7 D4
10:58:45.069 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:45.069 <-T-- fir: 1 fin: 1 seq: 7
10:58:45.069 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(NeedTime)
10:58:45.069 [PLG] Verified 1 Class 1, 2 and 3 event(s)
10:58:45.069 8.11.2.5.5
10:58:45.069 - Defers READ requests until after confirmation timeout
10:58:45.069 step: 1
10:58:45.069 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:45.069 - Read an unsolicited response
10:58:45.069 - Verify that the response contains events
10:58:45.069 - Leave the response unconfirmed for now
10:58:45.069 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185125069 (UNSYNCHRONIZED_TIME)
10:58:45.070 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:45.070 C8 F3 82 10 00 33 02 07 01 CD 92 29 82 A1 01 02 DD 1F
10:58:45.070 03 28 01 00 00 00 81 00 00 9A 2A
10:58:45.070 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:45.070 <-T-- fir: 1 fin: 1 seq: 8
10:58:45.070 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x03 IIN(NeedTime)
10:58:45.070 g51v2, 1-Byte Count, count: 1
10:58:45.070 time: 1793185125069
10:58:45.070 g2v3, 2-Byte Count and Index, count: 1
10:58:45.070 [0] - flags: 0x81 cto: 0
10:58:45.070 step: 2
10:58:45.070 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:58:45.070 - g60v2, All Objects
10:58:45.070 - g60v3, All Objects
10:58:45.070 - g60v4, All Objects
10:58:45.070 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:58:45.070 g60v2, All Objects
10:58:45.070 g60v3, All Objects
10:58:45.070 g60v4, All Objects
10:58:45.070 --T-> fir: 1 fin: 1 seq: 0
10:58:45.070 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:45.070 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:45.070 C0 C4 01 3C 02 06 3C 03 06 3C 04 06 A6 6A
10:58:45.070 steps: 3 to 7
10:58:45.070 - Expect no message until 500 ms before the confirm timeout
10:58:45.070 - Wait for the confirm timeout to expire
10:58:45.070 - Read any number of valid responses beginning w/ sequence(0x04) and incrementing by 1 modulo 16
10:58:45.070 - Verify that an application layer confirmation is requested.
10:58:45.070 - Verify that this response contains every event the previous response reported.
10:58:45.070 - Confirm each response that asks for a confirmation
10:58:47.072 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:47.072 C9 E4 81 10 00 33 02 07 01 CD 92 29 82 A1 01 02 9F 55
10:58:47.072 03 28 01 00 00 00 81 00 00 9A 2A
10:58:47.072 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:47.072 <-T-- fir: 1 fin: 1 seq: 9
10:58:47.072 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(NeedTime)
10:58:47.072 g51v2, 1-Byte Count, count: 1
10:58:47.072 time: 1793185125069
10:58:47.072 g2v3, 2-Byte Count and Index, count: 1
10:58:47.072 [0] - flags: 0x81 cto: 0
10:58:47.072 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:58:47.072 --T-> fir: 1 fin: 1 seq: 0
10:58:47.072 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:47.072 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:47.072 C0 C4 00 F9 A1
10:58:47.072 [PLG] Verified that the response retains the 1 earlier event(s)
10:58:47.072 step: 8
10:58:47.072 - Expect no unsolicited response within 2500 ms.
10:58:49.575 8.11.2.5.6
10:58:49.575 - Abandons READ requests upon subsequent non-READ requests
10:58:49.575 step: 1
10:58:49.575 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:49.575 - Read an unsolicited response
10:58:49.575 - Verify that the response contains events
10:58:49.575 - Leave the response unconfirmed for now
10:58:49.575 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793185129575 (UNSYNCHRONIZED_TIME)
10:58:49.576 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:49.576 CA F4 82 10 00 33 02 07 01 67 A4 29 82 A1 01 02 F6 C8
10:58:49.576 03 28 01 00 00 00 01 00 00 3D 7F
10:58:49.576 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:49.576 <-T-- fir: 1 fin: 1 seq: 10
10:58:49.576 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
10:58:49.576 g51v2, 1-Byte Count, count: 1
10:58:49.576 time: 1793185129575
10:58:49.576 g2v3, 2-Byte Count and Index, count: 1
10:58:49.576 [0] - flags: 0x01 cto: 0
10:58:49.576 step: 2
10:58:49.576 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
10:58:49.576 - g60v2, All Objects
10:58:49.576 - g60v3, All Objects
10:58:49.576 - g60v4, All Objects
10:58:49.576 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
10:58:49.576 g60v2, All Objects
10:58:49.576 g60v3, All Objects
10:58:49.576 g60v4, All Objects
10:58:49.576 --T-> fir: 1 fin: 1 seq: 0
10:58:49.576 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:49.576 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:49.576 C0 C5 01 3C 02 06 3C 03 06 3C 04 06 28 7C
10:58:49.577 step: 3
10:58:49.577 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
10:58:49.577 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:49.577 - [7] - BooleanValue(false)
10:58:49.577 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
10:58:49.577 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:49.577 [7] - BooleanValue(false)
10:58:49.577 --T-> fir: 1 fin: 1 seq: 0
10:58:49.577 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:58:49.577 --P-> 05 64 0E C4 00 04 01 00 43 72
10:58:49.577 C0 C6 02 50 01 00 07 07 00 40 3D
10:58:49.577 step: 4
10:58:49.577 - Read a single fragment response w/ sequence(0x06)
10:58:49.577 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:49.577 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:49.577 CB C6 81 10 00 75 13
10:58:49.577 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:49.577 <-T-- fir: 1 fin: 1 seq: 11
10:58:49.577 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06 IIN(NeedTime)
10:58:49.577 steps: 5 to 6
10:58:49.577 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:58:49.577 - Verify that this response contains every event the previous response reported.
10:58:51.578 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:51.578 CC F4 82 10 00 33 02 07 01 67 A4 29 82 A1 01 02 A7 28
10:58:51.578 03 28 01 00 00 00 01 00 00 3D 7F
10:58:51.578 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:51.578 <-T-- fir: 1 fin: 1 seq: 12
10:58:51.579 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
10:58:51.579 g51v2, 1-Byte Count, count: 1
10:58:51.579 time: 1793185129575
10:58:51.579 g2v3, 2-Byte Count and Index, count: 1
10:58:51.579 [0] - flags: 0x01 cto: 0
10:58:51.579 [PLG] Verified that the response retains the 1 earlier event(s)
10:58:51.579 8.11.2.5.7
10:58:51.579 - Abandons READ requests upon subsequent READ requests
10:58:51.579 step: 1
10:58:51.579 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
10:58:51.579 - g60v1, All Objects
10:58:51.579 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
10:58:51.579 g60v1, All Objects
10:58:51.579 --T-> fir: 1 fin: 1 seq: 0
10:58:51.579 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:58:51.579 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:58:51.579 C0 C7 01 3C 01 06 ED B9
10:58:51.579 step: 2
10:58:51.579 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:58:51.579 - g60v2, All Objects
10:58:51.579 - g60v3, All Objects
10:58:51.579 - g60v4, All Objects
10:58:51.579 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:58:51.579 g60v2, All Objects
10:58:51.579 g60v3, All Objects
10:58:51.579 g60v4, All Objects
10:58:51.579 --T-> fir: 1 fin: 1 seq: 0
10:58:51.579 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:51.579 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:51.579 C0 C8 01 3C 02 06 3C 03 06 3C 04 06 EE 84
10:58:51.579 steps: 3 to 8
10:58:51.579 - Expect no message until 500 ms before the confirm timeout
10:58:51.579 - Wait for the confirm timeout to expire
10:58:51.579 - Read any number of valid responses beginning w/ sequence(0x08) and incrementing by 1 modulo 16
10:58:51.579 - Verify that the response only contains objects, variations, and qualifiers specified in table 8-4
10:58:51.579 - Verify that an application layer confirmation is requested.
10:58:51.579 - Verify that this response contains every event the previous response reported.
10:58:51.579 - Confirm each response that asks for a confirmation
10:58:53.581 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:53.581 CD E8 81 10 00 33 02 07 01 67 A4 29 82 A1 01 02 86 77
10:58:53.581 03 28 01 00 00 00 01 00 00 3D 7F
10:58:53.581 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:53.581 <-T-- fir: 1 fin: 1 seq: 13
10:58:53.581 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x08 IIN(NeedTime)
10:58:53.581 g51v2, 1-Byte Count, count: 1
10:58:53.581 time: 1793185129575
10:58:53.581 g2v3, 2-Byte Count and Index, count: 1
10:58:53.581 [0] - flags: 0x01 cto: 0
10:58:53.581 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:58:53.581 --T-> fir: 1 fin: 1 seq: 0
10:58:53.581 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:53.581 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:53.581 C0 C8 00 A7 F9
10:58:53.582 [PLG] Verified that the response retains the 1 earlier event(s)
10:58:53.582 step: 9
10:58:53.582 - Expect no unsolicited response within 2500 ms.
10:58:56.084 8.11.2.5.8
10:58:56.084 - Inhibits Unsolicited responses until after polled confirmation
10:58:56.084 steps: 1 to 11
10:58:56.084 - 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.
10:58:56.084 8.11.2.5.9
10:58:56.084 - Retries unsolicited responses configurable number of times
10:58:56.084 step: 1
10:58:56.084 - Set the DUT's unsolicited retry limit to 5
10:58:56.084 [PLG] Max unsolicited response retries was set to 5
10:58:56.084 [PLG] Cycling power
10:58:56.085 step: 2
10:58:56.085 - Cycle power to the DUT
10:58:56.086 [PLG] Cycling power
10:58:56.086 step: 3
10:58:56.086 - If an unsolicited NULL response is received, confirm it
10:58:56.087 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.087 C0 F0 82 90 00 43 A2
10:58:56.087 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.087 <-T-- fir: 1 fin: 1 seq: 0
10:58:56.087 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:56.087 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:58:56.087 --T-> fir: 1 fin: 1 seq: 0
10:58:56.087 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:56.087 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:56.087 C0 D0 00 1B 49
10:58:56.088 step: 4
10:58:56.088 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
10:58:56.088 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:56.088 - [7] - BooleanValue(false)
10:58:56.088 - Read a single fragment response w/ sequence(0x09)
10:58:56.088 - Verify that the response is empty
10:58:56.088 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:56.088 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
10:58:56.088 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:56.088 [7] - BooleanValue(false)
10:58:56.088 --T-> fir: 1 fin: 1 seq: 0
10:58:56.088 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:58:56.088 --P-> 05 64 0E C4 00 04 01 00 43 72
10:58:56.088 C0 C9 02 50 01 00 07 07 00 AE EF
10:58:56.088 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.088 C1 C9 81 10 00 75 23
10:58:56.088 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.088 <-T-- fir: 1 fin: 1 seq: 1
10:58:56.088 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09 IIN(NeedTime)
10:58:56.088 step: 5
10:58:56.088 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
10:58:56.088 - g60v2, All Objects
10:58:56.088 - g60v3, All Objects
10:58:56.088 - g60v4, All Objects
10:58:56.088 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
10:58:56.088 g60v2, All Objects
10:58:56.088 g60v3, All Objects
10:58:56.088 g60v4, All Objects
10:58:56.088 --T-> fir: 1 fin: 1 seq: 0
10:58:56.088 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:56.088 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:56.088 C0 CA 14 3C 02 06 3C 03 06 3C 04 06 14 0F
10:58:56.088 step: 6
10:58:56.088 - Read a single fragment response w/ sequence(0x0A)
10:58:56.088 - Verify that the response is empty
10:58:56.088 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:56.088 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.088 C2 CA 81 10 00 3E 4C
10:58:56.088 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.088 <-T-- fir: 1 fin: 1 seq: 2
10:58:56.088 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A IIN(NeedTime)
10:58:56.088 step: 7
10:58:56.088 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:56.089 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185136088 (UNSYNCHRONIZED_TIME)
10:58:56.089 steps: 8 to 9
10:58:56.089 - Read an unsolicited response
10:58:56.089 - Verify that the response contains events
10:58:56.089 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:56.089 C3 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 DC FA
10:58:56.089 03 28 01 00 00 00 81 00 00 9A 2A
10:58:56.089 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:56.089 <-T-- fir: 1 fin: 1 seq: 3
10:58:56.089 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:58:56.089 g51v2, 1-Byte Count, count: 1
10:58:56.089 time: 1793185136088
10:58:56.089 g2v3, 2-Byte Count and Index, count: 1
10:58:56.089 [0] - flags: 0x81 cto: 0
10:58:56.089 step: 10
10:58:56.089 - Read an unsolicited response
10:58:56.089 - Repeat these steps 5 times
10:58:56.089 - Expect no unsolicited response within 2500 ms.
10:58:56.089 Repeat 1 of 5
10:58:58.090 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:58.090 C4 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 01 57
10:58:58.090 03 28 01 00 00 00 81 00 00 9A 2A
10:58:58.090 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:58.090 <-T-- fir: 1 fin: 1 seq: 4
10:58:58.090 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:58:58.090 g51v2, 1-Byte Count, count: 1
10:58:58.090 time: 1793185136088
10:58:58.090 g2v3, 2-Byte Count and Index, count: 1
10:58:58.090 [0] - flags: 0x81 cto: 0
10:58:58.091 Repeat 2 of 5
10:59:00.091 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:00.091 C5 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 8D 1A
10:59:00.091 03 28 01 00 00 00 81 00 00 9A 2A
10:59:00.091 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:00.091 <-T-- fir: 1 fin: 1 seq: 5
10:59:00.092 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:00.092 g51v2, 1-Byte Count, count: 1
10:59:00.092 time: 1793185136088
10:59:00.092 g2v3, 2-Byte Count and Index, count: 1
10:59:00.092 [0] - flags: 0x81 cto: 0
10:59:00.092 Repeat 3 of 5
10:59:02.093 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:02.093 C6 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 19 CC
10:59:02.093 03 28 01 00 00 00 81 00 00 9A 2A
10:59:02.093 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:02.093 <-T-- fir: 1 fin: 1 seq: 6
10:59:02.093 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:02.093 g51v2, 1-Byte Count, count: 1
10:59:02.093 time: 1793185136088
10:59:02.093 g2v3, 2-Byte Count and Index, count: 1
10:59:02.093 [0] - flags: 0x81 cto: 0
10:59:02.093 Repeat 4 of 5
10:59:04.094 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:04.094 C7 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 95 81
10:59:04.094 03 28 01 00 00 00 81 00 00 9A 2A
10:59:04.094 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:04.094 <-T-- fir: 1 fin: 1 seq: 7
10:59:04.094 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:04.094 g51v2, 1-Byte Count, count: 1
10:59:04.094 time: 1793185136088
10:59:04.094 g2v3, 2-Byte Count and Index, count: 1
10:59:04.094 [0] - flags: 0x81 cto: 0
10:59:04.094 Repeat 5 of 5
10:59:06.095 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:06.095 C8 F1 82 10 00 33 02 07 01 D8 BD 29 82 A1 01 02 DA DA
10:59:06.095 03 28 01 00 00 00 81 00 00 9A 2A
10:59:06.095 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:06.095 <-T-- fir: 1 fin: 1 seq: 8
10:59:06.095 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:06.095 g51v2, 1-Byte Count, count: 1
10:59:06.095 time: 1793185136088
10:59:06.095 g2v3, 2-Byte Count and Index, count: 1
10:59:06.095 [0] - flags: 0x81 cto: 0
10:59:08.598 step: 11
10:59:08.598 - Cycle power to the DUT
10:59:08.598 [PLG] Cycling power
10:59:08.599 step: 12
10:59:08.599 - If an unsolicited NULL response is received, confirm it
10:59:08.600 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:08.600 C0 F0 82 90 00 43 A2
10:59:08.600 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:08.600 <-T-- fir: 1 fin: 1 seq: 0
10:59:08.600 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:59:08.600 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:59:08.600 --T-> fir: 1 fin: 1 seq: 0
10:59:08.600 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:08.600 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:08.600 C0 D0 00 1B 49
10:59:08.600 step: 13
10:59:08.600 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
10:59:08.600 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:08.600 - [7] - BooleanValue(false)
10:59:08.600 - Read a single fragment response w/ sequence(0x0B)
10:59:08.600 - Verify that the response is empty
10:59:08.600 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:08.600 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
10:59:08.600 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:08.600 [7] - BooleanValue(false)
10:59:08.600 --T-> fir: 1 fin: 1 seq: 0
10:59:08.600 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:08.600 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:08.600 C0 CB 02 50 01 00 07 07 00 8A C3
10:59:08.601 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:08.601 C1 CB 81 10 00 DC EB
10:59:08.601 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:08.601 <-T-- fir: 1 fin: 1 seq: 1
10:59:08.601 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B IIN(NeedTime)
10:59:08.601 step: 14
10:59:08.601 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
10:59:08.601 - g60v2, All Objects
10:59:08.601 - g60v3, All Objects
10:59:08.601 - g60v4, All Objects
10:59:08.601 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
10:59:08.601 g60v2, All Objects
10:59:08.601 g60v3, All Objects
10:59:08.601 g60v4, All Objects
10:59:08.601 --T-> fir: 1 fin: 1 seq: 0
10:59:08.601 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:08.601 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:08.601 C0 CC 14 3C 02 06 3C 03 06 3C 04 06 30 78
10:59:08.601 step: 15
10:59:08.601 - Read a single fragment response w/ sequence(0x0C)
10:59:08.601 - Verify that the response is empty
10:59:08.601 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:08.601 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:08.601 C2 CC 81 10 00 BC 58
10:59:08.601 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:08.601 <-T-- fir: 1 fin: 1 seq: 2
10:59:08.601 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C IIN(NeedTime)
10:59:08.601 steps: 16 to 17
10:59:08.601 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:08.601 - Read an unsolicited response
10:59:08.601 - Verify that the response contains events
10:59:08.601 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185148601 (UNSYNCHRONIZED_TIME)
10:59:08.601 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:08.601 C3 F1 82 10 00 33 02 07 01 B9 EE 29 82 A1 01 02 38 F2
10:59:08.601 03 28 01 00 00 00 81 00 00 9A 2A
10:59:08.601 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:08.602 <-T-- fir: 1 fin: 1 seq: 3
10:59:08.602 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:08.602 g51v2, 1-Byte Count, count: 1
10:59:08.602 time: 1793185148601
10:59:08.602 g2v3, 2-Byte Count and Index, count: 1
10:59:08.602 [0] - flags: 0x81 cto: 0
10:59:08.602 step: 18
10:59:08.602 - Read an unsolicited response
10:59:08.602 - Confirm each response that asks for a confirmation
10:59:08.602 - Expect no unsolicited response within 2500 ms.
10:59:10.603 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:10.603 C4 F1 82 10 00 33 02 07 01 B9 EE 29 82 A1 01 02 E5 5F
10:59:10.603 03 28 01 00 00 00 81 00 00 9A 2A
10:59:10.603 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:10.603 <-T-- fir: 1 fin: 1 seq: 4
10:59:10.603 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:10.603 g51v2, 1-Byte Count, count: 1
10:59:10.603 time: 1793185148601
10:59:10.603 g2v3, 2-Byte Count and Index, count: 1
10:59:10.603 [0] - flags: 0x81 cto: 0
10:59:10.603 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
10:59:10.603 --T-> fir: 1 fin: 1 seq: 0
10:59:10.603 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:10.603 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:10.603 C0 D1 00 55 E2
10:59:13.105 step: 19
10:59:13.105 - Set the DUT's unsolicited retry limit to indefinite
10:59:13.105 [PLG] Max unsolicited response retries was set to Infinite
10:59:13.105 [PLG] Cycling power
10:59:13.106 step: 20
10:59:13.106 - Cycle power to the DUT
10:59:13.106 [PLG] Cycling power
10:59:13.107 step: 21
10:59:13.107 - If an unsolicited NULL response is received, confirm it
10:59:13.108 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:13.108 C0 F0 82 90 00 43 A2
10:59:13.108 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:13.108 <-T-- fir: 1 fin: 1 seq: 0
10:59:13.108 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:59:13.108 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:59:13.108 --T-> fir: 1 fin: 1 seq: 0
10:59:13.108 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:13.108 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:13.108 C0 D0 00 1B 49
10:59:13.108 step: 22
10:59:13.108 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
10:59:13.108 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:13.108 - [7] - BooleanValue(false)
10:59:13.108 - Read a single fragment response w/ sequence(0x0D)
10:59:13.108 - Verify that the response is empty
10:59:13.108 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:13.108 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
10:59:13.108 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:13.108 [7] - BooleanValue(false)
10:59:13.108 --T-> fir: 1 fin: 1 seq: 0
10:59:13.108 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:13.108 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:13.108 C0 CD 02 50 01 00 07 07 00 E6 B7
10:59:13.108 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:13.108 C1 CD 81 10 00 5E FF
10:59:13.108 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:13.108 <-T-- fir: 1 fin: 1 seq: 1
10:59:13.109 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D IIN(NeedTime)
10:59:13.109 step: 23
10:59:13.109 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
10:59:13.109 - g60v2, All Objects
10:59:13.109 - g60v3, All Objects
10:59:13.109 - g60v4, All Objects
10:59:13.109 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
10:59:13.109 g60v2, All Objects
10:59:13.109 g60v3, All Objects
10:59:13.109 g60v4, All Objects
10:59:13.109 --T-> fir: 1 fin: 1 seq: 0
10:59:13.109 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:13.109 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:13.109 C0 CE 14 3C 02 06 3C 03 06 3C 04 06 2C 55
10:59:13.109 step: 24
10:59:13.109 - Read a single fragment response w/ sequence(0x0E)
10:59:13.109 - Verify that the response is empty
10:59:13.109 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:13.109 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:13.109 C2 CE 81 10 00 15 90
10:59:13.109 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:13.109 <-T-- fir: 1 fin: 1 seq: 2
10:59:13.109 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E IIN(NeedTime)
10:59:13.109 step: 25
10:59:13.109 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:13.109 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185153109 (UNSYNCHRONIZED_TIME)
10:59:13.109 steps: 26 to 27
10:59:13.109 - Read an unsolicited response
10:59:13.109 - Verify that the response contains events
10:59:13.109 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:13.109 C3 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 7D 97
10:59:13.109 03 28 01 00 00 00 81 00 00 9A 2A
10:59:13.109 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:13.109 <-T-- fir: 1 fin: 1 seq: 3
10:59:13.110 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:13.110 g51v2, 1-Byte Count, count: 1
10:59:13.110 time: 1793185153109
10:59:13.110 g2v3, 2-Byte Count and Index, count: 1
10:59:13.110 [0] - flags: 0x81 cto: 0
10:59:13.110 step: 28
10:59:13.110 - Read an unsolicited response
10:59:13.110 - Repeat these steps 7 times
10:59:13.110 Repeat 1 of 7
10:59:15.111 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:15.111 C4 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 A0 3A
10:59:15.111 03 28 01 00 00 00 81 00 00 9A 2A
10:59:15.111 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:15.111 <-T-- fir: 1 fin: 1 seq: 4
10:59:15.111 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:15.111 g51v2, 1-Byte Count, count: 1
10:59:15.111 time: 1793185153109
10:59:15.111 g2v3, 2-Byte Count and Index, count: 1
10:59:15.111 [0] - flags: 0x81 cto: 0
10:59:15.111 Repeat 2 of 7
10:59:17.112 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:17.112 C5 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 2C 77
10:59:17.112 03 28 01 00 00 00 81 00 00 9A 2A
10:59:17.112 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:17.112 <-T-- fir: 1 fin: 1 seq: 5
10:59:17.112 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:17.112 g51v2, 1-Byte Count, count: 1
10:59:17.112 time: 1793185153109
10:59:17.112 g2v3, 2-Byte Count and Index, count: 1
10:59:17.112 [0] - flags: 0x81 cto: 0
10:59:17.112 Repeat 3 of 7
10:59:19.113 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:19.113 C6 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 B8 A1
10:59:19.113 03 28 01 00 00 00 81 00 00 9A 2A
10:59:19.113 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:19.113 <-T-- fir: 1 fin: 1 seq: 6
10:59:19.113 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:19.113 g51v2, 1-Byte Count, count: 1
10:59:19.113 time: 1793185153109
10:59:19.113 g2v3, 2-Byte Count and Index, count: 1
10:59:19.113 [0] - flags: 0x81 cto: 0
10:59:19.113 Repeat 4 of 7
10:59:21.114 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:21.114 C7 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 34 EC
10:59:21.114 03 28 01 00 00 00 81 00 00 9A 2A
10:59:21.114 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:21.114 <-T-- fir: 1 fin: 1 seq: 7
10:59:21.115 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:21.115 g51v2, 1-Byte Count, count: 1
10:59:21.115 time: 1793185153109
10:59:21.115 g2v3, 2-Byte Count and Index, count: 1
10:59:21.115 [0] - flags: 0x81 cto: 0
10:59:21.115 Repeat 5 of 7
10:59:23.116 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:23.116 C8 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 7B B7
10:59:23.116 03 28 01 00 00 00 81 00 00 9A 2A
10:59:23.116 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:23.116 <-T-- fir: 1 fin: 1 seq: 8
10:59:23.116 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:23.116 g51v2, 1-Byte Count, count: 1
10:59:23.116 time: 1793185153109
10:59:23.116 g2v3, 2-Byte Count and Index, count: 1
10:59:23.116 [0] - flags: 0x81 cto: 0
10:59:23.116 Repeat 6 of 7
10:59:25.117 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:25.117 C9 F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 F7 FA
10:59:25.117 03 28 01 00 00 00 81 00 00 9A 2A
10:59:25.117 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:25.117 <-T-- fir: 1 fin: 1 seq: 9
10:59:25.117 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:25.117 g51v2, 1-Byte Count, count: 1
10:59:25.117 time: 1793185153109
10:59:25.117 g2v3, 2-Byte Count and Index, count: 1
10:59:25.117 [0] - flags: 0x81 cto: 0
10:59:25.117 Repeat 7 of 7
10:59:27.118 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:27.118 CA F1 82 10 00 33 02 07 01 55 00 2A 82 A1 01 02 63 2C
10:59:27.118 03 28 01 00 00 00 81 00 00 9A 2A
10:59:27.118 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:27.118 <-T-- fir: 1 fin: 1 seq: 10
10:59:27.118 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:27.118 g51v2, 1-Byte Count, count: 1
10:59:27.118 time: 1793185153109
10:59:27.118 g2v3, 2-Byte Count and Index, count: 1
10:59:27.118 [0] - flags: 0x81 cto: 0
10:59:27.118 Passed procedure: 8.11.2.5