vicidial.org

Posted: **Mon Jan 25, 2021 12:09 pm**

had to reload one of my dialers on a cluster. it places call but no longer plays message or bridges call to agent. dialer 3 is failing. log follows

( i have replaced //192.0.0.1 with ??192.0.0.1 so as to be able to post with less than 5 urls)

dl3

Dl3:~ # asterisk -vvvr
[Jan 25 11:16:27] Asterisk 13.34.0-vici, Copyright (C) 1999 - 2014, Digium, Inc. and others.
[Jan 25 11:16:27] Created by Mark Spencer <markster@digium.com>
[Jan 25 11:16:27] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
[Jan 25 11:16:27] This is free software, with components licensed under the GNU General Public
[Jan 25 11:16:27] License version 2 and other licenses; you are welcome to redistribute it under
[Jan 25 11:16:27] certain conditions. Type 'core show license' for details.
[Jan 25 11:16:27] =========================================================================
[Jan 25 11:16:27] Connected to Asterisk 13.34.0-vici currently running on LeadgenDl3 (pid = 2851)
[Jan 25 11:16:57] NOTICE[2900]: chan_sip.c:28865 handle_request_register: Registration from '<sip:2712@47.206.105.234>' failed for '82.102.17.180:63955' - Wrong password
[Jan 25 11:16:59] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 11:16:59] -- Called 18136822440@default
[Jan 25 11:16:59] -- Executing [18136822440@default:1] AGI("Local/18136822440@default-00000001;2", "agi:??127.0.0.1:4577/call_log") in new stack
[Jan 25 11:16:59] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=Dallas))
[Jan 25 11:16:59] -- <Local/18136822440@default-00000001;2>AGI Script agi:??127.0.0.1:4577/call_log completed, returning 0
[Jan 25 11:16:59] -- Executing [18136822440@default:2] NoOp("Local/18136822440@default-00000001;2", "leadgen1 Dialing thru aka Customer: 18136822440") in new stack
[Jan 25 11:16:59] -- Executing [18136822440@default:3] Dial("Local/18136822440@default-00000001;2", "SIP/18136822440@aka,,To") in new stack
[Jan 25 11:16:59] == Using SIP RTP CoS mark 5
[Jan 25 11:16:59] -- Called SIP/18136822440@aka
[Jan 25 11:17:01] > 0x7fb50800d830 -- Strict RTP learning after remote address set to: 8.19.146.173:43348
[Jan 25 11:17:01] -- SIP/aka-00000001 is making progress passing it to Local/18136822440@default-00000001;2
[Jan 25 11:17:01] -- Local/18136822440@default-00000001;1 is making progress
[Jan 25 11:17:01] > 0x7fb50800d830 -- Strict RTP switching to RTP target address 8.19.146.173:43348 as source
[Jan 25 11:17:02] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 11:17:02] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 11:17:02] -- Remote UNIX connection
[Jan 25 11:17:02] -- Remote UNIX connection disconnected
[Jan 25 11:17:03] -- SIP/aka-00000001 answered Local/18136822440@default-00000001;2
[Jan 25 11:17:03] -- Channel SIP/aka-00000001 joined 'simple_bridge' basic-bridge <3ea55100-4a01-485a-b985-4a41efca97b8>
[Jan 25 11:17:03] -- Local/18136822440@default-00000001;1 answered
[Jan 25 11:17:03] -- Executing [138366@default:1] AGI("Local/18136822440@default-00000001;1", "agi-VDAD_local_optimize.agi,V1251108090030862535") in new stack
[Jan 25 11:17:03] -- Channel Local/18136822440@default-00000001;2 joined 'simple_bridge' basic-bridge <3ea55100-4a01-485a-b985-4a41efca97b8>
[Jan 25 11:17:03] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Jan 25 11:17:03] -- <Local/18136822440@default-00000001;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Jan 25 11:17:03] -- Executing [138366@default:2] Wait("Local/18136822440@default-00000001;1", "2") in new stack
[Jan 25 11:17:04] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 11:17:05] -- Executing [138366@default:3] Hangup("Local/18136822440@default-00000001;1", "") in new stack
[Jan 25 11:17:05] == Spawn extension (default, 138366, 3) exited non-zero on 'Local/18136822440@default-00000001;1'
[Jan 25 11:17:05] WARNING[7330][C-00000003]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Jan 25 11:17:05] -- Executing [h@default:1] AGI("Local/18136822440@default-00000001;1", "agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Jan 25 11:17:06] > 0x7fb50800d830 -- Strict RTP learning complete - Locking on source address 8.19.146.173:43348
[Jan 25 11:17:06] -- <Local/18136822440@default-00000001;1>AGI Script agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Jan 25 11:17:06] -- Channel Local/18136822440@default-00000001;2 left 'simple_bridge' basic-bridge <3ea55100-4a01-485a-b985-4a41efca97b8>
[Jan 25 11:17:06] -- Channel SIP/aka-00000001 left 'simple_bridge' basic-bridge <3ea55100-4a01-485a-b985-4a41efca97b8>
[Jan 25 11:17:06] == Spawn extension (default, 18136822440, 3) exited non-zero on 'Local/18136822440@default-00000001;2'
[Jan 25 11:17:06] -- Executing [h@default:1] AGI("Local/18136822440@default-00000001;2", "agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----3-----SIP 200 OK)") in new stack
[Jan 25 11:17:07] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 11:17:07] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 11:17:08] -- <Local/18136822440@default-00000001;2>AGI Script agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----3-----SIP 200 OK) completed, returning 0
LeadgenDl3*CLI>

===================================================== log from working server ============

dl2

LeadgenD2:~ # asterisk -vvvr
[Jan 25 06:16:14] Asterisk 13.21.1-vici, Copyright (C) 1999 - 2014, Digium, Inc. and others.
[Jan 25 06:16:14] Created by Mark Spencer <markster@digium.com>
[Jan 25 06:16:14] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
[Jan 25 06:16:14] This is free software, with components licensed under the GNU General Public
[Jan 25 06:16:14] License version 2 and other licenses; you are welcome to redistribute it under
[Jan 25 06:16:14] certain conditions. Type 'core show license' for details.
[Jan 25 06:16:14] =========================================================================
[Jan 25 06:16:14] Connected to Asterisk 13.21.1-vici currently running on LeadgenD2 (pid = 2258)
[Jan 25 06:16:29] WARNING[2373]: chan_sip.c:4127 retrans_pkt: Timeout on 1253253164-2024153850-222817053 on non-critical invite transaction.
[Jan 25 06:16:40] NOTICE[2373]: chan_sip.c:28691 handle_request_register: Registration from '<sip:18@47.206.105.233>' failed for '185.108.106.147:64619' - Wrong password
[Jan 25 06:16:56] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 06:16:56] -- Called 18136822440@default
[Jan 25 06:16:56] -- Executing [18136822440@default:1] AGI("Local/18136822440@default-00000001;2", "agi:??127.0.0.1:4577/call_log") in new stack
[Jan 25 06:16:56] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=Dallas))
[Jan 25 06:16:56] -- <Local/18136822440@default-00000001;2>AGI Script agi:??127.0.0.1:4577/call_log completed, returning 0
[Jan 25 06:16:56] -- Executing [18136822440@default:2] NoOp("Local/18136822440@default-00000001;2", "leadgen1 Dialing thru aka Customer: 18136822440") in new stack
[Jan 25 06:16:56] -- Executing [18136822440@default:3] Dial("Local/18136822440@default-00000001;2", "SIP/18136822440@aka,,To") in new stack
[Jan 25 06:16:56] == Using SIP RTP CoS mark 5
[Jan 25 06:16:56] -- Called SIP/18136822440@aka
[Jan 25 06:16:57] -- SIP/aka-00000002 is ringing
[Jan 25 06:16:57] -- Local/18136822440@default-00000001;1 is ringing
[Jan 25 06:16:59] > 0x7f7a4002bd40 -- Strict RTP learning after remote address set to: 40.139.11.237:51976
[Jan 25 06:16:59] -- SIP/aka-00000002 answered Local/18136822440@default-00000001;2
[Jan 25 06:16:59] -- Local/18136822440@default-00000001;1 answered
[Jan 25 06:16:59] -- Executing [138366@default:1] AGI("Local/18136822440@default-00000001;1", "agi-VDAD_local_optimize.agi,V1251105180030862534") in new stack
[Jan 25 06:16:59] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Jan 25 06:16:59] -- Channel SIP/aka-00000002 joined 'simple_bridge' basic-bridge <478501dd-2169-4f09-a8d1-c831573929e7>
[Jan 25 06:16:59] -- Channel Local/18136822440@default-00000001;2 joined 'simple_bridge' basic-bridge <478501dd-2169-4f09-a8d1-c831573929e7>
[Jan 25 06:16:59] -- <Local/18136822440@default-00000001;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Jan 25 06:16:59] -- Executing [138366@default:2] Wait("Local/18136822440@default-00000001;1", "2") in new stack
[Jan 25 06:16:59] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 06:16:59] -- Channel SIP/aka-00000002 left 'simple_bridge' basic-bridge <478501dd-2169-4f09-a8d1-c831573929e7>
[Jan 25 06:16:59] -- Channel Local/18136822440@default-00000001;2 left 'simple_bridge' basic-bridge <478501dd-2169-4f09-a8d1-c831573929e7>
[Jan 25 06:16:59] == Spawn extension (default, 18136822440, 3) exited non-zero on 'Local/18136822440@default-00000001;2'
[Jan 25 06:16:59] -- Executing [h@default:1] AGI("Local/18136822440@default-00000001;2", "agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----2-----0-----SIP 200 OK)") in new stack
[Jan 25 06:16:59] -- Executing [8366@default:1] AGI("SIP/aka-00000002", "agi:??127.0.0.1:4577/call_log") in new stack
[Jan 25 06:16:59] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=Dallas))
[Jan 25 06:16:59] -- <SIP/aka-00000002>AGI Script agi:??127.0.0.1:4577/call_log completed, returning 0
[Jan 25 06:16:59] -- Executing [8366@default:2] AGI("SIP/aka-00000002", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251105180030862534") in new stack
[Jan 25 06:16:59] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Jan 25 06:16:59] > 0x7f7a4002bd40 -- Strict RTP switching to RTP target address 40.139.11.237:51976 as source
[Jan 25 06:16:59] -- <SIP/aka-00000002> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Jan 25 06:16:59] -- <SIP/aka-00000002> Playing 'dallasgevc.slin' (escape_digits=09) (sample_offset 0) (language 'en')
[Jan 25 06:17:00] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 06:17:00] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 06:17:00] -- <Local/18136822440@default-00000001;2>AGI Script agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----2-----0-----SIP 200 OK) completed, returning 0
[Jan 25 06:17:00] == Spawn extension (default, 138366, 2) exited non-zero on 'Local/18136822440@default-00000001;1'
[Jan 25 06:17:00] WARNING[29006][C-00002c35]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Jan 25 06:17:00] -- Executing [h@default:1] AGI("Local/18136822440@default-00000001;1", "agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Jan 25 06:17:01] -- <Local/18136822440@default-00000001;1>AGI Script agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Jan 25 06:17:01] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 06:17:01] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 06:17:04] > 0x7f7a4002bd40 -- Strict RTP learning complete - Locking on source address 40.139.11.237:51976
[Jan 25 06:17:04] DTMF[29012][C-00002c34]: channel.c:4124 __ast_read: DTMF begin '0' received on SIP/aka-00000002
[Jan 25 06:17:04] DTMF[29012][C-00002c34]: channel.c:4128 __ast_read: DTMF begin ignored '0' on SIP/aka-00000002
[Jan 25 06:17:04] DTMF[29012][C-00002c34]: channel.c:4038 __ast_read: DTMF end '0' received on SIP/aka-00000002, duration 100 ms
[Jan 25 06:17:04] DTMF[29012][C-00002c34]: channel.c:4108 __ast_read: DTMF end passthrough '0' on SIP/aka-00000002
[Jan 25 06:17:04] WARNING[29012][C-00002c34]: file.c:774 ast_openstream_full: File Hold_Transfer does not exist in any format
[Jan 25 06:17:04] -- <SIP/aka-00000002>AGI Script agi-VDAD_ALL_outbound.agi completed, returning -1
[Jan 25 06:17:04] -- Executing [s@CallDallas:1] Answer("SIP/aka-00000002", "") in new stack
[Jan 25 06:17:04] -- Executing [s@CallDallas:2] AGI("SIP/aka-00000002", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----YES-----CallDallas-------------------------NO") in new stack
[Jan 25 06:17:04] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Jan 25 06:17:04] -- <SIP/aka-00000002> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Jan 25 06:17:04] -- <SIP/aka-00000002> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Jan 25 06:17:04] -- <SIP/aka-00000002>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Jan 25 06:17:04] -- Executing [s@CallDallas:3] Set("SIP/aka-00000002", "INVCOUNT=0") in new stack
[Jan 25 06:17:04] -- Executing [s@CallDallas:4] BackGround("SIP/aka-00000002", "sip-silence") in new stack
[Jan 25 06:17:04] -- <SIP/aka-00000002> Playing 'sip-silence.gsm' (language 'en')
[Jan 25 06:17:04] -- Executing [s@CallDallas:5] WaitExten("SIP/aka-00000002", "1") in new stack
[Jan 25 06:17:05] -- Timeout on SIP/aka-00000002, going to 't'
[Jan 25 06:17:05] -- Executing [t@CallDallas:1] Goto("SIP/aka-00000002", "default,814697085780,1") in new stack
[Jan 25 06:17:05] -- Goto (default,814697085780,1)
[Jan 25 06:17:05] -- Executing [814697085780@default:1] AGI("SIP/aka-00000002", "agi:??127.0.0.1:4577/call_log") in new stack
[Jan 25 06:17:05] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=Dallas))
[Jan 25 06:17:05] -- <SIP/aka-00000002>AGI Script agi:??127.0.0.1:4577/call_log completed, returning 0
[Jan 25 06:17:05] -- Executing [814697085780@default:2] AGI("SIP/aka-00000002", "agi-NVA_recording.agi,BOTH---TRANSFER---Y---Y---Y") in new stack
[Jan 25 06:17:05] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-NVA_recording.agi
[Jan 25 06:17:05] -- AGI Script Executing Application: (Monitor) Options: (wav,/var/spool/asterisk/monitor/MIX/20210125061705_TRANSFER_814697085780)
[Jan 25 06:17:05] -- <SIP/aka-00000002>AGI Script agi-NVA_recording.agi completed, returning 0
[Jan 25 06:17:05] -- Executing [814697085780@default:3] NoOp("SIP/aka-00000002", "leadgen2 Dialing BridgeCall thru BridgeCall to Customer: 8136822440 Bridgeto: 814697085780") in new stack
[Jan 25 06:17:05] -- Executing [814697085780@default:4] Dial("SIP/aka-00000002", "SIP/14697085780@BridgeCall,,To") in new stack
[Jan 25 06:17:05] == Using SIP RTP CoS mark 5
[Jan 25 06:17:05] -- Called SIP/14697085780@BridgeCall
[Jan 25 06:17:06] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 25 06:17:06] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 25 06:17:08] > 0x7f7a480569f0 -- Strict RTP learning after remote address set to: 8.14.115.85:13412
[Jan 25 06:17:08] -- SIP/BridgeCall-00000003 is making progress passing it to SIP/aka-00000002
[Jan 25 06:17:08] > 0x7f7a480569f0 -- Strict RTP switching to RTP target address 8.14.115.85:13412 as source
[Jan 25 06:17:08] -- SIP/BridgeCall-00000003 answered SIP/aka-00000002
[Jan 25 06:17:08] -- Channel SIP/BridgeCall-00000003 joined 'simple_bridge' basic-bridge <d0a970fa-3cae-4412-ae32-2305100e8494>
[Jan 25 06:17:08] -- Channel SIP/aka-00000002 joined 'simple_bridge' basic-bridge <d0a970fa-3cae-4412-ae32-2305100e8494>
[Jan 25 06:17:10] WARNING[2373]: chan_sip.c:4127 retrans_pkt: Timeout on 1107879841-349754584-998510297 on non-critical invite transaction.
[Jan 25 06:17:13] > 0x7f7a480569f0 -- Strict RTP learning complete - Locking on source address 8.14.115.85:13412
[Jan 25 06:17:13] -- Channel SIP/aka-00000002 left 'simple_bridge' basic-bridge <d0a970fa-3cae-4412-ae32-2305100e8494>
[Jan 25 06:17:13] -- Channel SIP/BridgeCall-00000003 left 'simple_bridge' basic-bridge <d0a970fa-3cae-4412-ae32-2305100e8494>
[Jan 25 06:17:13] == Spawn extension (default, 814697085780, 4) exited non-zero on 'SIP/aka-00000002'
[Jan 25 06:17:13] WARNING[29012][C-00002c34]: func_hangupcause.c:149 hangupcause_read: Information type not recognized (SIP/BridgeCall-00000003,tech)
[Jan 25 06:17:13] -- Executing [h@default:1] AGI("SIP/aka-00000002", "agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----16-----14-----)") in new stack
[Jan 25 06:17:13] -- <SIP/aka-00000002>AGI Script agi:??127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----16-----14-----) completed, returning 0
[Jan 25 06:17:43] WARNING[2373]: chan_sip.c:4127 retrans_pkt: Timeout on 206630606-444259272-1487115068 on non-critical invite transaction.
LeadgenD2*CLI>

any suggestions? thanx in advance

Vicidial Version 2.14-730A SVN 3180 DB schema version 1582 asterisk version 13.21.1-vici

I have a 4 machine cluster
Database/ WEB / Dialer machine dual: quad core Xeon 2.67 GHz 48GB mem / Dialer dual: dual core Xeon 2.80 GHz 8GB mem / dialer core i5 3.0 GHz 4GB mem / dialer core-2 duo 2.93GHz 3GB mem / soon archive I3 3.1 GHz 10GB mem
.. all machines except archive have 2 nic's.. one internal and 1 external for traffic.

Posted: **Tue Jan 26, 2021 2:15 pm**

I don't see dialer 3 even trying to play sounds but I was gonna tell you to check the permissions for that server to the sounds web server but Im not sure, it would be easier to troubleshoot an issue like this in live format, if you can, hit me up on skype and then we can post the solution back here after

vicidial.org

press 1 campaign 1 dialer not playing sound or bridging afte

press 1 campaign 1 dialer not playing sound or bridging afte

Re: press 1 campaign 1 dialer not playing sound or bridging