10:59:51.997		Running procedure: 8.14.2c
10:59:51.999		This procedure is enabled because the following options:
10:59:51.999		- supports.binaryInput == true
10:59:51.999		- supports.binaryInputChangeEvents == true
10:59:51.999		- supports.binaryInputEventReads == true
10:59:51.999		step: 1
10:59:51.999		- Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:59:51.999		- g2v0, All Objects
10:59:51.999	--A->	Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01
10:59:51.999		g2v0, All Objects
10:59:51.999	--T->	fir: 1 fin: 1 seq: 0
10:59:51.999	--L->	master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:51.999	--P->	05 64 0B C4 00 04 01 00 CA 8A
10:59:51.999		C0 C1 01 02 00 06 95 76
10:59:52.000		step: 2
10:59:52.000		- Read any number of valid responses beginning w/ sequence(0x01) and incrementing by 1 modulo 16
10:59:52.000		- If the response carries data, verify that it asks for an application layer confirmation.
10:59:52.000		- Confirm each response that asks for a confirmation
10:59:52.000	<-P--	05 64 0A 44 01 00 00 04 67 88
10:59:52.000		C0 C1 81 90 00 73 EE
10:59:52.000	<-L--	master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:52.000	<-T--	fir: 1 fin: 1 seq: 0
10:59:52.000	<-A--	Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x01 IIN(DeviceRestart, NeedTime)
10:59:52.000		step: 3
10:59:52.000		- Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:59:52.000		- g2v0, All Objects
10:59:52.000	--A->	Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02
10:59:52.000		g2v0, All Objects
10:59:52.000	--T->	fir: 1 fin: 1 seq: 0
10:59:52.000	--L->	master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:52.000	--P->	05 64 0B C4 00 04 01 00 CA 8A
10:59:52.000		C0 C2 01 02 00 06 9F 13
10:59:52.000		step: 4
10:59:52.000		- Read a single fragment response w/ sequence(0x02)
10:59:52.000		- Verify that the response is empty
10:59:52.000		- Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:52.001	<-P--	05 64 0A 44 01 00 00 04 67 88
10:59:52.001		C1 C2 81 90 00 34 C7
10:59:52.001	<-L--	master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:52.001	<-T--	fir: 1 fin: 1 seq: 1
10:59:52.001	<-A--	Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x02 IIN(DeviceRestart, NeedTime)
10:59:52.001		step: 5
10:59:52.001		- Change binary inputs several times, one or more of them, to create events
10:59:52.001	[PLG]	Updated BI 0: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.001	[PLG]	Updated BI 1: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.001	[PLG]	Updated BI 2: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.001	[PLG]	Updated BI 3: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.001	[PLG]	Updated BI 4: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.001	[PLG]	Updated BI 5: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.002	[PLG]	Updated BI 6: value=true, flags=81, timestamp=1793185192001 (UNSYNCHRONIZED_TIME)
10:59:52.002	[PLG]	Updated BI 7: value=true, flags=81, timestamp=1793185192002 (UNSYNCHRONIZED_TIME)
10:59:52.002	[PLG]	Updated BI 8: value=true, flags=81, timestamp=1793185192002 (UNSYNCHRONIZED_TIME)
10:59:52.002	[PLG]	Updated BI 9: value=true, flags=81, timestamp=1793185192002 (UNSYNCHRONIZED_TIME)
10:59:52.002		The plugin reports the DUT's clock unsynchronized
10:59:52.002		step: 6
10:59:52.002		- Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:59:52.002		- g2v0, All Objects
10:59:52.002	--A->	Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:59:52.002		g2v0, All Objects
10:59:52.002	--T->	fir: 1 fin: 1 seq: 0
10:59:52.002	--L->	master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:52.002	--P->	05 64 0B C4 00 04 01 00 CA 8A
10:59:52.002		C0 C3 01 02 00 06 99 30
10:59:52.002		steps: 7 to 12
10:59:52.002		- Read a single fragment response w/ sequence(0x03)
10:59:52.002		- Verify that the response holds every Binary Input event generated
10:59:52.002		- If the events carry timestamps, verify that the gaps between them match the gaps between when the events were generated, to the Device Profile's resolution
10:59:52.002		- Verify that an application layer confirmation is requested.
10:59:52.002		- Verify that the events are reported as 3.1r1's rules for event reporting require, for a read of g2v0, by the state of the DUT's clock when it recorded them, where the procedure or the plugin knows it
10:59:52.002		- Verify that the flags behave as the note on flags in Section 2 requires (Note 5 in 2.7, Note 6 in 3.1r1).
10:59:52.002		- Confirm each response that asks for a confirmation
10:59:52.002	<-P--	05 64 4B 44 01 00 00 04 FD 93
10:59:52.002		C2 E3 81 90 00 33 02 07 01 41 98 2A 82 A1 01 02 5A 96
10:59:52.002		03 28 0A 00 00 00 81 00 00 01 00 81 00 00 02 00 AC 42
10:59:52.002		81 00 00 03 00 81 00 00 04 00 81 00 00 05 00 81 9B 00
10:59:52.002		00 00 06 00 81 00 00 07 00 81 01 00 08 00 81 01 10 79
10:59:52.002		00 09 00 81 01 00 E1 6E
10:59:52.002	<-L--	master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 75 dest: 1 src: 1024
10:59:52.002	<-T--	fir: 1 fin: 1 seq: 2
10:59:52.003	<-A--	Response(0x81) fir: 1 fin: 1 con: 1 uns: 0 seq: 0x03 IIN(DeviceRestart, NeedTime)
10:59:52.003		g51v2, 1-Byte Count, count: 1
10:59:52.003		time: 1793185192001
10:59:52.003		g2v3, 2-Byte Count and Index, count: 10
10:59:52.003		[0] - flags: 0x81 cto: 0
10:59:52.003		[1] - flags: 0x81 cto: 0
10:59:52.003		[2] - flags: 0x81 cto: 0
10:59:52.003		[3] - flags: 0x81 cto: 0
10:59:52.003		[4] - flags: 0x81 cto: 0
10:59:52.003		[5] - flags: 0x81 cto: 0
10:59:52.003		[6] - flags: 0x81 cto: 0
10:59:52.003		[7] - flags: 0x81 cto: 1
10:59:52.003		[8] - flags: 0x81 cto: 1
10:59:52.003		[9] - flags: 0x81 cto: 1
10:59:52.003	--A->	Confirm(0x00) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x03
10:59:52.003	--T->	fir: 1 fin: 1 seq: 0
10:59:52.003	--L->	master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 8 dest: 1024 src: 1
10:59:52.003	--P->	05 64 08 C4 00 04 01 00 9A 19
10:59:52.003		C0 C3 00 98 26
10:59:52.007	[PLG]	Verified 10 binary input point event(s)
10:59:52.010		step: 13
10:59:52.010		- Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:59:52.010		- g2v0, All Objects
10:59:52.010	--A->	Read(0x01) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04
10:59:52.010		g2v0, All Objects
10:59:52.010	--T->	fir: 1 fin: 1 seq: 0
10:59:52.010	--L->	master: 1 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0xC4 length: 11 dest: 1024 src: 1
10:59:52.010	--P->	05 64 0B C4 00 04 01 00 CA 8A
10:59:52.010		C0 C4 01 02 00 06 8B D9
10:59:52.010		step: 14
10:59:52.010		- Read a single fragment response w/ sequence(0x04)
10:59:52.010		- Verify that the response is empty
10:59:52.010		- Verify that the IIN field does not contain bits IIN(ParamError, ObjectUnknown, FuncNotSupported)
10:59:52.010	<-P--	05 64 0A 44 01 00 00 04 67 88
10:59:52.010		C3 C4 81 90 00 BA 95
10:59:52.010	<-L--	master: 0 pri: 1 fcb: 0 fcv: 0 func: UNCONFIRMED_USER_DATA(0x04) 0x44 length: 10 dest: 1 src: 1024
10:59:52.010	<-T--	fir: 1 fin: 1 seq: 3
10:59:52.010	<-A--	Response(0x81) fir: 1 fin: 1 con: 0 uns: 0 seq: 0x04 IIN(DeviceRestart, NeedTime)
10:59:52.010		Passed procedure: 8.14.2c