-- Executing [6010@default-super:1] GotoIf("SIP/6002-08f02538", "0?3") in new stack -- Executing [6010@default-super:2] Set("SIP/6002-08f02538", "GROUP(OUTGOING)=6002") in new stack -- Executing [6010@default-super:3] Set("SIP/6002-08f02538", "OUTBOUND_GROUP_ONCE=6010@INCOMING") in new stack -- Executing [6010@default-super:4] Set("SIP/6002-08f02538", "GROUPCOUNT=0") in new stack -- Executing [6010@default-super:5] Set("SIP/6002-08f02538", "GROUPCOUNT2=0") in new stack -- Executing [6010@default-super:6] Set("SIP/6002-08f02538", "DB(default/wrapup/6010/lastcall)=1227732812.2") in new stack -- Executing [6010@default-super:7] Macro("SIP/6002-08f02538", "default-dial|SIP/6010|6010|default|20|en|b6010@default|twWkKM(all-tapi^1227732812.2)||default||Local/0@default-local-devices|vm") in new stack -- Executing [s@macro-default-dial:1] NoOp("SIP/6002-08f02538", ""CALL TO LOCAL EXTENSION FROM 6002(Reception 6002)"") in new stack -- Executing [s@macro-default-dial:2] UserEvent("SIP/6002-08f02538", "TAPI|TAPIEVENT: LINE_NEWCALL default") in new stack -- Executing [s@macro-default-dial:3] UserEvent("SIP/6002-08f02538", "TAPI|TAPIEVENT: LINE_CALLSTATE LINECALLSTATE_OFFERING") in new stack -- Executing [s@macro-default-dial:4] UserEvent("SIP/6002-08f02538", "TAPI|TAPIEVENT: SET CALLERID ") in new stack -- Executing [s@macro-default-dial:5] UserEvent("SIP/6002-08f02538", "TAPI|TAPIEVENT: LINE_CALLINFO LINECALLINFOSTATE_CALLERID") in new stack -- Executing [s@macro-default-dial:6] AGI("SIP/6002-08f02538", "/var/www/scopserv/telephony/scripts/agi/dial.php") in new stack -- Launched AGI Script /var/www/scopserv/telephony/scripts/agi/dial.php AGI Tx >> agi_request: /var/www/scopserv/telephony/scripts/agi/dial.php AGI Tx >> agi_channel: SIP/6002-08f02538 AGI Tx >> agi_language: en AGI Tx >> agi_type: SIP AGI Tx >> agi_uniqueid: 1227732812.2 AGI Tx >> agi_callerid: 6002 AGI Tx >> agi_calleridname: Reception 6002 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 0 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 6010 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: macro-default-dial AGI Tx >> agi_extension: s AGI Tx >> agi_priority: 6 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: 6002 AGI Tx >> AGI Rx << GET VARIABLE ARG1 AGI Tx >> 200 result=1 (SIP/6010) AGI Rx << GET VARIABLE ARG2 AGI Tx >> 200 result=1 (6010) AGI Rx << GET VARIABLE ARG3 AGI Tx >> 200 result=1 (default) AGI Rx << GET VARIABLE ARG4 AGI Tx >> 200 result=1 (20) AGI Rx << GET VARIABLE ARG5 AGI Tx >> 200 result=1 (en) AGI Rx << GET VARIABLE ARG6 AGI Tx >> 200 result=1 (b6010@default) AGI Rx << GET VARIABLE ARG7 AGI Tx >> 200 result=1 (twWkKM(all-tapi^1227732812.2)) AGI Rx << GET VARIABLE ARG8 AGI Tx >> 200 result=1 () AGI Rx << GET VARIABLE ARG9 AGI Tx >> 200 result=1 (default) AGI Rx << GET VARIABLE ARG10 AGI Tx >> 200 result=1 () AGI Rx << GET VARIABLE ARG11 AGI Tx >> 200 result=1 (Local/0@default-local-devices) AGI Rx << GET VARIABLE ARG12 AGI Tx >> 200 result=1 (vm) AGI Rx << GET VARIABLE MACRO_PRIORITY AGI Tx >> 200 result=1 (7) AGI Rx << DATABASE GET "default/6010" "MonitorIncoming" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "HotDesk" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "DoNotDisturb" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "dnd" "SIP/6010" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForward" AGI Tx >> 200 result=1 (none) AGI Rx << DATABASE GET "default/6010" "CallForward_CIDNum" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForward_CIDName" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnBusy" AGI Tx >> 200 result=1 (vmail:b6010@default) AGI Rx << DATABASE GET "default/6010" "CallForwardOnBusy_CIDNum" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnBusy_CIDName" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnNoAnswer" AGI Tx >> 200 result=1 (vmail:b6010@default) AGI Rx << DATABASE GET "default/6010" "CallForwardOnNoAnswer_CIDNum" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnNoAnswer_CIDName" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnUnavailable" AGI Tx >> 200 result=1 (none) AGI Rx << DATABASE GET "default/6010" "CallForwardOnUnavailable_CIDNum" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnUnavailable_CIDName" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallWaiting" AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE INCOMINGLINE AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForward_Internal" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnBusy_Internal" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnNoAnswer_Internal" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6010" "CallForwardOnUnavailable_Internal" AGI Tx >> 200 result=0 AGI Rx << VERBOSE "Added extension '6010' to extension map." 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: Added extension '6010' to extension map. AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE CHANNEL(language) "en" AGI Tx >> 200 result=1 AGI Rx << EXEC SetMusicOnHold default -- AGI Script Executing Application: (SetMusicOnHold) Options: (default) AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE AUTO_RECORDING AGI Tx >> 200 result=1 (6002) AGI Rx << SET VARIABLE TOUCH_MONITOR "6002_1227732812.2" AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE FORCE_RECORDING AGI Tx >> 200 result=0 AGI Rx << GET VARIABLE LIMIT_IN_DEFAULT_6010 AGI Tx >> 200 result=1 (4) AGI Rx << GET VARIABLE GROUPCOUNT AGI Tx >> 200 result=1 (0) AGI Rx << GET VARIABLE GROUPCOUNT2 AGI Tx >> 200 result=1 (0) == Parsing '/etc/asterisk/manager.conf': Found AGI Rx << VERBOSE "Extension State for '6010' is '0'." 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: Extension State for '6010' is '0'. AGI Tx >> 200 result=1 AGI Rx << EXEC NoOp STATUS: 0 -- AGI Script Executing Application: (NoOp) Options: (STATUS:) AGI Tx >> 200 result=0 AGI Rx << VERBOSE "-------------------------------------------------------------------------------" 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: ------------------------------------------------------------------------------- AGI Tx >> 200 result=1 AGI Rx << VERBOSE "Doing the action dial" 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: Doing the action dial AGI Tx >> 200 result=1 AGI Rx << VERBOSE "Caller ID number is '6002'" 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: Caller ID number is '6002' AGI Tx >> 200 result=1 AGI Rx << DATABASE GET "default/6002" "CallerInternalNumber" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6002" "CallerInternalName" AGI Tx >> 200 result=0 AGI Rx << DATABASE GET "default/6002" "HotDesk" AGI Tx >> 200 result=0 AGI Rx << DATABASE PUT "default/6010" "CallTrace" "6002" AGI Tx >> 200 result=1 AGI Rx << VERBOSE "DbSet default/6010/CallTrace to 6002" 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: DbSet default/6010/CallTrace to 6002 AGI Tx >> 200 result=1 AGI Rx << VERBOSE "Dial string is SIP/6010|20|twWkKM(all-tapi^1227732812.2)T|." 2 == /var/www/scopserv/telephony/scripts/agi/dial.php: Dial string is SIP/6010|20|twWkKM(all-tapi^1227732812.2)T|. AGI Tx >> 200 result=1 AGI Rx << EXEC Dial SIP/6010|20|twWkKM(all-tapi^1227732812.2)T| -- AGI Script Executing Application: (Dial) Options: (SIP/6010|20|twWkKM(all-tapi^1227732812.2)T|) -- Called 6010 -- SIP/6010-08f28f80 is ringing -- SIP/6010-08f28f80 answered SIP/6002-08f02538 -- Executing [s@macro-all-tapi:1] UserEvent("SIP/6010-08f28f80", "TAPI|TAPIEVENT [~1227732812.2] LINE_CALLSTATE LINECALLSTATE_CONNECTED") in new stack -- Started music on hold, class 'default', on SIP/6002-08f02538 -- Playing 'pbx-transfer' (language 'en') -- Stopped music on hold on SIP/6002-08f02538 -- Started music on hold, class 'default', on SIP/6002-08f02538 == Parked SIP/6002-08f02538 on 7001@parkedcalls. Will timeout back to extension [default-super] 6010, 7 in 45 seconds -- Added extension '7001' priority 1 to parkedcalls -- Playing 'digits/7' (language 'en') -- Playing 'digits/0' (language 'en') -- Playing 'digits/0' (language 'en') -- Playing 'digits/1' (language 'en') AGI Tx >> 200 result=10 -- Executing [6010@default-super:8] GotoIf("SIP/6002-08f02538", "1?9:10") in new stack -- Goto (default-super,6010,9) -- Executing [6010@default-super:9] Hangup("SIP/6002-08f02538", "") in new stack == Spawn extension (default-super, 6010, 9) exited non-zero on 'SIP/6002-08f02538' -- Stopped music on hold on SIP/6002-08f02538 == SIP/6002-08f02538 got tired of being parked [Nov 26 15:53:41] ERROR[12369]: channel.c:1214 ast_channel_free: Unable to find channel in list to free. Assuming it has already been done. [Nov 26 15:53:41] ERROR[12369]: /dar/build/asterisk-1.4.23/include/asterisk/lock.h:511 __ast_pthread_mutex_unlock: channel.c line 1290 (ast_channel_free): mutex '&(&channels)->lock' freed more times than we've locked! [Nov 26 15:53:41] ERROR[12369]: /dar/build/asterisk-1.4.23/include/asterisk/lock.h:528 __ast_pthread_mutex_unlock: channel.c line 1290 (ast_channel_free): Error releasing mutex: Operation not permitted lab*CLI> core show locks lab*CLI> ======================================================================= === Currently Held Locks ============================================== ======================================================================= === === (times locked) === =======================================================================