truncated system call

From Mike Thomson, 1 Year ago, written in Plain Text, viewed 132 times.
URL https://pastebin.freepbx.org/view/ec4b589e Embed
Download Paste or View Raw
  1. [root@server1 tmp]# rasterisk
  2. Asterisk 13.13.1, Copyright (C) 1999 - 2014, Digium, Inc. and others.
  3. Created by Mark Spencer <markster@digium.com>
  4. Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
  5. This is free software, with components licensed under the GNU General Public
  6. License version 2 and other licenses; you are welcome to redistribute it under
  7. certain conditions. Type 'core show license' for details.
  8. =========================================================================
  9. Connected to Asterisk 13.13.1 currently running on server1 (pid = 1799)
  10. server1*CLI> core set debug 1
  11. Core debug was OFF and is now 1.
  12. [2017-02-19 00:04:43] DEBUG[3042]: manager.c:6313 process_message: Running action 'Login'
  13. [2017-02-19 00:04:43] DEBUG[3042]: manager.c:6313 process_message: Running action 'Command'
  14. [2017-02-19 00:04:43] DEBUG[3042]: manager.c:6313 process_message: Running action 'Command'
  15. [2017-02-19 00:04:43] DEBUG[3042]: manager.c:6313 process_message: Running action 'Command'
  16. [2017-02-19 00:04:46] DEBUG[1878]: chan_sip.c:3870 ast_sip_ouraddrfor: Target address 46.31.231.185:5060 is not local, substituting externaddr
  17. [2017-02-19 00:04:46] DEBUG[1878]: chan_sip.c:8981 __sip_alloc: Allocating new SIP dialog for VF287b7b2c336ce75638ef0619cfceae@voipfone - INVITE (No RTP)
  18. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:454 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7fadf401baa0'
  19. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: res_rtp_asterisk.c:2679 ast_rtp_new: Allocated port 11938 for RTP instance '0x7fadf401baa0'
  20. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:463 ast_rtp_instance_new: RTP instance '0x7fadf401baa0' is setup and ready to go
  21. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: acl.c:958 ast_find_ourip: Not an IPv4 nor IPv6 address, cannot get port.
  22. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: res_rtp_asterisk.c:4920 ast_rtp_prop_set: Setup RTCP on RTP instance '0x7fadf401baa0'
  23. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: chan_sip.c:5788 do_setnat: Setting NAT on RTP to On
  24. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:689 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 8 based on m type on 0x7fadbaf490a0
  25. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:684 ast_rtp_codecs_payloads_set_m_type: Don't have a default tx payload type 2 format for m type on 0x7fadbaf490a0
  26. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:689 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 97 based on m type on 0x7fadbaf490a0
  27. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:689 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 3 based on m type on 0x7fadbaf490a0
  28. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:689 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 110 based on m type on 0x7fadbaf490a0
  29. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: rtp_engine.c:689 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 101 based on m type on 0x7fadbaf490a0
  30. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: acl.c:920 ast_ouraddrfor: Not an IPv4 nor IPv6 address, cannot get port.
  31. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: res_rtp_asterisk.c:4986 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x7fadf401baa0'
  32. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: res_rtp_asterisk.c:4871 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x7fadf401baa0'
  33. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: chan_sip.c:26212 handle_request_invite: Checking SIP call limits for device 30153282
  34. [2017-02-19 00:04:46] WARNING[3050][C-000022b1]: pbx_variables.c:659 pbx_substitute_variables_helper_full: Error in extension logic (missing '}')
  35. [2017-02-19 00:04:46] WARNING[3050][C-000022b1]: pbx_functions.c:460 func_args: Can't find trailing parenthesis for function 'CALLERID(nu'?
  36. [2017-02-19 00:04:46] ERROR[3050][C-000022b1]: func_callerid.c:1110 callerid_read: Unknown callerid data type 'nu'.
  37. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'System'
  38. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Gosub'
  39. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:606 gosub_exec: Channel SIP/Voipfone-SIP-0000004e has no datastore, so we're allocating one.
  40. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG1' to 'in'
  41. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG2' to '30153282*212'
  42. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG3' to 'force'
  43. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  44. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  45. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  46. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  47. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  48. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  49. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  50. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  51. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  52. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  53. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  54. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  55. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  56. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  57. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  58. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  59. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  60. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  61. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  62. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  63. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '2'
  64. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  65. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  66. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  67. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  68. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  69. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '11'
  70. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  71. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Gosub'
  72. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG1' to 'force'
  73. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG2' to 'in'
  74. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG3' to '30153282*212'
  75. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  76. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Goto'
  77. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  78. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  79. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  80. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  81. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  82. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  83. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'MixMonitor'
  84. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: autochan.c:58 ast_autochan_setup: Created autochan 0x3248a10 to hold channel SIP/Voipfone-SIP-0000004e (0x7fadf4072d10)
  85. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  86. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  87. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  88. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  89. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Return'
  90. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Return'
  91. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Gosub'
  92. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key '07977415639' in family 'blacklist'
  93. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  94. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key 'Non-specific clients' in family 'blacklist'
  95. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  96. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  97. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  98. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  99. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Return'
  100. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  101. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  102. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  103. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  104. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  105. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  106. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  107. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  108. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  109. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  110. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  111. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  112. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  113. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  114. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Goto'
  115. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  116. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  117. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  118. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Progress'
  119. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13393 add_sdp: ** Our capability: (alaw) Video flag: True Text flag: True
  120. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13394 add_sdp: ** Our prefcodec: (nothing)
  121. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13589 add_sdp: Setting framing on incoming call: 20
  122. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Macro'
  123. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  124. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  125. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  126. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  127. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  128. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  129. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  130. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  131. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  132. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  133. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  134. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: ExecIf
  135. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key '07977415639/user' in family 'DEVICE'
  136. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: func_db.c:151 function_db_read: DB: DEVICE/07977415639/user not found in database.
  137. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  138. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  139. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  140. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  141. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  142. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  143. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key '/cidname' in family 'AMPUSER'
  144. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: func_db.c:151 function_db_read: DB: AMPUSER//cidname not found in database.
  145. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  146. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  147. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  148. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  149. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  150. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  151. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  152. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  153. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  154. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  155. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  156. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key '9399/accountcode' in family 'AMPUSER'
  157. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: func_db.c:151 function_db_read: DB: AMPUSER/9399/accountcode not found in database.
  158. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  159. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: ExecIf
  160. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: db.c:376 db_get_common: Unable to find key '9399/accountcode' in family 'AMPUSER'
  161. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: func_db.c:151 function_db_read: DB: AMPUSER/9399/accountcode not found in database.
  162. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  163. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '-1'
  164. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  165. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  166. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  167. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  168. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  169. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  170. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  171. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  172. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  173. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  174. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  175. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  176. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  177. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  178. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  179. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  180. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  181. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  182. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  183. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Macro'
  184. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  185. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  186. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  187. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  188. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  189. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  190. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  191. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  192. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: Set
  193. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'MacroExit'
  194. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  195. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  196. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  197. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  198. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  199. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GosubIf'
  200. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Gosub'
  201. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG1' to 'rg'
  202. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG2' to '9399'
  203. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG3' to 'dontcare'
  204. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '11'
  205. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  206. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  207. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  208. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  209. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  210. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  211. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  212. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '2'
  213. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  214. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  215. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  216. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  217. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  218. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Gosub'
  219. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG1' to 'dontcare'
  220. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG2' to 'rg'
  221. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: app_stack.c:648 gosub_exec: Setting 'ARG3' to '9399'
  222. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'NoOp'
  223. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Goto'
  224. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Return'
  225. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Return'
  226. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Set'
  227. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  228. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  229. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx_builtins.c:1174 pbx_builtin_gotoif: Not taking any branch
  230. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Answer'
  231. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:7397 sip_answer: SIP answering channel: SIP/Voipfone-SIP-0000004e
  232. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13393 add_sdp: ** Our capability: (alaw) Video flag: True Text flag: True
  233. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13394 add_sdp: ** Our prefcodec: (nothing)
  234. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: chan_sip.c:13589 add_sdp: Setting framing on incoming call: 20
  235. [2017-02-19 00:04:46] DEBUG[1878][C-000022b1]: chan_sip.c:4527 __sip_ack: Stopping retransmission on 'VF287b7b2c336ce75638ef0619cfceae@voipfone' of Response 102: Match Found
  236. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: res_rtp_asterisk.c:4519 ast_rtp_read: 0x7fadf4075890 -- Probation learning mode pass with source address 46.31.231.185:57648
  237. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Wait'
  238. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: channel.c:5657 set_format: Channel SIP/Voipfone-SIP-0000004e setting write format path: slin -> alaw
  239. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: channel.c:3461 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  240. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: channel.c:8084 ast_channel_start_silence_generator: Started silence generator on 'SIP/Voipfone-SIP-0000004e'
  241. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:329 audiohook_read_frame_both: Failed to get 160 samples from write factory 0x3970258
  242. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:329 audiohook_read_frame_both: Failed to get 160 samples from write factory 0x3970258
  243. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  244. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:329 audiohook_read_frame_both: Failed to get 160 samples from write factory 0x3970258
  245. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  246. [2017-02-19 00:04:46] DEBUG[3050][C-000022b1]: res_rtp_asterisk.c:3601 ast_rtp_write: Ooh, format changed from none to alaw
  247. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  248. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  249. [2017-02-19 00:04:46] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  250. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  251. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  252. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  253. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  254. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  255. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  256. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  257. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  258. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  259. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  260. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  261. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  262. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  263. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  264. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  265. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  266. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  267. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  268. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  269. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  270. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  271. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  272. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  273. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  274. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  275. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  276. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  277. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  278. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  279. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  280. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  281. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  282. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  283. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  284. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  285. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  286. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  287. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  288. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  289. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  290. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  291. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  292. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  293. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  294. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  295. [2017-02-19 00:04:47] DEBUG[3052][C-000022b1]: audiohook.c:276 audiohook_read_frame_both: Read factory 0x396f818 and write factory 0x3970258 both fail to provide 160 samples
  296. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: channel.c:3461 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  297. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: channel.c:8119 ast_channel_stop_silence_generator: Stopped silence generator on 'SIP/Voipfone-SIP-0000004e'
  298. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: channel.c:5657 set_format: Channel SIP/Voipfone-SIP-0000004e setting write format path: alaw -> alaw
  299. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Playback'
  300. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: channel.c:5657 set_format: Channel SIP/Voipfone-SIP-0000004e setting write format path: slin -> alaw
  301. [2017-02-19 00:04:47] DEBUG[3050][C-000022b1]: channel.c:3461 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  302. [2017-02-19 00:04:51] DEBUG[1878][C-000022b1]: res_rtp_asterisk.c:4986 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x7fadf401baa0'
  303. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:3461 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  304. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:3461 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  305. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:5657 set_format: Channel SIP/Voipfone-SIP-0000004e setting write format path: alaw -> alaw
  306. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:4321 __ast_pbx_run: Extension 9399, priority 16 returned normally even though call was hung up
  307. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:2571 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'SIP/Voipfone-SIP-0000004e'
  308. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:2571 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'SIP/Voipfone-SIP-0000004e'
  309. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Macro'
  310. [2017-02-19 00:04:51] DEBUG[1829]: cdr.c:1293 cdr_object_finalize: Finalized CDR for SIP/Voipfone-SIP-0000004e - start 1487462686.628149 answer 1487462686.816040 end 1487462691.570017 dispo ANSWERED
  311. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  312. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  313. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  314. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  315. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  316. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: app_macro.c:459 _macro_exec: Executed application: ExecIf
  317. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:2875 pbx_extension_helper: Launching 'Hangup'
  318. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:2571 ast_softhangup_nolock: Soft-Hanging (0x20) up channel 'SIP/Voipfone-SIP-0000004e'
  319. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: pbx.c:4157 ast_pbx_h_exten_run: Spawn extension (ext-group,h,1) exited non-zero on 'SIP/Voipfone-SIP-0000004e'
  320. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: channel.c:2720 ast_hangup: Hanging up channel 'SIP/Voipfone-SIP-0000004e'
  321. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: chan_sip.c:7145 sip_hangup: Hangup call SIP/Voipfone-SIP-0000004e, SIP callid VF287b7b2c336ce75638ef0619cfceae@voipfone
  322. [2017-02-19 00:04:51] DEBUG[3050][C-000022b1]: res_rtp_asterisk.c:4986 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x7fadf401baa0'
  323. [2017-02-19 00:04:51] DEBUG[3052][C-000022b1]: autochan.c:71 ast_autochan_destroy: Removed autochan 0x3248a10 from the list, about to free it
  324. server1*CLI> core set debug 0
  325. Core debug was 1 and is now OFF.
  326.  

Reply to "truncated system call"

Here you can reply to the paste above