[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: = Found Their Call ID: 0be9dda070db4046143021c42fcf2aac@172.17.0.250 Their Tag f6e8pgpoqp Our tag: as32a75c59
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: Acked pending invite 102
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: Stopping retransmission on '0be9dda070db4046143021c42fcf2aac@172.17.0.250' of Request 102: Match Found
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: SIP response 200 to standard invite
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: T38 state changed to 0 on channel SIP/9304-07618644
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: We're settling with these formats: 0x8 (alaw)
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: We have an owner, now see if we need to change this call
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: Updating call counter for outgoing call
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'CallWaiting' in family 'default/9306'
[Sep 16 08:32:25] DEBUG[2416] devicestate.c: Notification of state change to be queued on device/channel SIP/9304
[Sep 16 08:32:25] DEBUG[2416] chan_sip.c: build_route: Contact hop: <sip:9304@172.17.0.104:2048;line=ober5azr>;reg-id=1
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:25] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:25] DEBUG[13758] devicestate.c: Notification of state change to be queued on device/channel SIP/9304
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: Changing state for SIP/9304 - state 2 (In use)
[Sep 16 08:32:25] VERBOSE[13758] logger.c:     -- SIP/9304-07618644 answered SIP/1015-b8167114
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:25] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:25] DEBUG[2393] app_queue.c: Device 'SIP/9304' changed to state '2' (In use)
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'Paused' in family 'default/9306/Agent'
[Sep 16 08:32:25] VERBOSE[13758] logger.c:     -- Stopped music on hold on SIP/1015-b8167114
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:25] VERBOSE[2340] logger.c:  Extension Changed 9304[default-local] new state InUse for Notify User 9306
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:25] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: Changing state for SIP/9304 - state 2 (In use)
[Sep 16 08:32:25] DEBUG[2393] app_queue.c: Device 'SIP/9304' changed to state '2' (In use)
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'HotDesk' in family 'default/9306'
[Sep 16 08:32:25] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:25] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:25] DEBUG[13758] channel.c: Set channel SIP/1015-b8167114 to write format ulaw
[Sep 16 08:32:25] DEBUG[13758] channel.c: Set channel SIP/1015-b8167114 to read format alaw
[Sep 16 08:32:25] DEBUG[13758] channel.c: Set channel SIP/9304-07618644 to read format ulaw
[Sep 16 08:32:25] DEBUG[13758] app_queue.c: Queue 'default-queuetest' Leave, Channel 'SIP/1015-b8167114'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'ChanSpy' in family 'default/9307'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'DoNotDisturb' in family 'default/9307'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] db.c: Unable to find key 'DoNotDisturb_Reason' in family 'default/9307'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'
[Sep 16 08:32:25] DEBUG[31227] manager.c: Manager received command 'Command'



[Sep 16 08:32:31] DEBUG[2416] chan_sip.c: = Found Their Call ID: 0be9dda070db4046143021c42fcf2aac@172.17.0.250 Their Tag f6e8pgpoqp Our tag: as32a75c59
[Sep 16 08:32:31] DEBUG[2416] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
[Sep 16 08:32:31] DEBUG[2416] chan_sip.c: Setting SIP_ALREADYGONE on dialog 0be9dda070db4046143021c42fcf2aac@172.17.0.250
[Sep 16 08:32:31] DEBUG[2416] chan_sip.c: Received bye, issuing owner hangup
[Sep 16 08:32:31] DEBUG[13758] channel.c: Didn't get a frame from channel: SIP/9304-07618644
[Sep 16 08:32:31] DEBUG[13758] channel.c: Bridge stops bridging channels SIP/1015-b8167114 and SIP/9304-07618644
[Sep 16 08:32:31] DEBUG[13758] channel.c: Hanging up channel 'SIP/9304-07618644'
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Hangup call SIP/9304-07618644, SIP callid 0be9dda070db4046143021c42fcf2aac@172.17.0.250)
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Updating call counter for outgoing call
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Call to peer '9304' removed from call limit 4
[Sep 16 08:32:31] DEBUG[13758] devicestate.c: Notification of state change to be queued on device/channel SIP/9304
[Sep 16 08:32:31] DEBUG[13758] devicestate.c: Notification of state change to be queued on device/channel SIP/9304
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Spawn extension (default-application-acd-queuetest,s,9) exited non-zero on 'SIP/1015-b8167114'
[Sep 16 08:32:31] VERBOSE[13758] logger.c:   == Spawn extension (default-application-acd-queuetest, s, 9) exited non-zero on 'SIP/1015-b8167114'
[Sep 16 08:32:31] DEBUG[13758] channel.c: Soft-Hanging up channel 'SIP/1015-b8167114'
[Sep 16 08:32:31] DEBUG[13758] channel.c: Hanging up channel 'SIP/1015-b8167114'
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Hangup call SIP/1015-b8167114, SIP callid 3106dc3f7b296026NmNjODVhNjNkYmMyMTdkMDJkYWMzZDg1MzgyMzgwMzY.)
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Updating call counter for incoming call
[Sep 16 08:32:31] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:31] DEBUG[13758] chan_sip.c: Call from peer '1015' removed from call limit 4
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: Changing state for SIP/9304 - state 1 (Not in use)
[Sep 16 08:32:31] DEBUG[13758] devicestate.c: Notification of state change to be queued on device/channel SIP/1015
[Sep 16 08:32:31] DEBUG[2393] app_queue.c: Device 'SIP/9304' changed to state '1' (Not in use)
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:31] DEBUG[13758] devicestate.c: Notification of state change to be queued on device/channel SIP/1015
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '"Doug Softphone" <1015>'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '1015'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 's'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'default-application-acd-queuetest'
[Sep 16 08:32:31] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'SIP/1015-b8167114'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'SIP/9304-07618644'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'Queue'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'default-queuetest|t|||300|'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '2008-09-16 08:32:20'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '2008-09-16 08:32:20'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '2008-09-16 08:32:31'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '11'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '11'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'ANSWERED'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is 'DOCUMENTATION'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '1015'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is '1221546740.1398'
[Sep 16 08:32:31] DEBUG[13758] pbx.c: Function result is ''
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:31] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:31] VERBOSE[2340] logger.c:  Extension Changed 9304[default-local] new state Idle for Notify User 9306
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:31] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: Changing state for SIP/9304 - state 1 (Not in use)
[Sep 16 08:32:31] DEBUG[2393] app_queue.c: Device 'SIP/9304' changed to state '1' (Not in use)
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 9304
[Sep 16 08:32:31] DEBUG[2340] chan_sip.c: Checking device state for peer 9304
[Sep 16 08:32:31] DEBUG[2340] devicestate.c: No provider found, checking channel drivers for SIP - 1015
[Sep 16 08:32:31] DEBUG[13758] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Sep 16 08:32:31] DEBUG[13758] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,
accountcode,uniqueid,userfield) VALUES ('2008-09-16 08:32:20','\"Doug Softphone\" <1015>','1015','s','default-application-acd-queuetest', 'SIP/1015-b8167114','SIP/9304-07618644','Queue','default-queuetest|t|
||300|',11,11,'ANSWERED',3,'1015','1221546740.1398','')