[2010-10-13 22:38:56.903680] DEBUG[23880] pbx.c: Launching 'Dial'
[2010-10-13 22:38:56.903754] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 2 (In use)
[2010-10-13 22:38:56.903773] VERBOSE[23880] pbx.c:     -- Executing [89@phones:1] Dial("SIP/bt100-00000007", "DAHDI/35,60") in new stack
[2010-10-13 22:38:56.903806] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '2'
[2010-10-13 22:38:56.904306] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:38:56.904400] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 2 (In use)
[2010-10-13 22:38:56.904457] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '2'
[2010-10-13 22:38:56.904820] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '2' (In use)
[2010-10-13 22:38:56.905080] DEBUG[23518] app_queue.c: Extension '649@phones' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:38:56.905563] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '2' (In use)
[2010-10-13 22:38:56.906972] DEBUG[23880] sig_analog.c: analog_available 35
[2010-10-13 22:38:56.907110] DEBUG[23880] chan_dahdi.c: Using channel 35
[2010-10-13 22:38:56.907180] DEBUG[23880] sig_analog.c: analog_request 35
[2010-10-13 22:38:56.907563] DEBUG[23880] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[2010-10-13 22:38:56.907882] DEBUG[23880] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[2010-10-13 22:38:56.908093] DEBUG[23880] devicestate.c: device 'DAHDI/35-1' state '2'
[2010-10-13 22:38:56.908982] DEBUG[23551] app_queue.c: Device 'DAHDI/35-1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:38:56.909369] DEBUG[23880] rtp_engine.c: Can't find native functions for channel 'DAHDI/35-1'
[2010-10-13 22:38:56.909467] DEBUG[23880] rtp_engine.c: Seeded SDP of 'DAHDI/35-1' with that of 'SIP/bt100-00000007'
[2010-10-13 22:38:56.909519] DEBUG[23880] channel.c: Not copying variable DIALEDTIME.
[2010-10-13 22:38:56.909562] DEBUG[23880] channel.c: Not copying variable ANSWEREDTIME.
[2010-10-13 22:38:56.909603] DEBUG[23880] channel.c: Not copying variable DIALEDPEERNAME.
[2010-10-13 22:38:56.909644] DEBUG[23880] channel.c: Not copying variable DIALEDPEERNUMBER.
[2010-10-13 22:38:56.909687] DEBUG[23880] channel.c: Not copying variable DIALSTATUS.
[2010-10-13 22:38:56.909991] DEBUG[23880] channel.c: Not copying variable SIPCALLID.
[2010-10-13 22:38:56.910082] DEBUG[23880] channel.c: Not copying variable SIPDOMAIN.
[2010-10-13 22:38:56.910147] DEBUG[23880] channel.c: Not copying variable SIPURI.
[2010-10-13 22:38:56.910354] DEBUG[23880] sig_analog.c: CALLING CID_NAME: Alec Davis CID_NUM:: 855
[2010-10-13 22:38:56.910743] VERBOSE[23880] app_dial.c:     -- Called 35
[2010-10-13 22:38:56.910858] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 6 (Ringing)
[2010-10-13 22:38:56.911023] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '6'
[2010-10-13 22:38:56.911680] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[2010-10-13 22:38:56.912011] VERBOSE[23880] app_dial.c:     -- DAHDI/35-1 is ringing
[2010-10-13 22:38:56.912202] DEBUG[23880] chan_sip.c: Trying to put 'SIP/2.0 180' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:38:57.309599] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:38:57.309678] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:38:57.309764] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:38:57.309840] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 35 (index 0)
[2010-10-13 22:38:57.309907] VERBOSE[23880] app_dial.c:     -- DAHDI/35-1 is ringing
[2010-10-13 22:38:57.509611] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:38:57.509685] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:38:57.509765] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:38:57.509838] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 35 (index 0)
[2010-10-13 22:38:57.909601] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:38:57.909683] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:38:57.909762] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:38:57.909842] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 35 (index 0)
[2010-10-13 22:38:57.909918] DEBUG[23880] sig_analog.c: Sending callerid.  CID_NAME: 'Alec Davis' CID_NUM: '855'
[2010-10-13 22:38:57.910025] DEBUG[23880] chan_dahdi.c: Starting cid spill
[2010-10-13 22:38:57.910735] VERBOSE[23880] app_dial.c:     -- DAHDI/35-1 is ringing
[2010-10-13 22:38:59.049573] DEBUG[23880] chan_dahdi.c: Restored conferencing
[2010-10-13 22:38:59.286690] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:38:59.286761] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:38:59.286843] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:38:59.286922] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 35 (index 0)
[2010-10-13 22:38:59.287081] DEBUG[23880] chan_dahdi.c: Enabled echo cancellation on channel 35
[2010-10-13 22:38:59.287184] DEBUG[23880] chan_dahdi.c: Engaged echo training on channel 35
[2010-10-13 22:38:59.287267] DEBUG[23880] sig_analog.c: channel 35 answered
[2010-10-13 22:38:59.287634] VERBOSE[23880] app_dial.c:     -- DAHDI/35-1 answered SIP/bt100-00000007
[2010-10-13 22:38:59.287641] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 2 (In use)
[2010-10-13 22:38:59.287775] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '2'
[2010-10-13 22:38:59.288490] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:38:59.289276] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:38:59.289324] DEBUG[23880] chan_sip.c: SIP answering channel: SIP/bt100-00000007
[2010-10-13 22:38:59.289436] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 2 (In use)
[2010-10-13 22:38:59.289587] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:38:59.289606] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '2'
[2010-10-13 22:38:59.289701] DEBUG[23880] chan_sip.c: Setting framing from config on incoming call
[2010-10-13 22:38:59.290160] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '2' (In use)
[2010-10-13 22:38:59.290419] DEBUG[23880] chan_sip.c: ** Our capability: 0xc (ulaw|alaw) Video flag: True Text flag: True
[2010-10-13 22:38:59.290563] DEBUG[23880] chan_sip.c: ** Our prefcodec: 0x0 (nothing) 
[2010-10-13 22:38:59.290655] DEBUG[23880] chan_sip.c: -- Done with adding codecs to SDP
[2010-10-13 22:38:59.290796] DEBUG[23880] chan_sip.c: Done building SDP. Settling with this capability: 0xc (ulaw|alaw)
[2010-10-13 22:38:59.291006] DEBUG[23880] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:38:59.291637] DEBUG[23880] res_rtp_asterisk.c: Not changing SSRC since we haven't sent any RTP yet
[2010-10-13 22:38:59.291772] DEBUG[23880] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-1
[2010-10-13 22:38:59.292385] DEBUG[23880] res_rtp_asterisk.c: Ooh, format changed from unknown to alaw
[2010-10-13 22:38:59.292463] DEBUG[23880] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[2010-10-13 22:38:59.292685] DEBUG[23880] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x8ce6730'
[2010-10-13 22:38:59.321260] DEBUG[23539] chan_sip.c: Stopping retransmission on '8e0c33fcfa4adc5c@172.16.0.99' of Response 11157: Match Found
[2010-10-13 22:39:05.365494] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog '1776543944@10.1.1.3'
[2010-10-13 22:39:05.365613] DEBUG[23539] chan_sip.c: Destroying SIP dialog 1776543944@10.1.1.3
[2010-10-13 22:39:44.603100] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:39:44.603273] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for ZGRlZmUwZjcwY2JlZjU5ZTM0N2VmOTMxMTE4YmE4ZDA. - SUBSCRIBE (No RTP)
[2010-10-13 22:39:44.603474] DEBUG[23539] chan_sip.c: build_route: Contact hop: <sip:alec-xlite@172.16.0.250:18842>
[2010-10-13 22:39:44.603648] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.0.250:18842
[2010-10-13 22:39:44.715261] DEBUG[23539] chan_sip.c: build_route: Retaining previous route: <sip:alec-xlite@172.16.0.250:18842>
[2010-10-13 22:39:44.715524] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 172.16.0.250:18842
[2010-10-13 22:39:44.715704] NOTICE[23539] chan_sip.c: Received SIP subscribe for peer without mailbox: alec-xlite
[2010-10-13 22:39:44.716078] DEBUG[23539] chan_sip.c: Destroying SIP dialog ZGRlZmUwZjcwY2JlZjU5ZTM0N2VmOTMxMTE4YmE4ZDA.
[2010-10-13 22:39:58.589303] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:39:58.589437] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for 0c0f43f7b6c84691@172.16.0.99 - REGISTER (No RTP)
[2010-10-13 22:39:58.589729] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:39:58.618299] DEBUG[23539] chan_sip.c: Store REGISTER's Contact header for call routing.
[2010-10-13 22:39:58.618656] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:39:58.618847] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:39:58.619064] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 2 (In use)
[2010-10-13 22:39:58.619210] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '2'
[2010-10-13 22:39:58.619779] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '2' (In use)
[2010-10-13 22:39:58.626940] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:39:58.627869] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for 30b0c6db7273eecb@172.16.0.99 - SUBSCRIBE (No RTP)
[2010-10-13 22:39:58.628105] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 481' onto UDP socket destined for 172.16.0.99:5060

[2010-10-13 22:40:03.622155] DEBUG[23525] chan_dahdi.c: Monitor doohicky got event Polarity Reversal on channel 36
[2010-10-13 22:40:03.622236] DEBUG[23525] sig_analog.c: channel (36) - signaling (4) - event (ANALOG_EVENT_POLARITY)
[2010-10-13 22:40:04.281222] DEBUG[23525] chan_dahdi.c: Monitor doohicky got event Ring Begin on channel 36
[2010-10-13 22:40:04.281295] DEBUG[23525] sig_analog.c: channel (36) - signaling (4) - event (ANALOG_EVENT_RINGBEGIN)
[2010-10-13 22:40:05.515126] DEBUG[23525] chan_dahdi.c: Monitor doohicky got event Ring/Answered on channel 36
[2010-10-13 22:40:05.515216] DEBUG[23525] sig_analog.c: channel (36) - signaling (4) - event (ANALOG_EVENT_RINGOFFHOOK)
[2010-10-13 22:40:05.515514] DEBUG[23525] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[2010-10-13 22:40:05.515559] DEBUG[23525] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[2010-10-13 22:40:05.515637] DEBUG[23525] dsp.c: dsp busy pattern set to 250,250
[2010-10-13 22:40:05.515714] DEBUG[23525] devicestate.c: device 'DAHDI/36-1' state '2'
[2010-10-13 22:40:05.516125] DEBUG[23900] sig_analog.c: __analog_ss_thread 36
[2010-10-13 22:40:05.516202] VERBOSE[23900] sig_analog.c:     -- Starting simple switch on 'DAHDI/36-1'
[2010-10-13 22:40:05.516730] DEBUG[23551] app_queue.c: Device 'DAHDI/36-1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.516875] DEBUG[23900] pbx.c: Launching 'Progress'
[2010-10-13 22:40:05.516970] VERBOSE[23900] pbx.c:     -- Executing [s@incoming:1] Progress("DAHDI/36-1", "") in new stack
[2010-10-13 22:40:05.517296] DEBUG[23900] chan_dahdi.c: Requested indication 14 on channel DAHDI/36-1
[2010-10-13 22:40:05.517363] DEBUG[23900] chan_dahdi.c: Received AST_CONTROL_PROGRESS on DAHDI/36-1
[2010-10-13 22:40:05.517694] DEBUG[23900] pbx.c: Launching 'Dial'
[2010-10-13 22:40:05.517872] VERBOSE[23900] pbx.c:     -- Executing [s@incoming:2] Dial("DAHDI/36-1", "DAHDI/33&DAHDI/34&DAHDI/35") in new stack
[2010-10-13 22:40:05.519053] DEBUG[23900] sig_analog.c: analog_available 33
[2010-10-13 22:40:05.519184] DEBUG[23900] chan_dahdi.c: Using channel 33
[2010-10-13 22:40:05.519275] DEBUG[23900] sig_analog.c: analog_request 33
[2010-10-13 22:40:05.519602] DEBUG[23900] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[2010-10-13 22:40:05.519670] DEBUG[23900] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[2010-10-13 22:40:05.519731] DEBUG[23900] devicestate.c: device 'DAHDI/33-1' state '2'
[2010-10-13 22:40:05.520887] DEBUG[23551] app_queue.c: Device 'DAHDI/33-1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.520947] DEBUG[23900] channel.c: Not copying variable DIALEDTIME.
[2010-10-13 22:40:05.521365] DEBUG[23900] channel.c: Not copying variable ANSWEREDTIME.
[2010-10-13 22:40:05.521434] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNAME.
[2010-10-13 22:40:05.521559] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNUMBER.
[2010-10-13 22:40:05.521612] DEBUG[23900] channel.c: Not copying variable DIALSTATUS.
[2010-10-13 22:40:05.521867] DEBUG[23900] sig_analog.c: CALLING CID_NAME:  CID_NUM:: 
[2010-10-13 22:40:05.522304] VERBOSE[23900] app_dial.c:     -- Called 33
[2010-10-13 22:40:05.522301] DEBUG[23517] devicestate.c: Changing state for DAHDI/33 - state 6 (Ringing)
[2010-10-13 22:40:05.522647] DEBUG[23900] sig_analog.c: analog_available 34
[2010-10-13 22:40:05.522856] DEBUG[23900] chan_dahdi.c: Using channel 34
[2010-10-13 22:40:05.522918] DEBUG[23900] sig_analog.c: analog_request 34
[2010-10-13 22:40:05.523192] DEBUG[23900] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[2010-10-13 22:40:05.523232] DEBUG[23900] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[2010-10-13 22:40:05.523271] DEBUG[23900] devicestate.c: device 'DAHDI/34-1' state '2'
[2010-10-13 22:40:05.523446] DEBUG[23900] channel.c: Not copying variable DIALEDTIME.
[2010-10-13 22:40:05.523479] DEBUG[23900] channel.c: Not copying variable ANSWEREDTIME.
[2010-10-13 22:40:05.523510] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNAME.
[2010-10-13 22:40:05.523541] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNUMBER.
[2010-10-13 22:40:05.523571] DEBUG[23900] channel.c: Not copying variable DIALSTATUS.
[2010-10-13 22:40:05.523658] DEBUG[23900] sig_analog.c: CALLING CID_NAME:  CID_NUM:: 
[2010-10-13 22:40:05.524293] VERBOSE[23900] app_dial.c:     -- Called 34
[2010-10-13 22:40:05.522752] DEBUG[23517] devicestate.c: device 'DAHDI/33' state '6'
[2010-10-13 22:40:05.524972] DEBUG[23551] app_queue.c: Device 'DAHDI/34-1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.525480] DEBUG[23517] devicestate.c: Changing state for DAHDI/34 - state 6 (Ringing)
[2010-10-13 22:40:05.525777] DEBUG[23517] devicestate.c: device 'DAHDI/34' state '6'
[2010-10-13 22:40:05.525780] DEBUG[23551] app_queue.c: Device 'DAHDI/33' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.526935] DEBUG[23900] sig_analog.c: analog_available 35
[2010-10-13 22:40:05.527032] DEBUG[23551] app_queue.c: Device 'DAHDI/34' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.527252] DEBUG[23900] chan_dahdi.c: Using channel 35
[2010-10-13 22:40:05.527430] DEBUG[23900] sig_analog.c: analog_request 35
[2010-10-13 22:40:05.527553] DEBUG[23900] chan_dahdi.c: Allocated Callwait subchannel on FD 68 channel 40
[2010-10-13 22:40:05.527800] DEBUG[23900] devicestate.c: device 'DAHDI/35-2' state '2'
[2010-10-13 22:40:05.528271] DEBUG[23900] channel.c: Not copying variable DIALEDTIME.
[2010-10-13 22:40:05.528381] DEBUG[23900] channel.c: Not copying variable ANSWEREDTIME.
[2010-10-13 22:40:05.528584] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNAME.
[2010-10-13 22:40:05.528682] DEBUG[23900] channel.c: Not copying variable DIALEDPEERNUMBER.
[2010-10-13 22:40:05.528842] DEBUG[23900] channel.c: Not copying variable DIALSTATUS.
[2010-10-13 22:40:05.528926] DEBUG[23551] app_queue.c: Device 'DAHDI/35-2' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.529228] DEBUG[23900] sig_analog.c: CALLING CID_NAME:  CID_NUM:: 
[2010-10-13 22:40:05.529300] DEBUG[23900] chan_dahdi.c: Disabled conferencing
[2010-10-13 22:40:05.529723] VERBOSE[23900] app_dial.c:     -- Called 35
[2010-10-13 22:40:05.530475] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 6 (Ringing)
[2010-10-13 22:40:05.530646] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '6'
[2010-10-13 22:40:05.531058] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:05.531546] DEBUG[23900] chan_dahdi.c: Requested indication 3 on channel DAHDI/36-1
[2010-10-13 22:40:05.531717] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:05.531862] VERBOSE[23900] app_dial.c:     -- DAHDI/35-2 is ringing
[2010-10-13 22:40:05.532585] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:05.544299] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.564311] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.584303] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.604292] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.624327] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.644324] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.664314] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.684319] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.704307] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.724299] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.744322] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.764313] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.784302] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.804322] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.824310] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.844305] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.864296] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.884311] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.904303] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.921160] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:05.921240] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:05.921322] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:05.921405] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:05.921491] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:05.924321] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:05.924400] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:05.924536] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:05.924602] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:05.924716] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:05.924801] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:05.944310] DEBUG[23880] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 35
[2010-10-13 22:40:06.089172] DEBUG[23880] sig_analog.c: Got some DTMF, but it's for the CAS
[2010-10-13 22:40:06.089256] DEBUG[23880] sig_analog.c: Sending callerid.  CID_NAME: '' CID_NUM: ''
[2010-10-13 22:40:06.089343] DEBUG[23880] chan_dahdi.c: Starting cid spill
[2010-10-13 22:40:06.104347] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.121154] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:06.121224] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:06.121309] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:06.121412] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:06.123152] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:06.123214] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:06.123293] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:06.123379] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:06.124359] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.144346] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.164352] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.184338] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.204354] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.224352] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.244346] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.264347] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.284341] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.304322] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.324339] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.344336] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.364333] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.384347] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.404324] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.424334] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.444330] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.464328] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.484326] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.504316] DEBUG[23880] chan_dahdi.c: Dropping frame since I've still got a callerid spill
[2010-10-13 22:40:06.509120] DEBUG[23880] chan_dahdi.c: Restored conferencing
[2010-10-13 22:40:06.521165] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:06.521250] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:06.521331] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:06.521419] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:06.521516] DEBUG[23900] sig_analog.c: Sending callerid.  CID_NAME: '' CID_NUM: ''
[2010-10-13 22:40:06.521585] DEBUG[23900] chan_dahdi.c: Starting cid spill
[2010-10-13 22:40:06.522258] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:06.523194] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:06.523266] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:06.523350] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:06.523430] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:06.523502] DEBUG[23900] sig_analog.c: Sending callerid.  CID_NAME: '' CID_NUM: ''
[2010-10-13 22:40:06.523578] DEBUG[23900] chan_dahdi.c: Starting cid spill
[2010-10-13 22:40:06.524656] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:07.276137] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:07.276226] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:07.276305] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:07.276404] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGBEGIN(12) on channel 36 (index 0)
[2010-10-13 22:40:07.276514] DEBUG[23900] sig_analog.c: Dunno what to do with event 12 on channel 36
[2010-10-13 22:40:07.581121] DEBUG[23900] chan_dahdi.c: Restored conferencing
[2010-10-13 22:40:07.583121] DEBUG[23900] chan_dahdi.c: Restored conferencing
[2010-10-13 22:40:08.519120] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:08.519205] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:08.519303] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:08.519392] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 36 (index 0)
[2010-10-13 22:40:08.519449] DEBUG[23900] sig_analog.c: Ring detected
[2010-10-13 22:40:08.521166] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:08.521239] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:08.521277] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:08.521313] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:08.523162] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:08.523227] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:08.523286] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:08.523368] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:08.921169] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:08.921261] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:08.921345] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:08.921424] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:08.921511] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:08.923170] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:08.923252] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:08.923337] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:08.923416] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:08.923505] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:09.121172] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:09.121263] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:09.121348] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:09.121423] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:09.123165] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:09.123228] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:09.123295] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:09.123365] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:09.521212] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:09.521302] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:09.521387] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:09.521454] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:09.521554] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:09.523178] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:09.523260] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:09.523346] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:09.523437] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:09.523903] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:10.276160] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:10.276247] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:10.276327] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:10.276431] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGBEGIN(12) on channel 36 (index 0)
[2010-10-13 22:40:10.276522] DEBUG[23900] sig_analog.c: Dunno what to do with event 12 on channel 36
[2010-10-13 22:40:11.515146] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:11.515223] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:11.515272] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:11.515359] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 36 (index 0)
[2010-10-13 22:40:11.515416] DEBUG[23900] sig_analog.c: Ring detected
[2010-10-13 22:40:11.521191] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:11.521254] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:11.521346] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:11.521404] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:11.523185] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:11.523241] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:11.523315] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:11.523794] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:11.921197] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:11.921288] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:11.921339] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:11.921378] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:11.921419] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:11.923201] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:11.923279] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:11.923748] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:11.923993] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:11.924160] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:12.121199] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:12.121288] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:12.121340] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:12.121379] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:12.123193] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:12.123248] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:12.123708] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:12.123936] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:12.521199] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:12.521284] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:12.521361] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:12.521426] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:12.521539] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:12.523198] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:12.523654] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:12.524372] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:12.524557] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:12.524671] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:13.266191] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:13.266267] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:13.266316] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:13.266409] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGBEGIN(12) on channel 36 (index 0)
[2010-10-13 22:40:13.266483] DEBUG[23900] sig_analog.c: Dunno what to do with event 12 on channel 36
[2010-10-13 22:40:14.515171] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:14.515246] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:14.515383] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:14.515480] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 36 (index 0)
[2010-10-13 22:40:14.515569] DEBUG[23900] sig_analog.c: Ring detected
[2010-10-13 22:40:14.521216] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:14.521269] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:14.521445] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:14.521523] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:14.523601] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:14.523678] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:14.523777] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:14.523991] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:14.921216] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:14.921293] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:14.921345] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:14.921438] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 33 (index 0)
[2010-10-13 22:40:14.921506] VERBOSE[23900] app_dial.c:     -- DAHDI/33-1 is ringing
[2010-10-13 22:40:14.923581] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:14.923661] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:14.924210] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:14.924340] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 34 (index 0)
[2010-10-13 22:40:14.924417] VERBOSE[23900] app_dial.c:     -- DAHDI/34-1 is ringing
[2010-10-13 22:40:15.121222] DEBUG[23900] sig_analog.c: analog_exception 33
[2010-10-13 22:40:15.121295] DEBUG[23900] sig_analog.c: Exception on 40, channel 33
[2010-10-13 22:40:15.121347] DEBUG[23900] sig_analog.c: __analog_handle_event 33
[2010-10-13 22:40:15.121441] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 33 (index 0)
[2010-10-13 22:40:15.123553] DEBUG[23900] sig_analog.c: analog_exception 34
[2010-10-13 22:40:15.123630] DEBUG[23900] sig_analog.c: Exception on 41, channel 34
[2010-10-13 22:40:15.123727] DEBUG[23900] sig_analog.c: __analog_handle_event 34
[2010-10-13 22:40:15.123936] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 34 (index 0)
[2010-10-13 22:40:15.439213] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:40:15.439302] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:40:15.439351] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:40:15.439430] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_WINKFLASH(3) on channel 35 (index 0)
[2010-10-13 22:40:15.439488] DEBUG[23880] sig_analog.c: Winkflash, index: 0, normal: 42, callwait: 68, thirdcall: -1
[2010-10-13 22:40:15.439583] DEBUG[23880] sig_analog.c: Swapping 0 and 1
[2010-10-13 22:40:15.439814] DEBUG[23880] sig_analog.c: Making DAHDI/35-2 the new owner
[2010-10-13 22:40:15.440182] VERBOSE[23900] app_dial.c:     -- DAHDI/35-2 answered DAHDI/36-1
[2010-10-13 22:40:15.440188] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 2 (In use)
[2010-10-13 22:40:15.440102] DEBUG[23880] sig_analog.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:15.440382] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '2'
[2010-10-13 22:40:15.440839] DEBUG[23900] channel.c: Hanging up channel 'DAHDI/34-1'
[2010-10-13 22:40:15.441332] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:15.440934] DEBUG[23900] chan_dahdi.c: dahdi_hangup(DAHDI/34-1)
[2010-10-13 22:40:15.441628] DEBUG[23900] sig_analog.c: analog_hangup 34
[2010-10-13 22:40:15.441702] DEBUG[23900] sig_analog.c: Hangup: channel: 34 index = 0, normal = 1, callwait = 0, thirdcall = 0
[2010-10-13 22:40:15.441840] DEBUG[23900] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/34-1
[2010-10-13 22:40:15.441884] DEBUG[23900] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/34-1
[2010-10-13 22:40:15.442188] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:40:15.441996] DEBUG[23900] sig_analog.c: Updated conferencing on 34, with 0 conference users
[2010-10-13 22:40:15.442391] VERBOSE[23880] res_musiconhold.c:     -- Started music on hold, class 'default', on SIP/bt100-00000007
[2010-10-13 22:40:15.442504] DEBUG[23880] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:40:15.442543] VERBOSE[23900] sig_analog.c:     -- Hanging up on 'DAHDI/34-1'
[2010-10-13 22:40:15.442956] VERBOSE[23900] chan_dahdi.c:     -- Hungup 'DAHDI/34-1'
[2010-10-13 22:40:15.445306] DEBUG[23900] channel.c: Hanging up channel 'DAHDI/33-1'
[2010-10-13 22:40:15.445397] DEBUG[23900] chan_dahdi.c: dahdi_hangup(DAHDI/33-1)
[2010-10-13 22:40:15.445409] DEBUG[23517] devicestate.c: Changing state for DAHDI/34 - state 0 (Unknown)
[2010-10-13 22:40:15.445486] DEBUG[23517] devicestate.c: device 'DAHDI/34' state '0'
[2010-10-13 22:40:15.445497] DEBUG[23900] sig_analog.c: analog_hangup 33
[2010-10-13 22:40:15.446499] DEBUG[23551] app_queue.c: Device 'DAHDI/34' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:15.445694] DEBUG[23900] sig_analog.c: Hangup: channel: 33 index = 0, normal = 1, callwait = 0, thirdcall = 0
[2010-10-13 22:40:15.446680] DEBUG[23900] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/33-1
[2010-10-13 22:40:15.446718] DEBUG[23900] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/33-1
[2010-10-13 22:40:15.446751] DEBUG[23900] sig_analog.c: Updated conferencing on 33, with 0 conference users
[2010-10-13 22:40:15.446815] VERBOSE[23900] sig_analog.c:     -- Hanging up on 'DAHDI/33-1'
[2010-10-13 22:40:15.446897] VERBOSE[23900] chan_dahdi.c:     -- Hungup 'DAHDI/33-1'
[2010-10-13 22:40:15.448774] DEBUG[23517] devicestate.c: Changing state for DAHDI/33 - state 0 (Unknown)
[2010-10-13 22:40:15.448869] DEBUG[23517] devicestate.c: device 'DAHDI/33' state '0'
[2010-10-13 22:40:15.449108] DEBUG[23900] sig_analog.c: analog_answer 36
[2010-10-13 22:40:15.449169] DEBUG[23900] sig_analog.c: Took DAHDI/36-1 off hook
[2010-10-13 22:40:15.449700] DEBUG[23900] chan_dahdi.c: Enabled echo cancellation on channel 36
[2010-10-13 22:40:15.449849] DEBUG[23551] app_queue.c: Device 'DAHDI/33' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:15.449922] DEBUG[23900] chan_dahdi.c: Engaged echo training on channel 36
[2010-10-13 22:40:15.450061] DEBUG[23517] devicestate.c: Changing state for DAHDI/36 - state 2 (In use)
[2010-10-13 22:40:15.450133] DEBUG[23517] devicestate.c: device 'DAHDI/36' state '2'
[2010-10-13 22:40:15.451125] DEBUG[23551] app_queue.c: Device 'DAHDI/36' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:40:15.450815] DEBUG[23900] chan_dahdi.c: Requested indication -1 on channel DAHDI/36-1
[2010-10-13 22:40:15.451598] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:40:15.451700] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:40:15.452061] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:40:15.452101] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:40:15.452270] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:40:15.452395] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:40:15.452475] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:40:15.452561] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:40:15.452638] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:15.452727] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:15.452835] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:40:15.453180] DEBUG[23900] channel.c: Dropping duplicate answer!
[2010-10-13 22:40:15.453489] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:15.453568] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:15.453724] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:40:15.453797] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:40:15.453857] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:40:15.453951] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:15.454146] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:40:15.454271] DEBUG[23900] chan_dahdi.c: Requested indication 17 on channel DAHDI/36-1
[2010-10-13 22:40:15.454610] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:40:15.454721] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:40:15.455037] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:40:15.455075] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:40:15.455227] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:40:15.455359] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:40:15.455431] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:40:15.455509] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:40:15.455602] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:15.455672] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:15.455769] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:40:15.462805] DEBUG[23880] channel.c: Set channel SIP/bt100-00000007 to write format slin
[2010-10-13 22:40:15.463241] DEBUG[23880] res_musiconhold.c: SIP/bt100-00000007 Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day'
[2010-10-13 22:40:15.578205] DEBUG[23900] sig_analog.c: analog_exception 36
[2010-10-13 22:40:15.578278] DEBUG[23900] sig_analog.c: Exception on 43, channel 36
[2010-10-13 22:40:15.578386] DEBUG[23900] sig_analog.c: __analog_handle_event 36
[2010-10-13 22:40:15.578451] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_POLARITY(11) on channel 36 (index 0)
[2010-10-13 22:40:15.578546] DEBUG[23900] sig_analog.c: Polarity switch detected but NOT hanging up (too close to Answer event) on channel 36, state 6
[2010-10-13 22:40:15.578650] DEBUG[23900] sig_analog.c: Polarity Reversal event occured - DEBUG 2: channel 36, state 6, pol= 1, aonp= 1, honp= 1, pdelay= 600, tv= 129
[2010-10-13 22:40:30.626318] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog '0c0f43f7b6c84691@172.16.0.99'
[2010-10-13 22:40:30.626486] DEBUG[23539] chan_sip.c: Destroying SIP dialog 0c0f43f7b6c84691@172.16.0.99
[2010-10-13 22:40:30.633485] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog '30b0c6db7273eecb@172.16.0.99'
[2010-10-13 22:40:30.633674] DEBUG[23539] chan_sip.c: Destroying SIP dialog 30b0c6db7273eecb@172.16.0.99
[2010-10-13 22:40:52.743505] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:40:52.743581] DEBUG[23900] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:40:52.743659] DEBUG[23900] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:40:52.743731] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_WINKFLASH(3) on channel 35 (index 0)
[2010-10-13 22:40:52.743811] DEBUG[23900] sig_analog.c: Winkflash, index: 0, normal: 42, callwait: 68, thirdcall: -1
[2010-10-13 22:40:52.743943] DEBUG[23900] sig_analog.c: Swapping 0 and 1
[2010-10-13 22:40:52.744118] DEBUG[23900] sig_analog.c: Making DAHDI/35-1 the new owner
[2010-10-13 22:40:52.744288] DEBUG[23900] sig_analog.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:52.744640] DEBUG[23900] chan_dahdi.c: Something changed out on 36/0 to 35/0, returning -3 to restart
[2010-10-13 22:40:52.744690] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:52.744857] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:52.745038] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:40:52.745111] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:40:52.744951] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:40:52.745207] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:40:52.745607] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:52.745549] VERBOSE[23880] res_musiconhold.c:     -- Stopped music on hold on SIP/bt100-00000007
[2010-10-13 22:40:52.746246] DEBUG[23880] channel.c: Set channel SIP/bt100-00000007 to write format alaw
[2010-10-13 22:40:52.746384] VERBOSE[23880] res_musiconhold.c:     -- Started music on hold, class 'default', on SIP/bt100-00000007
[2010-10-13 22:40:52.746620] DEBUG[23880] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:40:52.746922] DEBUG[23900] chan_dahdi.c: master: 0, slave: 0, nothingok: 1
[2010-10-13 22:40:52.747017] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:52.747197] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:52.747347] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:40:52.747273] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:40:52.747586] VERBOSE[23880] res_musiconhold.c:     -- Stopped music on hold on SIP/bt100-00000007
[2010-10-13 22:40:52.748095] DEBUG[23880] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2010-10-13 22:40:52.747924] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:52.748514] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:40:52.748768] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:40:52.748879] DEBUG[23900] chan_dahdi.c: Requested indication 16 on channel DAHDI/36-1
[2010-10-13 22:40:52.749131] VERBOSE[23900] res_musiconhold.c:     -- Started music on hold, class 'default', on DAHDI/36-1
[2010-10-13 22:40:52.749214] DEBUG[23900] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:40:52.749395] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:40:52.749499] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:40:52.750286] DEBUG[23900] chan_dahdi.c: master: 0, slave: 0, nothingok: 1
[2010-10-13 22:40:52.750411] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:40:52.750462] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:40:52.750833] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:40:52.769487] DEBUG[23900] channel.c: Set channel DAHDI/36-1 to write format slin
[2010-10-13 22:40:52.769894] DEBUG[23900] res_musiconhold.c: DAHDI/36-1 Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day'
[2010-10-13 22:41:13.961120] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:41:13.961257] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for NTIxM2Y0OTFiOGJiNTg0N2ZlMDAyMGZmYTQ2ZDhjN2E. - REGISTER (No RTP)
[2010-10-13 22:41:13.961674] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.0.250:18842
[2010-10-13 22:41:14.073148] DEBUG[23539] chan_sip.c: Store REGISTER's src-IP:port for call routing.
[2010-10-13 22:41:14.073440] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.0.250:18842
[2010-10-13 22:41:14.073787] DEBUG[23517] chan_sip.c: Checking device state for peer alec-xlite
[2010-10-13 22:41:14.074030] DEBUG[23517] devicestate.c: Changing state for SIP/alec-xlite - state 1 (Not in use)
[2010-10-13 22:41:14.074168] DEBUG[23517] devicestate.c: device 'SIP/alec-xlite' state '1'
[2010-10-13 22:41:14.074765] DEBUG[23551] app_queue.c: Device 'SIP/alec-xlite' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-10-13 22:41:24.511824] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:41:24.512040] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:41:24.512091] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:41:24.512174] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_WINKFLASH(3) on channel 35 (index 0)
[2010-10-13 22:41:24.512242] DEBUG[23880] sig_analog.c: Winkflash, index: 0, normal: 42, callwait: 68, thirdcall: -1
[2010-10-13 22:41:24.512374] DEBUG[23880] sig_analog.c: Swapping 0 and 1
[2010-10-13 22:41:24.512824] DEBUG[23880] sig_analog.c: Making DAHDI/35-2 the new owner
[2010-10-13 22:41:24.513013] DEBUG[23880] sig_analog.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:24.513068] DEBUG[23900] chan_dahdi.c: Something changed out on 36/0 to 35/1, returning -3 to restart
[2010-10-13 22:41:24.513212] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.513428] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.513345] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:41:24.513862] VERBOSE[23880] res_musiconhold.c:     -- Started music on hold, class 'default', on SIP/bt100-00000007
[2010-10-13 22:41:24.514091] DEBUG[23880] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:41:24.514238] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:41:24.514501] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:41:24.514588] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:41:24.514746] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:41:24.514857] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:41:24.514939] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:41:24.515034] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:24.515094] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:24.515178] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:41:24.515401] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.515459] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.515885] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:41:24.515965] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:41:24.516038] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:41:24.516119] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:24.516312] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:41:24.516421] DEBUG[23900] chan_dahdi.c: Requested indication 16 on channel DAHDI/36-1
[2010-10-13 22:41:24.516772] VERBOSE[23900] res_musiconhold.c:     -- Stopped music on hold on DAHDI/36-1
[2010-10-13 22:41:24.516916] DEBUG[23900] channel.c: Set channel DAHDI/36-1 to write format alaw
[2010-10-13 22:41:24.516982] VERBOSE[23900] res_musiconhold.c:     -- Started music on hold, class 'default', on DAHDI/36-1
[2010-10-13 22:41:24.518152] DEBUG[23900] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:41:24.518742] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:41:24.518877] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:41:24.519182] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:41:24.519224] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:41:24.519304] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:41:24.519365] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:41:24.519451] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:41:24.519616] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:41:24.519748] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:24.520193] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:24.520301] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:41:24.520542] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.520601] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:24.520806] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:41:24.520881] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:41:24.520939] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:41:24.520980] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:24.521059] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:41:24.521105] DEBUG[23900] chan_dahdi.c: Requested indication 17 on channel DAHDI/36-1
[2010-10-13 22:41:24.521147] VERBOSE[23900] res_musiconhold.c:     -- Stopped music on hold on DAHDI/36-1
[2010-10-13 22:41:24.521192] DEBUG[23900] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2010-10-13 22:41:24.521331] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:41:24.521375] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:41:24.521650] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:41:24.521696] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:41:24.521740] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:41:24.521772] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:41:24.521807] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:41:24.521839] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:41:24.521870] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:24.521902] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:24.521938] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:41:24.534033] DEBUG[23880] channel.c: Set channel SIP/bt100-00000007 to write format slin
[2010-10-13 22:41:24.534381] DEBUG[23880] res_musiconhold.c: SIP/bt100-00000007 Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day'
[2010-10-13 22:41:38.631139] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:41:38.631284] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for 0c0f43f7b6c84691@172.16.0.99 - REGISTER (No RTP)
[2010-10-13 22:41:38.631578] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:41:38.661196] DEBUG[23539] chan_sip.c: Store REGISTER's Contact header for call routing.
[2010-10-13 22:41:38.661659] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:41:38.662100] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:41:38.662207] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 2 (In use)
[2010-10-13 22:41:38.662259] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '2'
[2010-10-13 22:41:38.663014] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '2' (In use)
[2010-10-13 22:41:38.670503] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.0.1:5060
[2010-10-13 22:41:38.670681] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for 30b0c6db7273eecb@172.16.0.99 - SUBSCRIBE (No RTP)
[2010-10-13 22:41:38.670905] DEBUG[23539] chan_sip.c: Trying to put 'SIP/2.0 481' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:41:46.077968] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog 'NTIxM2Y0OTFiOGJiNTg0N2ZlMDAyMGZmYTQ2ZDhjN2E.'
[2010-10-13 22:41:46.078133] DEBUG[23539] chan_sip.c: Destroying SIP dialog NTIxM2Y0OTFiOGJiNTg0N2ZlMDAyMGZmYTQ2ZDhjN2E.
[2010-10-13 22:41:51.655986] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:41:51.656190] DEBUG[23900] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:41:51.656262] DEBUG[23900] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:41:51.656359] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_WINKFLASH(3) on channel 35 (index 0)
[2010-10-13 22:41:51.656439] DEBUG[23900] sig_analog.c: Winkflash, index: 0, normal: 42, callwait: 68, thirdcall: -1
[2010-10-13 22:41:51.656596] DEBUG[23900] sig_analog.c: Swapping 0 and 1
[2010-10-13 22:41:51.656801] DEBUG[23900] sig_analog.c: Making DAHDI/35-1 the new owner
[2010-10-13 22:41:51.656998] DEBUG[23900] sig_analog.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:51.657173] DEBUG[23900] chan_dahdi.c: Something changed out on 36/0 to 35/0, returning -3 to restart
[2010-10-13 22:41:51.657206] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:51.657236] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:51.657283] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:41:51.657316] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:41:51.657349] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:41:51.657381] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:51.657594] DEBUG[23900] chan_dahdi.c: master: 0, slave: 0, nothingok: 1
[2010-10-13 22:41:51.657626] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:51.657657] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:51.657694] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:41:51.657809] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:51.657839] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:41:51.657956] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:41:51.658003] DEBUG[23900] chan_dahdi.c: Requested indication 16 on channel DAHDI/36-1
[2010-10-13 22:41:51.658109] VERBOSE[23900] res_musiconhold.c:     -- Started music on hold, class 'default', on DAHDI/36-1
[2010-10-13 22:41:51.658146] DEBUG[23900] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:41:51.658250] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:41:51.658294] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:41:51.658484] DEBUG[23900] chan_dahdi.c: master: 0, slave: 0, nothingok: 1
[2010-10-13 22:41:51.658516] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:41:51.658547] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:41:51.658582] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:41:51.659586] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:41:51.659960] VERBOSE[23880] res_musiconhold.c:     -- Stopped music on hold on SIP/bt100-00000007
[2010-10-13 22:41:51.660066] DEBUG[23880] channel.c: Set channel SIP/bt100-00000007 to write format alaw
[2010-10-13 22:41:51.660134] VERBOSE[23880] res_musiconhold.c:     -- Started music on hold, class 'default', on SIP/bt100-00000007
[2010-10-13 22:41:51.660198] DEBUG[23880] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2010-10-13 22:41:51.660380] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:41:51.660488] VERBOSE[23880] res_musiconhold.c:     -- Stopped music on hold on SIP/bt100-00000007
[2010-10-13 22:41:51.660569] DEBUG[23880] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2010-10-13 22:41:51.678486] DEBUG[23900] channel.c: Set channel DAHDI/36-1 to write format slin
[2010-10-13 22:41:51.678823] DEBUG[23900] res_musiconhold.c: DAHDI/36-1 Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day'
[2010-10-13 22:42:10.668492] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog '0c0f43f7b6c84691@172.16.0.99'
[2010-10-13 22:42:10.668688] DEBUG[23539] chan_sip.c: Destroying SIP dialog 0c0f43f7b6c84691@172.16.0.99
[2010-10-13 22:42:10.676508] DEBUG[23539] chan_sip.c: Auto destroying SIP dialog '30b0c6db7273eecb@172.16.0.99'
[2010-10-13 22:42:10.676663] DEBUG[23539] chan_sip.c: Destroying SIP dialog 30b0c6db7273eecb@172.16.0.99
[2010-10-13 22:42:29.454703] DEBUG[23539] chan_sip.c: Setting SIP_TRANSPORT_UDP with address xxx.yyy.zzz.123:5060
[2010-10-13 22:42:29.454825] DEBUG[23539] chan_sip.c: Allocating new SIP dialog for 1776543944@10.1.1.3 - REGISTER (No RTP)
[2010-10-13 22:42:29.554556] DEBUG[23539] chan_sip.c: Store REGISTER's src-IP:port for call routing.
[2010-10-13 22:42:29.555160] DEBUG[23517] chan_sip.c: Checking device state for peer bro-home
[2010-10-13 22:42:29.555300] DEBUG[23517] devicestate.c: Changing state for SIP/bro-home - state 1 (Not in use)
[2010-10-13 22:42:29.555424] DEBUG[23517] devicestate.c: device 'SIP/bro-home' state '1'
[2010-10-13 22:42:29.555811] DEBUG[23551] app_queue.c: Device 'SIP/bro-home' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-10-13 22:42:30.649265] DEBUG[23880] sig_analog.c: analog_exception 35
[2010-10-13 22:42:30.649349] DEBUG[23880] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:42:30.649437] DEBUG[23880] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:42:30.649521] DEBUG[23880] sig_analog.c: Got event ANALOG_EVENT_ONHOOK(1) on channel 35 (index 0)
[2010-10-13 22:42:30.649645] DEBUG[23880] sig_analog.c: Swapping 1 and 0
[2010-10-13 22:42:30.649857] VERBOSE[23880] sig_analog.c:     -- Channel 35 still has (callwait) call, ringing phone
[2010-10-13 22:42:30.649944] DEBUG[23880] chan_dahdi.c: Released sub 1 of channel 35
[2010-10-13 22:42:30.650425] DEBUG[23880] chan_dahdi.c: Disabled echo cancellation on channel 35
[2010-10-13 22:42:30.650512] DEBUG[23880] channel.c: Didn't get a frame from channel: DAHDI/35-1
[2010-10-13 22:42:30.650609] DEBUG[23900] chan_dahdi.c: Something changed out on 36/0 to 35/1, returning -3 to restart
[2010-10-13 22:42:30.650719] DEBUG[23880] res_rtp_asterisk.c: Setting the marker bit due to a source update
[2010-10-13 22:42:30.650819] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:42:30.650987] DEBUG[23900] chan_dahdi.c: Enabled echo cancellation on channel 35
[2010-10-13 22:42:30.651360] DEBUG[23880] channel.c: Bridge stops bridging channels SIP/bt100-00000007 and DAHDI/35-1
[2010-10-13 22:42:30.651984] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:42:30.652191] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:42:30.652329] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:42:30.652486] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:42:30.652649] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:42:30.652929] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:42:30.653287] DEBUG[23880] channel.c: Hanging up channel 'DAHDI/35-1'
[2010-10-13 22:42:30.653388] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:42:30.653430] DEBUG[23880] chan_dahdi.c: dahdi_hangup(DAHDI/35-1)
[2010-10-13 22:42:30.653581] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:42:30.653858] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:42:30.653912] DEBUG[23880] sig_analog.c: analog_hangup 35
[2010-10-13 22:42:30.654457] DEBUG[23880] sig_analog.c: Hangup: channel: 35 index = -1, normal = 1, callwait = 0, thirdcall = 0
[2010-10-13 22:42:30.654606] VERBOSE[23880] sig_analog.c:     -- Hanging up on 'DAHDI/35-1'
[2010-10-13 22:42:30.654709] VERBOSE[23880] chan_dahdi.c:     -- Hungup 'DAHDI/35-1'
[2010-10-13 22:42:30.655583] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 2 (In use)
[2010-10-13 22:42:30.655588] DEBUG[23880] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[2010-10-13 22:42:30.655764] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '2'
[2010-10-13 22:42:30.656737] DEBUG[23880] pbx.c: Spawn extension (phones,89,1) exited non-zero on 'SIP/bt100-00000007'
[2010-10-13 22:42:30.656939] VERBOSE[23880] pbx.c:   == Spawn extension (phones, 89, 1) exited non-zero on 'SIP/bt100-00000007'
[2010-10-13 22:42:30.657367] DEBUG[23880] channel.c: Soft-Hanging up channel 'SIP/bt100-00000007'
[2010-10-13 22:42:30.657696] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[2010-10-13 22:42:30.657926] DEBUG[23880] channel.c: Hanging up channel 'SIP/bt100-00000007'
[2010-10-13 22:42:30.657971] DEBUG[23880] chan_sip.c: Hangup call SIP/bt100-00000007, SIP callid 8e0c33fcfa4adc5c@172.16.0.99
[2010-10-13 22:42:30.658009] DEBUG[23880] chan_sip.c: Updating call counter for incoming call
[2010-10-13 22:42:30.658413] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:42:30.658430] DEBUG[23880] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x8ce6730'
[2010-10-13 22:42:30.658578] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 1 (Not in use)
[2010-10-13 22:42:30.658645] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '1'
[2010-10-13 22:42:30.659846] DEBUG[23518] app_queue.c: Extension '649@phones' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-10-13 22:42:30.660063] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '1' (Not in use)
[2010-10-13 22:42:30.659189] DEBUG[23880] chan_sip.c: Trying to put 'BYE sip:bt1' onto UDP socket destined for 172.16.0.99:5060
[2010-10-13 22:42:30.660916] DEBUG[23517] chan_sip.c: Checking device state for peer bt100
[2010-10-13 22:42:30.661047] DEBUG[23517] devicestate.c: Changing state for SIP/bt100 - state 1 (Not in use)
[2010-10-13 22:42:30.661085] DEBUG[23517] devicestate.c: device 'SIP/bt100' state '1'
[2010-10-13 22:42:30.661576] DEBUG[23551] app_queue.c: Device 'SIP/bt100' changed to state '1' (Not in use)
[2010-10-13 22:42:30.666359] DEBUG[23539] chan_sip.c: Stopping retransmission on '8e0c33fcfa4adc5c@172.16.0.99' of Request 102: Match Found
[2010-10-13 22:42:30.666635] DEBUG[23539] chan_sip.c: Destroying SIP dialog 8e0c33fcfa4adc5c@172.16.0.99
[2010-10-13 22:42:30.666826] DEBUG[23539] rtp_engine.c: Destroyed RTP instance '0x8ce6730'
[2010-10-13 22:42:31.049309] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:42:31.249304] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:42:31.649309] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:42:32.804397] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:42:32.804508] DEBUG[23900] sig_analog.c: Restoring owner of channel 35 on event 2
[2010-10-13 22:42:32.804581] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:42:32.804685] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:42:32.804719] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:42:32.804787] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:42:32.804826] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:42:32.804860] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:42:32.804892] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:42:32.804969] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:42:32.805014] DEBUG[23900] chan_dahdi.c: Requested indication 17 on channel DAHDI/36-1
[2010-10-13 22:42:32.805093] VERBOSE[23900] res_musiconhold.c:     -- Stopped music on hold on DAHDI/36-1
[2010-10-13 22:42:32.805151] DEBUG[23900] channel.c: Set channel DAHDI/36-1 to write format alaw
[2010-10-13 22:42:32.805190] DEBUG[23900] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2010-10-13 22:42:32.805381] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/36-1
[2010-10-13 22:42:32.805426] DEBUG[23900] chan_dahdi.c: Requested indication 26 on channel DAHDI/35-2
[2010-10-13 22:42:32.805633] DEBUG[23900] chan_dahdi.c: master: 36, slave: 35, nothingok: 0
[2010-10-13 22:42:32.805665] DEBUG[23900] chan_dahdi.c: Stopping tones on 36/0 talking to 35/0
[2010-10-13 22:42:32.805698] DEBUG[23900] chan_dahdi.c: Stopping tones on 35/0 talking to 36/0
[2010-10-13 22:42:32.805734] DEBUG[23900] chan_dahdi.c: Making 35 slave to master 36 at 0
[2010-10-13 22:42:32.805790] DEBUG[23900] chan_dahdi.c: Added 42 to conference 9/36
[2010-10-13 22:42:32.805824] DEBUG[23900] chan_dahdi.c: Added 43 to conference 9/35
[2010-10-13 22:42:32.805855] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:42:32.805887] DEBUG[23900] chan_dahdi.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:42:32.805923] VERBOSE[23900] chan_dahdi.c:     -- Native bridging DAHDI/36-1 and DAHDI/35-2
[2010-10-13 22:42:35.029290] DEBUG[23900] sig_analog.c: analog_exception 35
[2010-10-13 22:42:35.029369] DEBUG[23900] sig_analog.c: Exception on 42, channel 35
[2010-10-13 22:42:35.029434] DEBUG[23900] sig_analog.c: __analog_handle_event 35
[2010-10-13 22:42:35.029500] DEBUG[23900] sig_analog.c: Got event ANALOG_EVENT_ONHOOK(1) on channel 35 (index 0)
[2010-10-13 22:42:35.029637] DEBUG[23900] chan_dahdi.c: Disabled echo cancellation on channel 35
[2010-10-13 22:42:35.029702] DEBUG[23900] chan_dahdi.c: Echo cancellation already on
[2010-10-13 22:42:35.029774] DEBUG[23900] chan_dahdi.c: Enabled echo cancellation on channel 35
[2010-10-13 22:42:35.029862] DEBUG[23900] chan_dahdi.c: Unlinking slave 35 from 36
[2010-10-13 22:42:35.029943] DEBUG[23900] chan_dahdi.c: Removed 42 from conference 9/36
[2010-10-13 22:42:35.030014] DEBUG[23900] chan_dahdi.c: Removed 43 from conference 9/35
[2010-10-13 22:42:35.030081] DEBUG[23900] chan_dahdi.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:42:35.030303] DEBUG[23900] channel.c: Returning from native bridge, channels: DAHDI/36-1, DAHDI/35-2
[2010-10-13 22:42:35.030852] DEBUG[23900] channel.c: Hanging up channel 'DAHDI/35-2'
[2010-10-13 22:42:35.030903] DEBUG[23900] chan_dahdi.c: dahdi_hangup(DAHDI/35-2)
[2010-10-13 22:42:35.031050] DEBUG[23900] sig_analog.c: analog_hangup 35
[2010-10-13 22:42:35.031098] DEBUG[23900] sig_analog.c: Hangup: channel: 35 index = 0, normal = 1, callwait = 0, thirdcall = 0
[2010-10-13 22:42:35.031185] DEBUG[23900] chan_dahdi.c: Disabled echo cancellation on channel 35
[2010-10-13 22:42:35.031319] DEBUG[23900] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/35-2
[2010-10-13 22:42:35.031398] DEBUG[23900] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/35-2
[2010-10-13 22:42:35.031502] DEBUG[23900] sig_analog.c: Updated conferencing on 35, with 0 conference users
[2010-10-13 22:42:35.031629] VERBOSE[23900] sig_analog.c:     -- Hanging up on 'DAHDI/35-2'
[2010-10-13 22:42:35.031711] VERBOSE[23900] chan_dahdi.c:     -- Hungup 'DAHDI/35-2'
[2010-10-13 22:42:35.032929] DEBUG[23900] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[2010-10-13 22:42:35.033035] DEBUG[23517] devicestate.c: Changing state for DAHDI/35 - state 0 (Unknown)
[2010-10-13 22:42:35.033154] DEBUG[23900] pbx.c: Spawn extension (incoming,s,2) exited non-zero on 'DAHDI/36-1'
[2010-10-13 22:42:35.033160] DEBUG[23517] devicestate.c: device 'DAHDI/35' state '0'
[2010-10-13 22:42:35.033388] VERBOSE[23900] pbx.c:   == Spawn extension (incoming, s, 2) exited non-zero on 'DAHDI/36-1'
[2010-10-13 22:42:35.033490] DEBUG[23900] channel.c: Soft-Hanging up channel 'DAHDI/36-1'
[2010-10-13 22:42:35.034162] DEBUG[23551] app_queue.c: Device 'DAHDI/35' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[2010-10-13 22:42:35.034271] DEBUG[23900] channel.c: Hanging up channel 'DAHDI/36-1'
[2010-10-13 22:42:35.034323] DEBUG[23900] chan_dahdi.c: dahdi_hangup(DAHDI/36-1)
[2010-10-13 22:42:35.034437] DEBUG[23900] sig_analog.c: analog_hangup 36
[2010-10-13 22:42:35.034499] DEBUG[23900] sig_analog.c: Hangup: channel: 36 index = 0, normal = 1, callwait = 0, thirdcall = 0
[2010-10-13 22:42:35.034561] DEBUG[23900] chan_dahdi.c: Disabled echo cancellation on channel 36
[2010-10-13 22:42:35.034609] DEBUG[23900] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/36-1
[2010-10-13 22:42:35.034656] DEBUG[23900] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/36-1
[2010-10-13 22:42:35.034752] DEBUG[23900] sig_analog.c: Updated conferencing on 36, with 0 conference users
[2010-10-13 22:42:35.035086] VERBOSE[23900] sig_analog.c:     -- Hanging up on 'DAHDI/36-1'
[2010-10-13 22:42:35.035660] VERBOSE[23900] chan_dahdi.c:     -- Hungup 'DAHDI/36-1'
[2010-10-13 22:42:35.036397] DEBUG[23517] devicestate.c: Changing state for DAHDI/36 - state 0 (Unknown)
[2010-10-13 22:42:35.036489] DEBUG[23517] devicestate.c: device 'DAHDI/36' state '0'
[2010-10-13 22:42:35.037067] DEBUG[23551] app_queue.c: Device 'DAHDI/36' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.