Jul 6 12:19:13 VERBOSE[24215] logger.c: Asterisk Ready. Jul 6 12:19:24 DEBUG[24251] chan_sip.c: Stopping retransmission on '41c478c65f2470a060e148132982958e@200.196.44.45' of Request 102: Match Found Jul 6 12:19:48 DEBUG[24248] db.c: Unable to find key 'si-000fd3000a41' in family 'iax/provisioning/cache' Jul 6 12:19:48 DEBUG[24248] iax2-provision.c: Unable to create provisioning packet for 'si-000fd3000a41' Jul 6 12:20:12 DEBUG[24248] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Jul 6 12:20:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:20:12 DEBUG[24248] chan_iax2.c: Peer lastms 2, historicms 2, maxms 2000 Jul 6 12:20:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '2c4b976052b012cf5f93abc9366a282d@200.196.44.45' of Request 102: Match Found Jul 6 12:20:46 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:20:46 DEBUG[24251] chan_sip.c: Stopping retransmission on '872470ba-94a685f0-5e683b6d@172.16.10.112' of Response 1: Match Found Jul 6 12:20:46 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:20:46 DEBUG[24251] chan_sip.c: Checking SIP call limits for device 3053 Jul 6 12:20:46 DEBUG[24251] chan_sip.c: build_route: Contact hop: Jul 6 12:20:46 DEBUG[24310] pbx.c: Function result is '0' Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing NoOp("SIP/3053-09bc3d60", "BEFORE SETGROUP: Group: 3053 Count: 0") in new stack Jul 6 12:20:46 DEBUG[24310] pbx.c: Function result is '0' Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing NoOp("SIP/3053-09bc3d60", "BEFORE SETOUTBOUNDGROUP: Group: 3052 Count: 0") in new stack Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing Set("SIP/3053-09bc3d60", "GROUP=3053") in new stack Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing Set("SIP/3053-09bc3d60", "OUTBOUND_GROUP=3052") in new stack Jul 6 12:20:46 DEBUG[24310] pbx.c: Function result is '1' Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing NoOp("SIP/3053-09bc3d60", "AFTER SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:20:46 DEBUG[24310] pbx.c: Function result is '0' Jul 6 12:20:46 DEBUG[24310] pbx.c: Expression result is '0' Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing GotoIf("SIP/3053-09bc3d60", "0 ? 1000") in new stack Jul 6 12:20:46 DEBUG[24310] pbx.c: Not taking any branch Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Executing Dial("SIP/3053-09bc3d60", "SIP/3052|15|tT") in new stack Jul 6 12:20:46 DEBUG[24310] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:20:46 DEBUG[24310] chan_sip.c: Outgoing Call for 3052 Jul 6 12:20:46 VERBOSE[24310] logger.c: -- Called 3052 Jul 6 12:20:47 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '6264dfc53813221005357809631f6335@200.196.44.45' Request 102: Found Jul 6 12:20:47 VERBOSE[24310] logger.c: -- SIP/3052-09bc9310 is ringing Jul 6 12:20:48 DEBUG[24251] chan_sip.c: Acked pending invite 102 Jul 6 12:20:48 DEBUG[24251] chan_sip.c: Stopping retransmission on '6264dfc53813221005357809631f6335@200.196.44.45' of Request 102: Match Found Jul 6 12:20:48 DEBUG[24251] chan_sip.c: build_route: Contact hop: Jul 6 12:20:48 VERBOSE[24310] logger.c: -- SIP/3052-09bc9310 answered SIP/3053-09bc3d60 Jul 6 12:20:48 VERBOSE[24310] logger.c: -- Attempting native bridge of SIP/3053-09bc3d60 and SIP/3052-09bc9310 Jul 6 12:20:48 DEBUG[24251] chan_sip.c: Stopping retransmission on '872470ba-94a685f0-5e683b6d@172.16.10.112' of Response 2: Match Found Jul 6 12:20:58 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 524288 Jul 6 12:20:58 DEBUG[24251] chan_sip.c: Stopping retransmission on '4soArYiSulRzbvUQ@172.16.10.124' of Response 1: Match Found Jul 6 12:20:58 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 524288 Jul 6 12:20:58 DEBUG[24251] chan_sip.c: Checking SIP call limits for device 3050 Jul 6 12:20:58 DEBUG[24251] chan_sip.c: build_route: Contact hop: Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'SIP/3050-09bd6700' Jul 6 12:20:58 VERBOSE[24315] logger.c: -- Executing Queue("SIP/3050-09bd6700", "myqueue|tr|||600") in new stack Jul 6 12:20:58 VERBOSE[24315] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:20:58 DEBUG[24316] pbx.c: Function result is '1' Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-56bd,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing Set("Local/3053@queue_to_agent-56bd,2", "GROUP=3053") in new stack Jul 6 12:20:58 DEBUG[24316] pbx.c: Function result is '2' Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-56bd,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:20:58 DEBUG[24316] pbx.c: Function result is '2' Jul 6 12:20:58 DEBUG[24316] pbx.c: Expression result is '1' Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-56bd,2", "1 ? 1000") in new stack Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:20:58 DEBUG[24316] pbx.c: Function result is '2' Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-56bd,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:20:58 VERBOSE[24316] logger.c: -- Executing Busy("Local/3053@queue_to_agent-56bd,2", "") in new stack Jul 6 12:20:58 VERBOSE[24315] logger.c: -- Local/3053@queue_to_agent-56bd,1 is busy Jul 6 12:20:58 VERBOSE[24315] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:20:58 VERBOSE[24316] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 WARNING[24234] channel.c: Avoided initial deadlock for '0x9bd9840', 10 retries! Jul 6 12:20:58 WARNING[24320] channel.c: Avoided deadlock for '0x9bd9840', 10 retries! Jul 6 12:20:58 DEBUG[24320] pbx.c: Function result is '0' Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2f12,2", "BEFORE SETGROUP: Group: 3052 Count: 0") in new stack Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Executing Set("Local/3052@queue_to_agent-2f12,2", "GROUP=3052") in new stack Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 WARNING[24320] channel.c: Avoided deadlock for '0x9bd9840', 10 retries! Jul 6 12:20:58 DEBUG[24320] pbx.c: Function result is '1' Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2f12,2", "AFTER SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 DEBUG[24320] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-56bd,2' Jul 6 12:20:58 WARNING[24320] channel.c: Avoided deadlock for '0x9bd9840', 10 retries! Jul 6 12:20:58 DEBUG[24320] pbx.c: Function result is '1' Jul 6 12:20:58 DEBUG[24320] pbx.c: Expression result is '0' Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-2f12,2", "0 ? 1000") in new stack Jul 6 12:20:58 DEBUG[24320] pbx.c: Not taking any branch Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Executing Dial("Local/3052@queue_to_agent-2f12,2", "SIP/3052|15|T") in new stack Jul 6 12:20:58 DEBUG[24320] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:20:58 DEBUG[24320] chan_sip.c: Outgoing Call for 3052 Jul 6 12:20:58 VERBOSE[24320] logger.c: -- Called 3052 Jul 6 12:20:58 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '28d05fb6319336893350f48142fbbeaf@200.196.44.45' Request 102: Found Jul 6 12:20:58 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '28d05fb6319336893350f48142fbbeaf@200.196.44.45' Request 102: Found Jul 6 12:20:58 VERBOSE[24320] logger.c: -- SIP/3052-09c1c720 is ringing Jul 6 12:20:58 VERBOSE[24315] logger.c: -- Local/3052@queue_to_agent-2f12,1 is ringing Jul 6 12:21:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:21:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:21:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:21:13 VERBOSE[24315] logger.c: -- Nobody picked up in 15000 ms Jul 6 12:21:13 DEBUG[24320] chan_sip.c: update_call_counter(3052) - decrement call limit counter Jul 6 12:21:13 DEBUG[24320] chan_sip.c: Acked pending invite 102 Jul 6 12:21:13 DEBUG[24320] chan_sip.c: Stopping retransmission on '28d05fb6319336893350f48142fbbeaf@200.196.44.45' of Request 102: Match Found Jul 6 12:21:13 DEBUG[24320] app_dial.c: Exiting with DIALSTATUS=CANCEL. Jul 6 12:21:13 VERBOSE[24320] logger.c: == Spawn extension (queue_to_agent, 3052, 5) exited non-zero on 'Local/3052@queue_to_agent-2f12,2' Jul 6 12:21:13 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-2f12,2' Jul 6 12:21:13 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-2f12,2' Jul 6 12:21:13 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-2f12,2' Jul 6 12:21:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '28d05fb6319336893350f48142fbbeaf@200.196.44.45' of Request 102: Match Found Jul 6 12:21:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '28d05fb6319336893350f48142fbbeaf@200.196.44.45' of Request 102: Match Not Found Jul 6 12:21:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '5b151b3f5962686a1c7787bb5aef19ad@200.196.44.45' of Request 102: Match Found Jul 6 12:21:18 VERBOSE[24315] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:21:18 DEBUG[24333] pbx.c: Function result is '1' Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-95a0,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing Set("Local/3053@queue_to_agent-95a0,2", "GROUP=3053") in new stack Jul 6 12:21:18 DEBUG[24333] pbx.c: Function result is '2' Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-95a0,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:21:18 DEBUG[24333] pbx.c: Function result is '2' Jul 6 12:21:18 DEBUG[24333] pbx.c: Expression result is '1' Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-95a0,2", "1 ? 1000") in new stack Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:21:18 DEBUG[24333] pbx.c: Function result is '2' Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-95a0,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:21:18 VERBOSE[24333] logger.c: -- Executing Busy("Local/3053@queue_to_agent-95a0,2", "") in new stack Jul 6 12:21:18 VERBOSE[24315] logger.c: -- Local/3053@queue_to_agent-95a0,1 is busy Jul 6 12:21:18 VERBOSE[24315] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:21:18 VERBOSE[24333] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-95a0,2' Jul 6 12:21:18 DEBUG[24335] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-95a0,2' Jul 6 12:21:18 DEBUG[24335] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-95a0,2' Jul 6 12:21:18 DEBUG[24335] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-95a0,2' Jul 6 12:21:18 DEBUG[24335] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-95a0,2' Jul 6 12:21:18 DEBUG[24335] pbx.c: Function result is '1' Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2aea,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing Set("Local/3052@queue_to_agent-2aea,2", "GROUP=3052") in new stack Jul 6 12:21:18 DEBUG[24335] pbx.c: Function result is '2' Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2aea,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:21:18 DEBUG[24335] pbx.c: Function result is '2' Jul 6 12:21:18 DEBUG[24335] pbx.c: Expression result is '1' Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-2aea,2", "1 ? 1000") in new stack Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:21:18 DEBUG[24335] pbx.c: Function result is '2' Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2aea,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:21:18 VERBOSE[24335] logger.c: -- Executing Busy("Local/3052@queue_to_agent-2aea,2", "") in new stack Jul 6 12:21:18 VERBOSE[24315] logger.c: -- Local/3052@queue_to_agent-2aea,1 is busy Jul 6 12:21:18 DEBUG[24315] app_queue.c: Everyone is busy at this time Jul 6 12:21:18 VERBOSE[24335] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-2aea,2' Jul 6 12:21:23 VERBOSE[24315] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:21:23 DEBUG[24345] pbx.c: Function result is '1' Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-15a4,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing Set("Local/3053@queue_to_agent-15a4,2", "GROUP=3053") in new stack Jul 6 12:21:23 DEBUG[24345] pbx.c: Function result is '2' Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-15a4,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:21:23 DEBUG[24345] pbx.c: Function result is '2' Jul 6 12:21:23 DEBUG[24345] pbx.c: Expression result is '1' Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-15a4,2", "1 ? 1000") in new stack Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:21:23 DEBUG[24345] pbx.c: Function result is '2' Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-15a4,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:21:23 VERBOSE[24345] logger.c: -- Executing Busy("Local/3053@queue_to_agent-15a4,2", "") in new stack Jul 6 12:21:23 VERBOSE[24315] logger.c: -- Local/3053@queue_to_agent-15a4,1 is busy Jul 6 12:21:23 VERBOSE[24315] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:21:23 VERBOSE[24345] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-15a4,2' Jul 6 12:21:23 DEBUG[24347] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-15a4,2' Jul 6 12:21:23 DEBUG[24347] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-15a4,2' Jul 6 12:21:23 DEBUG[24347] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-15a4,2' Jul 6 12:21:23 DEBUG[24347] pbx.c: Function result is '1' Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-cbb2,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing Set("Local/3052@queue_to_agent-cbb2,2", "GROUP=3052") in new stack Jul 6 12:21:23 DEBUG[24347] pbx.c: Function result is '2' Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-cbb2,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:21:23 DEBUG[24347] pbx.c: Function result is '2' Jul 6 12:21:23 DEBUG[24347] pbx.c: Expression result is '1' Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-cbb2,2", "1 ? 1000") in new stack Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:21:23 DEBUG[24347] pbx.c: Function result is '2' Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-cbb2,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:21:23 VERBOSE[24347] logger.c: -- Executing Busy("Local/3052@queue_to_agent-cbb2,2", "") in new stack Jul 6 12:21:23 VERBOSE[24315] logger.c: -- Local/3052@queue_to_agent-cbb2,1 is busy Jul 6 12:21:23 DEBUG[24315] app_queue.c: Everyone is busy at this time Jul 6 12:21:23 VERBOSE[24347] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-cbb2,2' Jul 6 12:21:28 VERBOSE[24315] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:21:28 DEBUG[24357] pbx.c: Function result is '1' Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-44b6,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing Set("Local/3053@queue_to_agent-44b6,2", "GROUP=3053") in new stack Jul 6 12:21:28 DEBUG[24357] pbx.c: Function result is '2' Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-44b6,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:21:28 DEBUG[24357] pbx.c: Function result is '2' Jul 6 12:21:28 DEBUG[24357] pbx.c: Expression result is '1' Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-44b6,2", "1 ? 1000") in new stack Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:21:28 DEBUG[24357] pbx.c: Function result is '2' Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-44b6,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:21:28 VERBOSE[24357] logger.c: -- Executing Busy("Local/3053@queue_to_agent-44b6,2", "") in new stack Jul 6 12:21:28 VERBOSE[24315] logger.c: -- Local/3053@queue_to_agent-44b6,1 is busy Jul 6 12:21:28 VERBOSE[24357] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-44b6,2' Jul 6 12:21:28 VERBOSE[24315] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:21:28 DEBUG[24359] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-44b6,2' Jul 6 12:21:28 DEBUG[24359] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-44b6,2' Jul 6 12:21:28 DEBUG[24359] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-44b6,2' Jul 6 12:21:28 DEBUG[24359] pbx.c: Function result is '1' Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-fb3a,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing Set("Local/3052@queue_to_agent-fb3a,2", "GROUP=3052") in new stack Jul 6 12:21:28 DEBUG[24359] pbx.c: Function result is '2' Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-fb3a,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:21:28 DEBUG[24359] pbx.c: Function result is '2' Jul 6 12:21:28 DEBUG[24359] pbx.c: Expression result is '1' Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-fb3a,2", "1 ? 1000") in new stack Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:21:28 DEBUG[24359] pbx.c: Function result is '2' Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-fb3a,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:21:28 VERBOSE[24359] logger.c: -- Executing Busy("Local/3052@queue_to_agent-fb3a,2", "") in new stack Jul 6 12:21:28 VERBOSE[24315] logger.c: -- Local/3052@queue_to_agent-fb3a,1 is busy Jul 6 12:21:28 DEBUG[24315] app_queue.c: Everyone is busy at this time Jul 6 12:21:28 VERBOSE[24359] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-fb3a,2' Jul 6 12:21:28 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-fb3a,2' Jul 6 12:21:28 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-fb3a,2' Jul 6 12:21:33 VERBOSE[24315] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:21:33 DEBUG[24367] pbx.c: Function result is '1' Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-47af,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing Set("Local/3053@queue_to_agent-47af,2", "GROUP=3053") in new stack Jul 6 12:21:33 DEBUG[24367] pbx.c: Function result is '2' Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-47af,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:21:33 DEBUG[24367] pbx.c: Function result is '2' Jul 6 12:21:33 DEBUG[24367] pbx.c: Expression result is '1' Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-47af,2", "1 ? 1000") in new stack Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:21:33 DEBUG[24367] pbx.c: Function result is '2' Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-47af,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:21:33 VERBOSE[24367] logger.c: -- Executing Busy("Local/3053@queue_to_agent-47af,2", "") in new stack Jul 6 12:21:33 VERBOSE[24315] logger.c: -- Local/3053@queue_to_agent-47af,1 is busy Jul 6 12:21:33 VERBOSE[24315] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:21:33 VERBOSE[24367] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-47af,2' Jul 6 12:21:33 DEBUG[24369] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-47af,2' Jul 6 12:21:33 DEBUG[24369] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-47af,2' Jul 6 12:21:33 DEBUG[24369] pbx.c: Function result is '1' Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-6545,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing Set("Local/3052@queue_to_agent-6545,2", "GROUP=3052") in new stack Jul 6 12:21:33 DEBUG[24369] pbx.c: Function result is '2' Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-6545,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:21:33 DEBUG[24369] pbx.c: Function result is '2' Jul 6 12:21:33 DEBUG[24369] pbx.c: Expression result is '1' Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-6545,2", "1 ? 1000") in new stack Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:21:33 DEBUG[24369] pbx.c: Function result is '2' Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-6545,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:21:33 VERBOSE[24369] logger.c: -- Executing Busy("Local/3052@queue_to_agent-6545,2", "") in new stack Jul 6 12:21:33 VERBOSE[24315] logger.c: -- Local/3052@queue_to_agent-6545,1 is busy Jul 6 12:21:33 DEBUG[24315] app_queue.c: Everyone is busy at this time Jul 6 12:21:33 VERBOSE[24369] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-6545,2' Jul 6 12:21:33 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-6545,2' Jul 6 12:21:35 VERBOSE[24315] logger.c: -- User disconnected from queue myqueue when they almost made it Jul 6 12:21:35 VERBOSE[24315] logger.c: == Spawn extension (sip_to_anywhere, 3097, 1) exited non-zero on 'SIP/3050-09bd6700' Jul 6 12:21:35 DEBUG[24315] chan_sip.c: update_call_counter(3050) - decrement call limit counter Jul 6 12:21:35 DEBUG[24251] chan_sip.c: Stopping retransmission on '4soArYiSulRzbvUQ@172.16.10.124' of Response 2: Match Found Jul 6 12:22:12 DEBUG[24248] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Jul 6 12:22:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:22:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:22:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '3aa5c8b11d3039c274db7a527da18760@200.196.44.45' of Request 102: Match Found Jul 6 12:23:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:23:12 DEBUG[24248] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Jul 6 12:23:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:23:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '7ad54ef166b5d3575a62ed876774e491@200.196.44.45' of Request 102: Match Found Jul 6 12:23:44 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 524288 Jul 6 12:23:44 DEBUG[24251] chan_sip.c: Stopping retransmission on 'iuB1S72R2rcvWYtP@172.16.10.124' of Response 1: Match Found Jul 6 12:23:44 DEBUG[24251] chan_sip.c: Setting NAT on RTP to 524288 Jul 6 12:23:44 DEBUG[24251] chan_sip.c: Checking SIP call limits for device 3050 Jul 6 12:23:44 DEBUG[24251] chan_sip.c: build_route: Contact hop: Jul 6 12:23:44 VERBOSE[24451] logger.c: -- Executing Queue("SIP/3050-09bd6700", "myqueue|tr|||600") in new stack Jul 6 12:23:44 VERBOSE[24451] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:23:44 DEBUG[24453] pbx.c: Function result is '1' Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-255f,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing Set("Local/3053@queue_to_agent-255f,2", "GROUP=3053") in new stack Jul 6 12:23:44 DEBUG[24453] pbx.c: Function result is '2' Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-255f,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:23:44 DEBUG[24453] pbx.c: Function result is '2' Jul 6 12:23:44 DEBUG[24453] pbx.c: Expression result is '1' Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-255f,2", "1 ? 1000") in new stack Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:23:44 DEBUG[24453] pbx.c: Function result is '2' Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-255f,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:23:44 VERBOSE[24453] logger.c: -- Executing Busy("Local/3053@queue_to_agent-255f,2", "") in new stack Jul 6 12:23:44 VERBOSE[24451] logger.c: -- Local/3053@queue_to_agent-255f,1 is busy Jul 6 12:23:44 VERBOSE[24451] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:23:44 VERBOSE[24453] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 WARNING[24455] channel.c: Avoided deadlock for '0x9bdb028', 10 retries! Jul 6 12:23:44 DEBUG[24455] pbx.c: Function result is '0' Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2a6e,2", "BEFORE SETGROUP: Group: 3052 Count: 0") in new stack Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Executing Set("Local/3052@queue_to_agent-2a6e,2", "GROUP=3052") in new stack Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 WARNING[24455] channel.c: Avoided deadlock for '0x9bdb028', 10 retries! Jul 6 12:23:44 DEBUG[24455] pbx.c: Function result is '1' Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-2a6e,2", "AFTER SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 DEBUG[24455] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-255f,2' Jul 6 12:23:44 WARNING[24455] channel.c: Avoided deadlock for '0x9bdb028', 10 retries! Jul 6 12:23:44 DEBUG[24455] pbx.c: Function result is '1' Jul 6 12:23:44 DEBUG[24455] pbx.c: Expression result is '0' Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-2a6e,2", "0 ? 1000") in new stack Jul 6 12:23:44 DEBUG[24455] pbx.c: Not taking any branch Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Executing Dial("Local/3052@queue_to_agent-2a6e,2", "SIP/3052|15|T") in new stack Jul 6 12:23:44 DEBUG[24455] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:23:44 DEBUG[24455] chan_sip.c: Outgoing Call for 3052 Jul 6 12:23:44 VERBOSE[24455] logger.c: -- Called 3052 Jul 6 12:23:44 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3cf34dac613ef4156bdd25676da4ec17@200.196.44.45' Request 102: Found Jul 6 12:23:44 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3cf34dac613ef4156bdd25676da4ec17@200.196.44.45' Request 102: Found Jul 6 12:23:44 VERBOSE[24455] logger.c: -- SIP/3052-09c1e218 is ringing Jul 6 12:23:44 VERBOSE[24451] logger.c: -- Local/3052@queue_to_agent-2a6e,1 is ringing Jul 6 12:23:59 VERBOSE[24451] logger.c: -- Nobody picked up in 15000 ms Jul 6 12:23:59 DEBUG[24455] chan_sip.c: update_call_counter(3052) - decrement call limit counter Jul 6 12:23:59 DEBUG[24455] chan_sip.c: Acked pending invite 102 Jul 6 12:23:59 DEBUG[24455] chan_sip.c: Stopping retransmission on '3cf34dac613ef4156bdd25676da4ec17@200.196.44.45' of Request 102: Match Found Jul 6 12:23:59 DEBUG[24455] app_dial.c: Exiting with DIALSTATUS=CANCEL. Jul 6 12:23:59 VERBOSE[24455] logger.c: == Spawn extension (queue_to_agent, 3052, 5) exited non-zero on 'Local/3052@queue_to_agent-2a6e,2' Jul 6 12:23:59 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-2a6e,2' Jul 6 12:23:59 DEBUG[24251] chan_sip.c: Stopping retransmission on '3cf34dac613ef4156bdd25676da4ec17@200.196.44.45' of Request 102: Match Found Jul 6 12:23:59 DEBUG[24251] chan_sip.c: Stopping retransmission on '3cf34dac613ef4156bdd25676da4ec17@200.196.44.45' of Request 102: Match Not Found Jul 6 12:24:04 VERBOSE[24451] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:24:04 DEBUG[24474] pbx.c: Function result is '1' Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-a5c1,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing Set("Local/3053@queue_to_agent-a5c1,2", "GROUP=3053") in new stack Jul 6 12:24:04 DEBUG[24474] pbx.c: Function result is '2' Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-a5c1,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:24:04 DEBUG[24474] pbx.c: Function result is '2' Jul 6 12:24:04 DEBUG[24474] pbx.c: Expression result is '1' Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-a5c1,2", "1 ? 1000") in new stack Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:24:04 DEBUG[24474] pbx.c: Function result is '2' Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-a5c1,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:24:04 VERBOSE[24474] logger.c: -- Executing Busy("Local/3053@queue_to_agent-a5c1,2", "") in new stack Jul 6 12:24:04 VERBOSE[24451] logger.c: -- Local/3053@queue_to_agent-a5c1,1 is busy Jul 6 12:24:04 VERBOSE[24451] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:24:04 VERBOSE[24474] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 WARNING[24476] channel.c: Avoided deadlock for '0x9c0c650', 10 retries! Jul 6 12:24:04 DEBUG[24476] pbx.c: Function result is '0' Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-9474,2", "BEFORE SETGROUP: Group: 3052 Count: 0") in new stack Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Executing Set("Local/3052@queue_to_agent-9474,2", "GROUP=3052") in new stack Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 WARNING[24476] channel.c: Avoided deadlock for '0x9c0c650', 10 retries! Jul 6 12:24:04 DEBUG[24476] pbx.c: Function result is '1' Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-9474,2", "AFTER SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 DEBUG[24476] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-a5c1,2' Jul 6 12:24:04 WARNING[24476] channel.c: Avoided deadlock for '0x9c0c650', 10 retries! Jul 6 12:24:04 DEBUG[24476] pbx.c: Function result is '1' Jul 6 12:24:04 DEBUG[24476] pbx.c: Expression result is '0' Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-9474,2", "0 ? 1000") in new stack Jul 6 12:24:04 DEBUG[24476] pbx.c: Not taking any branch Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Executing Dial("Local/3052@queue_to_agent-9474,2", "SIP/3052|15|T") in new stack Jul 6 12:24:04 DEBUG[24476] chan_sip.c: Setting NAT on RTP to 0 Jul 6 12:24:04 DEBUG[24476] chan_sip.c: Outgoing Call for 3052 Jul 6 12:24:04 VERBOSE[24476] logger.c: -- Called 3052 Jul 6 12:24:04 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2b6c7be13d2fbed914467a2d08a1a141@200.196.44.45' Request 102: Found Jul 6 12:24:04 DEBUG[24251] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2b6c7be13d2fbed914467a2d08a1a141@200.196.44.45' Request 102: Found Jul 6 12:24:04 VERBOSE[24476] logger.c: -- SIP/3052-09c0e218 is ringing Jul 6 12:24:04 VERBOSE[24451] logger.c: -- Local/3052@queue_to_agent-9474,1 is ringing Jul 6 12:24:12 DEBUG[24248] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Jul 6 12:24:12 DEBUG[24248] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000 Jul 6 12:24:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:24:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '7ba7f6b848ae65153ec6edc3389b4464@200.196.44.45' of Request 102: Match Found Jul 6 12:24:19 VERBOSE[24451] logger.c: -- Nobody picked up in 15000 ms Jul 6 12:24:19 DEBUG[24476] chan_sip.c: update_call_counter(3052) - decrement call limit counter Jul 6 12:24:19 DEBUG[24476] chan_sip.c: Acked pending invite 102 Jul 6 12:24:19 DEBUG[24476] chan_sip.c: Stopping retransmission on '2b6c7be13d2fbed914467a2d08a1a141@200.196.44.45' of Request 102: Match Found Jul 6 12:24:19 DEBUG[24476] app_dial.c: Exiting with DIALSTATUS=CANCEL. Jul 6 12:24:19 VERBOSE[24476] logger.c: == Spawn extension (queue_to_agent, 3052, 5) exited non-zero on 'Local/3052@queue_to_agent-9474,2' Jul 6 12:24:19 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-9474,2' Jul 6 12:24:19 DEBUG[24234] channel.c: Avoiding initial deadlock for 'Local/3052@queue_to_agent-9474,2' Jul 6 12:24:19 DEBUG[24251] chan_sip.c: Stopping retransmission on '2b6c7be13d2fbed914467a2d08a1a141@200.196.44.45' of Request 102: Match Found Jul 6 12:24:19 DEBUG[24251] chan_sip.c: Stopping retransmission on '2b6c7be13d2fbed914467a2d08a1a141@200.196.44.45' of Request 102: Match Not Found Jul 6 12:24:24 VERBOSE[24451] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:24:24 DEBUG[24489] pbx.c: Function result is '1' Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-132a,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing Set("Local/3053@queue_to_agent-132a,2", "GROUP=3053") in new stack Jul 6 12:24:24 DEBUG[24489] pbx.c: Function result is '2' Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-132a,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:24:24 DEBUG[24489] pbx.c: Function result is '2' Jul 6 12:24:24 DEBUG[24489] pbx.c: Expression result is '1' Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-132a,2", "1 ? 1000") in new stack Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:24:24 DEBUG[24489] pbx.c: Function result is '2' Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-132a,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:24:24 VERBOSE[24489] logger.c: -- Executing Busy("Local/3053@queue_to_agent-132a,2", "") in new stack Jul 6 12:24:24 VERBOSE[24451] logger.c: -- Local/3053@queue_to_agent-132a,1 is busy Jul 6 12:24:24 VERBOSE[24451] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:24:24 VERBOSE[24489] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-132a,2' Jul 6 12:24:24 DEBUG[24491] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-132a,2' Jul 6 12:24:24 DEBUG[24491] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-132a,2' Jul 6 12:24:24 DEBUG[24491] pbx.c: Function result is '1' Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-307b,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing Set("Local/3052@queue_to_agent-307b,2", "GROUP=3052") in new stack Jul 6 12:24:24 DEBUG[24491] pbx.c: Function result is '2' Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-307b,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:24:24 DEBUG[24491] pbx.c: Function result is '2' Jul 6 12:24:24 DEBUG[24491] pbx.c: Expression result is '1' Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-307b,2", "1 ? 1000") in new stack Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:24:24 DEBUG[24491] pbx.c: Function result is '2' Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-307b,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:24:24 VERBOSE[24491] logger.c: -- Executing Busy("Local/3052@queue_to_agent-307b,2", "") in new stack Jul 6 12:24:24 VERBOSE[24451] logger.c: -- Local/3052@queue_to_agent-307b,1 is busy Jul 6 12:24:24 DEBUG[24451] app_queue.c: Everyone is busy at this time Jul 6 12:24:24 VERBOSE[24491] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-307b,2' Jul 6 12:24:29 VERBOSE[24451] logger.c: -- Called Local/3053@queue_to_agent Jul 6 12:24:29 DEBUG[24499] pbx.c: Function result is '1' Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-693a,2", "BEFORE SETGROUP: Group: 3053 Count: 1") in new stack Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing Set("Local/3053@queue_to_agent-693a,2", "GROUP=3053") in new stack Jul 6 12:24:29 DEBUG[24499] pbx.c: Function result is '2' Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-693a,2", "AFTER SETGROUP: Group: 3053 Count: 2") in new stack Jul 6 12:24:29 DEBUG[24499] pbx.c: Function result is '2' Jul 6 12:24:29 DEBUG[24499] pbx.c: Expression result is '1' Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing GotoIf("Local/3053@queue_to_agent-693a,2", "1 ? 1000") in new stack Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Goto (queue_to_agent,3053,1000) Jul 6 12:24:29 DEBUG[24499] pbx.c: Function result is '2' Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing NoOp("Local/3053@queue_to_agent-693a,2", "Agent 3053 is busy. Count: 2") in new stack Jul 6 12:24:29 VERBOSE[24499] logger.c: -- Executing Busy("Local/3053@queue_to_agent-693a,2", "") in new stack Jul 6 12:24:29 VERBOSE[24451] logger.c: -- Local/3053@queue_to_agent-693a,1 is busy Jul 6 12:24:29 VERBOSE[24451] logger.c: -- Called Local/3052@queue_to_agent Jul 6 12:24:29 VERBOSE[24499] logger.c: == Spawn extension (queue_to_agent, 3053, 1001) exited non-zero on 'Local/3053@queue_to_agent-693a,2' Jul 6 12:24:29 DEBUG[24501] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-693a,2' Jul 6 12:24:29 DEBUG[24501] channel.c: Avoiding deadlock for 'Local/3053@queue_to_agent-693a,2' Jul 6 12:24:29 DEBUG[24501] pbx.c: Function result is '1' Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-ffc7,2", "BEFORE SETGROUP: Group: 3052 Count: 1") in new stack Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing Set("Local/3052@queue_to_agent-ffc7,2", "GROUP=3052") in new stack Jul 6 12:24:29 DEBUG[24501] pbx.c: Function result is '2' Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-ffc7,2", "AFTER SETGROUP: Group: 3052 Count: 2") in new stack Jul 6 12:24:29 DEBUG[24501] pbx.c: Function result is '2' Jul 6 12:24:29 DEBUG[24501] pbx.c: Expression result is '1' Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing GotoIf("Local/3052@queue_to_agent-ffc7,2", "1 ? 1000") in new stack Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Goto (queue_to_agent,3052,1000) Jul 6 12:24:29 DEBUG[24501] pbx.c: Function result is '2' Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing NoOp("Local/3052@queue_to_agent-ffc7,2", "Agent 3052 is busy. Count: 2") in new stack Jul 6 12:24:29 VERBOSE[24501] logger.c: -- Executing Busy("Local/3052@queue_to_agent-ffc7,2", "") in new stack Jul 6 12:24:29 VERBOSE[24451] logger.c: -- Local/3052@queue_to_agent-ffc7,1 is busy Jul 6 12:24:29 DEBUG[24451] app_queue.c: Everyone is busy at this time Jul 6 12:24:29 VERBOSE[24501] logger.c: == Spawn extension (queue_to_agent, 3052, 1001) exited non-zero on 'Local/3052@queue_to_agent-ffc7,2' Jul 6 12:24:30 VERBOSE[24451] logger.c: -- User disconnected from queue myqueue when they almost made it Jul 6 12:24:30 VERBOSE[24451] logger.c: == Spawn extension (sip_to_anywhere, 3097, 1) exited non-zero on 'SIP/3050-09bd6700' Jul 6 12:24:30 DEBUG[24451] chan_sip.c: update_call_counter(3050) - decrement call limit counter Jul 6 12:24:30 DEBUG[24251] chan_sip.c: Stopping retransmission on 'iuB1S72R2rcvWYtP@172.16.10.124' of Response 2: Match Found Jul 6 12:25:07 VERBOSE[24251] logger.c: -- Saved useragent "Elite 1.0 Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26" for peer 3052 Jul 6 12:25:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:25:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:25:12 DEBUG[24248] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000 Jul 6 12:25:13 DEBUG[24251] chan_sip.c: Stopping retransmission on '5b0dd1d6190ca7ba4f667cc86f544c04@200.196.44.45' of Request 102: Match Found