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

Device under test: Step Function I/O dnp3 outstation

10:58:56.009 Running procedure: 8.11.2.5
10:58:56.012 This procedure is enabled because the following options:
10:58:56.012 - supports.unsolicitedResponse == true
10:58:56.012 step: 0
10:58:56.012 - Configure the unsolicited response mode to 'true'
10:58:56.012 - Cycle power to the DUT
10:58:56.012 - If an unsolicited NULL response is received, confirm it
10:58:56.012 [PLG] Unsolicited responses were enabled
10:58:56.012 [PLG] Cycling power
10:58:56.013 [PLG] Cycling power
10:58:56.014 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.014 C0 F0 82 90 00 43 A2
10:58:56.014 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.014 <-T-- fir: 1 fin: 1 seq: 0
10:58:56.014 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:56.014 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:58:56.014 --T-> fir: 1 fin: 1 seq: 0
10:58:56.014 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:56.014 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:56.014 C0 D0 00 1B 49
10:58:56.014 8.11.2.5.1
10:58:56.014 - Transmits data-filled unsolicited responses
10:58:56.014 step: 1
10:58:56.014 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:56.014 - g60v2, All Objects
10:58:56.014 - g60v3, All Objects
10:58:56.014 - g60v4, All Objects
10:58:56.015 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00
10:58:56.015 g60v2, All Objects
10:58:56.015 g60v3, All Objects
10:58:56.015 g60v4, All Objects
10:58:56.015 --T-> fir: 1 fin: 1 seq: 0
10:58:56.015 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:56.015 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:56.015 C0 C0 14 3C 02 06 3C 03 06 3C 04 06 78 96
10:58:56.015 step: 2
10:58:56.015 - Read a single fragment response w/ sequence(0x00)
10:58:56.015 - Verify that the response is empty
10:58:56.015 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:56.016 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.016 C1 C0 81 90 00 9D 0F
10:58:56.016 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.016 <-T-- fir: 1 fin: 1 seq: 1
10:58:56.016 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:58:56.016 steps: 3 to 5
10:58:56.016 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:56.016 - Read an unsolicited response
10:58:56.016 - Verify that the response contains events
10:58:56.016 - Confirm each response that asks for a confirmation
10:58:56.016 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185136016 (UNSYNCHRONIZED_TIME)
10:58:56.017 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:56.017 C2 F1 82 90 00 33 02 07 01 90 BD 29 82 A1 01 02 E6 DE
10:58:56.017 03 28 01 00 00 00 81 00 00 9A 2A
10:58:56.017 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:56.017 <-T-- fir: 1 fin: 1 seq: 2
10:58:56.017 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:56.017 g51v2, 1-Byte Count, count: 1
10:58:56.017 time: 1793185136016
10:58:56.017 g2v3, 2-Byte Count and Index, count: 1
10:58:56.017 [0] - flags: 0x81 cto: 0
10:58:56.017 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
10:58:56.017 --T-> fir: 1 fin: 1 seq: 0
10:58:56.017 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:56.017 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:56.017 C0 D1 00 55 E2
10:58:56.017 8.11.2.5.2
10:58:56.017 - Clears transmitted data upon confirmation
10:58:56.017 step: 1
10:58:56.017 - The response in 8.11.2.5.1 was confirmed when it arrived
10:58:56.017 step: 2
10:58:56.017 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:56.017 - g60v2, All Objects
10:58:56.017 - g60v3, All Objects
10:58:56.017 - g60v4, All Objects
10:58:56.017 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:58:56.017 g60v2, All Objects
10:58:56.017 g60v3, All Objects
10:58:56.017 g60v4, All Objects
10:58:56.017 --T-> fir: 1 fin: 1 seq: 0
10:58:56.017 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:56.017 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:56.017 C0 C1 01 3C 02 06 3C 03 06 3C 04 06 10 26
10:58:56.018 step: 3
10:58:56.018 - Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
10:58:56.018 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
10:58:56.018 - Confirm each response that asks for a confirmation
10:58:56.018 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.018 C3 C1 81 90 00 79 8B
10:58:56.018 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.018 <-T-- fir: 1 fin: 1 seq: 3
10:58:56.018 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:58:56.018 [PLG] Verified 1 Class 1, 2 and 3 event(s)
10:58:56.019 8.11.2.5.3
10:58:56.019 - Processes non-READ requests immediately
10:58:56.019 step: 1
10:58:56.019 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:56.019 - Read an unsolicited response
10:58:56.019 - Verify that the response contains events
10:58:56.019 - Leave the response unconfirmed for now
10:58:56.019 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793185136019 (UNSYNCHRONIZED_TIME)
10:58:56.019 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:56.019 C4 F2 82 90 00 33 02 07 01 93 BD 29 82 A1 01 02 39 6D
10:58:56.019 03 28 01 00 00 00 01 00 00 3D 7F
10:58:56.019 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:56.019 <-T-- fir: 1 fin: 1 seq: 4
10:58:56.019 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:58:56.019 g51v2, 1-Byte Count, count: 1
10:58:56.019 time: 1793185136019
10:58:56.019 g2v3, 2-Byte Count and Index, count: 1
10:58:56.019 [0] - flags: 0x01 cto: 0
10:58:56.020 step: 2
10:58:56.020 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:58:56.020 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:56.020 - [7] - BooleanValue(false)
10:58:56.020 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:58:56.020 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:58:56.020 [7] - BooleanValue(false)
10:58:56.020 --T-> fir: 1 fin: 1 seq: 0
10:58:56.020 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:58:56.020 --P-> 05 64 0E C4 00 04 01 00 43 72
10:58:56.020 C0 C2 02 50 01 00 07 07 00 08 65
10:58:56.020 step: 3
10:58:56.020 - Read a single fragment response w/ sequence(0x02)
10:58:56.020 - Verify that the response is empty
10:58:56.020 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:58:56.020 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:56.020 C5 C2 81 10 00 03 50
10:58:56.020 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:56.020 <-T-- fir: 1 fin: 1 seq: 5
10:58:56.020 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(NeedTime)
10:58:56.020 steps: 4 to 5
10:58:56.020 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:58:56.020 - Verify that this response contains every event the previous response reported.
10:58:58.020 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:58.020 C6 F2 82 90 00 33 02 07 01 93 BD 29 82 A1 01 02 21 F6
10:58:58.020 03 28 01 00 00 00 01 00 00 3D 7F
10:58:58.020 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:58.020 <-T-- fir: 1 fin: 1 seq: 6
10:58:58.021 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:58:58.021 g51v2, 1-Byte Count, count: 1
10:58:58.021 time: 1793185136019
10:58:58.021 g2v3, 2-Byte Count and Index, count: 1
10:58:58.021 [0] - flags: 0x01 cto: 0
10:58:58.021 [PLG] Verified that the response retains the 1 earlier event(s)
10:58:58.021 8.11.2.5.4
10:58:58.021 - Defers READ requests until after confirmation received
10:58:58.021 step: 1
10:58:58.021 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:58:58.021 - g60v2, All Objects
10:58:58.021 - g60v3, All Objects
10:58:58.021 - g60v4, All Objects
10:58:58.021 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:58:58.021 g60v2, All Objects
10:58:58.021 g60v3, All Objects
10:58:58.021 g60v4, All Objects
10:58:58.021 --T-> fir: 1 fin: 1 seq: 0
10:58:58.021 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:58.021 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:58.021 C0 C3 01 3C 02 06 3C 03 06 3C 04 06 0C 0B
10:58:58.022 step: 2
10:58:58.022 - Expect no message until 1000 ms before the confirm timeout
10:58:59.022 step: 3
10:58:59.022 - Confirm the response
10:58:59.022 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x02
10:58:59.022 --T-> fir: 1 fin: 1 seq: 0
10:58:59.022 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:58:59.022 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:58:59.022 C0 D2 00 FE 52
10:58:59.022 steps: 4 to 6
10:58:59.022 - Read any number of valid responses beginning w/ sequence(0x03) and incrementing by 1 modulo 16
10:58:59.022 - Verify that the polled response repeats none of the events already sent in the unsolicited response.
10:58:59.022 - Confirm each response that asks for a confirmation
10:58:59.022 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:58:59.022 C7 C3 81 10 00 E7 D4
10:58:59.023 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:58:59.023 <-T-- fir: 1 fin: 1 seq: 7
10:58:59.023 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03 IIN(NeedTime)
10:58:59.023 [PLG] Verified 1 Class 1, 2 and 3 event(s)
10:58:59.023 8.11.2.5.5
10:58:59.023 - Defers READ requests until after confirmation timeout
10:58:59.023 step: 1
10:58:59.023 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:58:59.023 - Read an unsolicited response
10:58:59.023 - Verify that the response contains events
10:58:59.023 - Leave the response unconfirmed for now
10:58:59.023 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185139023 (UNSYNCHRONIZED_TIME)
10:58:59.023 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:58:59.023 C8 F3 82 10 00 33 02 07 01 4F C9 29 82 A1 01 02 66 82
10:58:59.023 03 28 01 00 00 00 81 00 00 9A 2A
10:58:59.023 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:58:59.023 <-T-- fir: 1 fin: 1 seq: 8
10:58:59.023 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x03 IIN(NeedTime)
10:58:59.023 g51v2, 1-Byte Count, count: 1
10:58:59.023 time: 1793185139023
10:58:59.023 g2v3, 2-Byte Count and Index, count: 1
10:58:59.023 [0] - flags: 0x81 cto: 0
10:58:59.023 step: 2
10:58:59.024 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:58:59.024 - g60v2, All Objects
10:58:59.024 - g60v3, All Objects
10:58:59.024 - g60v4, All Objects
10:58:59.024 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:58:59.024 g60v2, All Objects
10:58:59.024 g60v3, All Objects
10:58:59.024 g60v4, All Objects
10:58:59.024 --T-> fir: 1 fin: 1 seq: 0
10:58:59.024 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:58:59.024 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:58:59.024 C0 C4 01 3C 02 06 3C 03 06 3C 04 06 A6 6A
10:58:59.024 steps: 3 to 7
10:58:59.024 - Expect no message until 500 ms before the confirm timeout
10:58:59.024 - Wait for the confirm timeout to expire
10:58:59.024 - Read any number of valid responses beginning w/ sequence(0x04) and incrementing by 1 modulo 16
10:58:59.024 - Verify that an application layer confirmation is requested.
10:58:59.024 - Verify that this response contains every event the previous response reported.
10:58:59.024 - Confirm each response that asks for a confirmation
10:59:01.024 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:01.024 C9 E4 81 10 00 33 02 07 01 4F C9 29 82 A1 01 02 24 C8
10:59:01.024 03 28 01 00 00 00 81 00 00 9A 2A
10:59:01.024 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:01.024 <-T-- fir: 1 fin: 1 seq: 9
10:59:01.025 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x04 IIN(NeedTime)
10:59:01.025 g51v2, 1-Byte Count, count: 1
10:59:01.025 time: 1793185139023
10:59:01.025 g2v3, 2-Byte Count and Index, count: 1
10:59:01.025 [0] - flags: 0x81 cto: 0
10:59:01.025 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:59:01.025 --T-> fir: 1 fin: 1 seq: 0
10:59:01.025 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:01.025 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:01.025 C0 C4 00 F9 A1
10:59:01.025 [PLG] Verified that the response retains the 1 earlier event(s)
10:59:01.025 step: 8
10:59:01.025 - Expect no unsolicited response within 2500 ms.
10:59:03.527 8.11.2.5.6
10:59:03.527 - Abandons READ requests upon subsequent non-READ requests
10:59:03.528 step: 1
10:59:03.528 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:03.528 - Read an unsolicited response
10:59:03.528 - Verify that the response contains events
10:59:03.528 - Leave the response unconfirmed for now
10:59:03.528 [PLG] Updated BI 0: value=false, flags=1, timestamp=1793185143528 (UNSYNCHRONIZED_TIME)
10:59:03.529 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:03.529 CA F4 82 10 00 33 02 07 01 E8 DA 29 82 A1 01 02 37 52
10:59:03.529 03 28 01 00 00 00 01 00 00 3D 7F
10:59:03.529 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:03.529 <-T-- fir: 1 fin: 1 seq: 10
10:59:03.529 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
10:59:03.529 g51v2, 1-Byte Count, count: 1
10:59:03.529 time: 1793185143528
10:59:03.529 g2v3, 2-Byte Count and Index, count: 1
10:59:03.529 [0] - flags: 0x01 cto: 0
10:59:03.529 step: 2
10:59:03.529 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
10:59:03.529 - g60v2, All Objects
10:59:03.529 - g60v3, All Objects
10:59:03.529 - g60v4, All Objects
10:59:03.529 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x05
10:59:03.529 g60v2, All Objects
10:59:03.529 g60v3, All Objects
10:59:03.529 g60v4, All Objects
10:59:03.529 --T-> fir: 1 fin: 1 seq: 0
10:59:03.529 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:03.529 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:03.529 C0 C5 01 3C 02 06 3C 03 06 3C 04 06 28 7C
10:59:03.529 step: 3
10:59:03.529 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
10:59:03.529 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:03.529 - [7] - BooleanValue(false)
10:59:03.529 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06
10:59:03.529 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:03.529 [7] - BooleanValue(false)
10:59:03.529 --T-> fir: 1 fin: 1 seq: 0
10:59:03.530 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:03.530 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:03.530 C0 C6 02 50 01 00 07 07 00 40 3D
10:59:03.530 step: 4
10:59:03.530 - Read a single fragment response w/ sequence(0x06)
10:59:03.530 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:03.530 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:03.530 CB C6 81 10 00 75 13
10:59:03.530 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:03.530 <-T-- fir: 1 fin: 1 seq: 11
10:59:03.530 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x06 IIN(NeedTime)
10:59:03.530 steps: 5 to 6
10:59:03.530 - Expect a retry 2000 ms (+/- 500 ms) after the previous unsolicited response
10:59:03.530 - Verify that this response contains every event the previous response reported.
10:59:05.530 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:05.530 CC F4 82 10 00 33 02 07 01 E8 DA 29 82 A1 01 02 66 B2
10:59:05.530 03 28 01 00 00 00 01 00 00 3D 7F
10:59:05.530 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:05.530 <-T-- fir: 1 fin: 1 seq: 12
10:59:05.531 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x04 IIN(NeedTime)
10:59:05.531 g51v2, 1-Byte Count, count: 1
10:59:05.531 time: 1793185143528
10:59:05.531 g2v3, 2-Byte Count and Index, count: 1
10:59:05.531 [0] - flags: 0x01 cto: 0
10:59:05.531 [PLG] Verified that the response retains the 1 earlier event(s)
10:59:05.531 8.11.2.5.7
10:59:05.531 - Abandons READ requests upon subsequent READ requests
10:59:05.531 step: 1
10:59:05.531 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
10:59:05.531 - g60v1, All Objects
10:59:05.531 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x07
10:59:05.531 g60v1, All Objects
10:59:05.531 --T-> fir: 1 fin: 1 seq: 0
10:59:05.531 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:05.531 --P-> 05 64 0B C4 00 04 01 00 CA 8A
10:59:05.531 C0 C7 01 3C 01 06 ED B9
10:59:05.531 step: 2
10:59:05.531 - Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:59:05.531 - g60v2, All Objects
10:59:05.531 - g60v3, All Objects
10:59:05.531 - g60v4, All Objects
10:59:05.531 --A-> Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:59:05.531 g60v2, All Objects
10:59:05.531 g60v3, All Objects
10:59:05.531 g60v4, All Objects
10:59:05.531 --T-> fir: 1 fin: 1 seq: 0
10:59:05.531 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:05.531 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:05.531 C0 C8 01 3C 02 06 3C 03 06 3C 04 06 EE 84
10:59:05.531 steps: 3 to 8
10:59:05.531 - Expect no message until 500 ms before the confirm timeout
10:59:05.531 - Wait for the confirm timeout to expire
10:59:05.531 - Read any number of valid responses beginning w/ sequence(0x08) and incrementing by 1 modulo 16
10:59:05.531 - Verify that the response only contains objects, variations, and qualifiers specified in table 8-4, for subset level 3
10:59:05.531 - Verify that an application layer confirmation is requested.
10:59:05.531 - Verify that this response contains every event the previous response reported.
10:59:05.531 - Confirm each response that asks for a confirmation
10:59:07.531 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:07.531 CD E8 81 10 00 33 02 07 01 E8 DA 29 82 A1 01 02 47 ED
10:59:07.531 03 28 01 00 00 00 01 00 00 3D 7F
10:59:07.531 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:07.531 <-T-- fir: 1 fin: 1 seq: 13
10:59:07.531 <-A-- Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x08 IIN(NeedTime)
10:59:07.531 g51v2, 1-Byte Count, count: 1
10:59:07.531 time: 1793185143528
10:59:07.531 g2v3, 2-Byte Count and Index, count: 1
10:59:07.531 [0] - flags: 0x01 cto: 0
10:59:07.531 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x08
10:59:07.532 --T-> fir: 1 fin: 1 seq: 0
10:59:07.532 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:07.532 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:07.532 C0 C8 00 A7 F9
10:59:07.532 [PLG] Verified that the response retains the 1 earlier event(s)
10:59:07.532 step: 9
10:59:07.532 - Expect no unsolicited response within 2500 ms.
10:59:10.034 8.11.2.5.8
10:59:10.034 - Inhibits Unsolicited responses until after polled confirmation
10:59:10.034 steps: 1 to 11
10:59:10.034 - 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:59:10.034 8.11.2.5.9
10:59:10.034 - Retries unsolicited responses configurable number of times
10:59:10.034 step: 1
10:59:10.034 - Set the DUT's unsolicited retry limit to 5
10:59:10.034 [PLG] Max unsolicited response retries was set to 5
10:59:10.034 [PLG] Cycling power
10:59:10.035 step: 2
10:59:10.035 - Cycle power to the DUT
10:59:10.035 [PLG] Cycling power
10:59:10.036 step: 3
10:59:10.036 - If an unsolicited NULL response is received, confirm it
10:59:10.036 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:10.036 C0 F0 82 90 00 43 A2
10:59:10.036 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:10.036 <-T-- fir: 1 fin: 1 seq: 0
10:59:10.036 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:59:10.036 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:59:10.036 --T-> fir: 1 fin: 1 seq: 0
10:59:10.036 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:10.037 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:10.037 C0 D0 00 1B 49
10:59:10.037 step: 4
10:59:10.037 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
10:59:10.037 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:10.037 - [7] - BooleanValue(false)
10:59:10.037 - Read a single fragment response w/ sequence(0x09)
10:59:10.037 - Verify that the response is empty
10:59:10.037 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:10.037 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09
10:59:10.037 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:10.037 [7] - BooleanValue(false)
10:59:10.037 --T-> fir: 1 fin: 1 seq: 0
10:59:10.037 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:10.037 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:10.037 C0 C9 02 50 01 00 07 07 00 AE EF
10:59:10.037 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:10.037 C1 C9 81 10 00 75 23
10:59:10.037 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:10.037 <-T-- fir: 1 fin: 1 seq: 1
10:59:10.037 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x09 IIN(NeedTime)
10:59:10.037 step: 5
10:59:10.037 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
10:59:10.037 - g60v2, All Objects
10:59:10.037 - g60v3, All Objects
10:59:10.037 - g60v4, All Objects
10:59:10.037 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A
10:59:10.037 g60v2, All Objects
10:59:10.037 g60v3, All Objects
10:59:10.037 g60v4, All Objects
10:59:10.037 --T-> fir: 1 fin: 1 seq: 0
10:59:10.037 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:10.037 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:10.037 C0 CA 14 3C 02 06 3C 03 06 3C 04 06 14 0F
10:59:10.038 step: 6
10:59:10.038 - Read a single fragment response w/ sequence(0x0A)
10:59:10.038 - Verify that the response is empty
10:59:10.038 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:10.038 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:10.038 C2 CA 81 10 00 3E 4C
10:59:10.038 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:10.038 <-T-- fir: 1 fin: 1 seq: 2
10:59:10.038 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0A IIN(NeedTime)
10:59:10.038 step: 7
10:59:10.038 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:10.038 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185150038 (UNSYNCHRONIZED_TIME)
10:59:10.038 steps: 8 to 9
10:59:10.038 - Read an unsolicited response
10:59:10.038 - Verify that the response contains events
10:59:10.038 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:10.038 C3 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 02 68
10:59:10.038 03 28 01 00 00 00 81 00 00 9A 2A
10:59:10.038 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:10.038 <-T-- fir: 1 fin: 1 seq: 3
10:59:10.038 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:10.038 g51v2, 1-Byte Count, count: 1
10:59:10.038 time: 1793185150038
10:59:10.038 g2v3, 2-Byte Count and Index, count: 1
10:59:10.038 [0] - flags: 0x81 cto: 0
10:59:10.038 step: 10
10:59:10.038 - Read an unsolicited response
10:59:10.038 - Repeat these steps 5 times
10:59:10.038 - Expect no unsolicited response within 2500 ms.
10:59:10.038 Repeat 1 of 5
10:59:12.040 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:12.040 C4 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 DF C5
10:59:12.040 03 28 01 00 00 00 81 00 00 9A 2A
10:59:12.040 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:12.040 <-T-- fir: 1 fin: 1 seq: 4
10:59:12.040 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:12.040 g51v2, 1-Byte Count, count: 1
10:59:12.040 time: 1793185150038
10:59:12.040 g2v3, 2-Byte Count and Index, count: 1
10:59:12.040 [0] - flags: 0x81 cto: 0
10:59:12.040 Repeat 2 of 5
10:59:14.040 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:14.040 C5 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 53 88
10:59:14.040 03 28 01 00 00 00 81 00 00 9A 2A
10:59:14.040 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:14.040 <-T-- fir: 1 fin: 1 seq: 5
10:59:14.040 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:14.040 g51v2, 1-Byte Count, count: 1
10:59:14.040 time: 1793185150038
10:59:14.040 g2v3, 2-Byte Count and Index, count: 1
10:59:14.040 [0] - flags: 0x81 cto: 0
10:59:14.040 Repeat 3 of 5
10:59:16.041 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:16.041 C6 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 C7 5E
10:59:16.041 03 28 01 00 00 00 81 00 00 9A 2A
10:59:16.041 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:16.041 <-T-- fir: 1 fin: 1 seq: 6
10:59:16.041 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:16.041 g51v2, 1-Byte Count, count: 1
10:59:16.041 time: 1793185150038
10:59:16.041 g2v3, 2-Byte Count and Index, count: 1
10:59:16.041 [0] - flags: 0x81 cto: 0
10:59:16.041 Repeat 4 of 5
10:59:18.042 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:18.042 C7 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 4B 13
10:59:18.042 03 28 01 00 00 00 81 00 00 9A 2A
10:59:18.042 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:18.042 <-T-- fir: 1 fin: 1 seq: 7
10:59:18.043 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:18.043 g51v2, 1-Byte Count, count: 1
10:59:18.043 time: 1793185150038
10:59:18.043 g2v3, 2-Byte Count and Index, count: 1
10:59:18.043 [0] - flags: 0x81 cto: 0
10:59:18.043 Repeat 5 of 5
10:59:20.043 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:20.043 C8 F1 82 10 00 33 02 07 01 56 F4 29 82 A1 01 02 04 48
10:59:20.043 03 28 01 00 00 00 81 00 00 9A 2A
10:59:20.043 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:20.044 <-T-- fir: 1 fin: 1 seq: 8
10:59:20.044 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:20.044 g51v2, 1-Byte Count, count: 1
10:59:20.044 time: 1793185150038
10:59:20.044 g2v3, 2-Byte Count and Index, count: 1
10:59:20.044 [0] - flags: 0x81 cto: 0
10:59:22.547 step: 11
10:59:22.547 - Cycle power to the DUT
10:59:22.547 [PLG] Cycling power
10:59:22.548 step: 12
10:59:22.548 - If an unsolicited NULL response is received, confirm it
10:59:22.548 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:22.548 C0 F0 82 90 00 43 A2
10:59:22.548 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:22.548 <-T-- fir: 1 fin: 1 seq: 0
10:59:22.548 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:59:22.548 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:59:22.548 --T-> fir: 1 fin: 1 seq: 0
10:59:22.548 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:22.548 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:22.548 C0 D0 00 1B 49
10:59:22.549 step: 13
10:59:22.549 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
10:59:22.549 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:22.549 - [7] - BooleanValue(false)
10:59:22.549 - Read a single fragment response w/ sequence(0x0B)
10:59:22.549 - Verify that the response is empty
10:59:22.549 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:22.549 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B
10:59:22.549 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:22.549 [7] - BooleanValue(false)
10:59:22.549 --T-> fir: 1 fin: 1 seq: 0
10:59:22.549 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:22.549 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:22.549 C0 CB 02 50 01 00 07 07 00 8A C3
10:59:22.549 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:22.549 C1 CB 81 10 00 DC EB
10:59:22.549 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:22.549 <-T-- fir: 1 fin: 1 seq: 1
10:59:22.549 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0B IIN(NeedTime)
10:59:22.549 step: 14
10:59:22.549 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
10:59:22.549 - g60v2, All Objects
10:59:22.549 - g60v3, All Objects
10:59:22.549 - g60v4, All Objects
10:59:22.549 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C
10:59:22.549 g60v2, All Objects
10:59:22.549 g60v3, All Objects
10:59:22.549 g60v4, All Objects
10:59:22.549 --T-> fir: 1 fin: 1 seq: 0
10:59:22.549 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:22.549 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:22.549 C0 CC 14 3C 02 06 3C 03 06 3C 04 06 30 78
10:59:22.549 step: 15
10:59:22.549 - Read a single fragment response w/ sequence(0x0C)
10:59:22.549 - Verify that the response is empty
10:59:22.549 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:22.550 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:22.550 C2 CC 81 10 00 BC 58
10:59:22.550 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:22.550 <-T-- fir: 1 fin: 1 seq: 2
10:59:22.550 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0C IIN(NeedTime)
10:59:22.550 steps: 16 to 17
10:59:22.550 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:22.550 - Read an unsolicited response
10:59:22.550 - Verify that the response contains events
10:59:22.550 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185162550 (UNSYNCHRONIZED_TIME)
10:59:22.550 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:22.550 C3 F1 82 10 00 33 02 07 01 36 25 2A 82 A1 01 02 BA 6D
10:59:22.550 03 28 01 00 00 00 81 00 00 9A 2A
10:59:22.550 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:22.550 <-T-- fir: 1 fin: 1 seq: 3
10:59:22.550 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:22.550 g51v2, 1-Byte Count, count: 1
10:59:22.550 time: 1793185162550
10:59:22.550 g2v3, 2-Byte Count and Index, count: 1
10:59:22.550 [0] - flags: 0x81 cto: 0
10:59:22.550 step: 18
10:59:22.550 - Read an unsolicited response
10:59:22.550 - Confirm each response that asks for a confirmation
10:59:22.550 - Expect no unsolicited response within 2500 ms.
10:59:24.551 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:24.551 C4 F1 82 10 00 33 02 07 01 36 25 2A 82 A1 01 02 67 C0
10:59:24.551 03 28 01 00 00 00 81 00 00 9A 2A
10:59:24.551 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:24.551 <-T-- fir: 1 fin: 1 seq: 4
10:59:24.551 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:24.551 g51v2, 1-Byte Count, count: 1
10:59:24.551 time: 1793185162550
10:59:24.551 g2v3, 2-Byte Count and Index, count: 1
10:59:24.551 [0] - flags: 0x81 cto: 0
10:59:24.551 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x01
10:59:24.551 --T-> fir: 1 fin: 1 seq: 0
10:59:24.551 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:24.551 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:24.551 C0 D1 00 55 E2
10:59:27.054 step: 19
10:59:27.054 - Set the DUT's unsolicited retry limit to indefinite
10:59:27.054 [PLG] Max unsolicited response retries was set to Infinite
10:59:27.054 [PLG] Cycling power
10:59:27.055 step: 20
10:59:27.055 - Cycle power to the DUT
10:59:27.055 [PLG] Cycling power
10:59:27.056 step: 21
10:59:27.056 - If an unsolicited NULL response is received, confirm it
10:59:27.056 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:27.056 C0 F0 82 90 00 43 A2
10:59:27.056 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:27.056 <-T-- fir: 1 fin: 1 seq: 0
10:59:27.056 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x00 IIN(DeviceRestart, NeedTime)
10:59:27.056 --A-> Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 1 seq: 0x00
10:59:27.056 --T-> fir: 1 fin: 1 seq: 0
10:59:27.057 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:27.057 --P-> 05 64 08 C4 00 04 01 00 9A 19
10:59:27.057 C0 D0 00 1B 49
10:59:27.057 step: 22
10:59:27.057 - Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
10:59:27.057 - g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:27.057 - [7] - BooleanValue(false)
10:59:27.057 - Read a single fragment response w/ sequence(0x0D)
10:59:27.057 - Verify that the response is empty
10:59:27.057 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:27.057 --A-> Write(0x02) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D
10:59:27.057 g80v1, 1-Byte Start/Stop, start: 7 stop: 7
10:59:27.057 [7] - BooleanValue(false)
10:59:27.057 --T-> fir: 1 fin: 1 seq: 0
10:59:27.057 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 14 dest: 1024 src: 1
10:59:27.057 --P-> 05 64 0E C4 00 04 01 00 43 72
10:59:27.057 C0 CD 02 50 01 00 07 07 00 E6 B7
10:59:27.057 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:27.057 C1 CD 81 10 00 5E FF
10:59:27.057 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:27.057 <-T-- fir: 1 fin: 1 seq: 1
10:59:27.057 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0D IIN(NeedTime)
10:59:27.059 step: 23
10:59:27.059 - Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
10:59:27.059 - g60v2, All Objects
10:59:27.059 - g60v3, All Objects
10:59:27.059 - g60v4, All Objects
10:59:27.059 --A-> Enable Unsolicited(0x14) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E
10:59:27.059 g60v2, All Objects
10:59:27.059 g60v3, All Objects
10:59:27.059 g60v4, All Objects
10:59:27.059 --T-> fir: 1 fin: 1 seq: 0
10:59:27.059 --L-> master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 17 dest: 1024 src: 1
10:59:27.059 --P-> 05 64 11 C4 00 04 01 00 60 4E
10:59:27.059 C0 CE 14 3C 02 06 3C 03 06 3C 04 06 2C 55
10:59:27.060 step: 24
10:59:27.060 - Read a single fragment response w/ sequence(0x0E)
10:59:27.060 - Verify that the response is empty
10:59:27.060 - Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:27.060 <-P-- 05 64 0A 44 01 00 00 04 67 88
10:59:27.060 C2 CE 81 10 00 15 90
10:59:27.060 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:27.060 <-T-- fir: 1 fin: 1 seq: 2
10:59:27.060 <-A-- Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x0E IIN(NeedTime)
10:59:27.060 step: 25
10:59:27.060 - Generate Class 1, 2 or 3 events that would normally trigger an unsolicited response
10:59:27.060 [PLG] Updated BI 0: value=true, flags=81, timestamp=1793185167060 (UNSYNCHRONIZED_TIME)
10:59:27.060 steps: 26 to 27
10:59:27.060 - Read an unsolicited response
10:59:27.060 - Verify that the response contains events
10:59:27.060 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:27.060 C3 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 CA D6
10:59:27.060 03 28 01 00 00 00 81 00 00 9A 2A
10:59:27.060 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:27.060 <-T-- fir: 1 fin: 1 seq: 3
10:59:27.060 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:27.060 g51v2, 1-Byte Count, count: 1
10:59:27.060 time: 1793185167060
10:59:27.060 g2v3, 2-Byte Count and Index, count: 1
10:59:27.060 [0] - flags: 0x81 cto: 0
10:59:27.060 step: 28
10:59:27.060 - Read an unsolicited response
10:59:27.060 - Repeat these steps 7 times
10:59:27.060 Repeat 1 of 7
10:59:29.061 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:29.061 C4 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 17 7B
10:59:29.061 03 28 01 00 00 00 81 00 00 9A 2A
10:59:29.061 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:29.061 <-T-- fir: 1 fin: 1 seq: 4
10:59:29.062 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:29.062 g51v2, 1-Byte Count, count: 1
10:59:29.062 time: 1793185167060
10:59:29.062 g2v3, 2-Byte Count and Index, count: 1
10:59:29.062 [0] - flags: 0x81 cto: 0
10:59:29.062 Repeat 2 of 7
10:59:31.063 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:31.063 C5 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 9B 36
10:59:31.063 03 28 01 00 00 00 81 00 00 9A 2A
10:59:31.063 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:31.063 <-T-- fir: 1 fin: 1 seq: 5
10:59:31.063 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:31.063 g51v2, 1-Byte Count, count: 1
10:59:31.063 time: 1793185167060
10:59:31.063 g2v3, 2-Byte Count and Index, count: 1
10:59:31.063 [0] - flags: 0x81 cto: 0
10:59:31.063 Repeat 3 of 7
10:59:33.063 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:33.063 C6 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 0F E0
10:59:33.063 03 28 01 00 00 00 81 00 00 9A 2A
10:59:33.063 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:33.063 <-T-- fir: 1 fin: 1 seq: 6
10:59:33.063 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:33.063 g51v2, 1-Byte Count, count: 1
10:59:33.063 time: 1793185167060
10:59:33.063 g2v3, 2-Byte Count and Index, count: 1
10:59:33.063 [0] - flags: 0x81 cto: 0
10:59:33.063 Repeat 4 of 7
10:59:35.064 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:35.064 C7 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 83 AD
10:59:35.064 03 28 01 00 00 00 81 00 00 9A 2A
10:59:35.064 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:35.064 <-T-- fir: 1 fin: 1 seq: 7
10:59:35.064 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:35.064 g51v2, 1-Byte Count, count: 1
10:59:35.064 time: 1793185167060
10:59:35.064 g2v3, 2-Byte Count and Index, count: 1
10:59:35.064 [0] - flags: 0x81 cto: 0
10:59:35.064 Repeat 5 of 7
10:59:37.065 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:37.065 C8 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 CC F6
10:59:37.065 03 28 01 00 00 00 81 00 00 9A 2A
10:59:37.065 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:37.065 <-T-- fir: 1 fin: 1 seq: 8
10:59:37.066 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:37.066 g51v2, 1-Byte Count, count: 1
10:59:37.066 time: 1793185167060
10:59:37.066 g2v3, 2-Byte Count and Index, count: 1
10:59:37.066 [0] - flags: 0x81 cto: 0
10:59:37.066 Repeat 6 of 7
10:59:39.067 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:39.067 C9 F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 40 BB
10:59:39.067 03 28 01 00 00 00 81 00 00 9A 2A
10:59:39.067 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:39.067 <-T-- fir: 1 fin: 1 seq: 9
10:59:39.067 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:39.067 g51v2, 1-Byte Count, count: 1
10:59:39.067 time: 1793185167060
10:59:39.067 g2v3, 2-Byte Count and Index, count: 1
10:59:39.067 [0] - flags: 0x81 cto: 0
10:59:39.067 Repeat 7 of 7
10:59:41.068 <-P-- 05 64 1E 44 01 00 00 04 C8 BD
10:59:41.068 CA F1 82 10 00 33 02 07 01 D4 36 2A 82 A1 01 02 D4 6D
10:59:41.068 03 28 01 00 00 00 81 00 00 9A 2A
10:59:41.068 <-L-- master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 30 dest: 1 src: 1024
10:59:41.068 <-T-- fir: 1 fin: 1 seq: 10
10:59:41.068 <-A-- Unsolicited Response(0x82) fir: 1 fin: 1 con: 1 uns: 1 seq: 0x01 IIN(NeedTime)
10:59:41.068 g51v2, 1-Byte Count, count: 1
10:59:41.068 time: 1793185167060
10:59:41.068 g2v3, 2-Byte Count and Index, count: 1
10:59:41.068 [0] - flags: 0x81 cto: 0
10:59:41.068 Passed procedure: 8.11.2.5