Logging started 01/12/2017 18:44:52 MQTTWiFiIRDeviceRealScenario.xlsx
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000 > Config Start
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000   Using MQTT_IP_Address 'XX.XX.XX.XX'
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000   Using MQTT_IP_Port '1883'
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.005000   Using default Log_Path 'C:\Users\Win7\PycharmProjects\RealScenario'
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000   Using Spreadsheet name as Log_Filename 'MQTTWiFiIRDeviceRealScenario.txt'
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.005000   System Timer Value '67.0'
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.003000 > Config End
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000 > Preamble Start
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000 > Preamble End
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.004000 > Postamble Start
S 01/12/2017 18:44:52 F 18:44:52 D 00:00:0.003000 > Postamble End
S 01/12/2017 18:44:52 F 18:44:53 D 00:00:1.008058 > Connected to MQTT Broker, RC 0
S 01/12/2017 18:44:52 F 18:44:53 D 00:00:0.500029 > Subscribing to [WFD/IRTHSen/SwVer/Confirm]
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.506029 > Subscribing to [WFD/IRTHSen/1/Rem/Conf]

S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.012001 > Test0 Start
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.008000   Published Message/Payload : WFD/aa:bb:cc:dd:ee:ff/SwVer/Command  [Line 2]
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.034002   Received Topic/Payload WFD/IRTHSen/SwVer/Confirm esp8266,aa:bb:cc:dd:ee:ff,MQTTWiFiIRDevice16.ino
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.008000   Expecting *
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.003000 > Test0 End [Pass]

S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.007000 > Test1 Start
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.004000   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/Raw/1 1,0xA90,12,4,26,1,400,1 [Line 2]
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.695040   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:44:54 D 00:00:0.003000   Delay : 5000mS [Line 3]
S 01/12/2017 18:44:52 F 18:44:59 D 00:00:5.004286   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/Raw/1 1,0x540C,15,4,22,1,400,1 [Line 4]
S 01/12/2017 18:44:52 F 18:45:00 D 00:00:0.739042   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:00 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:00 D 00:00:0.003000   Delay : 500mS [Line 5]
S 01/12/2017 18:44:52 F 18:45:01 D 00:00:0.504029   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/Raw/1 0,0xC05C0C,24,3,124,1,400,1 [Line 6]
S 01/12/2017 18:44:52 F 18:45:02 D 00:00:0.971056   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:02 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:02 D 00:00:0.003000   Delay : 10000mS [Line 7]
S 01/12/2017 18:44:52 F 18:45:12 D 00:00:10.004572   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,chan,bbc1,1 [Line 8]
S 01/12/2017 18:44:52 F 18:45:14 D 00:00:2.272130   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:14 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:14 D 00:00:0.003000   Delay : 5000mS [Line 9]
S 01/12/2017 18:44:52 F 18:45:19 D 00:00:5.004286   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sonysb,mute,1 [Line 10]
S 01/12/2017 18:44:52 F 18:45:20 D 00:00:0.679039   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:20 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:20 D 00:00:0.003000   Delay : 5000mS [Line 11]
S 01/12/2017 18:44:52 F 18:45:25 D 00:00:5.004286   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,chan,itv,1 [Line 12]
S 01/12/2017 18:44:52 F 18:45:27 D 00:00:2.348134   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:27 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:27 D 00:00:0.003000   Delay : 5000mS [Line 13]
S 01/12/2017 18:44:52 F 18:45:32 D 00:00:5.004286   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sonysb,mute,1 [Line 14]
S 01/12/2017 18:44:52 F 18:45:33 D 00:00:0.782045   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:33 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:45:33 D 00:00:0.003000   Delay : 1000mS [Line 15]
S 01/12/2017 18:44:52 F 18:45:34 D 00:00:1.004057   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,1,1 [Line 16]
S 01/12/2017 18:44:52 F 18:45:35 D 00:00:0.863050   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:35 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:45:35 D 00:00:0.003000   Delay : 500mS [Line 17]
S 01/12/2017 18:44:52 F 18:45:35 D 00:00:0.504029   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,0,1 [Line 18]
S 01/12/2017 18:44:52 F 18:45:36 D 00:00:0.802046   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:36 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:36 D 00:00:0.003000   Delay : 500mS [Line 19]
S 01/12/2017 18:44:52 F 18:45:36 D 00:00:0.504029   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,2,1 [Line 20]
S 01/12/2017 18:44:52 F 18:45:37 D 00:00:0.803046   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:37 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:37 D 00:00:0.003000   Delay : 500mS [Line 21]
S 01/12/2017 18:44:52 F 18:45:38 D 00:00:0.504029   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sky,select,1 [Line 22]
S 01/12/2017 18:44:52 F 18:45:39 D 00:00:0.800046   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:39 D 00:00:0.009001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:39 D 00:00:0.004000   Delay : 1000mS [Line 23]
S 01/12/2017 18:44:52 F 18:45:40 D 00:00:1.004057   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/PartRaw/1 SonySB,SndDn,5,400,1 [Line 24]
S 01/12/2017 18:44:52 F 18:45:43 D 00:00:3.020173   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:43 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:45:43 D 00:00:0.003000   Delay : 5000mS [Line 25]
S 01/12/2017 18:44:52 F 18:45:48 D 00:00:5.004286   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/PartRaw/1 SonySB,SndUp,5,400,1 [Line 26]
S 01/12/2017 18:44:52 F 18:45:51 D 00:00:3.112178   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:45:51 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:45:51 D 00:00:0.003000   Delay : 10000mS [Line 27]
S 01/12/2017 18:44:52 F 18:46:01 D 00:00:10.004572   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sonytv,onoff,1 [Line 28]
S 01/12/2017 18:44:52 F 18:46:02 D 00:00:0.756043   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:46:02 D 00:00:0.008001   Expecting 0
S 01/12/2017 18:44:52 F 18:46:02 D 00:00:0.004000   Delay : 1000mS [Line 29]
S 01/12/2017 18:44:52 F 18:46:03 D 00:00:1.003057   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 sonysb,OnOff,1 [Line 30]
S 01/12/2017 18:44:52 F 18:46:03 D 00:00:0.698040   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:46:03 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:46:03 D 00:00:0.003000   Delay : 1000mS [Line 31]
S 01/12/2017 18:44:52 F 18:46:04 D 00:00:1.004057   Published Message/Payload : WFD/IRTHSen/1/Rem/Command/1 Sky,onoff,1 [Line 32]
S 01/12/2017 18:44:52 F 18:46:05 D 00:00:0.952055   Received Topic/Payload WFD/IRTHSen/1/Rem/Conf 0
S 01/12/2017 18:44:52 F 18:46:05 D 00:00:0.008000   Expecting 0
S 01/12/2017 18:44:52 F 18:46:05 D 00:00:0.003000 > Test1 End [Pass]

Logging Ends 18:46:05 Total Tests 2, Passes 2, Fails 0