Quantcast

Latency issue with Arduino usb IR receiver/girs driver

classic Classic list List threaded Threaded
9 messages Options
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Latency issue with Arduino usb IR receiver/girs driver

VDR User
Hi,

Is there any way to speed up the response time when using an
Arduino-based usb IR receiver? Anything in the Arduino firmware or
settings within lirc or the girs driver that might affect latency? I
recently got this setup working but there's lag between when I press a
button the remote, and when it registers (in both irw and when using
VDR+lirc).

Thanks for any help!

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

Bengt Martensson-2
On 08/23/16 06:55, VDR User wrote:
> Hi,
>
> Is there any way to speed up the response time when using an
> Arduino-based usb IR receiver? Anything in the Arduino firmware or
> settings within lirc or the girs driver that might affect latency? I
> recently got this setup working but there's lag between when I press a
> button the remote, and when it registers (in both irw and when using
> VDR+lirc).

Does the lircd option --immediate-init solve the problem?

If not, please post a log (edited) (using loglevel trace2) documenting
the problem. This should show when the signal is arrives, and when it is
processed.

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

VDR User
>> Is there any way to speed up the response time when using an
>> Arduino-based usb IR receiver? Anything in the Arduino firmware or
>> settings within lirc or the girs driver that might affect latency? I
>> recently got this setup working but there's lag between when I press a
>> button the remote, and when it registers (in both irw and when using
>> VDR+lirc).
>
> Does the lircd option --immediate-init solve the problem?

Unfortunately that seems to have no affect.

> If not, please post a log (edited) (using loglevel trace2) documenting
> the problem. This should show when the signal is arrives, and when it is
> processed.

Even after trimming a few snippets of logging, my previous post
exceeded the mailing list size. If needed, I can email or paste the
entire log (2MB plain text) to a paste site of your choice. Otherwise,
I'll post 3 small replies with 1 snippet each. This one will contain a
logging snippet after lircd.conf was loaded and beginning from when
everything seems to start fine. I haven't pressed any buttons on the
remote at this point. I have no clue if it's normal or why there's
continuous "Trace: decode: enter" stuff. Similar logging continues
until I press the `blue` button on the remote. Logging from there will
continue in my next post.

Aug 23 10:37:24.668924 pc lircd: Notice: lircd(girs) ready, using
/var/run/lirc/lircd
Aug 23 10:37:26.671373 pc lircd: Trace: registering local client
Aug 23 10:37:26.671431 pc lircd: Notice: accepted new client on
/var/run/lirc/lircd
Aug 23 10:37:26.671444 pc lircd: Trace1: girs: init
Aug 23 10:37:26.671467 pc lircd: Trace: girs: flushing the input
Aug 23 10:37:26.771625 pc lircd: Trace1: girs: written command "receive"
Aug 23 10:37:26.771689 pc lircd: Trace1: girs: written command "
"
Aug 23 10:37:26.772372 pc lircd: Trace: girs: flushing the input
Aug 23 10:37:36.706672 pc lircd: Debug: girs_receive
Aug 23 10:37:36.706737 pc lircd: Trace1: girs readdata, timeout = 0
Aug 23 10:37:36.708643 pc lircd: Trace: girs: readline returned "+100
-16964 +100 -16400 +100 -19028"
Aug 23 10:37:36.708697 pc lircd: Trace1: girs: written command "receive"
Aug 23 10:37:36.708711 pc lircd: Trace1: girs: written command "
"
Aug 23 10:37:36.709642 pc lircd: Trace: girs: flushing the input
Aug 23 10:37:36.809778 pc lircd: Debug: girs: initial dummy timeout
Aug 23 10:37:36.809801 pc lircd: Trace: readdata 0 1000000
Aug 23 10:37:36.809811 pc lircd: Trace2: c1000000
Aug 23 10:37:36.809821 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:36.809831 pc lircd: Trace2: pending space: 0
Aug 23 10:37:36.809841 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:36.809851 pc lircd: Trace: decode: enter
Aug 23 10:37:36.809860 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:36.809869 pc lircd: Trace2: pending space: 0
Aug 23 10:37:36.809879 pc lircd: Trace2: <s1000000
Aug 23 10:37:36.809889 pc lircd: Trace: sync
Aug 23 10:37:36.809899 pc lircd: Trace2: expecting pulse: 525
Aug 23 10:37:36.809909 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:36.809918 pc lircd: Trace: readdata 1 100
Aug 23 10:37:36.809928 pc lircd: Trace2: +p100
Aug 23 10:37:36.809938 pc lircd: Trace2: unget: 1
Aug 23 10:37:36.809947 pc lircd: Trace: failed on header
Aug 23 10:37:36.809956 pc lircd: Trace: decode returned: 0
Aug 23 10:37:36.809968 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.123641 pc lircd: Debug: girs_receive
Aug 23 10:37:37.123700 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.123711 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.123721 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.123731 pc lircd: Trace: decode: enter
Aug 23 10:37:37.123741 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.123750 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.123760 pc lircd: Trace2: <p100
Aug 23 10:37:37.123769 pc lircd: Trace1: space expected
Aug 23 10:37:37.123779 pc lircd: Trace: failed on sync
Aug 23 10:37:37.123788 pc lircd: Trace: decode returned: 0
Aug 23 10:37:37.123797 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.123815 pc lircd: Debug: girs_receive
Aug 23 10:37:37.123825 pc lircd: Trace1: girs readdata, timeout = 0
Aug 23 10:37:37.123835 pc lircd: Trace: readdata 0 16964
Aug 23 10:37:37.123844 pc lircd: Trace2: c16964
Aug 23 10:37:37.123854 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.123863 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.123872 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.123881 pc lircd: Trace: decode: enter
Aug 23 10:37:37.123890 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.123900 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.123909 pc lircd: Trace2: <s16964
Aug 23 10:37:37.123918 pc lircd: Trace: sync
Aug 23 10:37:37.123928 pc lircd: Trace2: expecting pulse: 525
Aug 23 10:37:37.123938 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:37.123947 pc lircd: Trace: readdata 1 100
Aug 23 10:37:37.123957 pc lircd: Trace2: +p100
Aug 23 10:37:37.123967 pc lircd: Trace2: unget: 1
Aug 23 10:37:37.123976 pc lircd: Trace: failed on header
Aug 23 10:37:37.123986 pc lircd: Trace: decode returned: 0
Aug 23 10:37:37.123995 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.124012 pc lircd: Debug: girs_receive
Aug 23 10:37:37.124021 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124031 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124040 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.124049 pc lircd: Trace: decode: enter
Aug 23 10:37:37.124058 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124067 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124077 pc lircd: Trace2: <p100
Aug 23 10:37:37.124086 pc lircd: Trace1: space expected
Aug 23 10:37:37.124095 pc lircd: Trace: failed on sync
Aug 23 10:37:37.124104 pc lircd: Trace: decode returned: 0
Aug 23 10:37:37.124113 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.124129 pc lircd: Debug: girs_receive
Aug 23 10:37:37.124139 pc lircd: Trace1: girs readdata, timeout = 0
Aug 23 10:37:37.124148 pc lircd: Trace: readdata 0 16400
Aug 23 10:37:37.124157 pc lircd: Trace2: c16400
Aug 23 10:37:37.124167 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124188 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124198 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.124207 pc lircd: Trace: decode: enter
Aug 23 10:37:37.124216 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124225 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124234 pc lircd: Trace2: <s16400
Aug 23 10:37:37.124243 pc lircd: Trace: sync
Aug 23 10:37:37.124252 pc lircd: Trace2: expecting pulse: 525
Aug 23 10:37:37.124261 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:37.124270 pc lircd: Trace: readdata 1 100
Aug 23 10:37:37.124279 pc lircd: Trace2: +p100
Aug 23 10:37:37.124289 pc lircd: Trace2: unget: 1
Aug 23 10:37:37.124298 pc lircd: Trace: failed on header
Aug 23 10:37:37.124307 pc lircd: Trace: decode returned: 0
Aug 23 10:37:37.124316 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.124333 pc lircd: Debug: girs_receive
Aug 23 10:37:37.124342 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124366 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124375 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.124384 pc lircd: Trace: decode: enter
Aug 23 10:37:37.124393 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124402 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124411 pc lircd: Trace2: <p100
Aug 23 10:37:37.124421 pc lircd: Trace1: space expected
Aug 23 10:37:37.124429 pc lircd: Trace: failed on sync
Aug 23 10:37:37.124438 pc lircd: Trace: decode returned: 0
Aug 23 10:37:37.124447 pc lircd: Trace: decoding failed for all remotes
Aug 23 10:37:37.124464 pc lircd: Debug: girs_receive
Aug 23 10:37:37.124473 pc lircd: Trace1: girs readdata, timeout = 0
Aug 23 10:37:37.124482 pc lircd: Trace: readdata 0 19028
Aug 23 10:37:37.124492 pc lircd: Trace2: c19028
Aug 23 10:37:37.124501 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:37.124510 pc lircd: Trace2: pending space: 0
Aug 23 10:37:37.124519 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:37.124528 pc lircd: Trace: decode: enter
---snip---

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

VDR User
In reply to this post by Bengt Martensson-2
The following log snippet includes a single press of the `blue` button
on the remote:

Similar logging continues until I press the blue button on the remote:
Aug 23 10:37:40.891231 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:40.891240 pc lircd: Trace: decode: enter
Aug 23 10:37:40.891249 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:40.891258 pc lircd: Trace2: pending space: 0
Aug 23 10:37:40.891267 pc lircd: Trace2: <s6150
Aug 23 10:37:40.891276 pc lircd: Trace: sync
Aug 23 10:37:40.891285 pc lircd: Trace2: expecting pulse: 525
Aug 23 10:37:40.891294 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891303 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891312 pc lircd: Trace2: +p350
Aug 23 10:37:40.891322 pc lircd: Trace2: unget: 1
Aug 23 10:37:40.891331 pc lircd: Trace: header
Aug 23 10:37:40.891340 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891363 pc lircd: Trace2: <p350
Aug 23 10:37:40.891373 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891382 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891391 pc lircd: Trace: readdata 0 1700
Aug 23 10:37:40.891400 pc lircd: Trace2: +s1700
Aug 23 10:37:40.891410 pc lircd: Trace1: 1
Aug 23 10:37:40.891419 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891428 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891437 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891446 pc lircd: Trace2: +p350
Aug 23 10:37:40.891461 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891471 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891480 pc lircd: Trace: readdata 0 2900
Aug 23 10:37:40.891489 pc lircd: Trace2: +s2900
Aug 23 10:37:40.891498 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.891507 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891517 pc lircd: Trace2: <p350
Aug 23 10:37:40.891526 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.891535 pc lircd: Trace2: <s2900
Aug 23 10:37:40.891544 pc lircd: Trace1: 0
Aug 23 10:37:40.891553 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891562 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891571 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891581 pc lircd: Trace2: +p350
Aug 23 10:37:40.891590 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891599 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891608 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.891617 pc lircd: Trace2: +s2850
Aug 23 10:37:40.891627 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.891636 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891645 pc lircd: Trace2: <p350
Aug 23 10:37:40.891654 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.891663 pc lircd: Trace2: <s2850
Aug 23 10:37:40.891672 pc lircd: Trace1: 0
Aug 23 10:37:40.891681 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891690 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891699 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891708 pc lircd: Trace2: +p350
Aug 23 10:37:40.891717 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891727 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891736 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.891745 pc lircd: Trace2: +s2850
Aug 23 10:37:40.891754 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.891763 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891772 pc lircd: Trace2: <p350
Aug 23 10:37:40.891782 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.891792 pc lircd: Trace2: <s2850
Aug 23 10:37:40.891801 pc lircd: Trace1: 0
Aug 23 10:37:40.891810 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891820 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891829 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891838 pc lircd: Trace2: +p350
Aug 23 10:37:40.891847 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891856 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891865 pc lircd: Trace: readdata 0 1700
Aug 23 10:37:40.891874 pc lircd: Trace2: +s1700
Aug 23 10:37:40.891884 pc lircd: Trace1: 1
Aug 23 10:37:40.891892 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891902 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891911 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.891920 pc lircd: Trace2: +p350
Aug 23 10:37:40.891929 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.891938 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891947 pc lircd: Trace: readdata 0 1700
Aug 23 10:37:40.891956 pc lircd: Trace2: +s1700
Aug 23 10:37:40.891966 pc lircd: Trace1: 1
Aug 23 10:37:40.891974 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.891984 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.891993 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892002 pc lircd: Trace2: +p350
Aug 23 10:37:40.892011 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892020 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892029 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892038 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892048 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892057 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892066 pc lircd: Trace2: <p350
Aug 23 10:37:40.892075 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892084 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892098 pc lircd: Trace1: 0
Aug 23 10:37:40.892107 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892116 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892125 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892135 pc lircd: Trace2: +p350
Aug 23 10:37:40.892144 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892153 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892162 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892171 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892180 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892189 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892198 pc lircd: Trace2: <p350
Aug 23 10:37:40.892207 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892216 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892226 pc lircd: Trace1: 0
Aug 23 10:37:40.892234 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892243 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892252 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892262 pc lircd: Trace2: +p350
Aug 23 10:37:40.892271 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892280 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892289 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892298 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892307 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892316 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892325 pc lircd: Trace2: <p350
Aug 23 10:37:40.892334 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892344 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892365 pc lircd: Trace1: 0
Aug 23 10:37:40.892374 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892383 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892392 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892402 pc lircd: Trace2: +p350
Aug 23 10:37:40.892411 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892420 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892429 pc lircd: Trace: readdata 0 2900
Aug 23 10:37:40.892438 pc lircd: Trace2: +s2900
Aug 23 10:37:40.892447 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892456 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892465 pc lircd: Trace2: <p350
Aug 23 10:37:40.892474 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892483 pc lircd: Trace2: <s2900
Aug 23 10:37:40.892493 pc lircd: Trace1: 0
Aug 23 10:37:40.892501 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892510 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892519 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892529 pc lircd: Trace2: +p350
Aug 23 10:37:40.892538 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892547 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892556 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892565 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892574 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892583 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892592 pc lircd: Trace2: <p350
Aug 23 10:37:40.892601 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892611 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892620 pc lircd: Trace1: 0
Aug 23 10:37:40.892628 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892638 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892647 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892656 pc lircd: Trace2: +p350
Aug 23 10:37:40.892665 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892674 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892683 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892692 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892701 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892710 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892719 pc lircd: Trace2: <p350
Aug 23 10:37:40.892728 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892743 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892753 pc lircd: Trace1: 0
Aug 23 10:37:40.892761 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892770 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892780 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.892789 pc lircd: Trace2: +p350
Aug 23 10:37:40.892798 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892807 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892816 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.892825 pc lircd: Trace2: +s2850
Aug 23 10:37:40.892834 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892843 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892852 pc lircd: Trace2: <p350
Aug 23 10:37:40.892862 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892871 pc lircd: Trace2: <s2850
Aug 23 10:37:40.892880 pc lircd: Trace1: 0
Aug 23 10:37:40.892889 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892898 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892907 pc lircd: Trace: readdata 1 400
Aug 23 10:37:40.892916 pc lircd: Trace2: +p400
Aug 23 10:37:40.892925 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.892934 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.892943 pc lircd: Trace: readdata 0 2800
Aug 23 10:37:40.892952 pc lircd: Trace2: +s2800
Aug 23 10:37:40.892961 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.892970 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.892979 pc lircd: Trace2: <p400
Aug 23 10:37:40.892988 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.892998 pc lircd: Trace2: <s2800
Aug 23 10:37:40.893007 pc lircd: Trace1: 0
Aug 23 10:37:40.893015 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.893025 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893034 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.893043 pc lircd: Trace2: +p350
Aug 23 10:37:40.893052 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.893061 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893070 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.893079 pc lircd: Trace2: +s2850
Aug 23 10:37:40.893088 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.893097 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.893107 pc lircd: Trace2: <p350
Aug 23 10:37:40.893116 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.893125 pc lircd: Trace2: <s2850
Aug 23 10:37:40.893134 pc lircd: Trace1: 0
Aug 23 10:37:40.893143 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.893152 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893161 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.893170 pc lircd: Trace2: +p350
Aug 23 10:37:40.893179 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:40.893188 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893197 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:40.893206 pc lircd: Trace2: +s2850
Aug 23 10:37:40.893215 pc lircd: Trace2: unget: 2
Aug 23 10:37:40.893224 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:40.893234 pc lircd: Trace2: <p350
Aug 23 10:37:40.893243 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:40.893252 pc lircd: Trace2: <s2850
Aug 23 10:37:40.893261 pc lircd: Trace1: 0
Aug 23 10:37:40.893270 pc lircd: Trace: code: 8c00
Aug 23 10:37:40.893279 pc lircd: Trace2: expecting pulse: 450
Aug 23 10:37:40.893289 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893298 pc lircd: Trace: readdata 1 350
Aug 23 10:37:40.893307 pc lircd: Trace2: +p350
Aug 23 10:37:40.893316 pc lircd: Trace1: sum: 48200
Aug 23 10:37:40.893326 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:40.893335 pc lircd: Trace: readdata 0 6200
Aug 23 10:37:40.893344 pc lircd: Trace2: +s6200
Aug 23 10:37:40.893366 pc lircd: Trace2: unget: 1
Aug 23 10:37:40.893376 pc lircd: Trace: decode returned: 1
Aug 23 10:37:40.893400 pc lircd: Trace: found: blue
Aug 23 10:37:40.893412 pc lircd: Trace: 81c788a8 0 0 0 0 0 0 0 0 1
Aug 23 10:37:40.893423 pc lircd: Trace: release_gap: 186102
Aug 23 10:37:40.893436 pc lircd: Trace: writing to client 0:
0000000000008c00 00 blue 20.1_IR

Aug 23 10:37:40.893469 pc lircd: Debug: girs_receive
Aug 23 10:37:40.893480 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:40.893489 pc lircd: Trace2: pending space: 0
Aug 23 10:37:40.893499 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:40.893508 pc lircd: Trace: decode: enter
---snip---

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

VDR User
In reply to this post by Bengt Martensson-2
Lastly, I press and hold the `down arrow` button for several (5+)
seconds, expecting continuous repeating. I only see the `down arrow`
action about every 2 seconds. This only happens using arduino/girs. If
I plug in my usb mceusb ir receiver, using the same lircd.conf and
same remote, there's no real latency from when I press buttons on the
remote to when I see the appropriate actions, and repeating is fast &
continuous as expected. There is a lot of logging that looks similar
to the following:

Aug 23 10:37:44.148504 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:44.148513 pc lircd: Trace: decode: enter
Aug 23 10:37:44.148522 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:44.148531 pc lircd: Trace2: pending space: 0
Aug 23 10:37:44.148540 pc lircd: Trace2: <s19028
Aug 23 10:37:44.148555 pc lircd: Trace: sync
Aug 23 10:37:44.148564 pc lircd: Trace2: expecting pulse: 525
Aug 23 10:37:44.148573 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.317100 pc lircd: Trace: girs: readline returned "+400
-6100 +400 -2800 +400 -1650 +400 -1650 +400 -1650 +400 -1650 +400
-2850 +350 -2850 +350 -2850 +350 -2850 +400 -2800 +400 -2800 +400
-2850 +350 -2850 +350 -2850 +350 -2850 +400 -2800 +400 -6100 +400
-2800 +400 -1650 +400 -1650 +400 -1700 +350 -1700 +350 -2850 +350
-2850 +400 -2800 +400 -2800 +400 -2800 +400 -2800 +400 -2850 +350
-2850 +350 -2850 +400 -2800 +400 -2800 +400 -6100 +400 -2800 +400
-1700 +350 -1700 +350 -1700 +350 -1700 +350 -2850 +400 -2800 +400
-2800 +400 -2800 +400 -2800 +400 -2850 +350 -2850 +350 -2850 +400
-2800 +400 -2800 +400 -2800 +400 -6100 +400 -2850 +350 -1700 +350
-1700 +350 -1700 +350 -1700 +400 -2800 +400 -2800 +400 -2800 +400
-2800 +400 -2850 +350 -2850 +350 -2850 +350 -2850 +400 -2800 +400
-2800 +400 -2800 +400 -6150 +350 -2850 +350 -1700 +350 -1700 +400
-1650 +350 -1700 +400 -2800 +400 -2800 +400 -2800 +400 -2850 +350
-2850 +350 -2850 +350 -2850 +400 -2800 +400 -2800 +400 -2800 +400
-2850 +350 -6150 +350 -2850 +350 -1700 +400 -1650 +400 -1650 +400
-1650 +400 -2800 +400 -2800 +400 -2850 +350 -2850 +350 -2850 +350
-2850 +400 -2800 +400 -2800 +400 -2800 +400 -2850 +350 -2800 +400
-6150 +350 -2850 +400 -1650 +400 -1650 +400 -1650 +400 -1650 +400
-2800 +400 -2800 +400 -2850 +350 -2850 +350 -2850 +400 -2800 +400
-2800 +400 -2800 +400 -2850 +350 -2850 +350 -2850 +350 -6150 +400
-2800 +400 -1650 +400 -1650 +400 -1650 +400 -1650 +400 -2800 +400
-2850 +350 -2850 +350 -2850 +400 -2800 +400 -2800 +400 -2800 +400
-2800 +400 -2850 +350 -2850 +350 -2850 +400 -6100 +400 -2800 +400
-1650 +400 -1650 +400 -1650 +400 -1650 +400 -2850 +350 -2800 +400
-2850 +400 -2800 +400 -2800 +400 -2800 +400 -2800 +400 -2850 +350
-2850 +350 -2850 +350 -2850 +400 -6100 +400 -2800 +400 -1650 +400
-1650 +400 -1700 +350 -1700 +350 -2850 +350 -2850 +400 -2800 +400
-2800 +400 -2800 +400 -2800 +400 -2850 +350 -2850 +350 -2850 +350
-2850 +400 -2800 +400 -6100 +400 -2800 +400 -1650 +400 -1700 +350
-1700 +350 -1700 +350 -2850 +400 -2800 +400 -2800 +400 -2800 +400
-2800 +400 -2850 +350 -2850 +350 -2850 +350 -2850 +400 -2750 +450
-2800 +400 -6100 +400 -2850 +350 -1700 +350 -1700 +350 -1700 +350
-1700 +350 -2850 +400 -2800 +400 -2800 +400 -2800 +400 -2850 +350
-2850 +350 -2850"
Aug 23 10:37:44.317303 pc lircd: Trace1: girs: written command "receive"
Aug 23 10:37:44.317317 pc lircd: Trace1: girs: written command "
"
Aug 23 10:37:44.318090 pc lircd: Trace: girs: flushing the input
Aug 23 10:37:44.418216 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418272 pc lircd: Trace2: +p400
Aug 23 10:37:44.418284 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418294 pc lircd: Trace: readdata 0 6100
Aug 23 10:37:44.418303 pc lircd: Trace2: +s6100
Aug 23 10:37:44.418313 pc lircd: Trace: header
Aug 23 10:37:44.418323 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418332 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418341 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418364 pc lircd: Trace2: +p400
Aug 23 10:37:44.418374 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418384 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418393 pc lircd: Trace: readdata 0 2800
Aug 23 10:37:44.418402 pc lircd: Trace2: +s2800
Aug 23 10:37:44.418412 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.418421 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418430 pc lircd: Trace2: <p400
Aug 23 10:37:44.418439 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.418449 pc lircd: Trace2: <s2800
Aug 23 10:37:44.418458 pc lircd: Trace1: 0
Aug 23 10:37:44.418467 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418476 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418485 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418494 pc lircd: Trace2: +p400
Aug 23 10:37:44.418516 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418526 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418535 pc lircd: Trace: readdata 0 1650
Aug 23 10:37:44.418544 pc lircd: Trace2: +s1650
Aug 23 10:37:44.418554 pc lircd: Trace1: 1
Aug 23 10:37:44.418563 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418572 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418581 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418590 pc lircd: Trace2: +p400
Aug 23 10:37:44.418599 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418608 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418617 pc lircd: Trace: readdata 0 1650
Aug 23 10:37:44.418627 pc lircd: Trace2: +s1650
Aug 23 10:37:44.418636 pc lircd: Trace1: 1
Aug 23 10:37:44.418645 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418654 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418663 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418672 pc lircd: Trace2: +p400
Aug 23 10:37:44.418681 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418690 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418699 pc lircd: Trace: readdata 0 1650
Aug 23 10:37:44.418708 pc lircd: Trace2: +s1650
Aug 23 10:37:44.418718 pc lircd: Trace1: 1
Aug 23 10:37:44.418726 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418736 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418745 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418754 pc lircd: Trace2: +p400
Aug 23 10:37:44.418763 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418772 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418781 pc lircd: Trace: readdata 0 1650
Aug 23 10:37:44.418790 pc lircd: Trace2: +s1650
Aug 23 10:37:44.418799 pc lircd: Trace1: 1
Aug 23 10:37:44.418808 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418817 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418826 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.418835 pc lircd: Trace2: +p400
Aug 23 10:37:44.418844 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418854 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418863 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.418872 pc lircd: Trace2: +s2850
Aug 23 10:37:44.418881 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.418890 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418899 pc lircd: Trace2: <p400
Aug 23 10:37:44.418908 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.418917 pc lircd: Trace2: <s2850
Aug 23 10:37:44.418926 pc lircd: Trace1: 0
Aug 23 10:37:44.418935 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.418944 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418953 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.418962 pc lircd: Trace2: +p350
Aug 23 10:37:44.418971 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.418980 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.418989 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.418998 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419008 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419017 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419026 pc lircd: Trace2: <p350
Aug 23 10:37:44.419035 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419044 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419053 pc lircd: Trace1: 0
Aug 23 10:37:44.419062 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419071 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419080 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.419089 pc lircd: Trace2: +p350
Aug 23 10:37:44.419098 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419107 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419116 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.419125 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419140 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419149 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419158 pc lircd: Trace2: <p350
Aug 23 10:37:44.419167 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419176 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419185 pc lircd: Trace1: 0
Aug 23 10:37:44.419194 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419203 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419212 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.419221 pc lircd: Trace2: +p350
Aug 23 10:37:44.419230 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419239 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419248 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.419257 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419266 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419275 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419284 pc lircd: Trace2: <p350
Aug 23 10:37:44.419294 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419303 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419312 pc lircd: Trace1: 0
Aug 23 10:37:44.419320 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419329 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419338 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.419361 pc lircd: Trace2: +p400
Aug 23 10:37:44.419371 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419380 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419389 pc lircd: Trace: readdata 0 2800
Aug 23 10:37:44.419398 pc lircd: Trace2: +s2800
Aug 23 10:37:44.419408 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419416 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419425 pc lircd: Trace2: <p400
Aug 23 10:37:44.419435 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419444 pc lircd: Trace2: <s2800
Aug 23 10:37:44.419453 pc lircd: Trace1: 0
Aug 23 10:37:44.419461 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419470 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419479 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.419488 pc lircd: Trace2: +p400
Aug 23 10:37:44.419498 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419507 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419515 pc lircd: Trace: readdata 0 2800
Aug 23 10:37:44.419525 pc lircd: Trace2: +s2800
Aug 23 10:37:44.419534 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419543 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419552 pc lircd: Trace2: <p400
Aug 23 10:37:44.419561 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419570 pc lircd: Trace2: <s2800
Aug 23 10:37:44.419579 pc lircd: Trace1: 0
Aug 23 10:37:44.419587 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419596 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419605 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.419615 pc lircd: Trace2: +p400
Aug 23 10:37:44.419624 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419633 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419642 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.419651 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419660 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419669 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419678 pc lircd: Trace2: <p400
Aug 23 10:37:44.419687 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419696 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419705 pc lircd: Trace1: 0
Aug 23 10:37:44.419714 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419723 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419732 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.419741 pc lircd: Trace2: +p350
Aug 23 10:37:44.419750 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419759 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419768 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.419782 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419792 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419801 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419810 pc lircd: Trace2: <p350
Aug 23 10:37:44.419819 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419828 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419837 pc lircd: Trace1: 0
Aug 23 10:37:44.419846 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419855 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419864 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.419873 pc lircd: Trace2: +p350
Aug 23 10:37:44.419882 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.419891 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419900 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.419909 pc lircd: Trace2: +s2850
Aug 23 10:37:44.419918 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.419927 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419936 pc lircd: Trace2: <p350
Aug 23 10:37:44.419945 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.419954 pc lircd: Trace2: <s2850
Aug 23 10:37:44.419963 pc lircd: Trace1: 0
Aug 23 10:37:44.419972 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.419981 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.419990 pc lircd: Trace: readdata 1 350
Aug 23 10:37:44.419999 pc lircd: Trace2: +p350
Aug 23 10:37:44.420008 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.420017 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.420026 pc lircd: Trace: readdata 0 2850
Aug 23 10:37:44.420035 pc lircd: Trace2: +s2850
Aug 23 10:37:44.420044 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.420053 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.420062 pc lircd: Trace2: <p350
Aug 23 10:37:44.420071 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.420080 pc lircd: Trace2: <s2850
Aug 23 10:37:44.420089 pc lircd: Trace1: 0
Aug 23 10:37:44.420098 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.420107 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.420116 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.420125 pc lircd: Trace2: +p400
Aug 23 10:37:44.420134 pc lircd: Trace2: expecting space: 1645
Aug 23 10:37:44.420143 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.420152 pc lircd: Trace: readdata 0 2800
Aug 23 10:37:44.420161 pc lircd: Trace2: +s2800
Aug 23 10:37:44.420171 pc lircd: Trace2: unget: 2
Aug 23 10:37:44.420180 pc lircd: Trace2: expecting pulse: 440
Aug 23 10:37:44.420189 pc lircd: Trace2: <p400
Aug 23 10:37:44.420198 pc lircd: Trace2: expecting space: 2780
Aug 23 10:37:44.420207 pc lircd: Trace2: <s2800
Aug 23 10:37:44.420216 pc lircd: Trace1: 0
Aug 23 10:37:44.420225 pc lircd: Trace: code: 7800
Aug 23 10:37:44.420234 pc lircd: Trace2: expecting pulse: 450
Aug 23 10:37:44.420243 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.420252 pc lircd: Trace: readdata 1 400
Aug 23 10:37:44.420261 pc lircd: Trace2: +p400
Aug 23 10:37:44.420271 pc lircd: Trace1: sum: 53600
Aug 23 10:37:44.420280 pc lircd: Trace1: girs readdata, timeout = 100000
Aug 23 10:37:44.420289 pc lircd: Trace: readdata 0 6100
Aug 23 10:37:44.420298 pc lircd: Trace2: +s6100
Aug 23 10:37:44.420307 pc lircd: Trace2: unget: 1
Aug 23 10:37:44.420316 pc lircd: Trace: decode returned: 1
Aug 23 10:37:44.420332 pc lircd: Trace: found: down
Aug 23 10:37:44.420342 pc lircd: Trace: 81c788a8 0 81c788a8 1 0 0 0 0 0 1
Aug 23 10:37:44.420367 pc lircd: Trace: release_gap: 186102
Aug 23 10:37:44.420381 pc lircd: Trace: writing to client 0:
0000000000007800 00 down 20.1_IR

Aug 23 10:37:44.606622 pc lircd: Trace2: trigger
Aug 23 10:37:44.957072 pc lircd: Debug: girs_receive
Aug 23 10:37:44.957121 pc lircd: Trace2: pending pulse: 0
Aug 23 10:37:44.957132 pc lircd: Trace2: pending space: 0
Aug 23 10:37:44.957156 pc lircd: Trace: trying "20.1_IR" remote
Aug 23 10:37:44.957166 pc lircd: Trace: decode: enter
---snip---

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

Bengt Martensson-2
Try this:
https://github.com/bengtmartensson/AGirs/files/433399/GirsLite-2016-06-01-25-nanoflasher.sh.zip

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

VDR User
> Try this:
> https://github.com/bengtmartensson/AGirs/files/433399/GirsLite-2016-06-01-25-nanoflasher.sh.zip

The latency seems slightly improved (and better consistency) with
single button presses although there's still a 1-2 delay before I see
the action. It almost seems like it waits before you aren't pressing
the button anymore and then also a delay before action happens.
Holding down a button is still not behaving correctly. It takes
several seconds before I see any action and then each repeat has the
same delay as a single button press.

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

Bengt Martensson-2
On 08/23/16 22:12, VDR User wrote:

>> Try this:
>> https://github.com/bengtmartensson/AGirs/files/433399/GirsLite-2016-06-01-25-nanoflasher.sh.zip
>
> The latency seems slightly improved (and better consistency) with
> single button presses although there's still a 1-2 delay before I see
> the action. It almost seems like it waits before you aren't pressing
> the button anymore and then also a delay before action happens.
> Holding down a button is still not behaving correctly. It takes
> several seconds before I see any action and then each repeat has the
> same delay as a single button press.
>

Try this:
https://github.com/bengtmartensson/AGirs/files/434478/GirsLite-2016-08-24-6-nano-flasher.sh.zip

------------------------------------------------------------------------------
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: Latency issue with Arduino usb IR receiver/girs driver

VDR User
>>> Try this:
>>> https://github.com/bengtmartensson/AGirs/files/433399/GirsLite-2016-06-01-25-nanoflasher.sh.zip
>>
>> The latency seems slightly improved (and better consistency) with
>> single button presses although there's still a 1-2 delay before I see
>> the action. It almost seems like it waits before you aren't pressing
>> the button anymore and then also a delay before action happens.
>> Holding down a button is still not behaving correctly. It takes
>> several seconds before I see any action and then each repeat has the
>> same delay as a single button press.
>
> Try this:
> https://github.com/bengtmartensson/AGirs/files/434478/GirsLite-2016-08-24-6-nano-flasher.sh.zip

With this one the remote doesn't work at all. I'm not sure if this
would be helpful at all but I did come across this during my
researches:
https://github.com/z3t0/Arduino-IRremote/blob/master/ir_Dish.cpp

------------------------------------------------------------------------------
Loading...