[Twisted-Python] Garbage in logfiles?

Christopher Armstrong radix at twistedmatrix.com
Wed Oct 16 11:44:27 MDT 2013


On Wed, Oct 16, 2013 at 12:33 PM, Conor Sayres <sayresc at gmail.com> wrote:

> Hi All,
>
> I'm working for an astro observatory and we are upgrading our telescope
> control software.  We are swapping to twisted under the hood, and I have
> run into an issue regarding logging.
>
> I have set up twisted logging to record all communication between our
> devices.  When we simulate our system with a high frequency log rollover
> rate (~5 seconds), many of the logs contain only garbage.  Here is an
> example of a few lines in a given bad log file:
>
> 3230 3133 2d31 302d 3136 2031 303a 3131
> 3a34 352d 3037 3030 205b 2d5d 2044 6576
> 436d 6428 5354 4154 5553 290a 3230 3133
> 2d31 302d 3136 2031 303a 3131 3a34 352d
> 3037 3030 205b 2d5d 2044 6576 436d 6428
> 5354 4154 5553 290a 3230 3133 2d31 302d
>
> Here is an example of a few lines from a good log file:
>
> 2013-10-16 10:11:39-0700 [_SocketProtocol,client] Galil Reply(XQ#STATUS)
> 2013-10-16 10:11:39-0700 [-] Galil Reply(: 1,  1,  1,  1,  1 axis homed)
> 2013-10-16 10:11:39-0700 [-] To All Users(1 1 i axisHomed=1, 1, 1, 1, 1)
> 2013-10-16 10:11:39-0700 [-] Galil Reply(000000000, 000000000, 000000000,
> 000000000, 000000000 commanded position)
> 2013-10-16 10:11:39-0700 [-] To All Users(1 1 i cmdMount=0, 0, 0, 0, 0)
> 2013-10-16 10:11:39-0700 [_SocketProtocol,client] MirrorCtrl Reply(1 1 i
> axisHomed=1, 1, 1, 1, 1)
> 2013-10-16 10:11:39-0700 [_SocketProtocol,client] Galil Reply(XQ#STATUS)
>
> I haven't been able to reproduce the problem in a simpler framework than
> our simulation.  I am triggering the rollover by directly calling
> LogFile.rollover on a timer.  The problem also exists if I define a small
> file size limit for automatic rollover and let twisted do it automatically.
>


I don't think there's any method named "rollover" anywhere in Twisted (in
fact, I just confirmed this with grep).

Your message is pretty vague and context-free; could you try to add more
necessary detail? Is the problem with the output you're seeing the
*content* of the numbers/letters, or just the fact that they're appearing
at all? Maybe there's relevant output before or after that block of
numbers/letters? Did you leave out the timestamp prefixes when pasting to
this email, or is there really no timestamp prefixing the lines of that
output?

I don't know where those numbers/letters could be coming from. Does your
code (or any libraries you use) ever print to stdout? By default, Twisted
redirects all prints (and any writing to sys.stdout) to the log file.


-- 
Christopher Armstrong
http://radix.twistedmatrix.com/
http://planet-if.com/
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://twistedmatrix.com/pipermail/twisted-python/attachments/20131016/c09661eb/attachment.html>


More information about the Twisted-Python mailing list