*** The process got restarted [Apr 24 22:56:57] VERBOSE[18185] asterisk.c: Asterisk cleanly ending (0). [Apr 24 22:56:57] VERBOSE[18185] asterisk.c: Preparing for Asterisk restart... [Apr 24 22:56:57] VERBOSE[18185] asterisk.c: Asterisk is now restarting... *** No calls during this night period *** The first call received in the day [Apr 25 05:57:32] VERBOSE[20308] chan_iax2.c: -- Accepting AUTHENTICATED call from 132.6.200.70: > requested format = alaw, > requested prefs = (), > actual format = alaw, > host prefs = (alaw|ulaw), > priority = mine [Apr 25 05:57:32] VERBOSE[32117] pbx.c: -- Executing [7250@incomingdundi_queue:1] Goto("IAX2/privqueue-5066", "dundip2p,7250,1") in new stack [Apr 25 05:57:32] VERBOSE[32117] pbx.c: -- Goto (dundip2p,7250,1) [Apr 25 05:57:32] VERBOSE[32117] pbx.c: -- Executing [7250@dundip2p:1] NoOp("IAX2/privqueue-5066", "CALL TO AGENT 7250") in new stack [Apr 25 05:57:32] VERBOSE[32117] pbx.c: -- Executing [7250@dundip2p:2] Dial("IAX2/privqueue-5066", "SIP/7250") in new stack [Apr 25 05:57:32] VERBOSE[32117] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 05:57:32] VERBOSE[32117] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 05:57:32] VERBOSE[32117] app_dial.c: -- Called SIP/7250 [Apr 25 05:57:32] VERBOSE[32117] app_dial.c: -- SIP/7250-00000003 is ringing [Apr 25 05:57:36] VERBOSE[32117] app_dial.c: -- SIP/7250-00000003 answered IAX2/privqueue-5066 *** Another one a bit later [Apr 25 06:50:07] VERBOSE[20307] chan_iax2.c: -- Accepting AUTHENTICATED call from 132.6.200.70: > requested format = alaw, > requested prefs = (), > actual format = alaw, > host prefs = (alaw|ulaw), > priority = mine [Apr 25 06:50:07] VERBOSE[13484] pbx.c: -- Executing [7250@incomingdundi_queue:1] Goto("IAX2/privqueue-2963", "dundip2p,7250,1") in new stack [Apr 25 06:50:07] VERBOSE[13484] pbx.c: -- Goto (dundip2p,7250,1) [Apr 25 06:50:07] VERBOSE[13484] pbx.c: -- Executing [7250@dundip2p:1] NoOp("IAX2/privqueue-2963", "CALL TO AGENT 7250") in new stack [Apr 25 06:50:07] VERBOSE[13484] pbx.c: -- Executing [7250@dundip2p:2] Dial("IAX2/privqueue-2963", "SIP/7250") in new stack [Apr 25 06:50:07] VERBOSE[13484] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 06:50:07] VERBOSE[13484] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 06:50:07] VERBOSE[13484] app_dial.c: -- Called SIP/7250 [Apr 25 06:50:07] VERBOSE[13484] app_dial.c: -- SIP/7250-0000000a is ringing [Apr 25 06:50:19] VERBOSE[13484] app_dial.c: -- SIP/7250-0000000a answered IAX2/privqueue-2963 *** At 7:00am the queue was open but seem a lot of softphones were with DND button enabled [Apr 25 07:01:34] VERBOSE[20303] chan_iax2.c: -- Accepting AUTHENTICATED call from 132.6.200.70: > requested format = alaw, > requested prefs = (), > actual format = alaw, > host prefs = (alaw|ulaw), > priority = mine [Apr 25 07:01:34] VERBOSE[16326] pbx.c: -- Executing [7414@incomingdundi_queue:1] Goto("IAX2/privqueue-209", "dundip2p,7414,1") in new stack [Apr 25 07:01:34] VERBOSE[16326] pbx.c: -- Goto (dundip2p,7414,1) [Apr 25 07:01:34] VERBOSE[16326] pbx.c: -- Executing [7414@dundip2p:1] NoOp("IAX2/privqueue-209", "CALL TO AGENT 7414") in new stack [Apr 25 07:01:34] VERBOSE[16326] pbx.c: -- Executing [7414@dundip2p:2] Dial("IAX2/privqueue-209", "SIP/7414") in new stack [Apr 25 07:01:34] VERBOSE[16326] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 07:01:34] VERBOSE[16326] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 07:01:34] VERBOSE[16326] app_dial.c: -- Called SIP/7414 [Apr 25 07:01:34] VERBOSE[20298] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 07:01:34] VERBOSE[20298] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 07:01:34] VERBOSE[16327] pbx.c: -- Executing [*200@access-level-1:1] Set("SIP/7330-00000012", "RAMAL=7330") in new stack [Apr 25 07:01:34] VERBOSE[16327] pbx.c: -- Executing [*200@access-level-1:2] Set("SIP/7330-00000012", "FULLEXTEN=SIP/7330") in new stack [Apr 25 07:01:34] VERBOSE[16327] pbx.c: -- Executing [*200@access-level-1:3] ExecIf("SIP/7330-00000012", "0?Set(RAMAL=)") in new stack [Apr 25 07:01:34] VERBOSE[16327] pbx.c: -- Executing [*200@access-level-1:4] ExecIf("SIP/7330-00000012", "0?Set(FULLEXTEN=)") in new stack [Apr 25 07:01:34] VERBOSE[16327] pbx.c: -- Executing [*200@access-level-1:5] NoCDR("SIP/7330-00000012", "") in new stack [Apr 25 07:01:34] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:34] VERBOSE[16326] app_dial.c: -- SIP/7414-00000011 is circuit-busy [Apr 25 07:01:34] VERBOSE[16326] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) *** another attempt [Apr 25 07:01:36] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:36] VERBOSE[16332] app_dial.c: -- SIP/7414-00000014 is circuit-busy [Apr 25 07:01:36] VERBOSE[16332] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 25 07:01:37] VERBOSE[16333] app_dial.c: -- Called SIP/7414 [Apr 25 07:01:37] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:37] VERBOSE[16333] app_dial.c: -- SIP/7414-00000015 is circuit-busy [Apr 25 07:01:41] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:41] VERBOSE[16336] app_dial.c: -- SIP/7414-00000018 is circuit-busy [Apr 25 07:01:41] VERBOSE[16336] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 25 07:01:42] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:42] VERBOSE[16353] app_dial.c: -- SIP/7414-00000019 is circuit-busy [Apr 25 07:01:42] VERBOSE[16353] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 25 07:01:53] VERBOSE[16405] app_dial.c: -- Called SIP/7414 [Apr 25 07:01:53] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:53] VERBOSE[16405] app_dial.c: -- SIP/7414-00000023 is circuit-busy [Apr 25 07:01:54] VERBOSE[16410] app_dial.c: -- Called SIP/7414 [Apr 25 07:01:54] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:01:54] VERBOSE[16410] app_dial.c: -- SIP/7414-00000024 is circuit-busy *** The 7330 joined the DND team [Apr 25 07:02:08] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.30:11722 [Apr 25 07:02:08] VERBOSE[16632] app_dial.c: -- SIP/7330-00000035 is circuit-busy *** More 7414 [Apr 25 07:02:08] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.114:1726 [Apr 25 07:02:08] VERBOSE[16633] app_dial.c: -- SIP/7414-00000036 is circuit-busy *** 7317 joined the DND team [Apr 25 07:02:20] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.17:12476 [Apr 25 07:02:20] VERBOSE[16687] app_dial.c: -- SIP/7317-0000004c is circuit-busy *** During this time lots of calls attempts by app_queue to those members *** 7418 joined the DND team [Apr 25 07:04:00] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.118:47874 [Apr 25 07:04:00] VERBOSE[17486] app_dial.c: -- SIP/7418-000001fc is circuit-busy *** First call answered [Apr 25 07:04:19] VERBOSE[17822] app_dial.c: -- SIP/7380-00000267 answered IAX2/privqueue-731 *** After that lots of calls were answered but still lots of devices were kept with the DND enabled *** More than one hour answering this way, 7317 is one of the most stuck on the "sip show channels" output. [Apr 25 08:49:58] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.17:12476 [Apr 25 08:49:58] VERBOSE[24083] app_dial.c: -- SIP/7317-00001329 is circuit-busy *** IAX2 start *** This was the latest Dial IAX2 that worked [Apr 25 09:19:37] VERBOSE[3700] pbx.c: -- Executing [7510@macro-fila:1] Queue("DAHDI/102-1", "MC_Propria,w,,beep,1000") in new stack [Apr 25 09:19:37] VERBOSE[3700] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/102-1 [Apr 25 09:19:38] VERBOSE[4152] pbx.c: -- Executing [7378@MemberConnector:1] Set("Local/7378@MemberConnector-594c;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7378") in new stack [Apr 25 09:19:38] VERBOSE[4152] pbx.c: -- Executing [7378@MemberConnector:2] ExecIf("Local/7378@MemberConnector-594c;2", "1?Verbose(2, CONNECTING "1120486010" <1120486010> TO REMOTE REGISTERED AGENT AT 7378)") in new stack [Apr 25 09:19:38] VERBOSE[4152] app_verbose.c: == CONNECTING "1120486010" <1120486010> TO REMOTE REGISTERED AGENT AT 7378 [Apr 25 09:19:38] VERBOSE[4152] pbx.c: -- Executing [7378@MemberConnector:3] ExecIf("Local/7378@MemberConnector-594c;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7378,,tTwW)") in new stack [Apr 25 09:19:38] VERBOSE[4152] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7378 [Apr 25 09:19:38] VERBOSE[20301] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:38] VERBOSE[20301] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:38] VERBOSE[4152] app_dial.c: -- IAX2/132.6.200.70:4569-8069 is ringing [Apr 25 09:19:38] VERBOSE[3225] app_queue.c: -- Local/7378@MemberConnector-594c;1 is ringing [Apr 25 09:19:38] VERBOSE[4152] app_dial.c: -- IAX2/132.6.200.70:4569-8069 answered Local/7378@MemberConnector-594c;2 *** After that, this was the result, 7366 hammering the Dial: [Apr 25 09:19:42] VERBOSE[4164] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:42] VERBOSE[20303] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:42] VERBOSE[20303] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:42] VERBOSE[4164] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-4779' [Apr 25 09:19:42] VERBOSE[4164] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:45] VERBOSE[4179] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-10903' [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:45] VERBOSE[4179] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-10903' [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- No one is available to answer at this time (1:0/0/0) *** Specifically this one is very strange [Apr 25 09:19:45] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7302 [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-e352;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-e352;2", "1?Verbose(2, CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:19:45] VERBOSE[4179] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-e352;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:45] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:45] VERBOSE[4179] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-10903' [Apr 25 09:19:45] VERBOSE[4179] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:4] ExecIf("Local/7366@MemberConnector-e352;2", "0?Verbose(2, CONNECTING "1128195002" <1128195002> TO LOCAL REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:5] ExecIf("Local/7366@MemberConnector-e352;2", "0?Dial(SIP/7366)") in new stack [Apr 25 09:19:45] VERBOSE[4179] pbx.c: -- Executing [7366@MemberConnector:6] Hangup("Local/7366@MemberConnector-e352;2", "") in new stack [Apr 25 09:19:45] VERBOSE[4179] pbx.c: == Spawn extension (MemberConnector, 7366, 6) exited non-zero on 'Local/7366@MemberConnector-e352;2' [Apr 25 09:19:45] VERBOSE[2916] app_queue.c: -- Nobody picked up in 0 ms [Apr 25 09:19:46] VERBOSE[4180] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-fd35;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:19:46] VERBOSE[4181] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-8ca1;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:19:46] VERBOSE[4180] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-fd35;2", "1?Verbose(2, CONNECTING "1155602252" <1155602252> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:19:46] VERBOSE[4180] app_verbose.c: == CONNECTING "1155602252" <1155602252> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:19:46] VERBOSE[4181] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-8ca1;2", "1?Verbose(2, CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:19:46] VERBOSE[4180] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-fd35;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:19:46] VERBOSE[4181] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:19:46] VERBOSE[4181] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-8ca1;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:19:46] VERBOSE[4180] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:46] VERBOSE[4181] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:46] VERBOSE[20303] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:46] VERBOSE[20303] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:46] VERBOSE[20306] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:46] VERBOSE[20306] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:47] VERBOSE[4181] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-15102' *** During this we were accepting calls from IAX2 [Apr 25 09:19:48] VERBOSE[4184] pbx.c: -- Executing [7390@incomingdundi_queue:1] Goto("IAX2/privqueue-15060", "dundip2p,7390,1") in new stack [Apr 25 09:19:48] VERBOSE[4184] pbx.c: -- Goto (dundip2p,7390,1) [Apr 25 09:19:48] VERBOSE[4184] pbx.c: -- Executing [7390@dundip2p:1] NoOp("IAX2/privqueue-15060", "CALL TO AGENT 7390") in new stack [Apr 25 09:19:48] VERBOSE[4184] pbx.c: -- Executing [7390@dundip2p:2] Dial("IAX2/privqueue-15060", "SIP/7390") in new stack [Apr 25 09:19:48] VERBOSE[4184] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 09:19:48] VERBOSE[4184] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 09:19:48] VERBOSE[4184] app_dial.c: -- Called SIP/7390 [Apr 25 09:19:48] VERBOSE[4184] app_dial.c: -- SIP/7390-00001956 is ringing [Apr 25 09:19:48] VERBOSE[4184] app_dial.c: -- SIP/7390-00001956 answered IAX2/privqueue-15060 *** No more calls going through [Apr 25 09:19:52] VERBOSE[4189] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7341 [Apr 25 09:19:52] VERBOSE[4189] pbx.c: -- Executing [7341@MemberConnector:3] ExecIf("Local/7341@MemberConnector-2193;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7341,,tTwW)") in new stack [Apr 25 09:19:52] VERBOSE[4190] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7341 [Apr 25 09:19:52] VERBOSE[4189] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7341 [Apr 25 09:19:52] VERBOSE[20303] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:52] VERBOSE[20303] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:52] VERBOSE[20309] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:52] VERBOSE[20309] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:52] VERBOSE[4189] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-6581' [Apr 25 09:19:52] VERBOSE[4189] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0) *** More 7366 [Apr 25 09:19:54] VERBOSE[4192] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:19:54] VERBOSE[20303] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:19:54] VERBOSE[20303] chan_iax2.c: -- Format for call is alaw [Apr 25 09:19:54] VERBOSE[4192] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-12097' [Apr 25 09:19:54] VERBOSE[4192] app_dial.c: -- No one is available to answer at this time (1:0/0/0) *** Still 7366 [Apr 25 09:20:08] VERBOSE[4407] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:08] VERBOSE[20301] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:08] VERBOSE[20301] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:08] VERBOSE[4407] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-5903' *** Weird behaviour? [Apr 25 09:20:07] VERBOSE[3947] pbx.c: -- Executing [7510@macro-fila:1] Queue("DAHDI/133-1", "MC_Propria,w,,beep,1000") in new stack [Apr 25 09:20:07] VERBOSE[3331] pbx.c: == Spawn extension (ura-7610, 7610, 11) exited non-zero on 'DAHDI/28-1' [Apr 25 09:20:07] VERBOSE[3331] pbx.c: -- Executing [h@ura-7610:1] ExecIf("DAHDI/28-1", "0?Set(URA_INSERT_LOG()=7610,1140435076,"Cliente desligou","",pabx02-1335356272.25227,1335356272)") in new stack [Apr 25 09:20:07] VERBOSE[3331] chan_dahdi.c: -- Hungup 'DAHDI/28-1' [Apr 25 09:20:07] VERBOSE[3947] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/133-1 [Apr 25 09:20:08] VERBOSE[4406] manager.c: == Manager 'pbxmanager_pause' logged on from 132.6.200.72 [Apr 25 09:20:08] VERBOSE[4406] manager.c: == Manager 'pbxmanager_pause' logged off from 132.6.200.72 [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-d877;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-d877;2", "1?Verbose(2, CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:08] VERBOSE[4407] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-d877;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:08] VERBOSE[4407] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:08] VERBOSE[20301] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:08] VERBOSE[20301] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:08] VERBOSE[4407] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-5903' [Apr 25 09:20:08] VERBOSE[4407] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:4] ExecIf("Local/7366@MemberConnector-d877;2", "0?Verbose(2, CONNECTING "1128195002" <1128195002> TO LOCAL REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:5] ExecIf("Local/7366@MemberConnector-d877;2", "0?Dial(SIP/7366)") in new stack [Apr 25 09:20:08] VERBOSE[4407] pbx.c: -- Executing [7366@MemberConnector:6] Hangup("Local/7366@MemberConnector-d877;2", "") in new stack [Apr 25 09:20:08] VERBOSE[4407] pbx.c: == Spawn extension (MemberConnector, 7366, 6) exited non-zero on 'Local/7366@MemberConnector-d877;2' [Apr 25 09:20:08] VERBOSE[2916] app_queue.c: -- Nobody picked up in 0 ms [Apr 25 09:20:08] VERBOSE[2438] chan_dahdi.c: MFC/R2 call disconnected on channel 67 [Apr 25 09:20:08] VERBOSE[2438] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-4797' [Apr 25 09:20:08] VERBOSE[2438] app_macro.c: == Spawn extension (macro-fila, 7520, 1) exited non-zero on 'DAHDI/67-1' in macro 'fila' [Apr 25 09:20:08] VERBOSE[2438] app_macro.c: == Spawn extension (macro-check_tipo_urafila, s, 10) exited non-zero on 'DAHDI/67-1' in macro 'check_tipo_urafila' [Apr 25 09:20:08] VERBOSE[2438] pbx.c: == Spawn extension (p2p, 7520, 16) exited non-zero on 'DAHDI/67-1' [Apr 25 09:20:08] VERBOSE[2438] chan_dahdi.c: MFC/R2 call end on channel 67 [Apr 25 09:20:08] VERBOSE[2438] chan_dahdi.c: -- Hungup 'DAHDI/67-1' [Apr 25 09:20:08] VERBOSE[2846] app_mixmonitor.c: == MixMonitor close filestream [Apr 25 09:20:08] VERBOSE[2846] app_mixmonitor.c: == End MixMonitor Recording DAHDI/67-1 [Apr 25 09:20:09] VERBOSE[4332] pbx.c: -- Executing [s@pausa:1] ExecIf("SIP/7318-00001958", "1?Set(PAUSE_RET=removePause)") in new stack [Apr 25 09:20:09] VERBOSE[4332] pbx.c: -- Executing [s@pausa:2] ExecIf("SIP/7318-00001958", "1?NoOp(http://132.6.200.72/pbx/ModBCC/Acionar_Pausa?r=7318&p=3&cli=yes)") in new stack [Apr 25 09:20:09] VERBOSE[4332] pbx.c: -- Executing [s@pausa:3] ExecIf("SIP/7318-00001958", "0?Playback(pbx/pbx-cc-misslogin)") in new stack [Apr 25 09:20:09] VERBOSE[4332] pbx.c: -- Executing [s@pausa:4] ExecIf("SIP/7318-00001958", "0?Busy(2)") in new stack [Apr 25 09:20:09] VERBOSE[4332] pbx.c: -- Executing [s@pausa:5] ExecIf("SIP/7318-00001958", "1?Playback(pbx/pbx-cc-pausa-removida)") in new stack [Apr 25 09:20:09] VERBOSE[4332] file.c: -- Playing 'pbx/pbx-cc-pausa-removida.gsm' (language 'br') [Apr 25 09:20:09] NOTICE[4332] channel.c: Dropping incompatible voice frame on SIP/7318-00001958 of format alaw since our native format has changed to 0x4 (ulaw) [Apr 25 09:20:09] VERBOSE[4178] chan_dahdi.c: MFC/R2 call disconnected on channel 3 [Apr 25 09:20:09] VERBOSE[4178] pbx.c: == Spawn extension (ura-7610, 7610, 6) exited non-zero on 'DAHDI/3-1' [Apr 25 09:20:09] VERBOSE[4178] pbx.c: -- Executing [h@ura-7610:1] ExecIf("DAHDI/3-1", "1?Set(URA_INSERT_LOG()=7610,1125117554,"Cliente desligou","",pabx02-1335356385.25794,1335356385)") in new stack [Apr 25 09:20:09] VERBOSE[4178] chan_dahdi.c: MFC/R2 call end on channel 3 [Apr 25 09:20:09] VERBOSE[4178] chan_dahdi.c: -- Hungup 'DAHDI/3-1' [Apr 25 09:20:10] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7226 [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-301a;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-301a;2", "1?Verbose(2, CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:10] VERBOSE[4417] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-301a;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:10] VERBOSE[4417] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:10] VERBOSE[20308] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:10] VERBOSE[20308] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:10] VERBOSE[4417] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-3319' [Apr 25 09:20:10] VERBOSE[4417] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:4] ExecIf("Local/7366@MemberConnector-301a;2", "0?Verbose(2, CONNECTING "1128195002" <1128195002> TO LOCAL REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:5] ExecIf("Local/7366@MemberConnector-301a;2", "0?Dial(SIP/7366)") in new stack [Apr 25 09:20:10] VERBOSE[4417] pbx.c: -- Executing [7366@MemberConnector:6] Hangup("Local/7366@MemberConnector-301a;2", "") in new stack [Apr 25 09:20:10] VERBOSE[4417] pbx.c: == Spawn extension (MemberConnector, 7366, 6) exited non-zero on 'Local/7366@MemberConnector-301a;2' [Apr 25 09:20:10] VERBOSE[2916] app_queue.c: -- Nobody picked up in 1000 ms [Apr 25 09:20:11] VERBOSE[20291] chan_dahdi.c: New MFC/R2 call detected on chan 192. [Apr 25 09:20:11] VERBOSE[4332] pbx.c: -- Executing [s@pausa:6] ExecIf("SIP/7318-00001958", "0?Playback(pbx/pbx-cc-pausa-sucesso)") in new stack [Apr 25 09:20:11] VERBOSE[4332] pbx.c: -- Executing [s@pausa:7] ExecIf("SIP/7318-00001958", "1?Busy(2)") in new stack [Apr 25 09:20:11] VERBOSE[20293] chan_dahdi.c: New MFC/R2 call detected on chan 211. [Apr 25 09:20:11] DTMF[4330] channel.c: DTMF begin '1' received on DAHDI/69-1 [Apr 25 09:20:11] DTMF[4330] channel.c: DTMF begin ignored '1' on DAHDI/69-1 [Apr 25 09:20:12] VERBOSE[4451] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-0bc0;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:12] VERBOSE[4451] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-0bc0;2", "1?Verbose(2, CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:12] VERBOSE[4457] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-373e;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:12] VERBOSE[4454] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-38ba;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:12] VERBOSE[4454] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-38ba;2", "1?Verbose(2, CONNECTING "1130372900" <1130372900> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:12] VERBOSE[4457] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-373e;2", "1?Verbose(2, CONNECTING "1166959422" <1166959422> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:12] VERBOSE[4451] app_verbose.c: == CONNECTING "1128195002" <1128195002> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:12] VERBOSE[4451] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-0bc0;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4454] app_verbose.c: == CONNECTING "1130372900" <1130372900> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:12] VERBOSE[4454] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-38ba;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4460] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-51d3;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:12] VERBOSE[4457] app_verbose.c: == CONNECTING "1166959422" <1166959422> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:12] VERBOSE[4459] pbx.c: -- Executing [7366@MemberConnector:1] Set("Local/7366@MemberConnector-5924;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7366") in new stack [Apr 25 09:20:12] VERBOSE[4457] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-373e;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4454] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:12] VERBOSE[4460] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-51d3;2", "1?Verbose(2, CONNECTING "1132071448" <1132071448> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:12] VERBOSE[4460] app_verbose.c: == CONNECTING "1132071448" <1132071448> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:12] VERBOSE[4460] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-51d3;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4459] pbx.c: -- Executing [7366@MemberConnector:2] ExecIf("Local/7366@MemberConnector-5924;2", "1?Verbose(2, CONNECTING "1136876147" <1136876147> TO REMOTE REGISTERED AGENT AT 7366)") in new stack [Apr 25 09:20:12] VERBOSE[4459] app_verbose.c: == CONNECTING "1136876147" <1136876147> TO REMOTE REGISTERED AGENT AT 7366 [Apr 25 09:20:12] VERBOSE[4459] pbx.c: -- Executing [7366@MemberConnector:3] ExecIf("Local/7366@MemberConnector-5924;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7366,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4457] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:12] VERBOSE[4464] pbx.c: -- Executing [7377@MemberConnector:1] Set("Local/7377@MemberConnector-3e9a;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7377") in new stack [Apr 25 09:20:12] VERBOSE[4459] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:12] VERBOSE[4464] pbx.c: -- Executing [7377@MemberConnector:2] ExecIf("Local/7377@MemberConnector-3e9a;2", "1?Verbose(2, CONNECTING "1127355705" <1127355705> TO REMOTE REGISTERED AGENT AT 7377)") in new stack [Apr 25 09:20:12] VERBOSE[4464] app_verbose.c: == CONNECTING "1127355705" <1127355705> TO REMOTE REGISTERED AGENT AT 7377 [Apr 25 09:20:12] VERBOSE[4464] pbx.c: -- Executing [7377@MemberConnector:3] ExecIf("Local/7377@MemberConnector-3e9a;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7377,,tTwW)") in new stack [Apr 25 09:20:12] VERBOSE[4460] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:12] VERBOSE[4464] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7377 [Apr 25 09:20:12] VERBOSE[4451] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7366 [Apr 25 09:20:12] WARNING[20303] chan_iax2.c: Call rejected by 132.6.200.70: Unauthenticated call limit reached [Apr 25 09:20:12] VERBOSE[4460] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-9547' [Apr 25 09:20:12] VERBOSE[4460] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1) *** From now on [Apr 25 09:20:12] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:12] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:12] VERBOSE[20305] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:12] VERBOSE[20305] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:12] VERBOSE[20306] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:12] VERBOSE[20306] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:12] VERBOSE[20303] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:12] VERBOSE[20303] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:12] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:12] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:12] VERBOSE[4451] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-2922' [Apr 25 09:20:12] VERBOSE[4451] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0) *** Incoming calls still working using IAX2 [Apr 25 09:20:15] VERBOSE[20310] chan_iax2.c: -- Accepting AUTHENTICATED call from 132.6.200.70: > requested format = alaw, > requested prefs = (), > actual format = alaw, > host prefs = (alaw|ulaw), > priority = mine [Apr 25 09:20:15] VERBOSE[4494] pbx.c: -- Executing [7318@incomingdundi_queue:1] Goto("IAX2/privqueue-10247", "dundip2p,7318,1") in new stack [Apr 25 09:20:15] VERBOSE[4494] pbx.c: -- Goto (dundip2p,7318,1) [Apr 25 09:20:15] VERBOSE[4494] pbx.c: -- Executing [7318@dundip2p:1] NoOp("IAX2/privqueue-10247", "CALL TO AGENT 7318") in new stack [Apr 25 09:20:15] VERBOSE[4494] pbx.c: -- Executing [7318@dundip2p:2] Dial("IAX2/privqueue-10247", "SIP/7318") in new stack [Apr 25 09:20:15] VERBOSE[4494] netsock2.c: == Using SIP RTP TOS bits 184 [Apr 25 09:20:15] VERBOSE[4494] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 25 09:20:15] VERBOSE[4494] app_dial.c: -- Called SIP/7318 [Apr 25 09:20:15] VERBOSE[4494] app_dial.c: -- SIP/7318-0000195a is ringing *** Wait, This dial also worked [Apr 25 09:20:18] VERBOSE[4515] pbx.c: -- Executing [7351@MemberConnector:1] Set("Local/7351@MemberConnector-5f2b;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7351") in new stack [Apr 25 09:20:18] VERBOSE[4515] pbx.c: -- Executing [7351@MemberConnector:2] ExecIf("Local/7351@MemberConnector-5f2b;2", "1?Verbose(2, CONNECTING "1136876147" <1136876147> TO REMOTE REGISTERED AGENT AT 7351)") in new stack [Apr 25 09:20:18] VERBOSE[4515] app_verbose.c: == CONNECTING "1136876147" <1136876147> TO REMOTE REGISTERED AGENT AT 7351 [Apr 25 09:20:18] VERBOSE[4515] pbx.c: -- Executing [7351@MemberConnector:3] ExecIf("Local/7351@MemberConnector-5f2b;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7351,,tTwW)") in new stack [Apr 25 09:20:18] VERBOSE[4515] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7351 [Apr 25 09:20:18] VERBOSE[20310] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:18] VERBOSE[20310] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:18] VERBOSE[4515] app_dial.c: -- IAX2/132.6.200.70:4569-4292 is ringing [Apr 25 09:20:18] VERBOSE[3700] app_queue.c: -- Local/7351@MemberConnector-5f2b;1 is ringing [Apr 25 09:20:18] VERBOSE[4515] app_dial.c: -- IAX2/132.6.200.70:4569-4292 answered Local/7351@MemberConnector-5f2b;2 *** Another one [Apr 25 09:20:24] VERBOSE[4562] app_verbose.c: == CONNECTING "1132071448" <1132071448> TO REMOTE REGISTERED AGENT AT 7346 [Apr 25 09:20:24] VERBOSE[4562] pbx.c: -- Executing [7346@MemberConnector:3] ExecIf("Local/7346@MemberConnector-13eb;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7346,,tTwW)") in new stack [Apr 25 09:20:24] VERBOSE[4562] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7346 [Apr 25 09:20:24] VERBOSE[20305] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:20:24] VERBOSE[20305] chan_iax2.c: -- Format for call is alaw [Apr 25 09:20:24] VERBOSE[4562] app_dial.c: -- IAX2/132.6.200.70:4569-783 is ringing [Apr 25 09:20:24] VERBOSE[3695] app_queue.c: -- Local/7346@MemberConnector-13eb;1 is ringing [Apr 25 09:20:24] VERBOSE[20290] chan_dahdi.c: New MFC/R2 call detected on chan 185. [Apr 25 09:20:24] VERBOSE[20283] chan_dahdi.c: MFC/R2 call offered on chan 113. ANI = 1143068141, DNIS = 7000, Category = National Subscriber [Apr 25 09:20:25] VERBOSE[20284] chan_dahdi.c: MFC/R2 call end on channel 121 [Apr 25 09:20:25] VERBOSE[4562] app_dial.c: -- IAX2/132.6.200.70:4569-783 answered Local/7346@MemberConnector-13eb;2 [Apr 25 09:21:11] VERBOSE[4828] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7237 [Apr 25 09:21:11] VERBOSE[20301] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 25 09:21:11] VERBOSE[20301] chan_iax2.c: -- Format for call is alaw [Apr 25 09:21:11] VERBOSE[4112] res_musiconhold.c: -- Stopped music on hold on DAHDI/197-1 [Apr 25 09:21:11] VERBOSE[4112] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/197-1 [Apr 25 09:21:12] VERBOSE[4828] app_dial.c: -- IAX2/132.6.200.70:4569-4647 is ringing [Apr 25 09:21:12] VERBOSE[4491] app_queue.c: -- Local/7237@MemberConnector-e68d;1 is ringing *** For the rest of Apr 25 seemed to work fine - customer didn't report any issue *** Apr 26 - the day that the customer reported the issue *** Operation started 7.00am [Apr 26 07:04:22] VERBOSE[6094] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7461 [Apr 26 07:04:22] VERBOSE[20308] chan_iax2.c: -- Call accepted by 132.6.200.70 (format alaw) [Apr 26 07:04:22] VERBOSE[20308] chan_iax2.c: -- Format for call is alaw [Apr 26 07:04:22] VERBOSE[6094] app_dial.c: -- IAX2/132.6.200.70:4569-1399 is ringing [Apr 26 07:04:22] VERBOSE[5734] app_queue.c: -- Local/7461@MemberConnector-f772;1 is ringing [Apr 26 09:53:59] VERBOSE[5243] pbx.c: -- Executing [7520@macro-fila:1] Queue("DAHDI/116-1", "Aut_Guias_Ex,w,,beep,1000") in new stack [Apr 26 09:53:59] VERBOSE[5243] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/116-1 [Apr 26 09:53:59] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:1] Set("Local/7425@MemberConnector-bf39;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7425") in new stack [Apr 26 09:53:59] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:2] ExecIf("Local/7425@MemberConnector-bf39;2", "1?Verbose(2, CONNECTING "1178072682" <1178072682> TO REMOTE REGISTERED AGENT AT 7425)") in new stack [Apr 26 09:53:59] VERBOSE[5387] app_verbose.c: == CONNECTING "1178072682" <1178072682> TO REMOTE REGISTERED AGENT AT 7425 [Apr 26 09:53:59] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:3] ExecIf("Local/7425@MemberConnector-bf39;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7425,,tTwW)") in new stack [Apr 26 09:53:59] VERBOSE[5387] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7425 [Apr 26 09:53:59] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7331 [Apr 26 09:54:00] VERBOSE[4153] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/211-1 [Apr 26 09:54:00] VERBOSE[484] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-15462' [Apr 26 09:54:00] VERBOSE[484] app_macro.c: == Spawn extension (macro-fila, 7510, 1) exited non-zero on 'DAHDI/79-1' in macro 'fila' [Apr 26 09:54:00] VERBOSE[484] app_macro.c: == Spawn extension (macro-check_tipo_urafila, s, 10) exited non-zero on 'DAHDI/79-1' in macro 'check_tipo_urafila' [Apr 26 09:54:00] VERBOSE[484] pbx.c: == Spawn extension (p2p, 7510, 16) exited non-zero on 'DAHDI/79-1' [Apr 26 09:54:00] VERBOSE[484] chan_dahdi.c: -- Hungup 'DAHDI/79-1' [Apr 26 09:54:00] VERBOSE[1114] app_mixmonitor.c: == MixMonitor close filestream [Apr 26 09:54:00] VERBOSE[1114] app_mixmonitor.c: == End MixMonitor Recording DAHDI/79-1 [Apr 26 09:54:00] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:1] Set("Local/7407@MemberConnector-7191;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7407") in new stack [Apr 26 09:54:00] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:2] ExecIf("Local/7407@MemberConnector-7191;2", "1?Verbose(2, CONNECTING "1125835381" <1125835381> TO REMOTE REGISTERED AGENT AT 7407)") in new stack [Apr 26 09:54:00] VERBOSE[5388] app_verbose.c: == CONNECTING "1125835381" <1125835381> TO REMOTE REGISTERED AGENT AT 7407 [Apr 26 09:54:00] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:3] ExecIf("Local/7407@MemberConnector-7191;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7407,,tTwW)") in new stack [Apr 26 09:54:00] VERBOSE[5388] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7407 [Apr 26 09:54:00] VERBOSE[4601] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/80-1 [Apr 26 09:54:01] VERBOSE[20260] asterisk.c: -- Remote UNIX connection [Apr 26 09:54:01] VERBOSE[5404] asterisk.c: -- Remote UNIX connection disconnected [Apr 26 09:54:01] VERBOSE[20260] asterisk.c: -- Remote UNIX connection [Apr 26 09:54:01] VERBOSE[5406] asterisk.c: -- Remote UNIX connection disconnected [Apr 26 09:54:01] VERBOSE[5415] pbx.c: -- Executing [7407@MemberConnector:1] Set("Local/7407@MemberConnector-78d9;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7407") in new stack [Apr 26 09:54:01] VERBOSE[5415] pbx.c: -- Executing [7407@MemberConnector:2] ExecIf("Local/7407@MemberConnector-78d9;2", "1?Verbose(2, CONNECTING "1143624466" <1143624466> TO REMOTE REGISTERED AGENT AT 7407)") in new stack [Apr 26 09:54:01] VERBOSE[5415] app_verbose.c: == CONNECTING "1143624466" <1143624466> TO REMOTE REGISTERED AGENT AT 7407 [Apr 26 09:54:01] VERBOSE[5415] pbx.c: -- Executing [7407@MemberConnector:3] ExecIf("Local/7407@MemberConnector-78d9;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7407,,tTwW)") in new stack [Apr 26 09:54:01] VERBOSE[5415] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7407 [Apr 26 09:54:01] VERBOSE[5419] manager.c: == HTTP Manager 'pbxmanager_ajam' logged on from 132.6.200.71 [Apr 26 09:54:01] VERBOSE[5421] manager.c: == HTTP Manager 'pbxmanager_ajam' logged off from 132.6.200.71 [Apr 26 09:54:01] VERBOSE[20260] asterisk.c: -- Remote UNIX connection [Apr 26 09:54:01] VERBOSE[5426] asterisk.c: -- Remote UNIX connection disconnected [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:1] Set("Local/7407@MemberConnector-96f4;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7407") in new stack [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:2] ExecIf("Local/7407@MemberConnector-96f4;2", "1?Verbose(2, CONNECTING "1127410584" <1127410584> TO REMOTE REGISTERED AGENT AT 7407)") in new stack [Apr 26 09:54:01] VERBOSE[5427] app_verbose.c: == CONNECTING "1127410584" <1127410584> TO REMOTE REGISTERED AGENT AT 7407 [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:3] ExecIf("Local/7407@MemberConnector-96f4;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7407,,tTwW)") in new stack [Apr 26 09:54:01] VERBOSE[5427] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7407 [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:1] Set("Local/7407@MemberConnector-96f4;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7407") in new stack [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:2] ExecIf("Local/7407@MemberConnector-96f4;2", "1?Verbose(2, CONNECTING "1127410584" <1127410584> TO REMOTE REGISTERED AGENT AT 7407)") in new stack [Apr 26 09:54:01] VERBOSE[5427] app_verbose.c: == CONNECTING "1127410584" <1127410584> TO REMOTE REGISTERED AGENT AT 7407 [Apr 26 09:54:01] VERBOSE[5427] pbx.c: -- Executing [7407@MemberConnector:3] ExecIf("Local/7407@MemberConnector-96f4;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7407,,tTwW)") in new stack [Apr 26 09:54:01] VERBOSE[5427] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7407 [Apr 26 09:54:01] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7315 [Apr 26 09:54:01] VERBOSE[20291] chan_dahdi.c: New MFC/R2 call detected on chan 191. [Apr 26 09:54:01] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7353 [Apr 26 09:54:02] DTMF[5354] channel.c: DTMF begin '1' received on DAHDI/139-1 [Apr 26 09:54:02] DTMF[5354] channel.c: DTMF begin ignored '1' on DAHDI/139-1 [Apr 26 09:54:02] DTMF[5354] channel.c: DTMF end '1' received on DAHDI/139-1, duration 25 ms [Apr 26 09:54:02] DTMF[5354] channel.c: DTMF end passthrough '1' on DAHDI/139-1 [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [1@ura-7610:1] Set("DAHDI/139-1", "LOGGED=true") in new stack [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [1@ura-7610:2] Set("DAHDI/139-1", "URA_INSERT_LOG()=7610,1159290296,"7510","1",pabx02-1335444815.239434,1335444815") in new stack [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [1@ura-7610:3] Goto("DAHDI/139-1", "p2p,7510,1") in new stack [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Goto (p2p,7510,1) [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [7510@p2p:1] Set("DAHDI/139-1", "dstconf=7510") in new stack [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [7510@p2p:2] Set("DAHDI/139-1", "RAMALINEXISTENTE=7510") in new stack [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Goto (macro-fila,7510,1) [Apr 26 09:54:02] VERBOSE[5354] pbx.c: -- Executing [7510@macro-fila:1] Queue("DAHDI/139-1", "MC_Propria,w,,beep,1000") in new stack [Apr 26 09:54:02] VERBOSE[5354] res_musiconhold.c: -- Started music on hold, class 'GL7000', on DAHDI/139-1 [Apr 26 09:54:02] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7417 [Apr 26 09:54:02] VERBOSE[5055] pbx.c: -- Executing [7610@ura-7610:7] WaitExten("DAHDI/23-1", "3") in new stack [Apr 26 09:54:03] NOTICE[20307] chan_iax2.c: Auto-congesting call due to slow response [Apr 26 09:54:03] VERBOSE[5387] app_dial.c: -- IAX2/132.6.200.70:4569-7525 is circuit-busy [Apr 26 09:54:03] VERBOSE[5387] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-7525' [Apr 26 09:54:03] VERBOSE[5387] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 09:54:03] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:4] ExecIf("Local/7425@MemberConnector-bf39;2", "0?Verbose(2, CONNECTING "1178072682" <1178072682> TO LOCAL REGISTERED AGENT AT 7425)") in new stack [Apr 26 09:54:03] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:5] ExecIf("Local/7425@MemberConnector-bf39;2", "0?Dial(SIP/7425)") in new stack [Apr 26 09:54:03] VERBOSE[5387] pbx.c: -- Executing [7425@MemberConnector:6] Hangup("Local/7425@MemberConnector-bf39;2", "") in new stack [Apr 26 09:54:03] VERBOSE[5387] pbx.c: == Spawn extension (MemberConnector, 7425, 6) exited non-zero on 'Local/7425@MemberConnector-bf39;2' [Apr 26 09:54:03] VERBOSE[5243] app_queue.c: -- Nobody picked up in 4000 ms [Apr 26 09:54:03] VERBOSE[5528] pbx.c: -- Executing [7415@MemberConnector:1] Set("Local/7415@MemberConnector-42a5;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7415") in new stack [Apr 26 09:54:03] VERBOSE[5528] pbx.c: -- Executing [7415@MemberConnector:2] ExecIf("Local/7415@MemberConnector-42a5;2", "1?Verbose(2, CONNECTING "1178072682" <1178072682> TO REMOTE REGISTERED AGENT AT 7415)") in new stack [Apr 26 09:54:03] VERBOSE[5528] app_verbose.c: == CONNECTING "1178072682" <1178072682> TO REMOTE REGISTERED AGENT AT 7415 [Apr 26 09:54:03] VERBOSE[5528] pbx.c: -- Executing [7415@MemberConnector:3] ExecIf("Local/7415@MemberConnector-42a5;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7415,,tTwW)") in new stack [Apr 26 09:54:03] VERBOSE[5528] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7415 [Apr 26 09:54:04] VERBOSE[5388] app_dial.c: -- IAX2/132.6.200.70:4569-11967 is circuit-busy [Apr 26 09:54:04] NOTICE[5379] chan_iax2.c: Auto-congesting call due to slow response [Apr 26 09:54:04] VERBOSE[5388] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-11967' [Apr 26 09:54:04] VERBOSE[5388] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 09:54:04] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:4] ExecIf("Local/7407@MemberConnector-7191;2", "0?Verbose(2, CONNECTING "1125835381" <1125835381> TO LOCAL REGISTERED AGENT AT 7407)") in new stack [Apr 26 09:54:04] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:5] ExecIf("Local/7407@MemberConnector-7191;2", "0?Dial(SIP/7407)") in new stack [Apr 26 09:54:04] VERBOSE[5388] pbx.c: -- Executing [7407@MemberConnector:6] Hangup("Local/7407@MemberConnector-7191;2", "") in new stack [Apr 26 09:54:04] VERBOSE[5388] pbx.c: == Spawn extension (MemberConnector, 7407, 6) exited non-zero on 'Local/7407@MemberConnector-7191;2' [Apr 26 09:54:04] VERBOSE[4535] app_queue.c: -- Nobody picked up in 4000 ms [Apr 26 09:54:05] DTMF[5255] channel.c: DTMF begin '1' received on DAHDI/59-1 [Apr 26 09:54:05] DTMF[5255] channel.c: DTMF begin ignored '1' on DAHDI/59-1 [Apr 26 09:54:05] NOTICE[20298] chan_sip.c: Received SIP subscribe for peer without mailbox: 7327 [Apr 26 09:54:05] NOTICE[20307] chan_iax2.c: Auto-congesting call due to slow response [Apr 26 09:54:05] VERBOSE[5415] app_dial.c: -- IAX2/132.6.200.70:4569-10490 is circuit-busy [Apr 26 09:54:05] VERBOSE[5415] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-10490' [Apr 26 09:54:05] VERBOSE[5415] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 09:54:39] VERBOSE[4153] app_queue.c: -- Nobody picked up in 0 ms [Apr 26 09:54:39] VERBOSE[5703] app_dial.c: -- IAX2/132.6.200.70:4569-2139 is circuit-busy [Apr 26 09:54:39] NOTICE[20307] chan_iax2.c: Auto-congesting call due to slow response [Apr 26 09:54:39] VERBOSE[5703] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-2139' [Apr 26 09:54:39] VERBOSE[5703] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 09:54:40] VERBOSE[5704] app_dial.c: -- IAX2/132.6.200.70:4569-11294 is circuit-busy [Apr 26 09:54:40] NOTICE[20307] chan_iax2.c: Auto-congesting call due to slow response [Apr 26 09:54:40] VERBOSE[5704] chan_iax2.c: -- Hungup 'IAX2/132.6.200.70:4569-11294' [Apr 26 09:54:40] VERBOSE[5704] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) *** limit reached [Apr 26 10:43:38] VERBOSE[10954] pbx.c: -- Executing [7451@MemberConnector:3] ExecIf("Local/7451@MemberConnector-2b5b;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7451,,tTwW)") in new stack [Apr 26 10:43:38] VERBOSE[10954] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7451 [Apr 26 10:43:38] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.6:32806 [Apr 26 10:43:38] VERBOSE[10948] app_dial.c: -- SIP/7306-00009fe4 is circuit-busy [Apr 26 10:43:38] VERBOSE[10948] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 10:43:38] VERBOSE[10948] pbx.c: -- Executing [7306@MemberConnector:6] Hangup("Local/7306@MemberConnector-cf1b;2", "") in new stack [Apr 26 10:43:38] VERBOSE[10948] pbx.c: == Spawn extension (MemberConnector, 7306, 6) exited non-zero on 'Local/7306@MemberConnector-cf1b;2' [Apr 26 10:43:38] VERBOSE[8323] app_queue.c: -- Nobody picked up in 0 ms [Apr 26 10:43:38] VERBOSE[20298] chan_sip.c: -- Got SIP response 480 "Temporarily Unavailable" back from 132.6.140.17:37410 [Apr 26 10:43:38] VERBOSE[10949] app_dial.c: -- SIP/7317-00009fe5 is circuit-busy [Apr 26 10:43:38] VERBOSE[10949] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Apr 26 10:43:38] VERBOSE[10949] pbx.c: -- Executing [7317@MemberConnector:6] Hangup("Local/7317@MemberConnector-4ed0;2", "") in new stack [Apr 26 10:43:38] VERBOSE[10949] pbx.c: == Spawn extension (MemberConnector, 7317, 6) exited non-zero on 'Local/7317@MemberConnector-4ed0;2' [Apr 26 10:43:38] VERBOSE[7701] app_queue.c: -- Nobody picked up in 0 ms [Apr 26 10:43:38] VERBOSE[10955] pbx.c: -- Executing [7381@MemberConnector:1] Set("Local/7381@MemberConnector-b487;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7381") in new stack [Apr 26 10:43:38] VERBOSE[10955] pbx.c: -- Executing [7381@MemberConnector:2] ExecIf("Local/7381@MemberConnector-b487;2", "1?Verbose(2, CONNECTING "1135993420" <1135993420> TO REMOTE REGISTERED AGENT AT 7381)") in new stack [Apr 26 10:43:38] VERBOSE[10955] app_verbose.c: == CONNECTING "1135993420" <1135993420> TO REMOTE REGISTERED AGENT AT 7381 [Apr 26 10:43:38] VERBOSE[10955] pbx.c: -- Executing [7381@MemberConnector:3] ExecIf("Local/7381@MemberConnector-b487;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7381,,tTwW)") in new stack [Apr 26 10:43:38] VERBOSE[10955] app_dial.c: -- Called IAX2/privqueue:pbxcluster@132.6.200.70/7381 [Apr 26 10:43:38] VERBOSE[10956] pbx.c: -- Executing [7303@MemberConnector:1] Set("Local/7303@MemberConnector-ea77;2", "pbxCLUSTER=IAX2/privqueue:pbxcluster@132.6.200.70/7303") in new stack [Apr 26 10:43:38] VERBOSE[10956] pbx.c: -- Executing [7303@MemberConnector:2] ExecIf("Local/7303@MemberConnector-ea77;2", "1?Verbose(2, CONNECTING "1129419317" <1129419317> TO REMOTE REGISTERED AGENT AT 7303)") in new stack [Apr 26 10:43:38] VERBOSE[10956] app_verbose.c: == CONNECTING "1129419317" <1129419317> TO REMOTE REGISTERED AGENT AT 7303 [Apr 26 10:43:38] VERBOSE[10956] pbx.c: -- Executing [7303@MemberConnector:3] ExecIf("Local/7303@MemberConnector-ea77;2", "1?Dial(IAX2/privqueue:pbxcluster@132.6.200.70/7303,,tTwW)") in new stack [Apr 26 10:43:38] ERROR[10956] chan_iax2.c: maxcallnumber limit of 2048 for 132.6.200.70 has been reached! [Apr 26 10:43:38] WARNING[10956] chan_iax2.c: Unable to create call [Apr 26 10:43:38] WARNING[10956] app_dial.c: Unable to create channel of type 'IAX2' (cause 34 - Circuit/channel congestion) [Apr 26 10:43:38] VERBOSE[10956] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)