DEBUG: 5746523 sending ping
DEBUG: 5746587 command sent
DEBUG: 5746648 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5761735 sending ping
DEBUG: 5761786 command sent
DEBUG: 5761812 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5776941 sending ping
DEBUG: 5777012 command sent
DEBUG: 5777114 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5792154 sending ping
DEBUG: 5792237 command sent
DEBUG: 5792368 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5807362 sending ping
DEBUG: 5807411 command sent
DEBUG: 5807485 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5822575 sending ping
DEBUG: 5822712 command sent
DEBUG: 5822877 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5837780 sending ping
DEBUG: 5837836 command sent
DEBUG: 5837935 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5852992 sending ping
DEBUG: 5853112 command sent
DEBUG: 5853311 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5868204 sending ping
DEBUG: 5868512 command sent
DEBUG: 5868801 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5883413 sending ping
DEBUG: 5883738 command sent
DEBUG: 5884056 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5898619 sending ping
DEBUG: 5898787 command sent
DEBUG: 5898937 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5913829 sending ping
DEBUG: 5914012 command sent
DEBUG: 5914185 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5929042 sending ping
DEBUG: 5929542 command sent
DEBUG: 5929952 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5932005 turning autonomous mode on
DEBUG: 5932235 command sent
DEBUG: 5932508 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5932726 setting the device type to 1
DEBUG: 5932960 command sent
DEBUG: 5933217 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5933478 setting the default logical address to 1
DEBUG: 5933812 command sent
DEBUG: 5934035 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5934300 setting the logical address mask to 206
DEBUG: 5934567 command sent
DEBUG: 5934789 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5935038 setting the physical address to 1000
DEBUG: 5935285 command sent
DEBUG: 5935625 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5935852 setting the CEC version to 1.3a
DEBUG: 5936085 command sent
DEBUG: 5936312 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5936576 setting the OSD name to CECTester
DEBUG: 5936810 command sent
DEBUG: 5937043 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5937280 writing settings in the EEPROM
DEBUG: 5937535 command sent
DEBUG: 5937777 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5938018 setting ackmask to 0
DEBUG: 5938285 command sent
DEBUG: 5938523 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5938774 turning controlled mode off
DEBUG: 5939035 command sent
DEBUG: 5939276 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5939711 connection opened, clearing any previous input and waiting for active transmissions to end before starting
DEBUG: 5939935 sending ping
DEBUG: 5940160 command sent
DEBUG: 5940382 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5940599 requesting the firmware version
DEBUG: 5940835 command sent
DEBUG: 5947371 firmware version 2
DEBUG: 5947639 turning controlled mode on
DEBUG: 5947910 command sent
DEBUG: 5948192 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5948485 communication thread started
NOTICE: 5948761 connected to the CEC adapter. firmware version = 2, client version = 1.5.1
DEBUG: 5949047 requesting device type setting
DEBUG: 5949362 command sent
DEBUG: 5955560 using persisted device type setting recording device
DEBUG: 5955801 requesting physical address setting
DEBUG: 5956060 command sent
DEBUG: 5962972 using persisted physical address setting 1000
DEBUG: 5963263 requesting OSD name setting
DEBUG: 5963562 command sent
DEBUG: 5970380 using persisted device name setting CECTester
DEBUG: 5970631 sending ping
DEBUG: 5970890 CEC::CCECProcessor::FindLogicalAddresses - device 0: type 1
DEBUG: 5971144 detecting logical address for type 'recording device'
DEBUG: 5971398 command sent
DEBUG: 5971656 processor thread started
DEBUG: 5971916 trying logical address 'Recorder 1'
DEBUG: 5972206 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5972511 setting ackmask to 0
DEBUG: 5972835 command sent
DEBUG: 5973145 COMMAND_ACCEPTED - waiting for 0 more
NOTICE: 5973450 using logical address 'Recorder 1'
DEBUG: 5973756 setting ackmask to 2
DEBUG: 5974085 command sent
DEBUG: 5974398 COMMAND_ACCEPTED - waiting for 0 more
NOTICE: 5974706 setting the physical address to 1000
NOTICE: 5974974 << Recorder 1 (1) -> broadcast (F): physical adddress 1000
TRAFFIC: 5975247 << 1f:84:10:00:01
DEBUG: 5975839 setting the line timeout to 3
DEBUG: 5976135 command sent
DEBUG: 5976419 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5976710 command sent
DEBUG: 5976988 COMMAND_ACCEPTED - waiting for 5 more
DEBUG: 5977270 COMMAND_ACCEPTED - waiting for 4 more
DEBUG: 5977554 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 5977847 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 5978143 did not receive ack
DEBUG: 5978425 command was not acked
DEBUG: 5978717 COMMAND_ACCEPTED
DEBUG: 5979351 COMMAND_ACCEPTED
DEBUG: 5980142 TRANSMIT_SUCCEEDED
DEBUG: 5985590 sending ping
DEBUG: 5985862 command sent
DEBUG: 5986118 COMMAND_ACCEPTED - waiting for 0 more
TRAFFIC: 5991440 << 1f:84:10:00:01
DEBUG: 5991842 setting the line timeout to 3
DEBUG: 5992112 command sent
DEBUG: 5992372 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 5992662 command sent
DEBUG: 5992933 COMMAND_ACCEPTED - waiting for 5 more
DEBUG: 5993192 COMMAND_ACCEPTED - waiting for 4 more
DEBUG: 5993458 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 5993712 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 5993998 did not receive ack
DEBUG: 5994308 command was not acked
DEBUG: 5994599 COMMAND_ACCEPTED
DEBUG: 5994951 COMMAND_ACCEPTED
DEBUG: 5996119 TRANSMIT_SUCCEEDED
DEBUG: 6000799 sending ping
DEBUG: 6001087 command sent
DEBUG: 6001360 COMMAND_ACCEPTED - waiting for 0 more
ERROR: 6007429 unable to set the physical address to 1000
DEBUG: 6007747 >> Recorder 1 (1): power status changed from 'in transition from standby to on' to 'on'
TRAFFIC: 6008060 << 10:04
DEBUG: 6008600 setting the line timeout to 3
DEBUG: 6008937 command sent
DEBUG: 6009258 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6009586 command sent
DEBUG: 6009910 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 6010221 COMMAND_ACCEPTED - waiting for 1 more
DEBUG: 6010506 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6010794 TRANSMIT_FAILED_ACK
DEBUG: 6011080 did not receive ack
DEBUG: 6011367 command was not acked
DEBUG: 6016012 sending ping
DEBUG: 6016312 command sent
DEBUG: 6016601 COMMAND_ACCEPTED - waiting for 0 more
TRAFFIC: 6024199 << 10:04
DEBUG: 6024592 setting the line timeout to 3
DEBUG: 6024937 command sent
DEBUG: 6025268 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6025610 command sent
DEBUG: 6025948 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 6026279 COMMAND_ACCEPTED - waiting for 1 more
DEBUG: 6026604 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6026945 TRANSMIT_FAILED_ACK
DEBUG: 6027297 did not receive ack
DEBUG: 6027633 command was not acked
DEBUG: 6031219 sending ping
DEBUG: 6031587 command sent
DEBUG: 6031934 COMMAND_ACCEPTED - waiting for 0 more
NOTICE: 6040581 << Recorder 1 (1) -> broadcast (F): active source (1000)
TRAFFIC: 6041022 << 1f:82:10:00
DEBUG: 6041751 setting the line timeout to 3
DEBUG: 6042187 command sent
DEBUG: 6042592 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6043087 command sent
DEBUG: 6043914 COMMAND_ACCEPTED - waiting for 4 more
DEBUG: 6044724 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 6045518 did not receive ack
DEBUG: 6045984 command was not acked
DEBUG: 6046391 COMMAND_ACCEPTED
DEBUG: 6046791 sending ping
DEBUG: 6047213 command sent
DEBUG: 6047632 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6048380 COMMAND_ACCEPTED
DEBUG: 6048770 TRANSMIT_SUCCEEDED
DEBUG: 6049159 COMMAND_ACCEPTED
TRAFFIC: 6058912 << 1f:82:10:00
DEBUG: 6059299 setting the line timeout to 3
DEBUG: 6059737 command sent
DEBUG: 6060158 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6060610 command sent
DEBUG: 6061033 COMMAND_ACCEPTED - waiting for 4 more
DEBUG: 6061462 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 6061899 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 6062292 did not receive ack
DEBUG: 6062669 COMMAND_ACCEPTED
DEBUG: 6063053 command was not acked
DEBUG: 6063426 sending ping
DEBUG: 6063810 command sent
DEBUG: 6064174 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6064760 TRANSMIT_SUCCEEDED
DEBUG: 6065152 COMMAND_ACCEPTED
NOTICE: 6076069 << Recorder 1 (1) -> TV (0): menu state 'activated'
TRAFFIC: 6076448 << 10:8e:00
DEBUG: 6076852 setting the line timeout to 3
DEBUG: 6077262 command sent
DEBUG: 6077649 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6078062 command sent
DEBUG: 6078452 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 6078839 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 6079228 COMMAND_ACCEPTED - waiting for 1 more
DEBUG: 6079615 did not receive ack
DEBUG: 6080006 command was not acked
WARNING: 6080403 TRANSMIT_FAILED_ACK
DEBUG: 6080787 sending ping
DEBUG: 6081185 command sent
DEBUG: 6081533 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6081920 COMMAND_ACCEPTED
TRAFFIC: 6093229 << 10:8e:00
DEBUG: 6094011 setting the line timeout to 3
DEBUG: 6094437 command sent
DEBUG: 6094838 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6095262 command sent
DEBUG: 6095668 COMMAND_ACCEPTED - waiting for 3 more
DEBUG: 6096071 COMMAND_ACCEPTED - waiting for 2 more
DEBUG: 6096480 COMMAND_ACCEPTED - waiting for 1 more
DEBUG: 6096885 did not receive ack
DEBUG: 6097293 command was not acked
WARNING: 6097669 TRANSMIT_FAILED_ACK
DEBUG: 6098034 sending ping
DEBUG: 6098410 command sent
DEBUG: 6098778 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6099472 COMMAND_ACCEPTED
ERROR: 6110389 could not create a processor thread
ERROR: 6110780 could not start CEC communications
DEBUG: 6113089 sending ping
DEBUG: 6113462 command sent
DEBUG: 6113823 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6128139 sending ping
DEBUG: 6128594 command sent
DEBUG: 6129081 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6143246 sending ping
DEBUG: 6143760 command sent
DEBUG: 6144259 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6158472 sending ping
DEBUG: 6159012 command sent
DEBUG: 6159521 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6173668 sending ping
DEBUG: 6174387 command sent
DEBUG: 6174884 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6188877 sending ping
DEBUG: 6189437 command sent
DEBUG: 6189944 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6204090 sending ping
DEBUG: 6205113 command sent
DEBUG: 6206141 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6219299 sending ping
DEBUG: 6220342 command sent
DEBUG: 6220928 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6234633 sending ping
DEBUG: 6235360 command sent
DEBUG: 6235659 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6249715 sending ping
DEBUG: 6250012 command sent
DEBUG: 6250302 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6264928 sending ping
DEBUG: 6265339 command sent
DEBUG: 6265724 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6280137 sending ping
DEBUG: 6280737 command sent
DEBUG: 6281118 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6295346 sending ping
DEBUG: 6296137 command sent
DEBUG: 6296916 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6310556 sending ping
DEBUG: 6310987 command sent
DEBUG: 6311376 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6325768 sending ping
DEBUG: 6326185 command sent
DEBUG: 6326577 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6340977 sending ping
DEBUG: 6341412 command sent
DEBUG: 6341824 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6356187 sending ping
DEBUG: 6356639 command sent
DEBUG: 6357054 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6371397 sending ping
DEBUG: 6371837 command sent
DEBUG: 6372245 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6386607 sending ping
DEBUG: 6387238 command sent
DEBUG: 6387647 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6401817 sending ping
DEBUG: 6402262 command sent
DEBUG: 6402673 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6417032 sending ping
DEBUG: 6417913 command sent
DEBUG: 6418742 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6432237 sending ping
DEBUG: 6433111 command sent
DEBUG: 6433690 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6447446 sending ping
DEBUG: 6447912 command sent
DEBUG: 6448336 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6462655 sending ping
DEBUG: 6463112 command sent
DEBUG: 6463546 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6477870 sending ping
DEBUG: 6478763 command sent
DEBUG: 6479642 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6493077 sending ping
DEBUG: 6493737 command sent
DEBUG: 6494176 COMMAND_ACCEPTED - waiting for 0 more
DEBUG: 6508287 sending ping
DEBUG: 6508737 command sent
DEBUG: 6509187 COMMAND_ACCEPTED - waiting for 0 more