[Jan 19 13:55:24] VERBOSE[1724]: [Jan 19 13:55:24] Asterisk Event Logger restarted ..snip [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24324]: Setting NAT on RTP to 524288 [Jan 19 13:55:37] DEBUG[24324]: Checking SIP call limits for device kevin [Jan 19 13:55:37] DEBUG[24324]: build_route: Contact hop: [Jan 19 13:55:37] DEBUG[24317]: Avoiding initial deadlock for 'SIP/kevin-11a8' [Jan 19 13:55:37] VERBOSE[2451]: [Jan 19 13:55:37] -- Executing AgentLogin("SIP/kevin-11a8", "") in new stack [Jan 19 13:55:37] DEBUG[2451]: ast_sched_add() [Jan 19 13:55:37] DEBUG[2451]: Asterisk Schedule Dump (9 in Q, 62407 Total, 3 Cache) [Jan 19 13:55:37] DEBUG[2451]: ============================================================= [Jan 19 13:55:37] DEBUG[2451]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:37] DEBUG[2451]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:37] DEBUG[2451]: |62407 | 0xb78093e7 | 0x8285c18 | 000000 : 999999 | [Jan 19 13:55:37] DEBUG[2451]: |62402 | 0xb780a091 | 0x82ba840 | 000002 : 226615 | [Jan 19 13:55:37] DEBUG[2451]: |62404 | 0xb780a091 | 0x82bd398 | 000002 : 501704 | [Jan 19 13:55:37] DEBUG[2451]: |62400 | 0xb781affc | 0xb772c3a0 | 000008 : 952393 | [Jan 19 13:55:37] DEBUG[2451]: |62399 | 0xb780a091 | 0x82b7ce8 | 000016 : 952387 | [Jan 19 13:55:37] DEBUG[2451]: |62396 | 0xb781d964 | 0xb772d398 | 000035 : 353495 | [Jan 19 13:55:37] DEBUG[2451]: |62388 | 0xb781d5eb | 0xb772f970 | 000067 : 247026 | [Jan 19 13:55:37] DEBUG[2451]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000597 : 201089 | [Jan 19 13:55:37] DEBUG[2451]: |62403 | 0xb781d5eb | 0xb772e028 | 000597 : 488909 | [Jan 19 13:55:37] DEBUG[2451]: ============================================================= [Jan 19 13:55:37] DEBUG[2451]: Scheduling timer at 160 sample intervals [Jan 19 13:55:37] VERBOSE[2451]: [Jan 19 13:55:37] -- Playing 'agent-user' (language 'en') [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24324]: ast_sched_del() [Jan 19 13:55:37] DEBUG[24324]: Asterisk Schedule Dump (8 in Q, 62407 Total, 4 Cache) [Jan 19 13:55:37] DEBUG[24324]: ============================================================= [Jan 19 13:55:37] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:37] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:37] DEBUG[24324]: |62402 | 0xb780a091 | 0x82ba840 | 000002 : 151829 | [Jan 19 13:55:37] DEBUG[24324]: |62404 | 0xb780a091 | 0x82bd398 | 000002 : 426918 | [Jan 19 13:55:37] DEBUG[24324]: |62400 | 0xb781affc | 0xb772c3a0 | 000008 : 877607 | [Jan 19 13:55:37] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000016 : 877601 | [Jan 19 13:55:37] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000035 : 278709 | [Jan 19 13:55:37] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000067 : 172240 | [Jan 19 13:55:37] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000597 : 126303 | [Jan 19 13:55:37] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000597 : 414123 | [Jan 19 13:55:37] DEBUG[24324]: ============================================================= [Jan 19 13:55:37] DEBUG[24324]: Stopping retransmission on 'D02DE700-EDC7-41EF-A74C-C04774A595EB@192.168.1.99' of Response 1: Match Found [Jan 19 13:55:37] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:37] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:38] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[2451]: * Detected inband DTMF '1' [Jan 19 13:55:39] DEBUG[2451]: Scheduling timer at 0 sample intervals [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24324]: Auto destroying call 'bae61d6c62f2d6e0@192.168.1.201' [Jan 19 13:55:39] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:39] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[2451]: * Detected inband DTMF '1' [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24324]: Auto destroying call '731ccaabe0f37afb@192.168.1.200' [Jan 19 13:55:40] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:40] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[2451]: * Detected inband DTMF '0' [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:41] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[2451]: * Detected inband DTMF '0' [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[2451]: * Detected inband DTMF '0' [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:42] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: * Detected inband DTMF '#' [Jan 19 13:55:43] DEBUG[2451]: Scheduling timer at 160 sample intervals [Jan 19 13:55:43] VERBOSE[2451]: [Jan 19 13:55:43] -- Playing 'agent-pass' (language 'en') [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:43] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: * Detected inband DTMF '4' [Jan 19 13:55:44] DEBUG[2451]: Scheduling timer at 0 sample intervals [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[2451]: * Detected inband DTMF '3' [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:44] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[2451]: * Detected inband DTMF '2' [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[2451]: * Detected inband DTMF '1' [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:45] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_add() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (6 in Q, 62408 Total, 5 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 993729 | [Jan 19 13:55:46] DEBUG[24324]: |62408 | 0xb781b312 | 0xb772c3a0 | 000019 : 999999 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 394837 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 288368 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 242431 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 530251 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: Scheduled a registration timeout for sip.broadvoice.com id #62408 [Jan 19 13:55:46] DEBUG[24324]: ast_sched_add() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (7 in Q, 62409 Total, 4 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62409 | 0xb78093e7 | 0x8285c18 | 000000 : 999999 | [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 993103 | [Jan 19 13:55:46] DEBUG[24324]: |62408 | 0xb781b312 | 0xb772c3a0 | 000019 : 999373 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 394211 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 287742 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 241805 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 529625 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: * Detected inband DTMF '#' [Jan 19 13:55:46] DEBUG[2451]: Scheduling timer at 160 sample intervals [Jan 19 13:55:46] VERBOSE[2451]: [Jan 19 13:55:46] -- Playing 'agent-loginok' (language 'en') [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_del() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (6 in Q, 62409 Total, 6 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 921692 | [Jan 19 13:55:46] DEBUG[24324]: |62408 | 0xb781b312 | 0xb772c3a0 | 000019 : 927962 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 322800 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 216331 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 170394 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 458214 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: Stopping retransmission on '676a7ad86b9ffa17317f6114420ad523@66.235.239.231' of Request 11526: Match Found [Jan 19 13:55:46] DEBUG[24324]: Registration successful [Jan 19 13:55:46] DEBUG[24324]: Cancelling timeout 62408 [Jan 19 13:55:46] DEBUG[24324]: ast_sched_del() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (5 in Q, 62409 Total, 7 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 921033 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 322141 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 215672 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 169735 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 457555 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: ast_sched_add() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (6 in Q, 62410 Total, 6 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 920541 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 321649 | [Jan 19 13:55:46] DEBUG[24324]: |62410 | 0xb780a091 | 0x82ba840 | 000031 : 999999 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 215180 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 169243 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 457063 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: ast_sched_add() [Jan 19 13:55:46] DEBUG[24324]: Asterisk Schedule Dump (7 in Q, 62411 Total, 5 Cache) [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:55:46] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:55:46] DEBUG[24324]: |62399 | 0xb780a091 | 0x82b7ce8 | 000007 : 919986 | [Jan 19 13:55:46] DEBUG[24324]: |62411 | 0xb781affc | 0xb772c3a0 | 000023 : 999999 | [Jan 19 13:55:46] DEBUG[24324]: |62396 | 0xb781d964 | 0xb772d398 | 000026 : 321094 | [Jan 19 13:55:46] DEBUG[24324]: |62410 | 0xb780a091 | 0x82ba840 | 000031 : 999444 | [Jan 19 13:55:46] DEBUG[24324]: |62388 | 0xb781d5eb | 0xb772f970 | 000058 : 214625 | [Jan 19 13:55:46] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000588 : 168688 | [Jan 19 13:55:46] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000588 : 456508 | [Jan 19 13:55:46] DEBUG[24324]: ============================================================= [Jan 19 13:55:46] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:46] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24324]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24324]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[24323]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: Scheduling timer at 0 sample intervals [Jan 19 13:55:47] DEBUG[2451]: ast_sched_runq() [Jan 19 13:55:47] DEBUG[2451]: ast_sched_wait() [Jan 19 13:55:47] DEBUG[2451]: Scheduling timer at 0 sample intervals [Jan 19 13:55:47] VERBOSE[2451]: [Jan 19 13:55:47] -- Started music on hold, class 'default', on SIP/kevin-11a8 [Jan 19 13:55:47] DEBUG[2451]: Scheduling timer at 160 sample intervals [Jan 19 13:55:47] VERBOSE[2451]: [Jan 19 13:55:47] == Agent '11000' logged in (format ulaw/slin) [Jan 19 13:55:47] DEBUG[2451]: Generator got voice, switching to phase locked mode [Jan 19 13:55:47] DEBUG[2451]: Scheduling timer at 0 sample intervals ..snip [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24324]: = No match Their Call ID: 676a7ad86b9ffa17317f6114420ad523@66.235.239.231 Their Tag Our tag: as47315c84 [Jan 19 13:56:24] DEBUG[24324]: = No match Their Call ID: D02DE700-EDC7-41EF-A74C-C04774A595EB@192.168.1.99 Their Tag 23925002 Our tag: as15a93eba [Jan 19 13:56:24] DEBUG[24324]: Allocating new SIP dialog for 34ce0191dcbcc7b5@192.168.1.201 - INVITE (With RTP) [Jan 19 13:56:24] DEBUG[24324]: **** Received INVITE (5) - Command in SIP INVITE [Jan 19 13:56:24] DEBUG[24324]: * SIP extension value: 1 for call 34ce0191dcbcc7b5@192.168.1.201 [Jan 19 13:56:24] DEBUG[24324]: Setting NAT on RTP to 524288 [Jan 19 13:56:24] DEBUG[24324]: Checking SIP call limits for device 2 [Jan 19 13:56:24] DEBUG[24324]: Updating call counter for incoming call [Jan 19 13:56:24] DEBUG[24324]: build_route: Contact hop: [Jan 19 13:56:24] DEBUG[24317]: Checking device state for peer 2 [Jan 19 13:56:24] DEBUG[24317]: Changing state for SIP/2 - state 2 (In use) [Jan 19 13:56:24] DEBUG[2464]: Device 'SIP/2' changed to state '2' (In use) [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: Launching 'Dial' [Jan 19 13:56:24] VERBOSE[2463]: [Jan 19 13:56:24] -- Executing Dial("SIP/2-2954", "Agent/11000") in new stack [Jan 19 13:56:24] VERBOSE[2463]: [Jan 19 13:56:24] -- Stopped music on hold on SIP/kevin-11a8 [Jan 19 13:56:24] DEBUG[2463]: Set channel SIP/kevin-11a8 to write format ulaw [Jan 19 13:56:24] DEBUG[2463]: Scheduling timer at 0 sample intervals [Jan 19 13:56:24] DEBUG[2463]: Not copying variable STACK-default-300-1. [Jan 19 13:56:24] DEBUG[2463]: Not copying variable SIPCALLID. [Jan 19 13:56:24] DEBUG[2463]: Not copying variable SIPUSERAGENT. [Jan 19 13:56:24] DEBUG[2463]: Not copying variable SIPDOMAIN. [Jan 19 13:56:24] DEBUG[2463]: Not copying variable SIPURI. [Jan 19 13:56:24] VERBOSE[2463]: [Jan 19 13:56:24] -- agent_call, call to agent '11000' call on 'SIP/kevin-11a8' [Jan 19 13:56:24] DEBUG[2463]: Playing beep, lang 'en' [Jan 19 13:56:24] DEBUG[2463]: Set channel SIP/kevin-11a8 to write format ulaw [Jan 19 13:56:24] DEBUG[2463]: Scheduling timer at 160 sample intervals [Jan 19 13:56:24] VERBOSE[2463]: [Jan 19 13:56:24] -- Playing 'beep' (language 'en') [Jan 19 13:56:24] DEBUG[2463]: Played beep, result '0' [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24324]: = No match Their Call ID: 34ce0191dcbcc7b5@192.168.1.201 Their Tag 4cb401c5df447dad Our tag: as1f504646 [Jan 19 13:56:24] DEBUG[24324]: = No match Their Call ID: 676a7ad86b9ffa17317f6114420ad523@66.235.239.231 Their Tag Our tag: as47315c84 [Jan 19 13:56:24] DEBUG[24324]: = No match Their Call ID: D02DE700-EDC7-41EF-A74C-C04774A595EB@192.168.1.99 Their Tag 23925002 Our tag: as15a93eba [Jan 19 13:56:24] DEBUG[24324]: Allocating new SIP dialog for FA26E643-014A-472B-A773-5C61DA923718@192.168.1.99 - REGISTER (No RTP) [Jan 19 13:56:24] DEBUG[24324]: **** Received REGISTER (2) - Command in SIP REGISTER [Jan 19 13:56:24] DEBUG[24324]: ast_sched_del() [Jan 19 13:56:24] DEBUG[24324]: Asterisk Schedule Dump (5 in Q, 62419 Total, 7 Cache) [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:56:24] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:56:24] DEBUG[24324]: |62416 | 0xb781affc | 0xb772c3a0 | 000009 : 820999 | [Jan 19 13:56:24] DEBUG[24324]: |62415 | 0xb780a091 | 0x82ca8e8 | 000017 : 820468 | [Jan 19 13:56:24] DEBUG[24324]: |62419 | 0xb781d964 | 0xb772d398 | 000048 : 140728 | [Jan 19 13:56:24] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000549 : 908683 | [Jan 19 13:56:24] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000550 : 196503 | [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[24324]: ast_sched_add() [Jan 19 13:56:24] DEBUG[24324]: Asterisk Schedule Dump (6 in Q, 62420 Total, 6 Cache) [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:56:24] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:56:24] DEBUG[24324]: |62416 | 0xb781affc | 0xb772c3a0 | 000009 : 820476 | [Jan 19 13:56:24] DEBUG[24324]: |62415 | 0xb780a091 | 0x82ca8e8 | 000017 : 819945 | [Jan 19 13:56:24] DEBUG[24324]: |62419 | 0xb781d964 | 0xb772d398 | 000048 : 140205 | [Jan 19 13:56:24] DEBUG[24324]: |62420 | 0xb781d5eb | 0xb772f970 | 000129 : 999998 | [Jan 19 13:56:24] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000549 : 908160 | [Jan 19 13:56:24] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000550 : 195980 | [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_add() [Jan 19 13:56:24] DEBUG[24324]: Asterisk Schedule Dump (7 in Q, 62421 Total, 5 Cache) [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:56:24] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:56:24] DEBUG[24324]: |62416 | 0xb781affc | 0xb772c3a0 | 000009 : 794845 | [Jan 19 13:56:24] DEBUG[24324]: |62421 | 0xb780a091 | 0x82ba608 | 000014 : 999999 | [Jan 19 13:56:24] DEBUG[24324]: |62415 | 0xb780a091 | 0x82ca8e8 | 000017 : 794314 | [Jan 19 13:56:24] DEBUG[24324]: |62419 | 0xb781d964 | 0xb772d398 | 000048 : 114574 | [Jan 19 13:56:24] DEBUG[24324]: |62420 | 0xb781d5eb | 0xb772f970 | 000129 : 974367 | [Jan 19 13:56:24] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000549 : 882529 | [Jan 19 13:56:24] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000550 : 170349 | [Jan 19 13:56:24] DEBUG[24324]: ============================================================= [Jan 19 13:56:24] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:24] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:24] DEBUG[24317]: Checking device state for peer kevin [Jan 19 13:56:24] DEBUG[24317]: Changing state for SIP/kevin - state 2 (In use) [Jan 19 13:56:24] DEBUG[2465]: Device 'SIP/kevin' changed to state '2' (In use) [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: Scheduling timer at 0 sample intervals [Jan 19 13:56:25] DEBUG[2463]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[2463]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: Scheduling timer at 0 sample intervals [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/kevin-11a8 to write format ulaw [Jan 19 13:56:25] DEBUG[2463]: Waited for stream, result '0' [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/kevin-11a8 to read format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set read format, result '0' [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/kevin-11a8 to write format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set write format, result '0' [Jan 19 13:56:25] VERBOSE[2463]: [Jan 19 13:56:25] -- Called 11000 [Jan 19 13:56:25] DEBUG[2463]: Set channel Agent/11000 to read format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/2-2954 to write format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/2-2954 to read format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel Agent/11000 to write format ulaw [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24317]: Changing state for Agent/11000 - state 3 (Busy) [Jan 19 13:56:25] DEBUG[2466]: Device 'Agent/11000' changed to state '3' (Busy) [Jan 19 13:56:25] VERBOSE[2463]: [Jan 19 13:56:25] -- Agent/11000 answered SIP/2-2954 [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/2-2954 to read format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel Agent/11000 to write format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel Agent/11000 to read format ulaw [Jan 19 13:56:25] DEBUG[2463]: Set channel SIP/2-2954 to write format ulaw [Jan 19 13:56:25] DEBUG[2463]: sip_answer(SIP/2-2954) [Jan 19 13:56:25] DEBUG[2463]: ast_sched_add() [Jan 19 13:56:25] DEBUG[2463]: Asterisk Schedule Dump (8 in Q, 62422 Total, 4 Cache) [Jan 19 13:56:25] DEBUG[2463]: ============================================================= [Jan 19 13:56:25] DEBUG[2463]: |ID Callback Data Time (sec:ms) | [Jan 19 13:56:25] DEBUG[2463]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:56:25] DEBUG[2463]: |62422 | 0xb78093e7 | 0x82c9dc0 | 000000 : 999999 | [Jan 19 13:56:25] DEBUG[2463]: |62416 | 0xb781affc | 0xb772c3a0 | 000009 : 516971 | [Jan 19 13:56:25] DEBUG[2463]: |62421 | 0xb780a091 | 0x82ba608 | 000014 : 722125 | [Jan 19 13:56:25] DEBUG[2463]: |62415 | 0xb780a091 | 0x82ca8e8 | 000017 : 516440 | [Jan 19 13:56:25] DEBUG[2463]: |62419 | 0xb781d964 | 0xb772d398 | 000047 : 836700 | [Jan 19 13:56:25] DEBUG[2463]: |62420 | 0xb781d5eb | 0xb772f970 | 000129 : 696493 | [Jan 19 13:56:25] DEBUG[2463]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000549 : 604655 | [Jan 19 13:56:25] DEBUG[2463]: |62403 | 0xb781d5eb | 0xb772e028 | 000549 : 892475 | [Jan 19 13:56:25] DEBUG[2463]: ============================================================= [Jan 19 13:56:25] DEBUG[2463]: Bridge on 'SIP/kevin-11a8' being set to 'Agent/11000' (3) [Jan 19 13:56:25] DEBUG[2463]: Ooh, format changed from unknown to ulaw [Jan 19 13:56:25] DEBUG[24317]: Checking device state for peer 2 [Jan 19 13:56:25] DEBUG[24317]: Changing state for SIP/2 - state 2 (In use) [Jan 19 13:56:25] DEBUG[2467]: Device 'SIP/2' changed to state '2' (In use) [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24324]: = No match Their Call ID: FA26E643-014A-472B-A773-5C61DA923718@192.168.1.99 Their Tag 23972350 Our tag: as5026bc13 [Jan 19 13:56:25] DEBUG[24324]: = Found Their Call ID: 34ce0191dcbcc7b5@192.168.1.201 Their Tag 4cb401c5df447dad Our tag: as1f504646 [Jan 19 13:56:25] DEBUG[24324]: **** Received ACK (6) - Command in SIP ACK [Jan 19 13:56:25] DEBUG[24324]: ast_sched_del() [Jan 19 13:56:25] DEBUG[24324]: Asterisk Schedule Dump (7 in Q, 62422 Total, 5 Cache) [Jan 19 13:56:25] DEBUG[24324]: ============================================================= [Jan 19 13:56:25] DEBUG[24324]: |ID Callback Data Time (sec:ms) | [Jan 19 13:56:25] DEBUG[24324]: +-----+-----------------+-----------------+-----------------+ [Jan 19 13:56:25] DEBUG[24324]: |62416 | 0xb781affc | 0xb772c3a0 | 000009 : 480148 | [Jan 19 13:56:25] DEBUG[24324]: |62421 | 0xb780a091 | 0x82ba608 | 000014 : 685302 | [Jan 19 13:56:25] DEBUG[24324]: |62415 | 0xb780a091 | 0x82ca8e8 | 000017 : 479617 | [Jan 19 13:56:25] DEBUG[24324]: |62419 | 0xb781d964 | 0xb772d398 | 000047 : 799877 | [Jan 19 13:56:25] DEBUG[24324]: |62420 | 0xb781d5eb | 0xb772f970 | 000129 : 659670 | [Jan 19 13:56:25] DEBUG[24324]: |62401 | 0xb781d5eb | 0xb772ecb8 | 000549 : 567832 | [Jan 19 13:56:25] DEBUG[24324]: |62403 | 0xb781d5eb | 0xb772e028 | 000549 : 855652 | [Jan 19 13:56:25] DEBUG[24324]: ============================================================= [Jan 19 13:56:25] DEBUG[24324]: Stopping retransmission on '34ce0191dcbcc7b5@192.168.1.201' of Response 42450: Match Found [Jan 19 13:56:25] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[2463]: RTP NAT: Got audio from other end. Now sending to address 68.226.113.228:5004 [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:25] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24324]: = No match Their Call ID: FA26E643-014A-472B-A773-5C61DA923718@192.168.1.99 Their Tag 23972350 Our tag: as5026bc13 [Jan 19 13:56:26] DEBUG[24324]: = No match Their Call ID: 34ce0191dcbcc7b5@192.168.1.201 Their Tag 4cb401c5df447dad Our tag: as1f504646 [Jan 19 13:56:26] DEBUG[24324]: = No match Their Call ID: 676a7ad86b9ffa17317f6114420ad523@66.235.239.231 Their Tag Our tag: as47315c84 [Jan 19 13:56:26] DEBUG[24324]: = No match Their Call ID: D02DE700-EDC7-41EF-A74C-C04774A595EB@192.168.1.99 Their Tag 23925002 Our tag: as15a93eba [Jan 19 13:56:26] DEBUG[24324]: Allocating new SIP dialog for 7b5254cb4d829e004fe921a54bb3bc23@66.235.239.249 - OPTIONS (No RTP) [Jan 19 13:56:26] DEBUG[24324]: **** Received OPTIONS (3) - Command in SIP OPTIONS [Jan 19 13:56:26] DEBUG[24324]: SIP message could not be handled, bad request: 7b5254cb4d829e004fe921a54bb3bc23@66.235.239.249 [Jan 19 13:56:26] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:26] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:27] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:28] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:29] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:30] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 2000 (__login_exec): Deadlock? waited 5 sec for mutex '&p->app_lock'? [Jan 19 13:56:30] ERROR[2451]: chan_agent.c line 988 (agent_new): '&p->app_lock' was locked here. ..snip [Jan 19 13:56:33] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:33] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:33] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:33] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:33] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24324]: = No match Their Call ID: FA26E643-014A-472B-A773-5C61DA923718@192.168.1.99 Their Tag 23972350 Our tag: as5026bc13 [Jan 19 13:56:34] DEBUG[24324]: = Found Their Call ID: 34ce0191dcbcc7b5@192.168.1.201 Their Tag 4cb401c5df447dad Our tag: as1f504646 [Jan 19 13:56:34] DEBUG[24324]: **** Received BYE (8) - Command in SIP BYE [Jan 19 13:56:34] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24324]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24324]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[2463]: Didn't get a frame from channel: SIP/2-2954 [Jan 19 13:56:34] DEBUG[2463]: Bridge stops bridging channels SIP/2-2954 and Agent/11000 [Jan 19 13:56:34] DEBUG[2463]: Hanging up channel 'Agent/11000' [Jan 19 13:56:34] DEBUG[2463]: Hangup called for state Up [Jan 19 13:56:34] DEBUG[2463]: Set channel SIP/kevin-11a8 to write format slin [Jan 19 13:56:34] VERBOSE[2463]: [Jan 19 13:56:34] -- Started music on hold, class 'default', on SIP/kevin-11a8 [Jan 19 13:56:34] DEBUG[2463]: Scheduling timer at 160 sample intervals [Jan 19 13:56:34] DEBUG[24317]: Changing state for Agent/11000 - state 1 (Not in use) [Jan 19 13:56:34] DEBUG[2468]: Device 'Agent/11000' changed to state '1' (Not in use) [Jan 19 13:56:34] DEBUG[2463]: Exiting with DIALSTATUS=ANSWER. [Jan 19 13:56:34] DEBUG[2463]: Spawn extension (default,300,1) exited non-zero on 'SIP/2-2954' [Jan 19 13:56:34] DEBUG[2463]: Hanging up channel 'SIP/2-2954' [Jan 19 13:56:34] DEBUG[2463]: Hangup call SIP/2-2954, SIP callid 34ce0191dcbcc7b5@192.168.1.201) [Jan 19 13:56:34] DEBUG[2463]: update_call_counter(2) - decrement call limit counter [Jan 19 13:56:34] DEBUG[2463]: Updating call counter for incoming call [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait() [Jan 19 13:56:34] DEBUG[2451]: Generator got voice, switching to phase locked mode [Jan 19 13:56:34] DEBUG[2451]: Scheduling timer at 0 sample intervals [Jan 19 13:56:34] DEBUG[2451]: Set channel SIP/kevin-11a8 to write format slin [Jan 19 13:56:34] DEBUG[2451]: Set channel SIP/kevin-11a8 to write format ulaw [Jan 19 13:56:34] DEBUG[2451]: SIP/kevin-11a8 Opened file 2 '/var/lib/asterisk/moh-native/fpm-calm-river-0.8' [Jan 19 13:56:34] DEBUG[2451]: Difference is 12960, ms is 1640 [Jan 19 13:56:34] DEBUG[24317]: Changing state for Agent/11000 - state 1 (Not in use) [Jan 19 13:56:34] DEBUG[24317]: Checking device state for peer 2 [Jan 19 13:56:34] DEBUG[24317]: Changing state for SIP/2 - state 1 (Not in use) [Jan 19 13:56:34] DEBUG[2470]: Device 'SIP/2' changed to state '1' (Not in use) [Jan 19 13:56:34] DEBUG[2469]: Device 'Agent/11000' changed to state '1' (Not in use) [Jan 19 13:56:34] DEBUG[24323]: ast_sched_runq() [Jan 19 13:56:34] DEBUG[24323]: ast_sched_wait()