[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:33] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:34] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:34] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:35] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:35] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:36] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:36] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:37] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:37] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:38] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:38] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:39] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:39] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] app_queue.c: There are 0 available members.
[2009-03-10 18:35:40] DEBUG[31749] app_queue.c: It's not our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:40] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: There are 1 available members.
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: It's our turn (SIP/GW-08c4a238).
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: SIP/GW-08c4a238 is trying to call a queue member.
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Trying 'agent/1028' with metric 0
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Found matching member agent/1028 in queue '803'
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/4035-091110d8
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/4035-091110d8 to write format g729
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable MACRO_DEPTH.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable MONITOR_FILENAME.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG7.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG6.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG5.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG4.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG3.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG2.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable ARG1.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable MACRO_PRIORITY.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable MACRO_CONTEXT.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable MACRO_EXTEN.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable SIPCALLID.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable SIPUSERAGENT.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable SIPDOMAIN.
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Not copying variable SIPURI.
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- agent_call, call to agent '1028' call on 'SIP/4035-091110d8'
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Playing beep, lang 'pt_BR'
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/4035-091110d8 to write format slin
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Thread -1225827408 Blocking 'SIP/4035-091110d8', already blocked by thread -1243960400 in procedure ast_waitfor_nandfds
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- <SIP/4035-091110d8> Playing 'beep' (language 'pt_BR')
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Played beep, result '0'
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/4035-091110d8 to write format g729
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Waited for stream, result '0'
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Set read format, result '0'
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Set write format, result '0'
[2009-03-10 18:35:41] DEBUG[31749] devicestate.c: Notification of state change to be queued on device/channel Agent/1028
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- Called agent/1028
[2009-03-10 18:35:41] DEBUG[31749] devicestate.c: No provider found, checking channel drivers for agent - 1028
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- Agent/1028 answered SIP/GW-08c4a238
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Next is 'agent/1065' with metric 1
[2009-03-10 18:35:41] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/GW-08c4a238
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/GW-08c4a238 to write format alaw
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/GW-08c4a238 to read format slin
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel Agent/1028 to write format slin
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel Agent/1028 to read format slin
[2009-03-10 18:35:41] DEBUG[31749] channel.c: Set channel SIP/GW-08c4a238 to write format slin
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Starting MixMonitor as requested.
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Arguments being passed to MixMonitor: /var/spool/asterisk/queues/801-4139068400-1236720913.wav|b
[2009-03-10 18:35:41] DEBUG[31749] app_queue.c: Queue '801' Leave, Channel 'SIP/GW-08c4a238'
[2009-03-10 18:35:41] DEBUG[31749] chan_agent.c: Bridge on 'SIP/4035-091110d8' being set to 'Agent/1028' (3)
[2009-03-10 18:35:41] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:35:41] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:35:44] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:35:44] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:35:49] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:35:51] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:35:54] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:35:56] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:35:59] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:00] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:04] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:06] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:06] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:06] DTMF[31749] channel.c: DTMF begin '#' received on SIP/4035-091110d8
[2009-03-10 18:36:06] DTMF[31749] channel.c: DTMF begin passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:06] DTMF[31749] channel.c: DTMF begin '#' received on Agent/1028
[2009-03-10 18:36:06] DTMF[31749] channel.c: DTMF begin passthrough '#' on Agent/1028
[2009-03-10 18:36:06] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:06] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:06] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:06] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end '#' received on SIP/4035-091110d8, duration 90 ms
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end accepted with begin '#' on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end '#' received on Agent/1028, duration 90 ms
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end accepted with begin '#' on Agent/1028
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end passthrough '#' on Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:07] DEBUG[31749] res_features.c: Set time limit to 5000
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF begin '3' received on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF begin passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF begin '3' received on Agent/1028
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF begin passthrough '3' on Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end '3' received on SIP/4035-091110d8, duration 90 ms
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end accepted with begin '3' on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end '3' received on Agent/1028, duration 90 ms
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end accepted with begin '3' on Agent/1028
[2009-03-10 18:36:07] DTMF[31749] channel.c: DTMF end passthrough '3' on Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:07] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:07] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#3, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:07] DEBUG[31749] res_features.c: Executing Attended Transfer SIP/GW-08c4a238, Agent/1028 (sense=2) 
[2009-03-10 18:36:07] VERBOSE[31749] logger.c:     -- Started music on hold, class 'default', on SIP/GW-08c4a238
[2009-03-10 18:36:07] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'pbx-transfer' (language 'pt_BR')
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:07] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '7' received on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '7' received on Agent/1028
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin ignored '7' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '7' received on SIP/4035-091110d8, duration 120 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end accepted with begin '7' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '7' received on Agent/1028, duration 120 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '7' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '2' received on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '2' received on Agent/1028
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin ignored '2' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '2' received on SIP/4035-091110d8, duration 140 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end accepted with begin '2' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '2' received on Agent/1028, duration 140 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '2' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '1' received on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '1' received on Agent/1028
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin ignored '1' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '1' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end accepted with begin '1' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '1' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '1' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 54 (6), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '6' received on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin passthrough '6' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin '6' received on Agent/1028
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF begin ignored '6' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: Sending dtmf: 54 (6), at 10.25.24.44
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '6' received on SIP/4035-091110d8, duration 140 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end accepted with begin '6' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '6' on SIP/4035-091110d8
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end '6' received on Agent/1028, duration 140 ms
[2009-03-10 18:36:08] DTMF[31749] channel.c: DTMF end passthrough '6' on Agent/1028
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:08] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF begin '#' received on SIP/4035-091110d8
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF begin passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF begin '#' received on Agent/1028
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF begin ignored '#' on Agent/1028
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF end '#' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF end accepted with begin '#' on SIP/4035-091110d8
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF end passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF end '#' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:09] DTMF[31749] channel.c: DTMF end passthrough '#' on Agent/1028
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Not copying variable BRIDGEPEER.
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Copying hard-transferable variable MONITORED.
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Driver for channel 'Agent/1028' does not support indication 3, emulating it
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] rtp.c: Difference is 8784, ms is 1118
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] VERBOSE[31749] logger.c:     -- Local/7216@extensions-2-a8c9,1 is ringing
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Driver for channel 'Agent/1028' does not support indication 3, emulating it
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:09] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:10] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:11] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:12] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:13] DEBUG[31749] devicestate.c: Notification of state change to be queued on device/channel Local/7216@extensions-2
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Set channel Agent/1028 to read format g729
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Set channel Agent/1028 to write format g729
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Actually Masquerading SIP/7216-09159a48(6) into the structure of Local/7216@extensions-2-a8c9,1(6)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Got clone lock for masquerade on 'SIP/7216-09159a48' at 0x93abee0
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Putting channel SIP/7216-09159a48 in 256/256 formats
[2009-03-10 18:36:13] DEBUG[31749] chan_sip.c: SIP Fixup: New owner for dialogue 50a667b143406c100a70391a746317e5@10.25.24.41: SIP/7216-09159a48 (Old parent: Local/7216@extensions-2-a8c9,1<ZOMBIE>)
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Released clone lock on 'Local/7216@extensions-2-a8c9,1<ZOMBIE>'
[2009-03-10 18:36:13] DEBUG[31749] channel.c: Done Masquerading SIP/7216-09159a48 (6)
[2009-03-10 18:36:16] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:19] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:22] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Didn't get a frame from channel: SIP/7216-09159a48
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Bridge stops bridging channels Agent/1028 and SIP/7216-09159a48
[2009-03-10 18:36:26] DEBUG[31749] pbx.c: Launching 'Goto'
[2009-03-10 18:36:26] VERBOSE[31749] logger.c:     -- Executing [h@extensions-2:1] Goto("Agent/1028", "hangupcall|s|1") in new stack
[2009-03-10 18:36:26] VERBOSE[31749] logger.c:     -- Goto (hangupcall,s,1)
[2009-03-10 18:36:26] DEBUG[31749] pbx.c: Launching 'NoCDR'
[2009-03-10 18:36:26] VERBOSE[31749] logger.c:     -- Executing [s@hangupcall:1] NoCDR("Agent/1028", "") in new stack
[2009-03-10 18:36:26] DEBUG[31749] pbx.c: Launching 'PlayTones'
[2009-03-10 18:36:26] VERBOSE[31749] logger.c:     -- Executing [s@hangupcall:2] PlayTones("Agent/1028", "busy") in new stack
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Set channel Agent/1028 to write format slin
[2009-03-10 18:36:26] DEBUG[31749] pbx.c: Launching 'Wait'
[2009-03-10 18:36:26] VERBOSE[31749] logger.c:     -- Executing [s@hangupcall:3] Wait("Agent/1028", "5") in new stack
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:26] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:27] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:28] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:29] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:30] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:31] DEBUG[31749] pbx.c: Launching 'StopPlayTones'
[2009-03-10 18:36:31] VERBOSE[31749] logger.c:     -- Executing [s@hangupcall:4] StopPlayTones("Agent/1028", "") in new stack
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Set channel Agent/1028 to write format g729
[2009-03-10 18:36:31] DEBUG[31749] pbx.c: Launching 'Hangup'
[2009-03-10 18:36:31] VERBOSE[31749] logger.c:     -- Executing [s@hangupcall:5] Hangup("Agent/1028", "") in new stack
[2009-03-10 18:36:31] DEBUG[31749] res_features.c: Spawn h extension (hangupcall,s,5) exited non-zero on 'Agent/1028'
[2009-03-10 18:36:31] VERBOSE[31749] logger.c:   == Spawn h extension (hangupcall, s, 5) exited non-zero on 'Agent/1028'
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Hanging up channel 'SIP/7216-09159a48'
[2009-03-10 18:36:31] DEBUG[31749] chan_sip.c: Hangup call SIP/7216-09159a48, SIP callid 50a667b143406c100a70391a746317e5@10.25.24.41)
[2009-03-10 18:36:31] DEBUG[31749] chan_sip.c: Updating call counter for outgoing call
[2009-03-10 18:36:31] DEBUG[31749] chan_sip.c: Call to peer '7216' removed from call limit 10
[2009-03-10 18:36:31] DEBUG[31749] devicestate.c: Notification of state change to be queued on device/channel SIP/7216
[2009-03-10 18:36:31] DEBUG[31749] devicestate.c: Notification of state change to be queued on device/channel SIP/7216
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Set channel Agent/1028 to write format slin
[2009-03-10 18:36:31] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'beep' (language 'pt_BR')
[2009-03-10 18:36:31] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/GW-08c4a238
[2009-03-10 18:36:31] DEBUG[31749] channel.c: Set channel Agent/1028 to read format slin
[2009-03-10 18:36:31] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:34] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:38] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:39] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin '#' received on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin '#' received on Agent/1028
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin passthrough '#' on Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end '#' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end accepted with begin '#' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end '#' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end accepted with begin '#' on Agent/1028
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end passthrough '#' on Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:42] DEBUG[31749] res_features.c: Set time limit to 5000
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin '3' received on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin '3' received on Agent/1028
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF begin passthrough '3' on Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end '3' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end accepted with begin '3' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end '3' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end accepted with begin '3' on Agent/1028
[2009-03-10 18:36:42] DTMF[31749] channel.c: DTMF end passthrough '3' on Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:42] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:42] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#3, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:42] DEBUG[31749] res_features.c: Executing Attended Transfer SIP/GW-08c4a238, Agent/1028 (sense=2) 
[2009-03-10 18:36:42] VERBOSE[31749] logger.c:     -- Started music on hold, class 'default', on SIP/GW-08c4a238
[2009-03-10 18:36:42] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'pbx-transfer' (language 'pt_BR')
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:42] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '7' received on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '7' received on Agent/1028
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin ignored '7' on Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '7' received on SIP/4035-091110d8, duration 140 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end accepted with begin '7' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '7' received on Agent/1028, duration 140 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end passthrough '7' on Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:43] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '2' received on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '2' received on Agent/1028
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin ignored '2' on Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '2' received on SIP/4035-091110d8, duration 190 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end accepted with begin '2' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '2' received on Agent/1028, duration 190 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end passthrough '2' on Agent/1028
[2009-03-10 18:36:43] WARNING[31749] res_features.c: Did not read data.
[2009-03-10 18:36:43] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/GW-08c4a238
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Difference is 1648, ms is 226
[2009-03-10 18:36:43] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'beeperr' (language 'pt_BR')
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '1' received on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin '1' received on Agent/1028
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin ignored '1' on Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '1' received on SIP/4035-091110d8, duration 130 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end accepted with begin '1' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF end '1' received on Agent/1028, duration 130 ms
[2009-03-10 18:36:43] DTMF[31749] channel.c: DTMF begin emulation of '1' with duration 130 queued on Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:43] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:43] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end emulation of '1' queued on Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=1, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:44] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin '#' received on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin '#' received on Agent/1028
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin passthrough '#' on Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end '#' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end accepted with begin '#' on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end '#' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end accepted with begin '#' on Agent/1028
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF end passthrough '#' on Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:44] DEBUG[31749] res_features.c: Set time limit to 5000
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin '3' received on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin '3' received on Agent/1028
[2009-03-10 18:36:44] DTMF[31749] channel.c: DTMF begin passthrough '3' on Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:44] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:44] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '3' received on SIP/4035-091110d8, duration 120 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end accepted with begin '3' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '3' received on Agent/1028, duration 120 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end accepted with begin '3' on Agent/1028
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '3' on Agent/1028
[2009-03-10 18:36:45] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:45] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:45] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#3, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:45] DEBUG[31749] res_features.c: Executing Attended Transfer SIP/GW-08c4a238, Agent/1028 (sense=2) 
[2009-03-10 18:36:45] VERBOSE[31749] logger.c:     -- Started music on hold, class 'default', on SIP/GW-08c4a238
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Difference is 2504, ms is 333
[2009-03-10 18:36:45] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'pbx-transfer' (language 'pt_BR')
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin '7' received on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin '7' received on Agent/1028
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin ignored '7' on Agent/1028
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '7' received on SIP/4035-091110d8, duration 130 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end accepted with begin '7' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '7' received on Agent/1028, duration 130 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '7' on Agent/1028
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin '2' received on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin '2' received on Agent/1028
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF begin ignored '2' on Agent/1028
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '2' received on SIP/4035-091110d8, duration 140 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end accepted with begin '2' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end '2' received on Agent/1028, duration 140 ms
[2009-03-10 18:36:45] DTMF[31749] channel.c: DTMF end passthrough '2' on Agent/1028
[2009-03-10 18:36:45] WARNING[31749] res_features.c: Did not read data.
[2009-03-10 18:36:45] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/GW-08c4a238
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: Difference is 1656, ms is 227
[2009-03-10 18:36:45] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'beeperr' (language 'pt_BR')
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:45] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin '1' received on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin '1' received on Agent/1028
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin ignored '1' on Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: Sending dtmf: 49 (1), at 10.25.24.44
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end '1' received on SIP/4035-091110d8, duration 150 ms
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end accepted with begin '1' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end passthrough '1' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end '1' received on Agent/1028, duration 150 ms
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end passthrough '1' on Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: Sending dtmf: 54 (6), at 10.25.24.44
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin '6' received on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin passthrough '6' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin '6' received on Agent/1028
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin ignored '6' on Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: Sending dtmf: 54 (6), at 10.25.24.44
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end '6' received on SIP/4035-091110d8, duration 170 ms
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end accepted with begin '6' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end passthrough '6' on SIP/4035-091110d8
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end '6' received on Agent/1028, duration 170 ms
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF begin emulation of '6' with duration 170 queued on Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:46] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[2009-03-10 18:36:46] DTMF[31749] channel.c: DTMF end emulation of '6' queued on Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:46] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:46] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=6, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:46] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:49] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:49] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:49] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:51] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:54] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:54] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin '#' received on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin '#' received on Agent/1028
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin passthrough '#' on Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: Sending dtmf: 35 (#), at 10.25.24.44
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end '#' received on SIP/4035-091110d8, duration 110 ms
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end accepted with begin '#' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end passthrough '#' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end '#' received on Agent/1028, duration 110 ms
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end accepted with begin '#' on Agent/1028
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end passthrough '#' on Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:56] DEBUG[31749] res_features.c: Set time limit to 5000
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin '3' received on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin '3' received on Agent/1028
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF begin passthrough '3' on Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: Sending dtmf: 51 (3), at 10.25.24.44
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end '3' received on SIP/4035-091110d8, duration 100 ms
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end accepted with begin '3' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end passthrough '3' on SIP/4035-091110d8
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end '3' received on Agent/1028, duration 100 ms
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end accepted with begin '3' on Agent/1028
[2009-03-10 18:36:56] DTMF[31749] channel.c: DTMF end passthrough '3' on Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:56] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:56] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=#3, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:56] DEBUG[31749] res_features.c: Executing Attended Transfer SIP/GW-08c4a238, Agent/1028 (sense=2) 
[2009-03-10 18:36:56] VERBOSE[31749] logger.c:     -- Started music on hold, class 'default', on SIP/GW-08c4a238
[2009-03-10 18:36:56] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'pbx-transfer' (language 'pt_BR')
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:56] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000003 (len = 4)
[2009-03-10 18:36:57] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:57] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF begin '7' received on SIP/4035-091110d8
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF begin passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF begin '7' received on Agent/1028
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF begin ignored '7' on Agent/1028
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: Sending dtmf: 55 (7), at 10.25.24.44
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF end '7' received on SIP/4035-091110d8, duration 150 ms
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF end accepted with begin '7' on SIP/4035-091110d8
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF end passthrough '7' on SIP/4035-091110d8
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF end '7' received on Agent/1028, duration 150 ms
[2009-03-10 18:36:58] DTMF[31749] channel.c: DTMF end passthrough '7' on Agent/1028
[2009-03-10 18:36:58] WARNING[31749] res_features.c: Did not read data.
[2009-03-10 18:36:58] VERBOSE[31749] logger.c:     -- Stopped music on hold on SIP/GW-08c4a238
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: Difference is 1200, ms is 170
[2009-03-10 18:36:58] VERBOSE[31749] logger.c:     -- <Agent/1028> Playing 'beeperr' (language 'pt_BR')
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:58] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF begin '2' received on SIP/4035-091110d8
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF begin passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF begin '2' received on Agent/1028
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF begin ignored '2' on Agent/1028
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: Sending dtmf: 50 (2), at 10.25.24.44
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF end '2' received on SIP/4035-091110d8, duration 170 ms
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF end accepted with begin '2' on SIP/4035-091110d8
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF end passthrough '2' on SIP/4035-091110d8
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF end '2' received on Agent/1028, duration 170 ms
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF begin emulation of '2' with duration 170 queued on Agent/1028
[2009-03-10 18:36:59] DEBUG[31749] channel.c: Got DTMF begin on channel (Agent/1028)
[2009-03-10 18:36:59] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DEBUG[31749] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[2009-03-10 18:36:59] DTMF[31749] channel.c: DTMF end emulation of '2' queued on Agent/1028
[2009-03-10 18:36:59] DEBUG[31749] channel.c: Got DTMF end on channel (Agent/1028)
[2009-03-10 18:36:59] DEBUG[31749] channel.c: Bridge stops bridging channels SIP/GW-08c4a238 and Agent/1028
[2009-03-10 18:36:59] DEBUG[31749] res_features.c: Feature interpret: chan=SIP/GW-08c4a238, peer=Agent/1028, code=2, sense=2, features=2 dynamic=(null)
[2009-03-10 18:36:59] DEBUG[31749] audiohook.c: Flushing audiohook 0x9034ba0 so it remains in sync
[2009-03-10 18:37:00] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:37:03] DEBUG[31749] rtp.c: Got RTCP report of 68 bytes
[2009-03-10 18:37:04] DEBUG[31749] rtp.c: Got RTCP report of 64 bytes