[Mar 18 15:02:55] DEBUG[6428] manager.c: Manager received command 'login'
[Mar 18 15:02:55] VERBOSE[6428] logger.c:   == Parsing '/opt/voip/etc/asterisk/manager.conf': [Mar 18 15:02:55] DEBUG[6428] config.c: Parsing /opt/voip/etc/asterisk/manager.conf
[Mar 18 15:02:55] VERBOSE[6428] logger.c: Found
[Mar 18 15:02:55] VERBOSE[6428] logger.c:   == Manager 'dial' logged on from 192.168.1.232
[Mar 18 15:02:55] DEBUG[6428] manager.c: Manager received command 'Originate'
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Asked to create a SIP channel with formats: 0x40 (slin)
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Our T38 capability (3856)
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Setting NAT on RTP to On
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Setting NAT on UDPTL to On
[Mar 18 15:02:55] DEBUG[6428] acl.c: ##### Testing 69.43.168.93 with 10.0.0.0
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Target address 69.43.168.93 is not local, substituting externip
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: *** Our native formats are 0x4 (ulaw) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: *** Joint capabilities are 0x0 (nothing) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: *** Our capabilities are 0x104 (ulaw|g729) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: *** Our preferred formats from the incoming channel are 0x40 (slin) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: This channel will not be able to handle video.
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Outgoing Call for 22139955402
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Our T38 capability (3856), joint T38 capability (3856)
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: ** Our capability: 0x104 (ulaw|g729) Video flag: False
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: ** Our prefcodec: 0x40 (slin) 
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: -- Done with adding codecs to SDP
[Mar 18 15:02:55] DEBUG[6428] chan_sip.c: Done building SDP. Settling with this capability: 0x104 (ulaw|g729)
[Mar 18 15:02:55] DEBUG[6428] sched.c: Attempted to delete nonexistent schedule entry -1!
[Mar 18 15:02:55] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0859f8e722027cce106270d5310050d8@69.43.131.246' Request 102: Found
[Mar 18 15:02:55] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0859f8e722027cce106270d5310050d8@69.43.131.246' Request 102: Found
[Mar 18 15:02:55] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 91!
[Mar 18 15:02:55] DEBUG[6814] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-1267cfa0
[Mar 18 15:02:55] DEBUG[6814] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:02:55] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-1267cfa0
[Mar 18 15:02:55] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-1267cfa0 - state 1 (Not in use)
[Mar 18 15:02:55] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:02:55] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: Acked pending invite 102
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: Stopping retransmission on '0859f8e722027cce106270d5310050d8@69.43.131.246' of Request 102: Match Found
[Mar 18 15:02:57] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 91!
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: T38 state changed to 0 on channel SIP/ser2-1267cfa0
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: We're settling with these formats: 0x4 (ulaw)
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: build_route: Record-Route hop: <sip:69.43.168.93;lr=on;ftag=as6651ba04>
[Mar 18 15:02:57] DEBUG[6428] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-1267cfa0
[Mar 18 15:02:57] DEBUG[6428] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:02:57] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-1267cfa0
[Mar 18 15:02:57] VERBOSE[6428] logger.c:   == Manager 'dial' logged off from 192.168.1.232
[Mar 18 15:02:57] DEBUG[6429] pbx.c: Launching 'Set'
[Mar 18 15:02:57] VERBOSE[6429] logger.c:     -- Executing [1@insanity:1] Set("SIP/ser2-1267cfa0", "SPYGROUP=314159") in new stack
[Mar 18 15:02:57] DEBUG[6429] pbx.c: Launching 'Dial'
[Mar 18 15:02:57] VERBOSE[6429] logger.c:     -- Executing [1@insanity:2] Dial("SIP/ser2-1267cfa0", "SIP/5300117607040322@sansay1") in new stack
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw)
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Setting NAT on RTP to On
[Mar 18 15:02:57] DEBUG[6429] acl.c: ##### Testing 10.10.10.100 with 10.0.0.0
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: *** Our native formats are 0x4 (ulaw) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: *** Joint capabilities are 0x0 (nothing) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: *** Our capabilities are 0x4 (ulaw) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: This channel will not be able to handle video.
[Mar 18 15:02:57] DEBUG[6429] channel.c: Not copying variable SPYGROUP.
[Mar 18 15:02:57] DEBUG[6429] channel.c: Not copying variable SIPCALLID.
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Outgoing Call for 5300117607040322
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Our T38 capability (3856), joint T38 capability (3856)
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: False
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) 
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: -- Done with adding codecs to SDP
[Mar 18 15:02:57] DEBUG[6429] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw)
[Mar 18 15:02:57] DEBUG[6429] sched.c: Attempted to delete nonexistent schedule entry -1!
[Mar 18 15:02:57] VERBOSE[6429] logger.c:     -- Called 5300117607040322@sansay1
[Mar 18 15:02:57] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '45771427364f69df4527e8ec1c53fee4@69.43.131.246' Request 102: Found
[Mar 18 15:02:57] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-1267cfa0 - state 1 (Not in use)
[Mar 18 15:02:57] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:02:57] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:02:59] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '45771427364f69df4527e8ec1c53fee4@69.43.131.246' Request 102: Found
[Mar 18 15:02:59] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 93!
[Mar 18 15:02:59] DEBUG[6814] chan_sip.c: T38 state changed to 0 on channel SIP/sansay1-1269c210
[Mar 18 15:02:59] DEBUG[6814] chan_sip.c: We're settling with these formats: 0x4 (ulaw)
[Mar 18 15:02:59] VERBOSE[6429] logger.c:     -- SIP/sansay1-1269c210 is making progress passing it to SIP/ser2-1267cfa0
[Mar 18 15:02:59] DEBUG[6429] rtp.c: Ooh, format changed from unknown to ulaw
[Mar 18 15:02:59] DEBUG[6429] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Mar 18 15:02:59] DEBUG[6429] rtp.c: Ooh, format changed from unknown to ulaw
[Mar 18 15:02:59] DEBUG[6429] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: Acked pending invite 102
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: Stopping retransmission on '45771427364f69df4527e8ec1c53fee4@69.43.131.246' of Request 102: Match Found
[Mar 18 15:03:01] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 93!
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: T38 state changed to 0 on channel SIP/sansay1-1269c210
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: We're settling with these formats: 0x4 (ulaw)
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:03:01] DEBUG[6814] chan_sip.c: build_route: Contact hop: <sip:5300117607040322@10.10.10.100:5060;transport=udp>
[Mar 18 15:03:01] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/sansay1-1269c210
[Mar 18 15:03:01] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/sansay1
[Mar 18 15:03:01] VERBOSE[6429] logger.c:     -- SIP/sansay1-1269c210 answered SIP/ser2-1267cfa0
[Mar 18 15:03:01] DEBUG[6812] chan_sip.c: Checking device state for peer sansay1-1269c210
[Mar 18 15:03:01] DEBUG[6812] devicestate.c: Changing state for SIP/sansay1-1269c210 - state 1 (Not in use)
[Mar 18 15:03:01] DEBUG[6812] chan_sip.c: Checking device state for peer sansay1
[Mar 18 15:03:01] DEBUG[6812] devicestate.c: Changing state for SIP/sansay1 - state 1 (Not in use)
[Mar 18 15:03:01] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:06] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:11] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:16] DEBUG[6448] manager.c: Manager received command 'login'
[Mar 18 15:03:16] VERBOSE[6448] logger.c:   == Parsing '/opt/voip/etc/asterisk/manager.conf': [Mar 18 15:03:16] DEBUG[6448] config.c: Parsing /opt/voip/etc/asterisk/manager.conf
[Mar 18 15:03:16] VERBOSE[6448] logger.c: Found
[Mar 18 15:03:16] VERBOSE[6448] logger.c:   == Manager 'dial' logged on from 192.168.1.232
[Mar 18 15:03:16] DEBUG[6448] manager.c: Manager received command 'Originate'
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Asked to create a SIP channel with formats: 0x40 (slin)
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Our T38 capability (3856)
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Setting NAT on RTP to On
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Setting NAT on UDPTL to On
[Mar 18 15:03:16] DEBUG[6448] acl.c: ##### Testing 69.43.168.93 with 10.0.0.0
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Target address 69.43.168.93 is not local, substituting externip
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: *** Our native formats are 0x4 (ulaw) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: *** Joint capabilities are 0x0 (nothing) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: *** Our capabilities are 0x104 (ulaw|g729) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: *** Our preferred formats from the incoming channel are 0x40 (slin) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: This channel will not be able to handle video.
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Outgoing Call for 27604775684
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Our T38 capability (3856), joint T38 capability (3856)
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: ** Our capability: 0x104 (ulaw|g729) Video flag: False
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: ** Our prefcodec: 0x40 (slin) 
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: -- Done with adding codecs to SDP
[Mar 18 15:03:16] DEBUG[6448] chan_sip.c: Done building SDP. Settling with this capability: 0x104 (ulaw|g729)
[Mar 18 15:03:16] DEBUG[6448] sched.c: Attempted to delete nonexistent schedule entry -1!
[Mar 18 15:03:16] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '338c390c2c288a3958b67dde19693264@69.43.131.246' Request 102: Found
[Mar 18 15:03:16] DEBUG[6814] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '338c390c2c288a3958b67dde19693264@69.43.131.246' Request 102: Found
[Mar 18 15:03:16] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 97!
[Mar 18 15:03:16] DEBUG[6814] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-126b6980
[Mar 18 15:03:16] DEBUG[6814] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:03:16] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-126b6980
[Mar 18 15:03:16] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:16] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-126b6980 - state 1 (Not in use)
[Mar 18 15:03:16] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:03:16] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: Acked pending invite 102
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: Stopping retransmission on '338c390c2c288a3958b67dde19693264@69.43.131.246' of Request 102: Match Found
[Mar 18 15:03:19] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 97!
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: T38 state changed to 0 on channel SIP/ser2-126b6980
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: We're settling with these formats: 0x4 (ulaw)
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: Updating call counter for outgoing call
[Mar 18 15:03:19] DEBUG[6814] chan_sip.c: build_route: Record-Route hop: <sip:69.43.168.93;lr=on;ftag=as37027315>
[Mar 18 15:03:19] DEBUG[6448] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-126b6980
[Mar 18 15:03:19] DEBUG[6448] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:03:19] VERBOSE[6448] logger.c:   == Manager 'dial' logged off from 192.168.1.232
[Mar 18 15:03:19] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-126b6980
[Mar 18 15:03:19] DEBUG[6454] pbx.c: Launching 'ChanSpy'
[Mar 18 15:03:19] VERBOSE[6454] logger.c:     -- Executing [1@spy:1] ChanSpy("SIP/ser2-126b6980", "|wbg(314159)") in new stack
[Mar 18 15:03:19] DEBUG[6454] channel.c: Set channel SIP/ser2-126b6980 to write format slin
[Mar 18 15:03:19] DEBUG[6454] channel.c: Set channel SIP/ser2-126b6980 to write format gsm
[Mar 18 15:03:19] DEBUG[6454] rtp.c: Ooh, format changed from unknown to ulaw
[Mar 18 15:03:19] DEBUG[6454] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Mar 18 15:03:19] VERBOSE[6454] logger.c:     -- <SIP/ser2-126b6980> Playing 'beep' (language 'en')
[Mar 18 15:03:19] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-126b6980 - state 1 (Not in use)
[Mar 18 15:03:19] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:03:19] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:03:19] DEBUG[6454] channel.c: Set channel SIP/ser2-126b6980 to write format slin
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:19] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:20] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:21] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:22] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:23] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:24] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:25] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6429] rtp.c: Sending dtmf: 57 (9), at 76.216.115.120
[Mar 18 15:03:26] DTMF[6429] channel.c: DTMF begin '9' received on SIP/ser2-1267cfa0
[Mar 18 15:03:26] DTMF[6429] channel.c: DTMF begin passthrough '9' on SIP/ser2-1267cfa0
[Mar 18 15:03:26] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format slin
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6429] rtp.c: Sending dtmf: 57 (9), at 76.216.115.120
[Mar 18 15:03:26] DTMF[6429] channel.c: DTMF end '9' received on SIP/ser2-1267cfa0, duration 220 ms
[Mar 18 15:03:26] DTMF[6429] channel.c: DTMF end accepted with begin '9' on SIP/ser2-1267cfa0
[Mar 18 15:03:26] DTMF[6429] channel.c: DTMF end passthrough '9' on SIP/ser2-1267cfa0
[Mar 18 15:03:26] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format ulaw
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] channel.c: Thread 1076443456 Blocking 'SIP/sansay1-1269c210', already blocked by thread 1076443456 in procedure ast_write
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000009 (len = 4)
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:26] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 54 (6), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin '6' received on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin passthrough '6' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format slin
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 54 (6), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end '6' received on SIP/ser2-1267cfa0, duration 180 ms
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end accepted with begin '6' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end passthrough '6' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format ulaw
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] channel.c: Thread 1076443456 Blocking 'SIP/sansay1-1269c210', already blocked by thread 1076443456 in procedure ast_write
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000006 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 53 (5), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin '5' received on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin passthrough '5' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format slin
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 53 (5), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end '5' received on SIP/ser2-1267cfa0, duration 200 ms
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end accepted with begin '5' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end passthrough '5' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format ulaw
[Mar 18 15:03:27] DEBUG[6429] channel.c: Thread 1076443456 Blocking 'SIP/sansay1-1269c210', already blocked by thread 1076443456 in procedure ast_write
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000005 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 52 (4), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin '4' received on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF begin passthrough '4' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format slin
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6429] rtp.c: Sending dtmf: 52 (4), at 76.216.115.120
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end '4' received on SIP/ser2-1267cfa0, duration 150 ms
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end accepted with begin '4' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DTMF[6429] channel.c: DTMF end passthrough '4' on SIP/ser2-1267cfa0
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] channel.c: Set channel SIP/sansay1-1269c210 to write format ulaw
[Mar 18 15:03:27] DEBUG[6429] channel.c: Thread 1076443456 Blocking 'SIP/sansay1-1269c210', already blocked by thread 1076443456 in procedure ast_write
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6429] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:27] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:28] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6429] channel.c: Deadlock avoided for write to channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding deadlock for channel '0x12681430'
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: Failed to grab owner channel lock, trying again. (SIP call 338c390c2c288a3958b67dde19693264@69.43.131.246)
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: Failed to grab owner channel lock, trying again. (SIP call 338c390c2c288a3958b67dde19693264@69.43.131.246)
[Mar 18 15:03:29] DEBUG[6454] channel.c: Avoiding initial deadlock for channel '0x12680860'
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: Setting SIP_ALREADYGONE on dialog 338c390c2c288a3958b67dde19693264@69.43.131.246
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: Received bye, issuing owner hangup
[Mar 18 15:03:29] DEBUG[6454] channel.c: Set channel SIP/ser2-126b6980 to write format ulaw
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Extension 1, priority 1 returned normally even though call was hung up
[Mar 18 15:03:29] DEBUG[6454] channel.c: Soft-Hanging up channel 'SIP/ser2-126b6980'
[Mar 18 15:03:29] DEBUG[6454] channel.c: Hanging up channel 'SIP/ser2-126b6980'
[Mar 18 15:03:29] DEBUG[6454] chan_sip.c: Hangup call SIP/ser2-126b6980, SIP callid 338c390c2c288a3958b67dde19693264@69.43.131.246)
[Mar 18 15:03:29] DEBUG[6454] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-126b6980
[Mar 18 15:03:29] DEBUG[6454] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is ''
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is ''
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 's'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 'origination'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 'SIP/ser2-126b6980'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is ''
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 'ChanSpy'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '|wbg(314159)'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '2008-03-18 15:03:16'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '2008-03-18 15:03:19'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '2008-03-18 15:03:29'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '13'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '10'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 'ANSWERED'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is 'DOCUMENTATION'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is ''
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is '1205877796.54'
[Mar 18 15:03:29] DEBUG[6454] pbx.c: Function result is ''
[Mar 18 15:03:29] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-126b6980
[Mar 18 15:03:29] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-126b6980 - state 1 (Not in use)
[Mar 18 15:03:29] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:03:29] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:03:29] VERBOSE[6814] logger.c: Really destroying SIP dialog '338c390c2c288a3958b67dde19693264@69.43.131.246' Method: BYE
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: 
---------- SIP HISTORY for '338c390c2c288a3958b67dde19693264@69.43.131.246' 
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   * SIP Call
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   001. NewChan         Channel SIP/ser2-126b6980 - from 338c390c2c288a3958b67dde196932
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   002. TxReqRel        INVITE / 102 INVITE - -UNKNOWN-
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   003. Rx              SIP/2.0 / 102 INVITE / 100 Giving a try
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   004. Rx              SIP/2.0 / 102 INVITE / 180 Ringing
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   005. Rx              SIP/2.0 / 102 INVITE / 200 OK
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   006. TxReq           ACK / 102 ACK - -UNKNOWN-
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   007. Rx              BYE / 1 BYE / sip:asterisk@69.43.131.246
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   008. RTCPaudio       Quality:ssrc=882768242;themssrc=260273037;lp=0;rxjitter=0.00032
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   009. TxResp          SIP/2.0 / 1 BYE - 200 OK
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c:   010. Hangup          Cause Normal Clearing
[Mar 18 15:03:29] DEBUG[6814] chan_sip.c: 
---------- END SIP HISTORY for '338c390c2c288a3958b67dde19693264@69.43.131.246' 
[Mar 18 15:03:29] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 97!
[Mar 18 15:03:31] DEBUG[6429] rtp.c: Got RTCP report of 96 bytes
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: Setting SIP_ALREADYGONE on dialog 45771427364f69df4527e8ec1c53fee4@69.43.131.246
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: Received bye, issuing owner hangup
[Mar 18 15:03:32] DEBUG[6429] channel.c: Didn't get a frame from channel: SIP/sansay1-1269c210
[Mar 18 15:03:32] DEBUG[6429] channel.c: Bridge stops bridging channels SIP/ser2-1267cfa0 and SIP/sansay1-1269c210
[Mar 18 15:03:32] DEBUG[6429] channel.c: Hanging up channel 'SIP/sansay1-1269c210'
[Mar 18 15:03:32] DEBUG[6429] chan_sip.c: Hangup call SIP/sansay1-1269c210, SIP callid 45771427364f69df4527e8ec1c53fee4@69.43.131.246)
[Mar 18 15:03:32] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/sansay1-1269c210
[Mar 18 15:03:32] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/sansay1
[Mar 18 15:03:32] DEBUG[6429] rtp.c: Channel '<unspecified>' has no RTP, not doing anything
[Mar 18 15:03:32] DEBUG[6812] chan_sip.c: Checking device state for peer sansay1-1269c210
[Mar 18 15:03:32] DEBUG[6429] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Spawn extension (insanity,1,2) exited non-zero on 'SIP/ser2-1267cfa0'
[Mar 18 15:03:32] VERBOSE[6429] logger.c:   == Spawn extension (insanity, 1, 2) exited non-zero on 'SIP/ser2-1267cfa0'
[Mar 18 15:03:32] DEBUG[6429] channel.c: Soft-Hanging up channel 'SIP/ser2-1267cfa0'
[Mar 18 15:03:32] DEBUG[6429] channel.c: Hanging up channel 'SIP/ser2-1267cfa0'
[Mar 18 15:03:32] DEBUG[6429] chan_sip.c: Hangup call SIP/ser2-1267cfa0, SIP callid 0859f8e722027cce106270d5310050d8@69.43.131.246)
[Mar 18 15:03:32] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2-1267cfa0
[Mar 18 15:03:32] DEBUG[6429] devicestate.c: Notification of state change to be queued on device/channel SIP/ser2
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is ''
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is ''
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '1'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'insanity'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'SIP/ser2-1267cfa0'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'SIP/sansay1-1269c210'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'Dial'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'SIP/5300117607040322@sansay1'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '2008-03-18 15:02:55'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '2008-03-18 15:02:57'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '2008-03-18 15:03:32'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '37'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '35'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'ANSWERED'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is 'DOCUMENTATION'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is ''
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is '1205877775.52'
[Mar 18 15:03:32] DEBUG[6429] pbx.c: Function result is ''
[Mar 18 15:03:32] DEBUG[6812] devicestate.c: Changing state for SIP/sansay1-1269c210 - state 1 (Not in use)
[Mar 18 15:03:32] DEBUG[6812] chan_sip.c: Checking device state for peer sansay1
[Mar 18 15:03:32] DEBUG[6812] devicestate.c: Changing state for SIP/sansay1 - state 1 (Not in use)
[Mar 18 15:03:32] DEBUG[6812] chan_sip.c: Checking device state for peer ser2-1267cfa0
[Mar 18 15:03:32] DEBUG[6812] devicestate.c: Changing state for SIP/ser2-1267cfa0 - state 1 (Not in use)
[Mar 18 15:03:32] DEBUG[6812] chan_sip.c: Checking device state for peer ser2
[Mar 18 15:03:32] DEBUG[6812] devicestate.c: Changing state for SIP/ser2 - state 1 (Not in use)
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: Stopping retransmission on '0859f8e722027cce106270d5310050d8@69.43.131.246' of Request 103: Match Found
[Mar 18 15:03:32] VERBOSE[6814] logger.c: Really destroying SIP dialog '45771427364f69df4527e8ec1c53fee4@69.43.131.246' Method: BYE
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: 
---------- SIP HISTORY for '45771427364f69df4527e8ec1c53fee4@69.43.131.246' 
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   * SIP Call
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   001. NewChan         Channel SIP/sansay1-1269c210 - from 45771427364f69df4527e8ec1c5
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   002. TxReqRel        INVITE / 102 INVITE - -UNKNOWN-
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   003. Rx              SIP/2.0 / 102 INVITE / 100 Trying
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   004. Rx              SIP/2.0 / 102 INVITE / 183 Session Progress
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   005. Rx              SIP/2.0 / 102 INVITE / 200 OK
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   006. TxReq           ACK / 102 ACK - -UNKNOWN-
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   007. Rx              BYE / 1 BYE / sip:asterisk@10.10.10.12
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   008. RTCPaudio       Quality:ssrc=1509028330;themssrc=965181631;lp=0;rxjitter=0.0000
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   009. TxResp          SIP/2.0 / 1 BYE - 200 OK
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   010. Hangup          Cause Normal Clearing
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: 
---------- END SIP HISTORY for '45771427364f69df4527e8ec1c53fee4@69.43.131.246' 
[Mar 18 15:03:32] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 93!
[Mar 18 15:03:32] VERBOSE[6814] logger.c: Really destroying SIP dialog '0859f8e722027cce106270d5310050d8@69.43.131.246' Method: INVITE
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: 
---------- SIP HISTORY for '0859f8e722027cce106270d5310050d8@69.43.131.246' 
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   * SIP Call
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   001. NewChan         Channel SIP/ser2-1267cfa0 - from 0859f8e722027cce106270d5310050
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   002. TxReqRel        INVITE / 102 INVITE - -UNKNOWN-
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   003. Rx              SIP/2.0 / 102 INVITE / 100 Giving a try
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   004. Rx              SIP/2.0 / 102 INVITE / 180 Ringing
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   005. Rx              SIP/2.0 / 102 INVITE / 200 OK
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   006. TxReq           ACK / 102 ACK - -UNKNOWN-
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   007. Hangup          Cause Normal Clearing
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   008. SchedDestroy    32000 ms
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   009. TxReqRel        BYE / 103 BYE - -UNKNOWN-
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   010. RTCPaudio       Quality:ssrc=1424058061;themssrc=978334136;lp=0;rxjitter=0.0002
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c:   011. Rx              SIP/2.0 / 103 BYE / 200 OK
[Mar 18 15:03:32] DEBUG[6814] chan_sip.c: 
---------- END SIP HISTORY for '0859f8e722027cce106270d5310050d8@69.43.131.246' 
[Mar 18 15:03:32] DEBUG[6814] sched.c: Attempted to delete nonexistent schedule entry 91!