Filter Text to filter Highlight (None) Text to Highlight Auto-Scroll 5486 [2021-02-20 06:19:45] VERBOSE[24915] http.c: Bound HTTP server to address [::]:0 5487 [2021-02-20 06:19:45] VERBOSE[24915] tcptls.c: TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled 5488 [2021-02-20 06:19:45] VERBOSE[24915] tcptls.c: TLS/SSL certificate ok 5489 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'indications' (Indication Tone Handling) 5490 [2021-02-20 06:19:45] VERBOSE[24915] indications.c: Setting default indication country to 'us' 5491 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'acl' (Named ACL system) 5492 [2021-02-20 06:19:45] ERROR[24915] config_options.c: Unable to load config file 'acl.conf' 5493 [2021-02-20 06:19:45] WARNING[24915] named_acl.c: Could not reload ACL config 5494 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'manager' (Asterisk Manager Interface) 5495 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'plc' (PLC) 5496 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'udptl' (UDPTL) 5497 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjproject.so' (PJPROJECT Log and Utility Support) 5498 [2021-02-20 06:19:45] ERROR[24915] res_sorcery_config.c: Unable to load config file 'pjproject.conf' 5499 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip.so' (Basic SIP resource) 5500 [2021-02-20 06:19:45] ERROR[23712] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5501 [2021-02-20 06:19:45] ERROR[23712] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5502 [2021-02-20 06:19:45] ERROR[23712] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5503 [2021-02-20 06:19:45] ERROR[23712] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5504 [2021-02-20 06:19:45] NOTICE[23712] sorcery.c: Type 'system' is not reloadable, maintaining previous values 5505 [2021-02-20 06:19:45] ERROR[23712] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5506 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_authenticator_digest.so' (PJSIP authentication resource) 5507 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_resolver_unbound.so' (Unbound DNS Resolver Support) 5508 [2021-02-20 06:19:45] ERROR[24915] config_options.c: Unable to load config file 'resolver_unbound.conf' 5509 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_endpoint_identifier_ip.so' (PJSIP IP endpoint identifier) 5510 [2021-02-20 06:19:45] ERROR[24915] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5511 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_musiconhold.so' (Music On Hold Resource) 5512 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_smdi.so' (Simplified Message Desk Interface (SMDI) Resource) 5513 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_crypto.so' (Cryptographic Digital Signatures) 5514 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_rtp_asterisk.so' (Asterisk RTP Stack) 5515 [2021-02-20 06:19:45] VERBOSE[24915] res_rtp_asterisk.c: RTP Allocating from port range 10000 -> 20000 5516 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_outbound_publish.so' (PJSIP Outbound Publish Support) 5517 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_mwi.so' (PJSIP MWI resource) 5518 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_publish_asterisk.so' (PJSIP Asterisk Event PUBLISH Support) 5519 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'chan_iax2.so' (Inter Asterisk eXchange (Ver 2)) 5520 [2021-02-20 06:19:45] WARNING[24915] iax2/firmware.c: Error opening firmware directory '/var/lib/asterisk/firmware/iax': No such file or directory 5521 [2021-02-20 06:19:45] NOTICE[24915] iax2/provision.c: No IAX provisioning configuration found, IAX provisioning disabled. 5522 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'chan_sip.so' (Session Initiation Protocol (SIP)) 5523 [2021-02-20 06:19:45] VERBOSE[23753] chan_sip.c: Reloading SIP 5524 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'chan_dahdi.so' (DAHDI Telephony w/PRI & SS7 & MFC/R2) 5525 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_adsi.so' (ADSI Resource) 5526 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_fax.so' (Generic FAX Applications) 5527 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_ari.so' (Asterisk RESTful Interface) 5528 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_notify.so' (CLI/AMI PJSIP NOTIFY Support) 5529 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_pjsip_outbound_registration.so' (PJSIP Outbound Registration Support) 5530 [2021-02-20 06:19:45] VERBOSE[23753] netsock2.c: Using SIP TOS bits 96 5531 [2021-02-20 06:19:45] VERBOSE[23753] netsock2.c: Using SIP CoS mark 4 5532 [2021-02-20 06:19:45] ERROR[24915] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf' 5533 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_confbridge.so' (Conference Bridge Application) 5534 [2021-02-20 06:19:45] NOTICE[24915] confbridge/conf_config_parser.c: Adding default_menu menu to app_confbridge 5535 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'res_parking.so' (Call Parking Resource) 5536 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/71/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5537 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/72/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5538 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/73/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5539 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/74/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5540 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/75/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5541 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/76/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5542 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/77/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5543 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/78/1, registrar=res_parking/default; con=((nil)); con->root=(nil) 5544 [2021-02-20 06:19:45] VERBOSE[24915] pbx.c: Remove parkedcalls/70/1, registrar=res_parking; con=((nil)); con->root=(nil) 5545 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_meetme.so' (MeetMe conference bridge) 5546 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'cel_manager.so' (Asterisk Manager Interface CEL Backend) 5547 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'cel_odbc.so' (ODBC CEL backend) 5548 [2021-02-20 06:19:45] VERBOSE[24915] cel_odbc.c: Found CEL table cel@asteriskcdrdb. 5549 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_amd.so' (Answering Machine Detection Application) 5550 [2021-02-20 06:19:45] VERBOSE[24915] app_amd.c: AMD defaults: initialSilence [2500] greeting [1500] afterGreetingSilence [800] totalAnalysisTime [5000] minimumWordLength [100] betweenWordsSilence [50] maximumNumberOfWords [3] silenceThreshold [256] maximumWordLength [5000] 5551 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_playback.so' (Sound File Playback Application) 5552 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_flite.so' (Flite TTS Interface) 5553 [2021-02-20 06:19:45] WARNING[24915] app_flite.c: Flite: Unable to read config file flite.conf. Using default settings 5554 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'codec_dahdi.so' (Generic DAHDI Transcoder Codec Translator) 5555 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_voicemail.so' (Comedian Mail (Voicemail System)) 5556 [2021-02-20 06:19:45] WARNING[24915] app_voicemail.c: maxsilence should be less than minsecs or you may get empty messages 5557 [2021-02-20 06:19:45] VERBOSE[24915] loader.c: Reloading module 'app_queue.so' (True Call Queueing) 5558 [2021-02-20 06:19:45] NOTICE[24915] app_queue.c: queuerules.conf has not changed since it was last loaded. Not taking any action. 5559 [2021-02-20 06:19:45] VERBOSE[24915] asterisk.c: Remote UNIX connection disconnected 5560 [2021-02-20 06:20:02] VERBOSE[23753][C-00000001] netsock2.c: Using SIP RTP TOS bits 184 5561 [2021-02-20 06:20:02] VERBOSE[23753][C-00000001] netsock2.c: Using SIP RTP CoS mark 5 5562 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:1] Macro("SIP/2002-00000000", "user-callerid,LIMIT,EXTERNAL,") in new stack 5563 [2021-02-20 06:20:02] WARNING[25142][C-00000001] app_macro.c: Macro() is deprecated and will be removed from a future version of Asterisk. 5564 [2021-02-20 06:20:02] WARNING[25142][C-00000001] app_macro.c: Dialplan should be updated to use Gosub instead. 5565 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/2002-00000000", "TOUCH_MONITOR=1613802002.0") in new stack 5566 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/2002-00000000", "AMPUSER=2002") in new stack 5567 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/2002-00000000", "HOTDESCKCHAN=2002-00000000") in new stack 5568 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/2002-00000000", "HOTDESKEXTEN=2002") in new stack 5569 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/2002-00000000", "HOTDESKCALL=0") in new stack 5570 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/2002-00000000", "0?Set(HOTDESKCALL=1)") in new stack 5571 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(name)=)") in new stack 5572 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/2002-00000000", "0?report") in new stack 5573 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/2002-00000000", "1?Set(REALCALLERIDNUM=2002)") in new stack 5574 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/2002-00000000", "AMPUSER=2002") in new stack 5575 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/2002-00000000", "0?limit") in new stack 5576 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/2002-00000000", "AMPUSERCIDNAME=6614101") in new stack 5577 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/2002-00000000", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 5578 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/2002-00000000", "0?report") in new stack 5579 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/2002-00000000", "AMPUSERCID=2002") in new stack 5580 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/2002-00000000", "__DIAL_OPTIONS=HhTtr") in new stack 5581 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/2002-00000000", "CALLERID(all)="6614101" <2002>") in new stack 5582 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/2002-00000000", "0?Set(CUSDIAL=)") in new stack 5583 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(all)="6614101" <2002>)") in new stack 5584 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/2002-00000000", "0?limit") in new stack 5585 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/2002-00000000", "1?Set(GROUP(concurrency_limit)=2002)") in new stack 5586 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/2002-00000000", "0?Set(CHANNEL(language)=)") in new stack 5587 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/2002-00000000", "Macro Depth is 1") in new stack 5588 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/2002-00000000", "1?report2:macroerror") in new stack 5589 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,25) 5590 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/2002-00000000", "1?continue") in new stack 5591 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,44) 5592 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/2002-00000000", "CALLERID(number)=2002") in new stack 5593 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/2002-00000000", "CALLERID(name)=6614101") in new stack 5594 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/2002-00000000", "0?cnum") in new stack 5595 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/2002-00000000", "CDR(cnam)=6614101") in new stack 5596 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/2002-00000000", "CDR(cnum)=2002") in new stack 5597 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/2002-00000000", "CHANNEL(language)=en") in new stack 5598 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:2] Gosub("SIP/2002-00000000", "sub-record-check,s,1(out,7252818157,dontcare)") in new stack 5599 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/2002-00000000", "0?initialized") in new stack 5600 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:2] Set("SIP/2002-00000000", "__REC_STATUS=INITIALIZED") in new stack 5601 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:3] Set("SIP/2002-00000000", "NOW=1613802002") in new stack 5602 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:4] Set("SIP/2002-00000000", "__DAY=20") in new stack 5603 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:5] Set("SIP/2002-00000000", "__MONTH=02") in new stack 5604 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:6] Set("SIP/2002-00000000", "__YEAR=2021") in new stack 5605 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:7] Set("SIP/2002-00000000", "__TIMESTR=20210220-062002") in new stack 5606 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:8] Set("SIP/2002-00000000", "__FROMEXTEN=2002") in new stack 5607 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:9] Set("SIP/2002-00000000", "__MON_FMT=wav") in new stack 5608 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/2002-00000000", "Recordings initialized") in new stack 5609 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/2002-00000000", "0?Set(ARG3=dontcare)") in new stack 5610 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set("SIP/2002-00000000", "REC_POLICY_MODE_SAVE=") in new stack 5611 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/2002-00000000", "0?Set(REC_STATUS=NO)") in new stack 5612 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/2002-00000000", "3?checkaction") in new stack 5613 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17) 5614 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/2002-00000000", "1?sub-record-check,out,1") in new stack 5615 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (sub-record-check,out,1) 5616 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/2002-00000000", "Outbound Recording Check from 2002 to 7252818157") in new stack 5617 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [out@sub-record-check:2] Set("SIP/2002-00000000", "RECMODE=dontcare") in new stack 5618 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/2002-00000000", "1?Goto(routewins)") in new stack 5619 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (sub-record-check,out,7) 5620 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/2002-00000000", "recordcheck,1(dontcare,out,7252818157)") in new stack 5621 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/2002-00000000", "Starting recording check against dontcare") in new stack 5622 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/2002-00000000", "dontcare") in new stack 5623 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 5624 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/2002-00000000", "") in new stack 5625 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [out@sub-record-check:8] Return("SIP/2002-00000000", "") in new stack 5626 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:3] ExecIf("SIP/2002-00000000", "0 ?Set(CDR(accountcode)=)") in new stack 5627 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:4] Set("SIP/2002-00000000", "_ROUTEID=7") in new stack 5628 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:5] Set("SIP/2002-00000000", "_ROUTENAME=outgoing") in new stack 5629 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:6] Set("SIP/2002-00000000", "MOHCLASS=default") in new stack 5630 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:7] Set("SIP/2002-00000000", "_CALLERIDNAMEINTERNAL=6614101") in new stack 5631 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:8] Set("SIP/2002-00000000", "_CALLERIDNUMINTERNAL=2002") in new stack 5632 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:9] Set("SIP/2002-00000000", "_EMAILNOTIFICATION=FALSE") in new stack 5633 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:10] Set("SIP/2002-00000000", "_NODEST=") in new stack 5634 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:11] Macro("SIP/2002-00000000", "dialout-trunk,1,7252818157,,off") in new stack 5635 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/2002-00000000", "DIAL_TRUNK=1") in new stack 5636 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/2002-00000000", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack 5637 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/2002-00000000", "0?sub-pincheck,s,1()") in new stack 5638 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(num)=2002)") in new stack 5639 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/2002-00000000", "0?disabletrunk,1") in new stack 5640 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/2002-00000000", "DIAL_NUMBER=7252818157") in new stack 5641 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/2002-00000000", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack 5642 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/2002-00000000", "OUTBOUND_GROUP=OUT_1") in new stack 5643 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/2002-00000000", "DIAL_TRUNK_OPTIONS=T") in new stack 5644 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/2002-00000000", "0?nomax") in new stack 5645 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/2002-00000000", "0?chanfull") in new stack 5646 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/2002-00000000", "0?skipoutcid") in new stack 5647 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/2002-00000000", "outbound-callerid,1") in new stack 5648 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/2002-00000000", "2002") in new stack 5649 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/2002-00000000", "") in new stack 5650 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/2002-00000000", "off") in new stack 5651 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(name-pres)=)") in new stack 5652 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(num-pres)=)") in new stack 5653 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/2002-00000000", "HOTDESCKCHAN=2002-00000000") in new stack 5654 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/2002-00000000", "HOTDESKEXTEN=2002") in new stack 5655 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/2002-00000000", "HOTDESKCALL=0") in new stack 5656 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/2002-00000000", "0?Set(HOTDESKCALL=1)") in new stack 5657 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(name)=)") in new stack 5658 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/2002-00000000", "ALLOWTHISROUTE=NO") in new stack 5659 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/2002-00000000", "0?Set(ALLOWTHISROUTE=YES)") in new stack 5660 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/2002-00000000", "0?Hangup()") in new stack 5661 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/2002-00000000", "0?Set(REALCALLERIDNUM=2002)") in new stack 5662 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/2002-00000000", "0?Set(AMPUSER=2002)") in new stack 5663 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/2002-00000000", "1?normcid") in new stack 5664 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-outbound-callerid,s,20) 5665 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/2002-00000000", "USEROUTCID=6614101") in new stack 5666 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/2002-00000000", "EMERGENCYCID=") in new stack 5667 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/2002-00000000", "0?Set(EMERGENCYCID=)") in new stack 5668 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/2002-00000000", "TRUNKOUTCID=") in new stack 5669 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/2002-00000000", "1?trunkcid") in new stack 5670 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-outbound-callerid,s,30) 5671 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(all)=)") in new stack 5672 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/2002-00000000", "1?Set(CALLERID(all)=6614101)") in new stack 5673 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(all)=)") in new stack 5674 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/2002-00000000", "0?Set(CALLERID(all)=2002)") in new stack 5675 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/2002-00000000", "TIOHIDE=no") in new stack 5676 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 5677 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 5678 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 5679 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/2002-00000000", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 5680 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/2002-00000000", "CDR(outbound_cnum)=6614101") in new stack 5681 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/2002-00000000", "CDR(outbound_cnam)=") in new stack 5682 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/2002-00000000", "0?sub-flp-1,s,1()") in new stack 5683 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/2002-00000000", "OUTNUM=7252818157") in new stack 5684 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/2002-00000000", "custom=SIP/tatasip") in new stack 5685 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/2002-00000000", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack 5686 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/2002-00000000", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 5687 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/2002-00000000", "dialout-trunk-predial-hook,") in new stack 5688 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/2002-00000000", "") in new stack 5689 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/2002-00000000", "0?skipcrm") in new stack 5690 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/2002-00000000", "__CRM_DIRECTION=OUTBOUND") in new stack 5691 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/2002-00000000", "__CRM_DESTINATION=7252818157") in new stack 5692 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/2002-00000000", "__CRM_SOURCE=2002") in new stack 5693 [2021-02-20 06:20:02] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/2002-00000000", "agi://127.0.0.1/sangomacrm.agi") in new stack 5694 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 5695 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/2002-00000000", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack 5696 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/2002-00000000", "CRM Finished") in new stack 5697 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/2002-00000000", "0?bypass,1") in new stack 5698 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/2002-00000000", "1?Set(CONNECTEDLINE(num,i)=7252818157)") in new stack 5699 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/2002-00000000", "1?Set(CONNECTEDLINE(name,i)=CID:6614101)") in new stack 5700 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/2002-00000000", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614101)") in new stack 5701 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/2002-00000000", "0?customtrunk") in new stack 5702 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/2002-00000000", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack 5703 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/2002-00000000", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack 5704 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/2002-00000000", "SIP/tatasip/7252818157,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^7252818157^7252818157^1^1613802002^^6614101)") in new stack 5705 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] netsock2.c: Using SIP RTP TOS bits 184 5706 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] netsock2.c: Using SIP RTP CoS mark 5 5707 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] app_stack.c: SIP/tatasip-00000001 Internal Gosub(func-apply-sipheaders,s,1(1)) start 5708 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-00000001", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack 5709 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-00000001", "Applying SIP Headers to channel SIP/tatasip-00000001") in new stack 5710 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-00000001", "TECH=SIP") in new stack 5711 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-00000001", "SIPHEADERKEYS=Alert-Info") in new stack 5712 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000001", "1") in new stack 5713 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-00000001", "sipheader=unset") in new stack 5714 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-00000001", "1?SIPRemoveHeader(Alert-Info:)") in new stack 5715 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-00000001", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack 5716 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-00000001", "0?Set(sipheader=;info=unset)") in new stack 5717 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-00000001", "0?Set(sipheader=unset)") in new stack 5718 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-00000001", "0?SIPAddHeader(Alert-Info:unset)") in new stack 5719 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-00000001", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack 5720 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-00000001", "") in new stack 5721 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000001", "0") in new stack 5722 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-00000001", "") in new stack 5723 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] app_stack.c: Spawn extension (tata, 7252818157, 1) exited non-zero on 'SIP/tatasip-00000001' 5724 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] app_stack.c: SIP/tatasip-00000001 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 5725 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] app_dial.c: Called SIP/tatasip/7252818157 5726 [2021-02-20 06:20:03] WARNING[23753][C-00000001] chan_sip.c: Received response: "Forbidden" from ';tag=as416cd719' 5727 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] app_dial.c: Everyone is busy/congested at this time (1:0/0/1) 5728 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/2002-00000000", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack 5729 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/2002-00000000", "0?continue,1:s-CHANUNAVAIL,1") in new stack 5730 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1) 5731 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/2002-00000000", "RC=21") in new stack 5732 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/2002-00000000", "21,1") in new stack 5733 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-dialout-trunk,21,1) 5734 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/2002-00000000", "continue,1") in new stack 5735 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1) 5736 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/2002-00000000", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack 5737 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/2002-00000000", "1?Set(CALLERID(number)=2002)") in new stack 5738 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [7252818157@from-internal:12] Macro("SIP/2002-00000000", "outisbusy,") in new stack 5739 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/2002-00000000", "") in new stack 5740 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/2002-00000000", "0?emergency,1") in new stack 5741 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/2002-00000000", "0?intracompany,1") in new stack 5742 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/2002-00000000", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack 5743 [2021-02-20 06:20:03] VERBOSE[25142][C-00000001] file.c: Playing 'all-circuits-busy-now.ulaw' (language 'en') 5744 [2021-02-20 06:20:04] VERBOSE[23712] res_pjsip/pjsip_configuration.c: Endpoint U1981 is now Reachable 5745 [2021-02-20 06:20:04] VERBOSE[23712] res_pjsip/pjsip_options.c: Contact U1981/sip:10.100.104.5:5060 is now Reachable. RTT: 20.771 msec 5746 [2021-02-20 06:20:04] VERBOSE[25142][C-00000001] file.c: Playing 'please-try-call-later.ulaw' (language 'en') 5747 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [h@from-internal:1] Macro("SIP/2002-00000000", "hangupcall") in new stack 5748 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/2002-00000000", "1?theend") in new stack 5749 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,3) 5750 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/2002-00000000", "0?Set(CDR(recordingfile)=)") in new stack 5751 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/2002-00000000", " montior file= ") in new stack 5752 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/2002-00000000", "1?skipagi") in new stack 5753 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,7) 5754 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/2002-00000000", "") in new stack 5755 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/2002-00000000' in macro 'hangupcall' 5756 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-00000000' 5757 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] app_stack.c: SIP/2002-00000000 Internal Gosub(crm-hangup,s,1) start 5758 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/2002-00000000", "Sending Hangup to CRM") in new stack 5759 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/2002-00000000", "HANGUP CAUSE: 21") in new stack 5760 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/2002-00000000", "0?Set(__CRM_VOICEMAIL=)") in new stack 5761 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/2002-00000000", "MASTER CHANNEL: 1613802002.0 = 1613802002.0") in new stack 5762 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/2002-00000000", "0?return") in new stack 5763 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:6] Set("SIP/2002-00000000", "__CRM_HANGUP=1") in new stack 5764 [2021-02-20 06:20:05] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/2002-00000000", "agi://127.0.0.1/sangomacrm.agi") in new stack 5765 [2021-02-20 06:20:06] VERBOSE[25142][C-00000001] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 5766 [2021-02-20 06:20:06] VERBOSE[25142][C-00000001] pbx.c: Executing [s@crm-hangup:8] Return("SIP/2002-00000000", "") in new stack 5767 [2021-02-20 06:20:06] VERBOSE[25142][C-00000001] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-00000000' 5768 [2021-02-20 06:20:06] VERBOSE[25142][C-00000001] app_stack.c: SIP/2002-00000000 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= 5769 [2021-02-20 06:20:25] VERBOSE[23753] chan_sip.c: Unregistered SIP '2002' 5770 [2021-02-20 06:20:53] NOTICE[23753] chan_sip.c: Registration from '"TATA" ' failed for '10.100.50.228:51122' - Wrong password 5771 [2021-02-20 06:20:59] VERBOSE[23753] chan_sip.c: Registered SIP '6614101' at 10.100.50.228:51122 5772 [2021-02-20 06:20:59] NOTICE[23753] chan_sip.c: Peer '6614101' is now Reachable. (1ms / 2000ms) 5773 [2021-02-20 06:21:09] VERBOSE[23753][C-00000002] netsock2.c: Using SIP RTP TOS bits 184 5774 [2021-02-20 06:21:09] VERBOSE[23753][C-00000002] netsock2.c: Using SIP RTP CoS mark 5 5775 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:1] Macro("SIP/6614101-00000002", "user-callerid,LIMIT,EXTERNAL,") in new stack 5776 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/6614101-00000002", "TOUCH_MONITOR=1613802069.2") in new stack 5777 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/6614101-00000002", "AMPUSER=6614101") in new stack 5778 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/6614101-00000002", "HOTDESCKCHAN=6614101-00000002") in new stack 5779 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/6614101-00000002", "HOTDESKEXTEN=6614101") in new stack 5780 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/6614101-00000002", "HOTDESKCALL=0") in new stack 5781 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/6614101-00000002", "0?Set(HOTDESKCALL=1)") in new stack 5782 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(name)=)") in new stack 5783 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/6614101-00000002", "0?report") in new stack 5784 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/6614101-00000002", "1?Set(REALCALLERIDNUM=6614101)") in new stack 5785 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/6614101-00000002", "AMPUSER=6614101") in new stack 5786 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/6614101-00000002", "0?limit") in new stack 5787 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/6614101-00000002", "AMPUSERCIDNAME=Abhishek Thomas") in new stack 5788 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/6614101-00000002", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 5789 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/6614101-00000002", "0?report") in new stack 5790 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/6614101-00000002", "AMPUSERCID=6614101") in new stack 5791 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/6614101-00000002", "__DIAL_OPTIONS=HhTtr") in new stack 5792 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/6614101-00000002", "CALLERID(all)="Abhishek Thomas" <6614101>") in new stack 5793 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/6614101-00000002", "0?Set(CUSDIAL=)") in new stack 5794 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(all)="Abhishek Thomas" <6614101>)") in new stack 5795 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/6614101-00000002", "0?limit") in new stack 5796 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/6614101-00000002", "1?Set(GROUP(concurrency_limit)=6614101)") in new stack 5797 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/6614101-00000002", "0?Set(CHANNEL(language)=)") in new stack 5798 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/6614101-00000002", "Macro Depth is 1") in new stack 5799 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/6614101-00000002", "1?report2:macroerror") in new stack 5800 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-user-callerid,s,25) 5801 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/6614101-00000002", "1?continue") in new stack 5802 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-user-callerid,s,44) 5803 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/6614101-00000002", "CALLERID(number)=6614101") in new stack 5804 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/6614101-00000002", "CALLERID(name)=Abhishek Thomas") in new stack 5805 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/6614101-00000002", "0?cnum") in new stack 5806 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/6614101-00000002", "CDR(cnam)=Abhishek Thomas") in new stack 5807 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/6614101-00000002", "CDR(cnum)=6614101") in new stack 5808 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/6614101-00000002", "CHANNEL(language)=en") in new stack 5809 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:2] Gosub("SIP/6614101-00000002", "sub-record-check,s,1(out,7252818157,dontcare)") in new stack 5810 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/6614101-00000002", "0?initialized") in new stack 5811 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:2] Set("SIP/6614101-00000002", "__REC_STATUS=INITIALIZED") in new stack 5812 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:3] Set("SIP/6614101-00000002", "NOW=1613802069") in new stack 5813 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:4] Set("SIP/6614101-00000002", "__DAY=20") in new stack 5814 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:5] Set("SIP/6614101-00000002", "__MONTH=02") in new stack 5815 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:6] Set("SIP/6614101-00000002", "__YEAR=2021") in new stack 5816 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:7] Set("SIP/6614101-00000002", "__TIMESTR=20210220-062109") in new stack 5817 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:8] Set("SIP/6614101-00000002", "__FROMEXTEN=6614101") in new stack 5818 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:9] Set("SIP/6614101-00000002", "__MON_FMT=wav") in new stack 5819 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/6614101-00000002", "Recordings initialized") in new stack 5820 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/6614101-00000002", "0?Set(ARG3=dontcare)") in new stack 5821 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:12] Set("SIP/6614101-00000002", "REC_POLICY_MODE_SAVE=") in new stack 5822 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/6614101-00000002", "0?Set(REC_STATUS=NO)") in new stack 5823 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/6614101-00000002", "3?checkaction") in new stack 5824 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (sub-record-check,s,17) 5825 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/6614101-00000002", "1?sub-record-check,out,1") in new stack 5826 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (sub-record-check,out,1) 5827 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/6614101-00000002", "Outbound Recording Check from 6614101 to 7252818157") in new stack 5828 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [out@sub-record-check:2] Set("SIP/6614101-00000002", "RECMODE=dontcare") in new stack 5829 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/6614101-00000002", "1?Goto(routewins)") in new stack 5830 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (sub-record-check,out,7) 5831 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/6614101-00000002", "recordcheck,1(dontcare,out,7252818157)") in new stack 5832 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/6614101-00000002", "Starting recording check against dontcare") in new stack 5833 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/6614101-00000002", "dontcare") in new stack 5834 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 5835 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/6614101-00000002", "") in new stack 5836 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [out@sub-record-check:8] Return("SIP/6614101-00000002", "") in new stack 5837 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:3] ExecIf("SIP/6614101-00000002", "0 ?Set(CDR(accountcode)=)") in new stack 5838 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:4] Set("SIP/6614101-00000002", "_ROUTEID=7") in new stack 5839 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:5] Set("SIP/6614101-00000002", "_ROUTENAME=outgoing") in new stack 5840 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:6] Set("SIP/6614101-00000002", "MOHCLASS=default") in new stack 5841 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:7] Set("SIP/6614101-00000002", "_CALLERIDNAMEINTERNAL=Abhishek Thomas") in new stack 5842 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:8] Set("SIP/6614101-00000002", "_CALLERIDNUMINTERNAL=6614101") in new stack 5843 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:9] Set("SIP/6614101-00000002", "_EMAILNOTIFICATION=FALSE") in new stack 5844 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:10] Set("SIP/6614101-00000002", "_NODEST=") in new stack 5845 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:11] Macro("SIP/6614101-00000002", "dialout-trunk,1,7252818157,,off") in new stack 5846 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/6614101-00000002", "DIAL_TRUNK=1") in new stack 5847 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/6614101-00000002", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack 5848 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/6614101-00000002", "0?sub-pincheck,s,1()") in new stack 5849 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(num)=6614101)") in new stack 5850 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/6614101-00000002", "0?disabletrunk,1") in new stack 5851 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/6614101-00000002", "DIAL_NUMBER=7252818157") in new stack 5852 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/6614101-00000002", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack 5853 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/6614101-00000002", "OUTBOUND_GROUP=OUT_1") in new stack 5854 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/6614101-00000002", "DIAL_TRUNK_OPTIONS=T") in new stack 5855 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/6614101-00000002", "0?nomax") in new stack 5856 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/6614101-00000002", "0?chanfull") in new stack 5857 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/6614101-00000002", "0?skipoutcid") in new stack 5858 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/6614101-00000002", "outbound-callerid,1") in new stack 5859 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/6614101-00000002", "6614101") in new stack 5860 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/6614101-00000002", "") in new stack 5861 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/6614101-00000002", "off") in new stack 5862 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(name-pres)=)") in new stack 5863 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(num-pres)=)") in new stack 5864 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/6614101-00000002", "HOTDESCKCHAN=6614101-00000002") in new stack 5865 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/6614101-00000002", "HOTDESKEXTEN=6614101") in new stack 5866 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/6614101-00000002", "HOTDESKCALL=0") in new stack 5867 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/6614101-00000002", "0?Set(HOTDESKCALL=1)") in new stack 5868 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(name)=)") in new stack 5869 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/6614101-00000002", "ALLOWTHISROUTE=NO") in new stack 5870 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/6614101-00000002", "0?Set(ALLOWTHISROUTE=YES)") in new stack 5871 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/6614101-00000002", "0?Hangup()") in new stack 5872 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/6614101-00000002", "0?Set(REALCALLERIDNUM=6614101)") in new stack 5873 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/6614101-00000002", "0?Set(AMPUSER=6614101)") in new stack 5874 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/6614101-00000002", "1?normcid") in new stack 5875 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-outbound-callerid,s,20) 5876 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/6614101-00000002", "USEROUTCID=6614101") in new stack 5877 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/6614101-00000002", "EMERGENCYCID=") in new stack 5878 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/6614101-00000002", "0?Set(EMERGENCYCID=)") in new stack 5879 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/6614101-00000002", "TRUNKOUTCID=") in new stack 5880 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/6614101-00000002", "1?trunkcid") in new stack 5881 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-outbound-callerid,s,30) 5882 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(all)=)") in new stack 5883 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/6614101-00000002", "1?Set(CALLERID(all)=6614101)") in new stack 5884 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(all)=)") in new stack 5885 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/6614101-00000002", "0?Set(CALLERID(all)=6614101)") in new stack 5886 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/6614101-00000002", "TIOHIDE=no") in new stack 5887 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 5888 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 5889 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 5890 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/6614101-00000002", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 5891 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/6614101-00000002", "CDR(outbound_cnum)=6614101") in new stack 5892 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/6614101-00000002", "CDR(outbound_cnam)=") in new stack 5893 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/6614101-00000002", "0?sub-flp-1,s,1()") in new stack 5894 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/6614101-00000002", "OUTNUM=7252818157") in new stack 5895 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/6614101-00000002", "custom=SIP/tatasip") in new stack 5896 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/6614101-00000002", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack 5897 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/6614101-00000002", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 5898 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/6614101-00000002", "dialout-trunk-predial-hook,") in new stack 5899 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/6614101-00000002", "") in new stack 5900 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/6614101-00000002", "0?skipcrm") in new stack 5901 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/6614101-00000002", "__CRM_DIRECTION=OUTBOUND") in new stack 5902 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/6614101-00000002", "__CRM_DESTINATION=7252818157") in new stack 5903 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/6614101-00000002", "__CRM_SOURCE=6614101") in new stack 5904 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/6614101-00000002", "agi://127.0.0.1/sangomacrm.agi") in new stack 5905 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 5906 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/6614101-00000002", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack 5907 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/6614101-00000002", "CRM Finished") in new stack 5908 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/6614101-00000002", "0?bypass,1") in new stack 5909 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/6614101-00000002", "1?Set(CONNECTEDLINE(num,i)=7252818157)") in new stack 5910 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/6614101-00000002", "1?Set(CONNECTEDLINE(name,i)=CID:6614101)") in new stack 5911 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/6614101-00000002", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614101)") in new stack 5912 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/6614101-00000002", "0?customtrunk") in new stack 5913 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/6614101-00000002", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack 5914 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/6614101-00000002", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack 5915 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/6614101-00000002", "SIP/tatasip/7252818157,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^7252818157^7252818157^1^1613802069^^6614101)") in new stack 5916 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] netsock2.c: Using SIP RTP TOS bits 184 5917 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] netsock2.c: Using SIP RTP CoS mark 5 5918 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] app_stack.c: SIP/tatasip-00000003 Internal Gosub(func-apply-sipheaders,s,1(1)) start 5919 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-00000003", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack 5920 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-00000003", "Applying SIP Headers to channel SIP/tatasip-00000003") in new stack 5921 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-00000003", "TECH=SIP") in new stack 5922 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-00000003", "SIPHEADERKEYS=Alert-Info") in new stack 5923 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000003", "1") in new stack 5924 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-00000003", "sipheader=unset") in new stack 5925 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-00000003", "1?SIPRemoveHeader(Alert-Info:)") in new stack 5926 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-00000003", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack 5927 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-00000003", "0?Set(sipheader=;info=unset)") in new stack 5928 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-00000003", "0?Set(sipheader=unset)") in new stack 5929 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-00000003", "0?SIPAddHeader(Alert-Info:unset)") in new stack 5930 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-00000003", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack 5931 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-00000003", "") in new stack 5932 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000003", "0") in new stack 5933 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-00000003", "") in new stack 5934 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] app_stack.c: Spawn extension (tata, 7252818157, 1) exited non-zero on 'SIP/tatasip-00000003' 5935 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] app_stack.c: SIP/tatasip-00000003 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 5936 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] app_dial.c: Called SIP/tatasip/7252818157 5937 [2021-02-20 06:21:09] WARNING[23753][C-00000002] chan_sip.c: Received response: "Forbidden" from ';tag=as68b9e6fc' 5938 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] app_dial.c: Everyone is busy/congested at this time (1:0/0/1) 5939 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/6614101-00000002", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack 5940 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/6614101-00000002", "0?continue,1:s-CHANUNAVAIL,1") in new stack 5941 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1) 5942 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/6614101-00000002", "RC=21") in new stack 5943 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/6614101-00000002", "21,1") in new stack 5944 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-dialout-trunk,21,1) 5945 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/6614101-00000002", "continue,1") in new stack 5946 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1) 5947 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/6614101-00000002", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack 5948 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/6614101-00000002", "1?Set(CALLERID(number)=6614101)") in new stack 5949 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [7252818157@from-internal:12] Macro("SIP/6614101-00000002", "outisbusy,") in new stack 5950 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/6614101-00000002", "") in new stack 5951 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/6614101-00000002", "0?emergency,1") in new stack 5952 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/6614101-00000002", "0?intracompany,1") in new stack 5953 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/6614101-00000002", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack 5954 [2021-02-20 06:21:09] VERBOSE[25473][C-00000002] file.c: Playing 'all-circuits-busy-now.ulaw' (language 'en') 5955 [2021-02-20 06:21:11] VERBOSE[25473][C-00000002] file.c: Playing 'please-try-call-later.ulaw' (language 'en') 5956 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/6614101-00000002", "20") in new stack 5957 [2021-02-20 06:21:13] WARNING[25473][C-00000002] channel.c: Prodding channel 'SIP/6614101-00000002' failed 5958 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/6614101-00000002' in macro 'outisbusy' 5959 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Spawn extension (from-internal, 7252818157, 12) exited non-zero on 'SIP/6614101-00000002' 5960 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [h@from-internal:1] Macro("SIP/6614101-00000002", "hangupcall") in new stack 5961 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/6614101-00000002", "1?theend") in new stack 5962 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-hangupcall,s,3) 5963 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/6614101-00000002", "0?Set(CDR(recordingfile)=)") in new stack 5964 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/6614101-00000002", " montior file= ") in new stack 5965 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/6614101-00000002", "1?skipagi") in new stack 5966 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx_builtins.c: Goto (macro-hangupcall,s,7) 5967 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/6614101-00000002", "") in new stack 5968 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/6614101-00000002' in macro 'hangupcall' 5969 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/6614101-00000002' 5970 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] app_stack.c: SIP/6614101-00000002 Internal Gosub(crm-hangup,s,1) start 5971 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/6614101-00000002", "Sending Hangup to CRM") in new stack 5972 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/6614101-00000002", "HANGUP CAUSE: 34") in new stack 5973 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/6614101-00000002", "0?Set(__CRM_VOICEMAIL=)") in new stack 5974 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/6614101-00000002", "MASTER CHANNEL: 1613802069.2 = 1613802069.2") in new stack 5975 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/6614101-00000002", "0?return") in new stack 5976 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:6] Set("SIP/6614101-00000002", "__CRM_HANGUP=1") in new stack 5977 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/6614101-00000002", "agi://127.0.0.1/sangomacrm.agi") in new stack 5978 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 5979 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] pbx.c: Executing [s@crm-hangup:8] Return("SIP/6614101-00000002", "") in new stack 5980 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/6614101-00000002' 5981 [2021-02-20 06:21:13] VERBOSE[25473][C-00000002] app_stack.c: SIP/6614101-00000002 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= 5982 [2021-02-20 06:21:29] VERBOSE[23753] chan_sip.c: 5983 <--- SIP read from UDP:10.100.50.228:51122 ---> 5984 5985 <-------------> 5986 [2021-02-20 06:21:31] VERBOSE[23753] chan_sip.c: Really destroying SIP dialog '30c4ac91091e4fdba16ddc562d6f2737' Method: REGISTER 5987 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: 5988 <--- SIP read from UDP:10.100.50.228:51122 ---> 5989 INVITE sip:7252818157@10.100.51.227 SIP/2.0 5990 Via: SIP/2.0/UDP 10.100.50.228:51122;rport;branch=z9hG4bKPj310f7d3a8c2346399868f538b85302cf 5991 Max-Forwards: 70 5992 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 5993 To: 5994 Contact: "TATA" 5995 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 5996 CSeq: 14739 INVITE 5997 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 5998 Supported: replaces, 100rel, timer, norefersub 5999 Session-Expires: 1800 6000 Min-SE: 90 6001 User-Agent: MicroSIP/3.20.3 6002 Content-Type: application/sdp 6003 Content-Length: 369 6004 6005 v=0 6006 o=- 3822810694 3822810694 IN IP4 10.100.50.228 6007 s=pjmedia 6008 b=AS:84 6009 t=0 0 6010 a=X-nat:0 6011 m=audio 4002 RTP/AVP 18 8 0 101 6012 c=IN IP4 10.100.50.228 6013 b=TIAS:64000 6014 a=rtcp:4003 IN IP4 10.100.50.228 6015 a=sendrecv 6016 a=rtpmap:18 G729/8000 6017 a=rtpmap:8 PCMA/8000 6018 a=rtpmap:0 PCMU/8000 6019 a=rtpmap:101 telephone-event/8000 6020 a=fmtp:101 0-16 6021 a=ssrc:1573221107 cname:6b177ce317f75744 6022 <-------------> 6023 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: --- (15 headers 17 lines) --- 6024 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: Sending to 10.100.50.228:51122 (NAT) 6025 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Sending to 10.100.50.228:51122 (NAT) 6026 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Using INVITE request as basis request - 1db4a1bae6a24531b259121dcffd9f59 6027 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found peer '6614101' for '6614101' from 10.100.50.228:51122 6028 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: 6029 <--- Reliably Transmitting (NAT) to 10.100.50.228:51122 ---> 6030 SIP/2.0 401 Unauthorized 6031 Via: SIP/2.0/UDP 10.100.50.228:51122;branch=z9hG4bKPj310f7d3a8c2346399868f538b85302cf;received=10.100.50.228;rport=51122 6032 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6033 To: ;tag=as7e26d572 6034 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6035 CSeq: 14739 INVITE 6036 Server: FPBX-15.0.16.81(16.13.0) 6037 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 6038 Supported: replaces, timer 6039 WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="5081a62d" 6040 Content-Length: 0 6041 6042 6043 <------------> 6044 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Scheduling destruction of SIP dialog '1db4a1bae6a24531b259121dcffd9f59' in 6400 ms (Method: INVITE) 6045 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: 6046 <--- SIP read from UDP:10.100.50.228:51122 ---> 6047 ACK sip:7252818157@10.100.51.227 SIP/2.0 6048 Via: SIP/2.0/UDP 10.100.50.228:51122;rport;branch=z9hG4bKPj310f7d3a8c2346399868f538b85302cf 6049 Max-Forwards: 70 6050 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6051 To: ;tag=as7e26d572 6052 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6053 CSeq: 14739 ACK 6054 Content-Length: 0 6055 6056 <-------------> 6057 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: --- (8 headers 0 lines) --- 6058 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: 6059 <--- SIP read from UDP:10.100.50.228:51122 ---> 6060 INVITE sip:7252818157@10.100.51.227 SIP/2.0 6061 Via: SIP/2.0/UDP 10.100.50.228:51122;rport;branch=z9hG4bKPj0c0bf368fac249a5aaf85e8055631871 6062 Max-Forwards: 70 6063 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6064 To: 6065 Contact: "TATA" 6066 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6067 CSeq: 14740 INVITE 6068 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 6069 Supported: replaces, 100rel, timer, norefersub 6070 Session-Expires: 1800 6071 Min-SE: 90 6072 User-Agent: MicroSIP/3.20.3 6073 Authorization: Digest username="6614101", realm="asterisk", nonce="5081a62d", uri="sip:7252818157@10.100.51.227", response="a4ad191cc976bb989ee1aa1bbeeba3c5", algorithm=MD5 6074 Content-Type: application/sdp 6075 Content-Length: 369 6076 6077 v=0 6078 o=- 3822810694 3822810694 IN IP4 10.100.50.228 6079 s=pjmedia 6080 b=AS:84 6081 t=0 0 6082 a=X-nat:0 6083 m=audio 4002 RTP/AVP 18 8 0 101 6084 c=IN IP4 10.100.50.228 6085 b=TIAS:64000 6086 a=rtcp:4003 IN IP4 10.100.50.228 6087 a=sendrecv 6088 a=rtpmap:18 G729/8000 6089 a=rtpmap:8 PCMA/8000 6090 a=rtpmap:0 PCMU/8000 6091 a=rtpmap:101 telephone-event/8000 6092 a=fmtp:101 0-16 6093 a=ssrc:1573221107 cname:6b177ce317f75744 6094 <-------------> 6095 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: --- (16 headers 17 lines) --- 6096 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Sending to 10.100.50.228:51122 (NAT) 6097 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Using INVITE request as basis request - 1db4a1bae6a24531b259121dcffd9f59 6098 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found peer '6614101' for '6614101' from 10.100.50.228:51122 6099 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] netsock2.c: Using SIP RTP TOS bits 184 6100 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] netsock2.c: Using SIP RTP CoS mark 5 6101 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Got SDP version 3822810694 and unique parts [- 3822810694 IN IP4 10.100.50.228] 6102 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found RTP audio format 18 6103 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found RTP audio format 8 6104 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found RTP audio format 0 6105 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found RTP audio format 101 6106 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found audio description format G729 for ID 18 6107 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found audio description format PCMA for ID 8 6108 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found audio description format PCMU for ID 0 6109 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Found audio description format telephone-event for ID 101 6110 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw) 6111 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) 6112 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Peer audio RTP is at port 10.100.50.228:4002 6113 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Looking for 7252818157 in from-internal (domain 10.100.51.227) 6114 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] sip/route.c: sip_route_dump: route/path hop: 6115 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: 6116 <--- Transmitting (NAT) to 10.100.50.228:51122 ---> 6117 SIP/2.0 100 Trying 6118 Via: SIP/2.0/UDP 10.100.50.228:51122;branch=z9hG4bKPj0c0bf368fac249a5aaf85e8055631871;received=10.100.50.228;rport=51122 6119 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6120 To: 6121 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6122 CSeq: 14740 INVITE 6123 Server: FPBX-15.0.16.81(16.13.0) 6124 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 6125 Supported: replaces, timer 6126 Session-Expires: 1800;refresher=uas 6127 Contact: 6128 Content-Length: 0 6129 6130 6131 <------------> 6132 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:1] Macro("SIP/6614101-00000004", "user-callerid,LIMIT,EXTERNAL,") in new stack 6133 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/6614101-00000004", "TOUCH_MONITOR=1613802097.4") in new stack 6134 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/6614101-00000004", "AMPUSER=6614101") in new stack 6135 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/6614101-00000004", "HOTDESCKCHAN=6614101-00000004") in new stack 6136 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/6614101-00000004", "HOTDESKEXTEN=6614101") in new stack 6137 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/6614101-00000004", "HOTDESKCALL=0") in new stack 6138 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/6614101-00000004", "0?Set(HOTDESKCALL=1)") in new stack 6139 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(name)=)") in new stack 6140 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/6614101-00000004", "0?report") in new stack 6141 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/6614101-00000004", "1?Set(REALCALLERIDNUM=6614101)") in new stack 6142 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/6614101-00000004", "AMPUSER=6614101") in new stack 6143 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/6614101-00000004", "0?limit") in new stack 6144 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/6614101-00000004", "AMPUSERCIDNAME=Abhishek Thomas") in new stack 6145 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/6614101-00000004", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 6146 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/6614101-00000004", "0?report") in new stack 6147 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/6614101-00000004", "AMPUSERCID=6614101") in new stack 6148 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/6614101-00000004", "__DIAL_OPTIONS=HhTtr") in new stack 6149 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/6614101-00000004", "CALLERID(all)="Abhishek Thomas" <6614101>") in new stack 6150 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/6614101-00000004", "0?Set(CUSDIAL=)") in new stack 6151 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(all)="Abhishek Thomas" <6614101>)") in new stack 6152 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/6614101-00000004", "0?limit") in new stack 6153 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/6614101-00000004", "1?Set(GROUP(concurrency_limit)=6614101)") in new stack 6154 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/6614101-00000004", "0?Set(CHANNEL(language)=)") in new stack 6155 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/6614101-00000004", "Macro Depth is 1") in new stack 6156 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/6614101-00000004", "1?report2:macroerror") in new stack 6157 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,25) 6158 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/6614101-00000004", "1?continue") in new stack 6159 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,44) 6160 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/6614101-00000004", "CALLERID(number)=6614101") in new stack 6161 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/6614101-00000004", "CALLERID(name)=Abhishek Thomas") in new stack 6162 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/6614101-00000004", "0?cnum") in new stack 6163 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/6614101-00000004", "CDR(cnam)=Abhishek Thomas") in new stack 6164 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/6614101-00000004", "CDR(cnum)=6614101") in new stack 6165 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/6614101-00000004", "CHANNEL(language)=en") in new stack 6166 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:2] Gosub("SIP/6614101-00000004", "sub-record-check,s,1(out,7252818157,dontcare)") in new stack 6167 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/6614101-00000004", "0?initialized") in new stack 6168 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:2] Set("SIP/6614101-00000004", "__REC_STATUS=INITIALIZED") in new stack 6169 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:3] Set("SIP/6614101-00000004", "NOW=1613802097") in new stack 6170 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:4] Set("SIP/6614101-00000004", "__DAY=20") in new stack 6171 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:5] Set("SIP/6614101-00000004", "__MONTH=02") in new stack 6172 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:6] Set("SIP/6614101-00000004", "__YEAR=2021") in new stack 6173 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:7] Set("SIP/6614101-00000004", "__TIMESTR=20210220-062137") in new stack 6174 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:8] Set("SIP/6614101-00000004", "__FROMEXTEN=6614101") in new stack 6175 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:9] Set("SIP/6614101-00000004", "__MON_FMT=wav") in new stack 6176 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/6614101-00000004", "Recordings initialized") in new stack 6177 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/6614101-00000004", "0?Set(ARG3=dontcare)") in new stack 6178 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:12] Set("SIP/6614101-00000004", "REC_POLICY_MODE_SAVE=") in new stack 6179 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/6614101-00000004", "0?Set(REC_STATUS=NO)") in new stack 6180 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/6614101-00000004", "3?checkaction") in new stack 6181 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,17) 6182 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/6614101-00000004", "1?sub-record-check,out,1") in new stack 6183 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,1) 6184 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/6614101-00000004", "Outbound Recording Check from 6614101 to 7252818157") in new stack 6185 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [out@sub-record-check:2] Set("SIP/6614101-00000004", "RECMODE=dontcare") in new stack 6186 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/6614101-00000004", "1?Goto(routewins)") in new stack 6187 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,7) 6188 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/6614101-00000004", "recordcheck,1(dontcare,out,7252818157)") in new stack 6189 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/6614101-00000004", "Starting recording check against dontcare") in new stack 6190 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/6614101-00000004", "dontcare") in new stack 6191 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 6192 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/6614101-00000004", "") in new stack 6193 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [out@sub-record-check:8] Return("SIP/6614101-00000004", "") in new stack 6194 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:3] ExecIf("SIP/6614101-00000004", "0 ?Set(CDR(accountcode)=)") in new stack 6195 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:4] Set("SIP/6614101-00000004", "_ROUTEID=7") in new stack 6196 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:5] Set("SIP/6614101-00000004", "_ROUTENAME=outgoing") in new stack 6197 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:6] Set("SIP/6614101-00000004", "MOHCLASS=default") in new stack 6198 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:7] Set("SIP/6614101-00000004", "_CALLERIDNAMEINTERNAL=Abhishek Thomas") in new stack 6199 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:8] Set("SIP/6614101-00000004", "_CALLERIDNUMINTERNAL=6614101") in new stack 6200 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:9] Set("SIP/6614101-00000004", "_EMAILNOTIFICATION=FALSE") in new stack 6201 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:10] Set("SIP/6614101-00000004", "_NODEST=") in new stack 6202 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:11] Macro("SIP/6614101-00000004", "dialout-trunk,1,7252818157,,off") in new stack 6203 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/6614101-00000004", "DIAL_TRUNK=1") in new stack 6204 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/6614101-00000004", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack 6205 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/6614101-00000004", "0?sub-pincheck,s,1()") in new stack 6206 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(num)=6614101)") in new stack 6207 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/6614101-00000004", "0?disabletrunk,1") in new stack 6208 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/6614101-00000004", "DIAL_NUMBER=7252818157") in new stack 6209 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/6614101-00000004", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack 6210 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/6614101-00000004", "OUTBOUND_GROUP=OUT_1") in new stack 6211 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/6614101-00000004", "DIAL_TRUNK_OPTIONS=T") in new stack 6212 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/6614101-00000004", "0?nomax") in new stack 6213 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/6614101-00000004", "0?chanfull") in new stack 6214 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/6614101-00000004", "0?skipoutcid") in new stack 6215 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/6614101-00000004", "outbound-callerid,1") in new stack 6216 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/6614101-00000004", "6614101") in new stack 6217 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/6614101-00000004", "") in new stack 6218 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/6614101-00000004", "off") in new stack 6219 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(name-pres)=)") in new stack 6220 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(num-pres)=)") in new stack 6221 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/6614101-00000004", "HOTDESCKCHAN=6614101-00000004") in new stack 6222 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/6614101-00000004", "HOTDESKEXTEN=6614101") in new stack 6223 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/6614101-00000004", "HOTDESKCALL=0") in new stack 6224 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/6614101-00000004", "0?Set(HOTDESKCALL=1)") in new stack 6225 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(name)=)") in new stack 6226 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/6614101-00000004", "ALLOWTHISROUTE=NO") in new stack 6227 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/6614101-00000004", "0?Set(ALLOWTHISROUTE=YES)") in new stack 6228 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/6614101-00000004", "0?Hangup()") in new stack 6229 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/6614101-00000004", "0?Set(REALCALLERIDNUM=6614101)") in new stack 6230 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/6614101-00000004", "0?Set(AMPUSER=6614101)") in new stack 6231 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/6614101-00000004", "1?normcid") in new stack 6232 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-outbound-callerid,s,20) 6233 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/6614101-00000004", "USEROUTCID=6614101") in new stack 6234 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/6614101-00000004", "EMERGENCYCID=") in new stack 6235 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/6614101-00000004", "0?Set(EMERGENCYCID=)") in new stack 6236 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/6614101-00000004", "TRUNKOUTCID=") in new stack 6237 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/6614101-00000004", "1?trunkcid") in new stack 6238 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-outbound-callerid,s,30) 6239 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(all)=)") in new stack 6240 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/6614101-00000004", "1?Set(CALLERID(all)=6614101)") in new stack 6241 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(all)=)") in new stack 6242 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/6614101-00000004", "0?Set(CALLERID(all)=6614101)") in new stack 6243 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/6614101-00000004", "TIOHIDE=no") in new stack 6244 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 6245 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 6246 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 6247 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/6614101-00000004", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack 6248 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/6614101-00000004", "CDR(outbound_cnum)=6614101") in new stack 6249 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/6614101-00000004", "CDR(outbound_cnam)=") in new stack 6250 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/6614101-00000004", "0?sub-flp-1,s,1()") in new stack 6251 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/6614101-00000004", "OUTNUM=7252818157") in new stack 6252 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/6614101-00000004", "custom=SIP/tatasip") in new stack 6253 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/6614101-00000004", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack 6254 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/6614101-00000004", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 6255 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/6614101-00000004", "dialout-trunk-predial-hook,") in new stack 6256 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/6614101-00000004", "") in new stack 6257 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/6614101-00000004", "0?skipcrm") in new stack 6258 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/6614101-00000004", "__CRM_DIRECTION=OUTBOUND") in new stack 6259 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/6614101-00000004", "__CRM_DESTINATION=7252818157") in new stack 6260 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/6614101-00000004", "__CRM_SOURCE=6614101") in new stack 6261 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/6614101-00000004", "agi://127.0.0.1/sangomacrm.agi") in new stack 6262 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 6263 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/6614101-00000004", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack 6264 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/6614101-00000004", "CRM Finished") in new stack 6265 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/6614101-00000004", "0?bypass,1") in new stack 6266 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/6614101-00000004", "1?Set(CONNECTEDLINE(num,i)=7252818157)") in new stack 6267 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/6614101-00000004", "1?Set(CONNECTEDLINE(name,i)=CID:6614101)") in new stack 6268 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/6614101-00000004", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614101)") in new stack 6269 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/6614101-00000004", "0?customtrunk") in new stack 6270 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/6614101-00000004", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack 6271 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/6614101-00000004", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack 6272 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/6614101-00000004", "SIP/tatasip/7252818157,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^7252818157^7252818157^1^1613802097^^6614101)") in new stack 6273 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] netsock2.c: Using SIP RTP TOS bits 184 6274 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] netsock2.c: Using SIP RTP CoS mark 5 6275 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] app_stack.c: SIP/tatasip-00000005 Internal Gosub(func-apply-sipheaders,s,1(1)) start 6276 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-00000005", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack 6277 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-00000005", "Applying SIP Headers to channel SIP/tatasip-00000005") in new stack 6278 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-00000005", "TECH=SIP") in new stack 6279 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-00000005", "SIPHEADERKEYS=Alert-Info") in new stack 6280 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000005", "1") in new stack 6281 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-00000005", "sipheader=unset") in new stack 6282 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-00000005", "1?SIPRemoveHeader(Alert-Info:)") in new stack 6283 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-00000005", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack 6284 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-00000005", "0?Set(sipheader=;info=unset)") in new stack 6285 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-00000005", "0?Set(sipheader=unset)") in new stack 6286 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-00000005", "0?SIPAddHeader(Alert-Info:unset)") in new stack 6287 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-00000005", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack 6288 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-00000005", "") in new stack 6289 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000005", "0") in new stack 6290 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-00000005", "") in new stack 6291 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] app_stack.c: Spawn extension (tata, 7252818157, 1) exited non-zero on 'SIP/tatasip-00000005' 6292 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] app_stack.c: SIP/tatasip-00000005 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 6293 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Audio is at 13498 6294 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding codec ulaw to SDP 6295 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding codec alaw to SDP 6296 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding codec g729 to SDP 6297 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP 6298 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Reliably Transmitting (no NAT) to 10.50.185.2:5060: 6299 INVITE sip:7252818157@10.50.185.2 SIP/2.0 6300 Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK203099c3 6301 Max-Forwards: 70 6302 From: ;tag=as0a49581e 6303 To: 6304 Contact: 6305 Call-ID: 0bb1eb4c6a66499a674f3c131306c94a@10.50.192.54:5060 6306 CSeq: 102 INVITE 6307 User-Agent: FPBX-15.0.16.81(16.13.0) 6308 Date: Sat, 20 Feb 2021 06:21:37 GMT 6309 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 6310 Supported: replaces, timer 6311 Content-Type: application/sdp 6312 Content-Length: 323 6313 6314 v=0 6315 o=root 1730556088 1730556088 IN IP4 10.50.192.54 6316 s=Asterisk PBX 16.13.0 6317 c=IN IP4 10.50.192.54 6318 t=0 0 6319 m=audio 13498 RTP/AVP 0 8 18 101 6320 a=rtpmap:0 PCMU/8000 6321 a=rtpmap:8 PCMA/8000 6322 a=rtpmap:18 G729/8000 6323 a=fmtp:18 annexb=no 6324 a=rtpmap:101 telephone-event/8000 6325 a=fmtp:101 0-16 6326 a=ptime:20 6327 a=maxptime:150 6328 a=sendrecv 6329 6330 --- 6331 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] app_dial.c: Called SIP/tatasip/7252818157 6332 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: 6333 <--- SIP read from UDP:10.50.185.2:5060 ---> 6334 SIP/2.0 100 Trying 6335 From: ;tag=as0a49581e 6336 To: 6337 Call-ID: 0bb1eb4c6a66499a674f3c131306c94a@10.50.192.54:5060 6338 CSeq: 102 INVITE 6339 Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK203099c3 6340 Contact: 6341 Content-Length: 0 6342 6343 <-------------> 6344 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: --- (8 headers 0 lines) --- 6345 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: 6346 <--- SIP read from UDP:10.50.185.2:5060 ---> 6347 SIP/2.0 403 Forbidden 6348 From: ;tag=as0a49581e 6349 To: ;tag=BN1704928997-0-1613802094-573645112 6350 Call-ID: 0bb1eb4c6a66499a674f3c131306c94a@10.50.192.54:5060 6351 CSeq: 102 INVITE 6352 Warning: 399 10.50.185.2:5060 "Registration Cache Entry Not Found" 6353 Reason: SIP;cause=403;text="Subsystem Id: ICALLLEG Cause Code: Forbidden_403" 6354 Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK203099c3 6355 Content-Length: 0 6356 6357 <-------------> 6358 [2021-02-20 06:21:37] VERBOSE[23753] chan_sip.c: --- (9 headers 0 lines) --- 6359 [2021-02-20 06:21:37] VERBOSE[23753][C-00000003] chan_sip.c: Transmitting (no NAT) to 10.50.185.2:5060: 6360 ACK sip:7252818157@10.50.185.2 SIP/2.0 6361 Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK203099c3 6362 Max-Forwards: 70 6363 From: ;tag=as0a49581e 6364 To: ;tag=BN1704928997-0-1613802094-573645112 6365 Contact: 6366 Call-ID: 0bb1eb4c6a66499a674f3c131306c94a@10.50.192.54:5060 6367 CSeq: 102 ACK 6368 User-Agent: FPBX-15.0.16.81(16.13.0) 6369 Content-Length: 0 6370 6371 6372 --- 6373 [2021-02-20 06:21:37] WARNING[23753][C-00000003] chan_sip.c: Received response: "Forbidden" from ';tag=as0a49581e' 6374 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Scheduling destruction of SIP dialog '0bb1eb4c6a66499a674f3c131306c94a@10.50.192.54:5060' in 32000 ms (Method: INVITE) 6375 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] app_dial.c: Everyone is busy/congested at this time (1:0/0/1) 6376 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/6614101-00000004", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack 6377 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/6614101-00000004", "0?continue,1:s-CHANUNAVAIL,1") in new stack 6378 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1) 6379 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/6614101-00000004", "RC=21") in new stack 6380 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/6614101-00000004", "21,1") in new stack 6381 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-dialout-trunk,21,1) 6382 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/6614101-00000004", "continue,1") in new stack 6383 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1) 6384 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/6614101-00000004", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack 6385 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/6614101-00000004", "1?Set(CALLERID(number)=6614101)") in new stack 6386 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [7252818157@from-internal:12] Macro("SIP/6614101-00000004", "outisbusy,") in new stack 6387 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/6614101-00000004", "") in new stack 6388 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Audio is at 17912 6389 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding codec ulaw to SDP 6390 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding codec alaw to SDP 6391 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP 6392 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] chan_sip.c: 6393 <--- Transmitting (NAT) to 10.100.50.228:51122 ---> 6394 SIP/2.0 183 Session Progress 6395 Via: SIP/2.0/UDP 10.100.50.228:51122;branch=z9hG4bKPj0c0bf368fac249a5aaf85e8055631871;received=10.100.50.228;rport=51122 6396 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6397 To: ;tag=as7419954c 6398 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6399 CSeq: 14740 INVITE 6400 Server: FPBX-15.0.16.81(16.13.0) 6401 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 6402 Supported: replaces, timer 6403 Session-Expires: 1800;refresher=uas 6404 Contact: 6405 Content-Type: application/sdp 6406 Require: timer 6407 Content-Length: 276 6408 6409 v=0 6410 o=root 625739490 625739490 IN IP4 10.100.51.227 6411 s=Asterisk PBX 16.13.0 6412 c=IN IP4 10.100.51.227 6413 t=0 0 6414 m=audio 17912 RTP/AVP 0 8 101 6415 a=rtpmap:0 PCMU/8000 6416 a=rtpmap:8 PCMA/8000 6417 a=rtpmap:101 telephone-event/8000 6418 a=fmtp:101 0-16 6419 a=ptime:20 6420 a=maxptime:150 6421 a=sendrecv 6422 6423 <------------> 6424 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/6614101-00000004", "0?emergency,1") in new stack 6425 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/6614101-00000004", "0?intracompany,1") in new stack 6426 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/6614101-00000004", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack 6427 [2021-02-20 06:21:37] VERBOSE[25689][C-00000003] file.c: Playing 'all-circuits-busy-now.ulaw' (language 'en') 6428 [2021-02-20 06:21:39] VERBOSE[25689][C-00000003] file.c: Playing 'please-try-call-later.ulaw' (language 'en') 6429 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/6614101-00000004", "20") in new stack 6430 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] chan_sip.c: 6431 <--- Reliably Transmitting (NAT) to 10.100.50.228:51122 ---> 6432 SIP/2.0 503 Service Unavailable 6433 Via: SIP/2.0/UDP 10.100.50.228:51122;branch=z9hG4bKPj0c0bf368fac249a5aaf85e8055631871;received=10.100.50.228;rport=51122 6434 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6435 To: ;tag=as7419954c 6436 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6437 CSeq: 14740 INVITE 6438 Server: FPBX-15.0.16.81(16.13.0) 6439 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 6440 Supported: replaces, timer 6441 Session-Expires: 1800;refresher=uas 6442 X-Asterisk-HangupCause: Call Rejected 6443 X-Asterisk-HangupCauseCode: 21 6444 Content-Length: 0 6445 6446 6447 <------------> 6448 [2021-02-20 06:21:41] WARNING[25689][C-00000003] channel.c: Prodding channel 'SIP/6614101-00000004' failed 6449 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/6614101-00000004' in macro 'outisbusy' 6450 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Spawn extension (from-internal, 7252818157, 12) exited non-zero on 'SIP/6614101-00000004' 6451 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [h@from-internal:1] Macro("SIP/6614101-00000004", "hangupcall") in new stack 6452 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/6614101-00000004", "1?theend") in new stack 6453 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-hangupcall,s,3) 6454 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/6614101-00000004", "0?Set(CDR(recordingfile)=)") in new stack 6455 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/6614101-00000004", " montior file= ") in new stack 6456 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/6614101-00000004", "1?skipagi") in new stack 6457 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx_builtins.c: Goto (macro-hangupcall,s,7) 6458 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/6614101-00000004", "") in new stack 6459 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/6614101-00000004' in macro 'hangupcall' 6460 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/6614101-00000004' 6461 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] app_stack.c: SIP/6614101-00000004 Internal Gosub(crm-hangup,s,1) start 6462 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/6614101-00000004", "Sending Hangup to CRM") in new stack 6463 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/6614101-00000004", "HANGUP CAUSE: 34") in new stack 6464 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/6614101-00000004", "0?Set(__CRM_VOICEMAIL=)") in new stack 6465 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/6614101-00000004", "MASTER CHANNEL: 1613802097.4 = 1613802097.4") in new stack 6466 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/6614101-00000004", "0?return") in new stack 6467 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:6] Set("SIP/6614101-00000004", "__CRM_HANGUP=1") in new stack 6468 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/6614101-00000004", "agi://127.0.0.1/sangomacrm.agi") in new stack 6469 [2021-02-20 06:21:41] VERBOSE[23753] chan_sip.c: 6470 <--- SIP read from UDP:10.100.50.228:51122 ---> 6471 ACK sip:7252818157@10.100.51.227 SIP/2.0 6472 Via: SIP/2.0/UDP 10.100.50.228:51122;rport;branch=z9hG4bKPj0c0bf368fac249a5aaf85e8055631871 6473 Max-Forwards: 70 6474 From: "TATA" ;tag=4365b56976444bc487fd92d2dc6fcacd 6475 To: ;tag=as7419954c 6476 Call-ID: 1db4a1bae6a24531b259121dcffd9f59 6477 CSeq: 14740 ACK 6478 Content-Length: 0 6479 6480 <-------------> 6481 [2021-02-20 06:21:41] VERBOSE[23753] chan_sip.c: --- (8 headers 0 lines) --- 6482 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] res_agi.c: AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0 6483 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] pbx.c: Executing [s@crm-hangup:8] Return("SIP/6614101-00000004", "") in new stack 6484 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/6614101-00000004' 6485 [2021-02-20 06:21:41] VERBOSE[25689][C-00000003] app_stack.c: SIP/6614101-00000004 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= 6486 [2021-02-20 06:21:42] VERBOSE[23753] chan_sip.c: Really destroying SIP dialog '1db4a1bae6a24531b259121dcffd9f59' Method: ACK 6487 [2021-02-20 06:21:42] VERBOSE[23753] chan_sip.c: Really destroying SIP dialog '3243e44920ef31d300a9a6941907b7b4@10.50.192.54:5060' Method: INVITE 6488 [2021-02-20 06:21:44] VERBOSE[23753] chan_sip.c: 6489 <--- SIP read from UDP:10.100.50.228:51122 ---> 6490 6491 <------------->