[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: ;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','')